23:31:15.343 [7912.15840] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -owner NBU -fso -WOFB -fim VSS:prov_type=1,snap_attr=0,max_snapshots=1 -id E00001.srv.entreprise.int_1543444207 -nbu_version 135266304 C:\
23:31:15.343 [7912.15840] <2> bpfis main: received FIM as [43] VSS:prov_type=1,snap_attr=0,max_snapshots=1
23:31:15.343 [7912.15840] <2> bpfis main: VSS provider type: 1
23:31:15.343 [7912.15840] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:31:15.343 [7912.15840] <4> bpfis main: INF - BACKUP START 7912
23:31:15.343 [7912.15840] <2> check_special_names: got path entry as :<C:\>
23:31:15.624 [7912.14672] <2> send_keep_alive: INF - brm is gone
23:31:15.640 [7912.15840] <2> check_special_names: after conversion returning :<C:\>
23:31:15.640 [7912.15840] <2> bpfis main: filelist:<C:\>
23:31:15.640 [7912.15840] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS=<C:\>
23:31:15.640 [7912.15840] <2> ol_initialize: INF - stream_in_control = unknown+7912+1, stream_name = unknown+7912+1, streams = 1
23:31:15.640 [7912.15840] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI with vxms_started
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_OST
23:31:15.656 [7912.15840] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision$
23:31:15.656 [7912.15840] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer
23:31:15.656 [7912.15840] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision$
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=auto
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v2
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v3
23:31:15.656 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=OST_FIM
23:31:15.656 [7912.15840] <2> onlfi_vfms_logf: INF - FlashSnap_init: Veritas Volume Manager not installed.
23:31:15.656 [7912.15840] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:31:15.656 [7912.15840] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:31:15.656 [7912.15840] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:31:15.656 [7912.15840] <8> onlfi_initialize_vfms: WRN -
23:31:15.656 [7912.15840] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:31:15.656 [7912.15840] <8> onlfi_initialize_vfms: WRN -
23:31:15.671 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=HV
23:31:15.671 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=remote_vxfs
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - vxvm_init: Veritas Volume Manager not installed.
23:31:15.671 [7912.15840] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:31:15.671 [7912.15840] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:31:15.671 [7912.15840] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:31:15.671 [7912.15840] <8> onlfi_initialize_vfms: WRN -
23:31:15.671 [7912.15840] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:31:15.671 [7912.15840] <8> onlfi_initialize_vfms: WRN -
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision$
23:31:15.671 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware
23:31:15.671 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware_v2
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - @(#) $Source$ $Revision$
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:31:15.671 [7912.15840] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot
23:31:15.671 [7912.15840] <4> bpfis main: INF - FIS_ID=E00001.srv.entreprise.int_1543444207
23:31:15.671 [7912.15840] <2> onlfi_process_fs_list: INF - processing file=C:\Program Files\Veritas\NetBackup\temp\unknown+7912+1.std_filelist
23:31:15.671 [7912.15840] <2> onlfi_process_fs_list: INF - Checking the existence of entry [C:\] on local host
23:31:15.671 [7912.15840] <2> win_access: INF - file path: C:\
23:31:15.671 [7912.15840] <2> onlfi_process_fs_list: INF - calling onlfi_process_fs_list_fs_entry for entry [C:\] with Path C:\
23:31:15.671 [7912.15840] <2> devtomp: INF - matched GUID \\?\Volume{7736baa5-8e5a-11e7-80b5-806e6f6e6963} to device \Device\HarddiskVolume2
23:31:15.671 [7912.15840] <2> onlfi_process_fs_list_fs_entry: INF - Adding file system C:\ to list
23:31:15.671 [7912.15840] <4> bpfis main: parameter client_type 13, fis_accl 0, fis_accl_cksv -1, SNAPSHOT_IS_HERE 1
23:31:15.671 [7912.15840] <8> PauseChangeJournalDatabases: Failed to open flush NetBackup Change Journal databases mutex
23:31:15.671 [7912.15840] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:31:15.671 [7912.15840] <4> bpfis main: VSS Snapshot create Mutex lock acquired
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=VSS
23:31:15.671 [7912.15840] <4> onlfi_fim_dev_info: INF - Preparing freeze of C:\ using snapshot method VSS.
23:31:15.671 [7912.15840] <4> create_mount_point: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__7912_1
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - is_share, Path[C:\] is not a valid share
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - DBG: Resource 0 = C
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - DBG: nres=1 resource_count=0
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_OST
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_Writer
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=auto
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V_v2
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V_v3
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=OST_FIM
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=HV
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=remote_vxfs
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - remote_vxfs_query called
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=VMware
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=VMware_v2
23:31:15.671 [7912.15840] <2> onlfi_freeze_prepare: INF - snapshot method=NAS_Snapshot
23:31:15.671 [7912.15840] <2> onlfi_freeze: INF - snapshot method=VSS
23:31:15.671 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=VSS
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: fim VSS config=prov_type=1,snap_attr=0,max_snapshots=1,id=E00001.srv.entreprise.int_1543444207
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - Got delayed_mount_supported as 0
23:31:15.671 [7912.15840] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: Setting fim name in VxFI to VSS
23:31:15.703 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Wed Nov 28 2018 23:31:15.703000 <Thread id - 15840> Unable to import any login credentials for any appliances.
23:31:15.718 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:31:15.718000 <Thread id - 15840> CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found.
23:31:15.718 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:31:15.718000 <Thread id - 15840> CHitachiPlugin::init : Library RMLIB init failed
23:31:15.718 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Wed Nov 28 2018 23:31:15.718000 <Thread id - 15840> CHpEvaPlugin::init: CLI tool is not installed.
23:31:15.734 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Wed Nov 28 2018 23:31:15.734000 <Thread id - 15840> No array management credentials are available in configuration file.
23:31:15.734 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Wed Nov 28 2018 23:31:15.734000 <Thread id - 15840> DSCLI not found at path=[C:\Program Files\IBM\DSCLI\]
23:31:15.781 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: vss:Wed Nov 28 2018 23:31:15.781000 <Thread id - 15840> check_service_status - STATUS=[4]
23:31:15.828 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.828000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.828 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.828000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.828 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.828000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:31:15.828 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.828000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.828 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.828000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840> CWmiHelper::getVMList error from init()
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840> CWmiPlugin::claim error from getVMList(), not claiming
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.843 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.843000 <Thread id - 15840> CWmiHelper::getVMList error from init()
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.859000 <Thread id - 15840> CWmiPlugin::claim error from getVMList(), not claiming
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: winfivol:Wed Nov 28 2018 23:31:15.859000 DBG: getOffset: vol_name = \\?\Volume{7736baa5-8e5a-11e7-80b5-806e6f6e6963}
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: winfivol:Wed Nov 28 2018 23:31:15.859000 getOffset: Number of disk extents is [1]
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.859000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.859000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.859 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.859000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:31:15.874 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.874000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.874 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.874000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.874 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.874000 <Thread id - 15840> CWmiHelper::getVMList error from init()
23:31:15.874 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.874000 <Thread id - 15840> CWmiPlugin::claim error from getVMList(), not claiming
23:31:15.890 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: devicefi:Wed Nov 28 2018 23:31:15.890000 <Thread id - 15840> libscsitool: ERROR: sending SCSI CMD - ScsiStatus: 0x2
23:31:15.890 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: devicefi:Wed Nov 28 2018 23:31:15.890000 <Thread id - 15840> libscsitool: ERROR: CScsiTool::SendCdbToDevice() failed.
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840> CWmiHelper::getVMList error from init()
23:31:15.906 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:31:15.906000 <Thread id - 15840> CWmiPlugin::claim error from getVMList(), not claiming
23:31:15.984 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot]
23:31:15.999 [7912.15840] <2> onlfi_vfms_logf: INF - provider_type=VSS_PROV_SYSTEM
23:31:15.999 [7912.15840] <2> onlfi_vfms_logf: INF - <SET_CONFIG_INFO_LIST> <SET_CONFIG_INFO key=provider_type value=VSS_PROV_SYSTEM /> </SET_CONFIG_INFO_LIST>
23:31:16.031 [7912.15840] <2> onlfi_vfms_logf: INF - <SET_CONFIG_INFO_LIST> <SET_CONFIG_INFO key=provider_type value=VSS_PROV_SYSTEM /> </SET_CONFIG_INFO_LIST>
23:31:16.046 [7912.15840] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: Before calling vfi_set_import_options fi_nbu_catalog = [0]
23:31:22.640 [7912.15840] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[0]
23:31:22.687 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_build_alttree
23:31:22.703 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_build_alttree with 0
23:31:22.703 [7912.15840] <2> onlfi_vfms_logf: INF - DBG: FIM name = VSS and fisnapreplicate = 0
23:31:22.703 [7912.15840] <2> onlfi_vfms_logf: INF - fs=C:\
23:31:22.703 [7912.15840] <2> onlfi_vfms_logf: INF - Comparing C:\ and C:\
23:31:23.093 [7912.15840] <2> onlfi_vfms_logf: INF - Starting from node_children
23:31:23.109 [7912.15840] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_fiid =[<?xml version="1.0" encoding="UTF-16" standalone="no" ?><FIID xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:noNamespaceSchemaLocation="C:\Program Files\Common Files\Symantec Shared\VxFI\4\\Schema\vfiid_schema.xsd"><Node isGroup="0"><objid>root<!--objid--></objid><type>2050<!--type--></type><Node isGroup="0"><objid>C:\<!--objid--></objid><type>2050<!--type--></type><accessPoint>C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__7912_1<!--accessPoint--></accessPoint><nodeId>C:\<!--nodeId--></nodeId><providerPrefix>winfsys<!--providerPrefix--></providerPrefix><Node isGroup="0"><objid>&lt;vss&gt;\\?\Volume{7736baa5-8e5a-11e7-80b5-806e6f6e6963}<!--objid--></objid><type>4098<!--type--></type><fi_flags>9472<!--fi_flags--></fi_flags><tmpobjid>&lt;ShadowCopyID&gt;{98FC6ACF-C4BB-430B-8FA6-992B8CA40E83}<!--tmpobjid--></tmpobjid><altobjid>\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy199<!--altobjid--></altobjid><interposed_parent>C:\<!--interposed_parent--></interposed_parent><interposed>\\?\Volume{7736baa5-8e5a-11e7-80b5-806e6f6e6963}<!--interposed--></interposed><nodeId>&lt;vss&gt;\\?\Volume{7736baa5-8e5a-11e7-80b5-806e6f6e6963}<!--nodeId--></nodeId><providerPrefix>vss<!--providerPrefix--></providerPrefix><pluginBinaryData>PABEAEUATABBAFkARQBEAF8ASQBNAFAATwBSAFQAPgAwADwALwBEAEUATABB
23:31:23.109 [7912.15840] <2> onlfi_vfms_logf: INF - GetDiskFreeSpaceEx returned size for C:\ as 54487760896 bytes
23:31:23.109 [7912.15840] <2> onlfi_vfms_logf: INF - dump_fiid: Entry
23:31:23.109 [7912.15840] <2> onlfi_vfms_logf: INF - dump_fiid: NULL FIID name provided by caller. FIID file name C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.fiid
23:31:23.109 [7912.15840] <2> onlfi_vfms_logf: INF - dump_fiid: Exit normally
23:31:23.109 [7912.15840] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__7912_1
23:31:23.109 [7912.15840] <2> onlfi_vfms_logf: INF - VXMS ctl: getSnapshotSize = 53210704 Kbytes for afs = C:\
23:31:23.109 [7912.15840] <2> onlfi_fim_split: INF - Snapshot size for \Device\HarddiskVolume2 is returned as 53210704 Kbytes
23:31:23.109 [7912.15840] <2> onlfi_fim_clone: INF - cloning object \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy199 on
23:31:23.109 [7912.15840] <2> onlfi_fim_clone: INF - clone done
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=VSS_OST
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=VSS_OST
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=VSS_Writer
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=VSS_Writer
23:31:23.109 [7912.15840] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=auto
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=Hyper-V
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=Hyper-V_v2
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V_v2
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=Hyper-V_v3
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V_v3
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=OST_FIM
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=OST_FIM
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=HV
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=HV
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=remote_vxfs
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=remote_vxfs
23:31:23.109 [7912.15840] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=VMware
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=VMware_v2
23:31:23.109 [7912.15840] <2> onlfi_freeze: INF - snapshot method=NAS_Snapshot
23:31:23.109 [7912.15840] <2> onlfi_freeze_commit: INF - snapshot method=NAS_Snapshot
23:31:23.109 [7912.15840] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:31:23.109 [7912.15840] <2> determine_vss_provider_type: Get the VSS provider type used for the snapshot
23:31:23.109 [7912.15840] <2> determine_vss_provider_type: VSS system provider
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=VSS_OST
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[2].fim_name=VSS_Writer
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=auto
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=Hyper-V
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=Hyper-V_v2
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[6].fim_name=Hyper-V_v3
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[7].fim_name=OST_FIM
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[8].fim_name=HV
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[9].fim_name=remote_vxfs
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[10].fim_name=VMware
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[11].fim_name=VMware_v2
23:31:23.109 [7912.15840] <2> onlfi_dump_fim_list2: INF - fimlist_rec[12].fim_name=NAS_Snapshot
23:31:23.109 [7912.15840] <2> bpfis remap_name: Got snapshot size as 53210704 Kbytes
23:31:23.109 [7912.15840] <4> remap_file_name: INF - REMAP FILE BACKUP C:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy199\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_7912_1,FITYPE=MIRROR,MNTPOINT=C:\,FSTYPE=NTFS
23:31:23.109 [7912.15840] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:31:23.109 [7912.15840] <2> rw_lock: LockFileEx WRITELOCK
23:31:23.109 [7912.15840] <2> rw_lock: Lock acquired
23:31:23.109 [7912.15840] <2> addSnapshotReference: SnapShot Locked <E00001.srv.entreprise.int_1543444207>
23:31:23.109 [7912.15840] <2> addReference: Added  refferent jobid[0] pid[7912]
23:31:23.109 [7912.15840] <2> writeSnapshotLockFile: Writing type[0] refcount[1]
23:31:23.109 [7912.15840] <2> writeSnapshotLockFile: Writing Flags [0] [0]
23:31:23.109 [7912.15840] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Thu Jan 01 01:00:00 1970 ]
23:31:23.109 [7912.15840] <2> writeSnapshotLockFile: Writing Last Hostname []
23:31:23.109 [7912.15840] <2> writeSnapshotLockFile: Writing jobid[0] pid [7912]
23:31:23.171 [7912.15840] <2> addSnapshotReference: SnapShot unlockLocked <E00001.srv.entreprise.int_1543444207>
23:31:23.171 [7912.15840] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:31:23.171 [7912.15840] <2> do_post_processing: storing state files for E00001.srv.entreprise.int_1543444207
23:31:23.171 [7912.15840] <2> do_post_processing: storing state files for E00001.srv.entreprise.int_1543444207 is done
23:31:23.234 [7912.15840] <2> fis_db_process_file: INF - id = E00001.srv.entreprise.int_1543444207, copy number = 1
23:31:23.234 [7912.15840] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed
23:31:25.254 [7912.15840] <8> bpfis Exit: WRN - Error closing stdout
23:33:34.582 [16748.15952] <2> logparams: bpfis delete -id E00001.srv.entreprise.int_1543444207 -copy 1 -WOFB
23:33:34.582 [16748.15952] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:33:34.582 [16748.15952] <4> snapshotlock: Acquiring snapshot lock:retry count [0]
23:33:34.582 [16748.15952] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:33:34.582 [16748.15952] <2> rw_lock: LockFileEx WRITELOCK
23:33:34.582 [16748.15952] <2> rw_lock: Lock acquired
23:33:34.582 [16748.15952] <2> readSnapshotLockFile: Read type[0] refcount[1]
23:33:34.582 [16748.15952] <2> readSnapshotLockFile: Read Flags [0] [0]
23:33:34.582 [16748.15952] <2> readSnapshotLockFile: Read Last addReffernt Time [Thu Jan 01 01:00:00 1970 ]
23:33:34.582 [16748.15952] <2> readSnapshotLockFile: Read hostname []
23:33:34.582 [16748.15952] <2> readSnapshotLockFile: Read jobid[0] pid [7912]
23:33:34.582 [16748.15952] <2> cleanup: Deleting ref type jobid[0] pid [7912]
23:33:34.582 [16748.13936] <2> send_keep_alive: INF - brm is gone
23:33:34.582 [16748.15952] <4> bpfis main: INF - BACKUP START 16748
23:33:34.582 [16748.15952] <2> ol_initialize: INF - stream_in_control = unknown+d7912+1, stream_name = unknown+d7912+1, streams = 1
23:33:34.582 [16748.15952] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI with vxms_started
23:33:34.582 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS
23:33:34.582 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_OST
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision$
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision$
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=auto
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v2
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v3
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=OST_FIM
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - FlashSnap_init: Veritas Volume Manager not installed.
23:33:34.598 [16748.15952] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN -
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN -
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=HV
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=remote_vxfs
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - vxvm_init: Veritas Volume Manager not installed.
23:33:34.598 [16748.15952] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN -
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:33:34.598 [16748.15952] <8> onlfi_initialize_vfms: WRN -
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision$
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware_v2
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - @(#) $Source$ $Revision$
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot
23:33:34.598 [16748.15952] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS
23:33:34.598 [16748.15952] <2> rebuild_fim_list: INF - override_fim:[VSS], fis_cmd[4], sts_type[], fis_copy_no[1]
23:33:34.598 [16748.15952] <2> onlfi_thaw: INF - fim=VSS
23:33:34.598 [16748.15952] <4> onlfi_thaw: INF - Thawing C:\ using snapshot method VSS.
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - vfm_thaw: Before calling deport tree,  fi_name[VSS], copy_num [1], reaccessed[0], fi_keep_snapshot [0]
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ...
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - do_reaccess: fiid file [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.fiid]
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - do_reaccess: Setting fim name in VxFI to VSS
23:33:34.598 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_reaccess_tree
23:33:34.629 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Wed Nov 28 2018 23:33:34.629000 <Thread id - 15952> Unable to import any login credentials for any appliances.
23:33:34.629 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:33:34.629000 <Thread id - 15952> CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found.
23:33:34.629 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:33:34.629000 <Thread id - 15952> CHitachiPlugin::init : Library RMLIB init failed
23:33:34.644 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Wed Nov 28 2018 23:33:34.644000 <Thread id - 15952> CHpEvaPlugin::init: CLI tool is not installed.
23:33:34.644 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Wed Nov 28 2018 23:33:34.644000 <Thread id - 15952> No array management credentials are available in configuration file.
23:33:34.660 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Wed Nov 28 2018 23:33:34.644000 <Thread id - 15952> DSCLI not found at path=[C:\Program Files\IBM\DSCLI\]
23:33:34.676 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: vss:Wed Nov 28 2018 23:33:34.676000 <Thread id - 15952> check_service_status - STATUS=[4]
23:33:34.723 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: vss:Wed Nov 28 2018 23:33:34.723000 <Thread id - 15952> VssNode::VFI_SET_PROVIDERSTATE  m_snapshotSetId[{f2565daf-93da-4cd2-96b5-8d2331fb545e}][2872]
23:33:34.738 [16748.15952] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_reaccess_tree with 0
23:33:34.738 [16748.15952] <4> onlfi_thaw: INF - do_thaw return value: 0
23:33:34.738 [16748.15952] <2> onlfi_vfms_logf: INF - vfm_thaw: baseVersion: 3
23:33:34.738 [16748.15952] <2> onlfi_vfms_logf: INF - Not performing delete in vfm_thaw_commit
23:33:34.738 [16748.15952] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0
23:33:34.738 [16748.15952] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.fiid
23:33:34.738 [16748.15952] <4> do_post_processing: INF - BPFIS MESSAGE Routing to stream based backup for windows open file backup case, generating remap directive
23:33:34.738 [16748.15952] <4> remap_file_name: INF - REMAP FILE BACKUP C:\
23:33:34.738 [16748.15952] <2> do_post_processing: Failed to delete snapreplicapair file [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.snapreplicapair] return value[-1]						error [2] [No such file or directory]
23:33:34.738 [16748.15952] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:33:34.738 [16748.15952] <2> deleteReference: reference not found in the list
23:33:34.738 [16748.15952] <4> unlockSnapshot: Delete reference: status [-4]
23:33:34.738 [16748.15952] <2> writeSnapshotLockFile: Writing type[2] refcount[0]
23:33:34.738 [16748.15952] <2> writeSnapshotLockFile: Writing Flags [0] [0]
23:33:34.738 [16748.15952] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Thu Jan 01 01:00:00 1970 ]
23:33:34.738 [16748.15952] <2> writeSnapshotLockFile: Writing Last Hostname []
23:33:34.848 [16748.15952] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:33:34.848 [16748.15952] <2> unlockSnapshot: file[C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock] : zero reference count
23:33:34.848 [16748.15952] <4> unlockSnapshot: Released snapshot lock: status [0]
23:33:34.848 [16748.15952] <4> do_post_processing: Deleting snapshot lock files from client for id [E00001.srv.entreprise.int_1543444207] and copy_number [1]
23:33:34.848 [16748.15952] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:33:34.848 [16748.15952] <2> deleteSnapshotLockFile: Renamed lock file from [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock] to [\sgbc.] for deletion
23:33:34.848 [16748.15952] <2> deleteSnapshotLockFile: File[\sgbc.] delete: status[0]
23:33:35.269 [16748.15952] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed
23:33:37.285 [16748.15952] <2> bpfis Exit: INF - Close of stdout complete
23:33:37.629 [13264.15300] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -owner NBU -fso -WOFB -fim VSS:prov_type=1,snap_attr=0,max_snapshots=1 -id E00001.srv.entreprise.int_1543444207 -nbu_version 135266304 D:\
23:33:37.629 [13264.15300] <2> bpfis main: received FIM as [43] VSS:prov_type=1,snap_attr=0,max_snapshots=1
23:33:37.629 [13264.15300] <2> bpfis main: VSS provider type: 1
23:33:37.629 [13264.15300] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:33:37.629 [13264.15300] <4> bpfis main: INF - BACKUP START 13264
23:33:37.629 [13264.15300] <2> check_special_names: got path entry as :<D:\>
23:33:37.629 [13264.15300] <2> check_special_names: after conversion returning :<D:\>
23:33:37.629 [13264.15300] <2> bpfis main: filelist:<D:\>
23:33:37.629 [13264.15300] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS=<D:\>
23:33:37.629 [13264.17000] <2> send_keep_alive: INF - GET_FLIST_OPTS=<D:\>
23:33:37.629 [13264.15300] <2> ol_initialize: INF - stream_in_control = unknown+13264+1, stream_name = unknown+13264+1, streams = 1
23:33:37.644 [13264.15300] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI with vxms_started
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_OST
23:33:37.660 [13264.15300] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision$
23:33:37.660 [13264.15300] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer
23:33:37.660 [13264.15300] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision$
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=auto
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v2
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v3
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=OST_FIM
23:33:37.660 [13264.15300] <2> onlfi_vfms_logf: INF - FlashSnap_init: Veritas Volume Manager not installed.
23:33:37.660 [13264.15300] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:33:37.660 [13264.15300] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:33:37.660 [13264.15300] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:33:37.660 [13264.15300] <8> onlfi_initialize_vfms: WRN -
23:33:37.660 [13264.15300] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:33:37.660 [13264.15300] <8> onlfi_initialize_vfms: WRN -
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=HV
23:33:37.660 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=remote_vxfs
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - vxvm_init: Veritas Volume Manager not installed.
23:33:37.676 [13264.15300] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:33:37.676 [13264.15300] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:33:37.676 [13264.15300] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:33:37.676 [13264.15300] <8> onlfi_initialize_vfms: WRN -
23:33:37.676 [13264.15300] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:33:37.676 [13264.15300] <8> onlfi_initialize_vfms: WRN -
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision$
23:33:37.676 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware
23:33:37.676 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware_v2
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - @(#) $Source$ $Revision$
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:33:37.676 [13264.15300] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot
23:33:37.676 [13264.15300] <4> bpfis main: INF - FIS_ID=E00001.srv.entreprise.int_1543444207
23:33:37.676 [13264.15300] <2> onlfi_process_fs_list: INF - processing file=C:\Program Files\Veritas\NetBackup\temp\unknown+13264+1.std_filelist
23:33:37.676 [13264.15300] <2> onlfi_process_fs_list: INF - Checking the existence of entry [D:\] on local host
23:33:37.676 [13264.15300] <2> win_access: INF - file path: D:\
23:33:37.676 [13264.15300] <2> onlfi_process_fs_list: INF - calling onlfi_process_fs_list_fs_entry for entry [D:\] with Path D:\
23:33:37.676 [13264.15300] <2> devtomp: INF - matched GUID \\?\Volume{a35835d0-8e62-11e7-80b7-0a94ef45c44f} to device \Device\HarddiskVolume3
23:33:37.676 [13264.15300] <2> onlfi_process_fs_list_fs_entry: INF - Adding file system D:\ to list
23:33:37.676 [13264.15300] <4> bpfis main: parameter client_type 13, fis_accl 0, fis_accl_cksv -1, SNAPSHOT_IS_HERE 1
23:33:37.676 [13264.15300] <8> PauseChangeJournalDatabases: Failed to open flush NetBackup Change Journal databases mutex
23:33:37.676 [13264.15300] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:33:37.676 [13264.15300] <4> bpfis main: VSS Snapshot create Mutex lock acquired
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=VSS
23:33:37.676 [13264.15300] <4> onlfi_fim_dev_info: INF - Preparing freeze of D:\ using snapshot method VSS.
23:33:37.676 [13264.15300] <4> create_mount_point: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__13264_1
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - is_share, Path[D:\] is not a valid share
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - DBG: Resource 0 = D
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - DBG: nres=1 resource_count=0
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_OST
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_Writer
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=auto
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V_v2
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V_v3
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=OST_FIM
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=HV
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=remote_vxfs
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - remote_vxfs_query called
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=VMware
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=VMware_v2
23:33:37.676 [13264.15300] <2> onlfi_freeze_prepare: INF - snapshot method=NAS_Snapshot
23:33:37.676 [13264.15300] <2> onlfi_freeze: INF - snapshot method=VSS
23:33:37.676 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=VSS
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: fim VSS config=prov_type=1,snap_attr=0,max_snapshots=1,id=E00001.srv.entreprise.int_1543444207
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - Got delayed_mount_supported as 0
23:33:37.676 [13264.15300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: Setting fim name in VxFI to VSS
23:33:37.707 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Wed Nov 28 2018 23:33:37.707000 <Thread id - 15300> Unable to import any login credentials for any appliances.
23:33:37.707 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:33:37.707000 <Thread id - 15300> CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found.
23:33:37.723 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:33:37.723000 <Thread id - 15300> CHitachiPlugin::init : Library RMLIB init failed
23:33:37.723 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Wed Nov 28 2018 23:33:37.723000 <Thread id - 15300> CHpEvaPlugin::init: CLI tool is not installed.
23:33:37.738 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Wed Nov 28 2018 23:33:37.738000 <Thread id - 15300> No array management credentials are available in configuration file.
23:33:37.738 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Wed Nov 28 2018 23:33:37.738000 <Thread id - 15300> DSCLI not found at path=[C:\Program Files\IBM\DSCLI\]
23:33:37.785 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: vss:Wed Nov 28 2018 23:33:37.785000 <Thread id - 15300> check_service_status - STATUS=[4]
23:33:37.832 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.832000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.832 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.832000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.832 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.832000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:33:37.848 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.848000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.848 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.848000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.848 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.848000 <Thread id - 15300> CWmiHelper::getVMList error from init()
23:33:37.848 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.848000 <Thread id - 15300> CWmiPlugin::claim error from getVMList(), not claiming
23:33:37.848 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.848000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.848 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.848000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.863 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.863000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:33:37.863 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.863000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.863 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.863000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.863 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.863000 <Thread id - 15300> CWmiHelper::getVMList error from init()
23:33:37.863 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.863000 <Thread id - 15300> CWmiPlugin::claim error from getVMList(), not claiming
23:33:37.879 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: winfivol:Wed Nov 28 2018 23:33:37.879000 DBG: getOffset: vol_name = \\?\Volume{a35835d0-8e62-11e7-80b7-0a94ef45c44f}
23:33:37.879 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: winfivol:Wed Nov 28 2018 23:33:37.879000 getOffset: Number of disk extents is [1]
23:33:37.879 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.879000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.879 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.879000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.879 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.879000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:33:37.894 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.894000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.894 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.894000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.894 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.894000 <Thread id - 15300> CWmiHelper::getVMList error from init()
23:33:37.894 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.894000 <Thread id - 15300> CWmiPlugin::claim error from getVMList(), not claiming
23:33:37.910 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: devicefi:Wed Nov 28 2018 23:33:37.910000 <Thread id - 15300> libscsitool: ERROR: sending SCSI CMD - ScsiStatus: 0x2
23:33:37.910 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: devicefi:Wed Nov 28 2018 23:33:37.910000 <Thread id - 15300> libscsitool: ERROR: CScsiTool::SendCdbToDevice() failed.
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::initCluster() apparently this is not cluster. Initializing normal way
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300>  CWmiLibrary::initializeCom: ConnectServer() failed with error 0x8004100E. message: IDispatch error #3598
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300> CWmiHelper::init error from CWmiLibrary::getWmiConnection()
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300> CWmiHelper::getVMList error from init()
23:33:37.941 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Wed Nov 28 2018 23:33:37.941000 <Thread id - 15300> CWmiPlugin::claim error from getVMList(), not claiming
23:33:38.035 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot]
23:33:38.051 [13264.15300] <2> onlfi_vfms_logf: INF - provider_type=VSS_PROV_SYSTEM
23:33:38.066 [13264.15300] <2> onlfi_vfms_logf: INF - <SET_CONFIG_INFO_LIST> <SET_CONFIG_INFO key=provider_type value=VSS_PROV_SYSTEM /> </SET_CONFIG_INFO_LIST>
23:33:38.098 [13264.15300] <2> onlfi_vfms_logf: INF - <SET_CONFIG_INFO_LIST> <SET_CONFIG_INFO key=provider_type value=VSS_PROV_SYSTEM /> </SET_CONFIG_INFO_LIST>
23:33:38.113 [13264.15300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: Before calling vfi_set_import_options fi_nbu_catalog = [0]
23:33:43.801 [13264.15300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[0]
23:33:43.801 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_build_alttree
23:33:43.816 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_build_alttree with 0
23:33:43.816 [13264.15300] <2> onlfi_vfms_logf: INF - DBG: FIM name = VSS and fisnapreplicate = 0
23:33:43.816 [13264.15300] <2> onlfi_vfms_logf: INF - fs=D:\
23:33:43.816 [13264.15300] <2> onlfi_vfms_logf: INF - Comparing D:\ and D:\
23:33:43.816 [13264.15300] <2> onlfi_vfms_logf: INF - Starting from node_children
23:33:43.832 [13264.15300] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_fiid =[<?xml version="1.0" encoding="UTF-16" standalone="no" ?><FIID xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:noNamespaceSchemaLocation="C:\Program Files\Common Files\Symantec Shared\VxFI\4\\Schema\vfiid_schema.xsd"><Node isGroup="0"><objid>root<!--objid--></objid><type>2050<!--type--></type><Node isGroup="0"><objid>D:\<!--objid--></objid><type>2050<!--type--></type><accessPoint>C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__13264_1<!--accessPoint--></accessPoint><nodeId>D:\<!--nodeId--></nodeId><providerPrefix>winfsys<!--providerPrefix--></providerPrefix><Node isGroup="0"><objid>&lt;vss&gt;\\?\Volume{a35835d0-8e62-11e7-80b7-0a94ef45c44f}<!--objid--></objid><type>4098<!--type--></type><fi_flags>9472<!--fi_flags--></fi_flags><tmpobjid>&lt;ShadowCopyID&gt;{75739A41-B80C-4C39-A493-8AD184D3C216}<!--tmpobjid--></tmpobjid><altobjid>\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy200<!--altobjid--></altobjid><interposed_parent>D:\<!--interposed_parent--></interposed_parent><interposed>\\?\Volume{a35835d0-8e62-11e7-80b7-0a94ef45c44f}<!--interposed--></interposed><nodeId>&lt;vss&gt;\\?\Volume{a35835d0-8e62-11e7-80b7-0a94ef45c44f}<!--nodeId--></nodeId><providerPrefix>vss<!--providerPrefix--></providerPrefix><pluginBinaryData>PABEAEUATABBAFkARQBEAF8ASQBNAFAATwBSAFQAPgAwADwALwBEAEUATABB
23:33:43.879 [13264.15300] <2> onlfi_vfms_logf: INF - GetDiskFreeSpaceEx returned size for D:\ as 123974139904 bytes
23:33:43.879 [13264.15300] <2> onlfi_vfms_logf: INF - dump_fiid: Entry
23:33:43.879 [13264.15300] <2> onlfi_vfms_logf: INF - dump_fiid: NULL FIID name provided by caller. FIID file name C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.fiid
23:33:43.879 [13264.15300] <2> onlfi_vfms_logf: INF - dump_fiid: Exit normally
23:33:43.879 [13264.15300] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__13264_1
23:33:43.879 [13264.15300] <2> onlfi_vfms_logf: INF - VXMS ctl: getSnapshotSize = 121068496 Kbytes for afs = D:\
23:33:43.879 [13264.15300] <2> onlfi_fim_split: INF - Snapshot size for \Device\HarddiskVolume3 is returned as 121068496 Kbytes
23:33:43.879 [13264.15300] <2> onlfi_fim_clone: INF - cloning object \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy200 on
23:33:43.879 [13264.15300] <2> onlfi_fim_clone: INF - clone done
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=VSS_OST
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=VSS_OST
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=VSS_Writer
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=VSS_Writer
23:33:43.879 [13264.15300] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=auto
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=Hyper-V
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=Hyper-V_v2
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V_v2
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=Hyper-V_v3
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V_v3
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=OST_FIM
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=OST_FIM
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=HV
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=HV
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=remote_vxfs
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=remote_vxfs
23:33:43.879 [13264.15300] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=VMware
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=VMware_v2
23:33:43.879 [13264.15300] <2> onlfi_freeze: INF - snapshot method=NAS_Snapshot
23:33:43.879 [13264.15300] <2> onlfi_freeze_commit: INF - snapshot method=NAS_Snapshot
23:33:43.879 [13264.15300] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:33:43.879 [13264.15300] <2> determine_vss_provider_type: Get the VSS provider type used for the snapshot
23:33:43.879 [13264.15300] <2> determine_vss_provider_type: VSS system provider
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=VSS_OST
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[2].fim_name=VSS_Writer
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=auto
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=Hyper-V
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=Hyper-V_v2
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[6].fim_name=Hyper-V_v3
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[7].fim_name=OST_FIM
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[8].fim_name=HV
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[9].fim_name=remote_vxfs
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[10].fim_name=VMware
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[11].fim_name=VMware_v2
23:33:43.879 [13264.15300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[12].fim_name=NAS_Snapshot
23:33:43.879 [13264.15300] <2> bpfis remap_name: Got snapshot size as 121068496 Kbytes
23:33:43.879 [13264.15300] <4> remap_file_name: INF - REMAP FILE BACKUP D:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy200\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_13264_1,FITYPE=MIRROR,MNTPOINT=D:\,FSTYPE=NTFS
23:33:43.879 [13264.15300] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:33:43.879 [13264.15300] <2> rw_lock: LockFileEx WRITELOCK
23:33:43.879 [13264.15300] <2> rw_lock: Lock acquired
23:33:43.879 [13264.15300] <2> addSnapshotReference: SnapShot Locked <E00001.srv.entreprise.int_1543444207>
23:33:43.879 [13264.15300] <2> addReference: Added  refferent jobid[0] pid[13264]
23:33:43.879 [13264.15300] <2> writeSnapshotLockFile: Writing type[0] refcount[1]
23:33:43.879 [13264.15300] <2> writeSnapshotLockFile: Writing Flags [0] [0]
23:33:43.879 [13264.15300] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Thu Jan 01 01:00:00 1970 ]
23:33:43.879 [13264.15300] <2> writeSnapshotLockFile: Writing Last Hostname []
23:33:43.879 [13264.15300] <2> writeSnapshotLockFile: Writing jobid[0] pid [13264]
23:33:43.894 [13264.15300] <2> addSnapshotReference: SnapShot unlockLocked <E00001.srv.entreprise.int_1543444207>
23:33:43.894 [13264.15300] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:33:43.894 [13264.15300] <2> do_post_processing: storing state files for E00001.srv.entreprise.int_1543444207
23:33:43.894 [13264.15300] <2> do_post_processing: storing state files for E00001.srv.entreprise.int_1543444207 is done
23:33:43.957 [13264.15300] <2> fis_db_process_file: INF - id = E00001.srv.entreprise.int_1543444207, copy number = 1
23:33:43.957 [13264.15300] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed
23:33:45.972 [13264.15300] <2> bpfis Exit: INF - Close of stdout complete
23:37:18.047 [11384.8556] <2> logparams: bpfis delete -id E00001.srv.entreprise.int_1543444207 -copy 1 -WOFB
23:37:18.062 [11384.8556] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:37:18.062 [11384.8556] <4> snapshotlock: Acquiring snapshot lock:retry count [0]
23:37:18.062 [11384.8556] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:37:18.062 [11384.8556] <2> rw_lock: LockFileEx WRITELOCK
23:37:18.062 [11384.8556] <2> rw_lock: Lock acquired
23:37:18.062 [11384.8556] <2> readSnapshotLockFile: Read type[0] refcount[1]
23:37:18.062 [11384.8556] <2> readSnapshotLockFile: Read Flags [0] [0]
23:37:18.062 [11384.8556] <2> readSnapshotLockFile: Read Last addReffernt Time [Thu Jan 01 01:00:00 1970 ]
23:37:18.062 [11384.8556] <2> readSnapshotLockFile: Read hostname []
23:37:18.062 [11384.8556] <2> readSnapshotLockFile: Read jobid[0] pid [13264]
23:37:18.062 [11384.8556] <2> cleanup: Deleting ref type jobid[0] pid [13264]
23:37:18.062 [11384.15516] <2> send_keep_alive: INF - brm is gone
23:37:18.062 [11384.8556] <4> bpfis main: INF - BACKUP START 11384
23:37:18.062 [11384.8556] <2> ol_initialize: INF - stream_in_control = unknown+d13264+1, stream_name = unknown+d13264+1, streams = 1
23:37:18.078 [11384.8556] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI with vxms_started
23:37:18.078 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS
23:37:18.078 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_OST
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision$
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision$
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=auto
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v2
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V_v3
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=OST_FIM
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - FlashSnap_init: Veritas Volume Manager not installed.
23:37:18.094 [11384.8556] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN -
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN -
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=HV
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=remote_vxfs
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - vxvm_init: Veritas Volume Manager not installed.
23:37:18.094 [11384.8556] <2> onlfi_initialize_vfms: INF - Extracting snapshot client error = 0
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN -
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN - VfMS method error 0; see following message:
23:37:18.094 [11384.8556] <8> onlfi_initialize_vfms: WRN -
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision$
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware_v2
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - @(#) $Source$ $Revision$
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision$
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot
23:37:18.094 [11384.8556] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS
23:37:18.094 [11384.8556] <2> rebuild_fim_list: INF - override_fim:[VSS], fis_cmd[4], sts_type[], fis_copy_no[1]
23:37:18.094 [11384.8556] <2> onlfi_thaw: INF - fim=VSS
23:37:18.094 [11384.8556] <4> onlfi_thaw: INF - Thawing D:\ using snapshot method VSS.
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - vfm_thaw: Before calling deport tree,  fi_name[VSS], copy_num [1], reaccessed[0], fi_keep_snapshot [0]
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ...
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - do_reaccess: fiid file [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.fiid]
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - do_reaccess: Setting fim name in VxFI to VSS
23:37:18.094 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_reaccess_tree
23:37:18.125 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Wed Nov 28 2018 23:37:18.125000 <Thread id - 8556> Unable to import any login credentials for any appliances.
23:37:18.141 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:37:18.141000 <Thread id - 8556> CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found.
23:37:18.141 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Wed Nov 28 2018 23:37:18.141000 <Thread id - 8556> CHitachiPlugin::init : Library RMLIB init failed
23:37:18.156 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Wed Nov 28 2018 23:37:18.156000 <Thread id - 8556> CHpEvaPlugin::init: CLI tool is not installed.
23:37:18.156 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Wed Nov 28 2018 23:37:18.156000 <Thread id - 8556> No array management credentials are available in configuration file.
23:37:18.172 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Wed Nov 28 2018 23:37:18.172000 <Thread id - 8556> DSCLI not found at path=[C:\Program Files\IBM\DSCLI\]
23:37:18.203 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: vss:Wed Nov 28 2018 23:37:18.203000 <Thread id - 8556> check_service_status - STATUS=[4]
23:37:18.312 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: vss:Wed Nov 28 2018 23:37:18.250000 <Thread id - 8556> VssNode::VFI_SET_PROVIDERSTATE  m_snapshotSetId[{f7a810d4-7682-4330-9e82-d2c8f471e71d}][2872]
23:37:18.344 [11384.8556] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_reaccess_tree with 0
23:37:18.344 [11384.8556] <4> onlfi_thaw: INF - do_thaw return value: 0
23:37:18.344 [11384.8556] <2> onlfi_vfms_logf: INF - vfm_thaw: baseVersion: 3
23:37:18.344 [11384.8556] <2> onlfi_vfms_logf: INF - Not performing delete in vfm_thaw_commit
23:37:18.344 [11384.8556] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0
23:37:18.344 [11384.8556] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.fiid
23:37:18.344 [11384.8556] <4> do_post_processing: INF - BPFIS MESSAGE Routing to stream based backup for windows open file backup case, generating remap directive
23:37:18.344 [11384.8556] <4> remap_file_name: INF - REMAP FILE BACKUP D:\
23:37:18.344 [11384.8556] <2> do_post_processing: Failed to delete snapreplicapair file [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.E00001.srv.entreprise.int_1543444207.1.0.snapreplicapair] return value[-1]						error [2] [No such file or directory]
23:37:18.344 [11384.8556] <4> keep_alive_and_snapshot_control: Starting keep alive thread.
23:37:18.344 [11384.8556] <2> deleteReference: reference not found in the list
23:37:18.344 [11384.8556] <4> unlockSnapshot: Delete reference: status [-4]
23:37:18.344 [11384.8556] <2> writeSnapshotLockFile: Writing type[2] refcount[0]
23:37:18.344 [11384.8556] <2> writeSnapshotLockFile: Writing Flags [0] [0]
23:37:18.344 [11384.8556] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Thu Jan 01 01:00:00 1970 ]
23:37:18.344 [11384.8556] <2> writeSnapshotLockFile: Writing Last Hostname []
23:37:18.359 [11384.8556] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:37:18.359 [11384.8556] <2> unlockSnapshot: file[C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock] : zero reference count
23:37:18.359 [11384.8556] <4> unlockSnapshot: Released snapshot lock: status [0]
23:37:18.359 [11384.8556] <4> do_post_processing: Deleting snapshot lock files from client for id [E00001.srv.entreprise.int_1543444207] and copy_number [1]
23:37:18.359 [11384.8556] <2> getFileName: FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock]
23:37:18.359 [11384.8556] <2> deleteSnapshotLockFile: Renamed lock file from [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\E00001.srv.entreprise.int_1543444207_copy1.lock] to [\sb3o.] for deletion
23:37:18.359 [11384.8556] <2> deleteSnapshotLockFile: File[\sb3o.] delete: status[0]
23:37:18.625 [11384.8556] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed
23:37:20.641 [11384.8556] <2> bpfis Exit: INF - Close of stdout complete