02:48:28.061 [9612.9744] <2> logparams: bpfis query -id client.domain.com_1331793898 02:48:28.076 [9612.9744] <2> bpfis_state_on_master_xfer_level: bpfis state file transfer is disabled (level = 0), touch file exists: 1, -noserverstate: 0, -nbu: 0 02:48:28.170 [9612.9744] <4> bpfis main: INF - BACKUP START 9612 02:48:28.186 [9612.9744] <32> fis_rebuild_from_db: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0 02:48:28.186 [9612.9744] <4> bpfis Exit: INF - EXIT STATUS 12: file open failed 02:48:28.186 [9612.9744] <2> bpfis Exit: INF - Close of stdout complete 02:52:57.375 [9936.7952] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id client.domain.com_1331793898 C:\ 02:52:57.375 [9936.7952] <2> bpfis main: received FIM as [3] VSS 02:52:57.406 [9936.7952] <4> bpfis main: INF - BACKUP START 9936 02:52:57.406 [9936.7952] <2> bpfis main: filelist: 02:52:57.406 [9936.7952] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS= 02:52:57.406 [9936.7952] <2> ol_initialize: INF - stream_in_control = unknown+9936+1, stream_name = unknown+9936+1, streams = 1 02:52:57.484 [9936.7952] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.232: 0: starting cache size: 200 0x000000c8 02:52:57.484 [9936.7952] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 02:52:57.484 [9936.7952] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 02:52:57.484 [9936.7952] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: client.domain.com 02:52:57.484 [9936.7952] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 02:52:57.484 [9936.7952] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\vfm.conf 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 7: FIM auto "C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll" 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 27: FIM Hyper-V "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" nameuse=%dd[hyperv_client_name_type_list]#Client Name Selection,Virtual_machine_backup=%dd[hyperv_backup_type_list]#Virtual Machine Backup,prov_type=%dd[vss_provider_type_list]#Provider Type,snap_attr=%dd[snapshot_attribute_list]#Snapshot Attribute,allow_offline_backup=%b[1]#Allow Offline Backup of VM,csv_timeout=%d[0-:0]#CSV Timeout in Minutes 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 50: FIM VSS "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" prov_type=%d[0-3:0]#Provider Type (0-auto 1-system 2-software 3-hardware),snap_attr=%d[0-2:0]#Snapshot Attribute(0-unspecified 1-differential 2-plex),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 72: FIM FlashSnap "C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll" keep_fi=%b[0]#Keep snapshot after backup (not Instant Recovery),async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),dg_split_retry_times=%d[1]#Number of times to retry disk group split,dg_split_retry_wait_period=%d[60-:60]#Delay in seconds between disk group split retries,num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 93: FIM vxvm "C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll" async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),sync_wait=%b[1]#Wait for mirror sync completion (Data Mover backups only),num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 113: FIM VMware "C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll" nameuse=%dd[vmware_client_name_type_list]#Client name selection,Virtual_machine_backup=%dd[vmware_backup_type_list]#Virtual machine backup,trantype=%dd[VMware_transfer_list]#Transfer type,disable_quiesce=%dd[enabled_disable_quiesce_list]#Virtual machine quiesce,file_system_optimization=%dd[enabled_disabled_type_list]#Exclude unused and deleted blocks,snapact=%dd[snapshot_handling_list]#Existing snapshot handling,vmmono=%dd[disabled_enabled_type_list]#Monolithic export (VCB only),mntpt=%s#Snapshot mount point (VCB only) 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 135: FIM NAS_Snapshot "C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll" max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 02:52:57.500 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 156: FIM VSS_Writer "C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll" 02:52:57.500 [9936.7952] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\vfm.conf 02:52:57.531 [9936.7952] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $ 02:52:57.531 [9936.7952] <2> onlfi_add_to_fim_list: INF - snapshot method=auto 02:52:57.531 [9936.7952] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI 02:53:20.125 [9936.7952] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V 02:53:20.125 [9936.7952] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 02:53:20.187 [9936.7952] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 02:53:20.187 [9936.7952] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 02:53:20.187 [9936.7952] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 02:53:20.187 [9936.7952] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 02:53:20.187 [9936.7952] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed. 02:53:20.219 [9936.7952] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 02:53:20.219 [9936.7952] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 02:53:20.219 [9936.7952] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 02:53:20.219 [9936.7952] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 02:53:20.219 [9936.7952] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed. 02:53:20.422 [9936.7952] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 02:53:20.422 [9936.7952] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $ 02:53:20.422 [9936.7952] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $ 02:53:20.422 [9936.7952] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 02:53:20.422 [9936.7952] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $ 02:53:20.422 [9936.7952] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 02:53:20.422 [9936.7952] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware 02:53:20.453 [9936.7952] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 02:53:20.453 [9936.7952] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 02:53:20.453 [9936.7952] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 02:53:20.453 [9936.7952] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot 02:53:20.531 [9936.7952] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $ 02:53:20.531 [9936.7952] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 02:53:20.531 [9936.7952] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 02:53:20.531 [9936.7952] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer 02:53:20.531 [9936.7952] <4> bpfis main: INF - FIS_ID=client.domain.com_1331793898 02:53:20.547 [9936.7952] <2> onlfi_process_fs_list: INF - processing file=C:\Program Files\Veritas\NetBackup\temp\unknown+9936+1.std_filelist 02:53:20.547 [9936.7952] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\temp\unknown+9936+1.std_filelist 02:53:20.547 [9936.7952] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\temp\unknown+9936+1.std_filelist, line 1: C:\ 02:53:20.562 [9936.7952] <2> devtomp: INF - matched GUID \\?\Volume{006737fb-e0a8-11dd-98df-806e6f6e6963} to device \Device\HarddiskVolume1 02:53:20.562 [9936.7952] <2> make_fsmount_name: INF - name C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936, path C:\, stream_id 9936 02:53:20.562 [9936.7952] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 02:53:20.562 [9936.7952] <2> onlfi_process_fs_list_fs_entry: INF - Adding file system C:\ to list 02:53:20.562 [9936.7952] <2> onlfi_add_to_fsmap_list2: INF - Adding entry: 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=0 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:53:20.562 [9936.7952] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 02:53:20.562 [9936.7952] <2> make_fsmount_name: INF - name C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936, path C:\, stream_id 9936 02:53:20.562 [9936.7952] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\temp\unknown+9936+1.std_filelist 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - Have 6 snapshot list entries and 1 filesystem device list entries. 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fimlist_rec[0].fim_name=auto 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=Hyper-V 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fimlist_rec[2].fim_name=VSS 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fs_devlist_rec[0]= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=0 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:53:20.562 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=VMware 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=NAS_Snapshot 02:53:20.562 [9936.7952] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=VSS_Writer 02:53:20.562 [9936.7952] <2> onlfi_runtime_check: INF - snapshot method=auto 02:53:20.562 [9936.7952] <2> onlfi_runtime_check: INF - snapshot method=Hyper-V 02:53:20.562 [9936.7952] <2> onlfi_runtime_check: INF - snapshot method=VSS 02:53:20.562 [9936.7952] <2> onlfi_runtime_check: INF - snapshot method=VMware 02:53:20.562 [9936.7952] <2> onlfi_runtime_check: INF - snapshot method=NAS_Snapshot 02:53:20.562 [9936.7952] <2> onlfi_runtime_check: INF - snapshot method=VSS_Writer 02:53:20.562 [9936.7952] <2> onlfi_freeze_prepare: INF - snapshot method=auto 02:53:20.578 [9936.7952] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V 02:53:20.578 [9936.7952] <2> onlfi_freeze_prepare: INF - snapshot method=VSS 02:53:20.578 [9936.7952] <4> onlfi_fim_dev_info: INF - Preparing freeze of C:\ using snapshot method VSS. 02:53:20.578 [9936.7952] <2> onlfi_fim_dev_info: INF - Before freeze 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=0 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:53:20.578 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:53:20.578 [9936.7952] <4> create_mount_point: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:53:20.578 [9936.7952] <2> start_keep_alive_thread: INF - Start keep_alive thread 02:53:20.578 [9936.7952] <2> read_vfm_conf: options=0x2 02:53:20.578 [9936.4632] <2> send_keep_alive: INF - sending keep alive 02:53:20.578 [9936.7952] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: auto 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: Hyper-V 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword nameuse 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default hyperv_client_name_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Client Name Selection 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default hyperv_backup_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword prov_type 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification vss_provider_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='vss_provider_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default vss_provider_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Provider Type 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword snap_attr 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='snapshot_attribute_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default snapshot_attribute_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Snapshot Attribute 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword allow_offline_backup 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %b 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword csv_timeout 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0-:0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0-:0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 02:53:20.594 [9936.7952] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: VSS 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword prov_type 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0-3:0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0-3:0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword snap_attr 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0-2:0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0-2:0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword max_snapshots 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: FlashSnap 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword keep_fi 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %b 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword async_resync 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %b 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword max_snapshots 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword dg_split_retry_times 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 60-:60 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='60-:60' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 60 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword num_sync_vols 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-32:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-32:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword iosize 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-128:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-128:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword slow 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0-:0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0-:0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: vxvm 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword async_resync 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %b 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword max_snapshots 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword sync_wait 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %b 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword num_sync_vols 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-32:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-32:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword iosize 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-128:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-128:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword slow 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 0-:0 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='0-:0' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 0 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: VMware 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword nameuse 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default vmware_client_name_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Client name selection 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='vmware_backup_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default vmware_backup_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Virtual machine backup 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword trantype 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification VMware_transfer_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='VMware_transfer_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default VMware_transfer_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Transfer type 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword disable_quiesce 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default enabled_disable_quiesce_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword file_system_optimization 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default enabled_disabled_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword snapact 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification snapshot_handling_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='snapshot_handling_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default snapshot_handling_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Existing snapshot handling 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword vmmono 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %dd 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default disabled_enabled_type_list 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword mntpt 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %s 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 02:53:20.594 [9936.7952] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: NAS_Snapshot 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found keyword max_snapshots 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found data type %d 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found value specification 1-:1 02:53:20.594 [9936.7952] <2> parse_value_specification: Value specification='1-:1' 02:53:20.594 [9936.7952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 02:53:20.594 [9936.7952] <2> parse_value_specification: Found default 1 02:53:20.594 [9936.7952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found FIM: VSS_Writer 02:53:20.594 [9936.7952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 02:53:20.609 [9936.7952] <2> onlfi_fim_dev_info: INF - After freeze 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=1 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:53:20.609 [9936.7952] <2> onlfi_freeze_prepare: INF - snapshot method=VMware 02:53:20.609 [9936.7952] <2> onlfi_freeze_prepare: INF - snapshot method=NAS_Snapshot 02:53:20.609 [9936.7952] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_Writer 02:53:20.609 [9936.7952] <2> onlfi_freeze: INF - snapshot method=auto 02:53:20.609 [9936.7952] <2> onlfi_freeze: INF - snapshot method=Hyper-V 02:53:20.609 [9936.7952] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V 02:53:20.609 [9936.7952] <2> onlfi_freeze: INF - snapshot method=VSS 02:53:20.609 [9936.7952] <2> onlfi_freeze_commit: INF - snapshot method=VSS 02:53:20.609 [9936.7952] <2> onlfi_fim_split: INF - before split... 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=1 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:53:20.609 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:53:20.609 [9936.7952] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: config= 02:53:25.125 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 02:53:25.125000 WARNING: No credentials found for EMC CLARIION. 02:53:25.125 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 02:53:25.125000 Unable to import any login credentials for any appliances. 02:53:25.125 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcclariionfi : corresponding supported PTP not found. 02:53:25.391 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: emcsymfi: CLI tool C:\Program Files\EMC\SYMCLI\bin\ installation path symcfg.exe is invalid. 02:53:25.391 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcsymfi : corresponding supported PTP not found. 02:53:25.609 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 02:53:25.609000 CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found. 02:53:25.609 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 02:53:25.609000 CHitachiPlugin::init : Library RMLIB init failed 02:53:25.609 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hitachifi : corresponding supported PTP not found. 02:53:26.203 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi: CLI tool C:\Program Files\Hewlett-Packard\Sanworks\Element Manager for StorageWorks HSV\Bridge\ installation path sssu_win_ia64.exe is invalid. 02:53:26.203 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Thu Mar 15 2012 02:53:26.203000 CHpEvaPlugin::init: CLI tool is not installed. 02:53:26.203 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpevafi : corresponding supported PTP not found. 02:53:26.703 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Thu Mar 15 2012 02:53:26.703000 No array management credentials are available in configuration file. 02:53:26.703 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi: CLI tool C:\Program Files\Compaq\Hpacucli\Bin\ installation path hpacucli.exe is invalid. 02:53:26.703 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpmsafi : corresponding supported PTP not found. 02:53:27.016 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Thu Mar 15 2012 02:53:27.016000 DSCLI not found at path=[C:\Program Files\IBM\DSCLI\] 02:53:27.016 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: 02:53:27.016 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin ibmtsfi : corresponding supported PTP not found. 02:53:27.250 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: netappfi:Thu Mar 15 2012 02:53:27.250000 WARNING: No credentials found for NETAPP LUN. 02:53:27.250 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: netappfi: 02:53:27.250 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin netappfi : corresponding supported PTP not found. 02:53:27.937 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:53:27.937000 check_service_status - STATUS=[4] 02:53:29.641 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Thu Mar 15 2012 02:53:29.641000 CWmiPlugin::init WMI Provider is suppored on windows 2008 server only 02:53:29.641 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin wmifi : corresponding supported PTP not found. 02:53:29.812 [9936.9100] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:53:29.812000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 02:53:29.812 [9936.9100] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:53:29.812000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 02:53:29.812 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot] 02:53:29.828 [9936.7952] <2> onlfi_vfms_logf: INF - 02:53:30.125 [9936.9100] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:53:30.125000 VSS API ERROR:- API [QueryStatus] status = 42309 [VSS_S_ASYNC_PENDING] 02:53:41.141 [9936.9100] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:53:41.141000 VSS API ERROR:- API [DoSnapshotSetWorkItem:QueryStatus] status = 80042306 [VSS_E_PROVIDER_VETO] 02:53:41.141 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: vss: "IVssBackupComponents::DoSnapshotSet" failed with error "VSS_E_PROVIDER_VETO:(error value=0x80042306)" while trying to commit snapshot set {1831D1FC-F23E-4F8D-AB31-517957673A5C} 02:53:41.141 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:53:41.141000 VssNode::make: encountered VFI_EPROVIDERVETO 02:53:41.141 [9936.7952] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[41] 02:53:41.141 [9936.7952] <2> onlfi_vfms_logf: INF - make error [41] encountered. Sleeping for 300 seconds 02:54:20.610 [9936.4632] <2> send_keep_alive: INF - sending keep alive 02:55:20.610 [9936.4632] <2> send_keep_alive: INF - sending keep alive 02:56:20.610 [9936.4632] <2> send_keep_alive: INF - sending keep alive 02:57:20.611 [9936.4632] <2> send_keep_alive: INF - sending keep alive 02:58:20.611 [9936.4632] <2> send_keep_alive: INF - sending keep alive 02:58:41.158 [9936.7952] <2> onlfi_vfms_logf: INF - Retrying again.... 02:58:43.924 [9936.9100] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 02:58:43.893000 VSS API ERROR:- API [DoSnapshotSet] return val = 80042301 [VSS_E_BAD_STATE] 02:58:43.955 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: vss: "IVssBackupComponents::DoSnapshotSet" failed with error "VSS_E_BAD_STATE:(error value=0x80042301)" while trying to commit snapshot set {D10F9570-03EB-4E56-932D-E565AFC2062D} 02:58:43.955 [9936.7952] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[6] 02:58:44.033 [9936.7952] <32> onlfi_fim_split: FTL - VfMS error 11; see following messages: 02:58:44.033 [9936.7952] <32> onlfi_fim_split: FTL - Fatal method error was reported 02:58:44.033 [9936.7952] <32> onlfi_fim_split: FTL - vfm_freeze_commit: method: VSS, type: FIM, function: VSS_make 02:58:44.033 [9936.7952] <32> onlfi_fim_split: FTL - VfMS method error 6; see following message: 02:58:44.033 [9936.7952] <32> onlfi_fim_split: FTL - snapshot services: snapshot creation failed: provider-private error. 02:58:44.096 [9936.7952] <2> onlfi_fim_split: INF - After split.... 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=1 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:58:44.096 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:58:44.111 [9936.7952] <32> onlfi_freeze: FTL - VfMS error 11; see following messages: 02:58:44.111 [9936.7952] <32> onlfi_freeze: FTL - Fatal method error was reported 02:58:44.111 [9936.7952] <32> onlfi_freeze: FTL - vfm_freeze_commit: method: VSS, type: FIM, function: VSS_make 02:58:44.111 [9936.7952] <32> onlfi_freeze: FTL - VfMS method error 6; see following message: 02:58:44.111 [9936.7952] <32> onlfi_freeze: FTL - snapshot services: snapshot creation failed: provider-private error. 02:58:44.111 [9936.7952] <2> onlfi_thaw: INF - fim=auto 02:58:44.111 [9936.7952] <2> onlfi_thaw: INF - fim=Hyper-V 02:58:44.111 [9936.7952] <2> onlfi_thaw: INF - fim=VSS 02:58:44.111 [9936.7952] <4> onlfi_thaw: INF - Thawing C:\ using snapshot method VSS. 02:58:44.111 [9936.7952] <2> do_thaw: INF - do_thaw: parent: thaw flag on 02:58:44.127 [9936.7952] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ... 02:58:44.189 [9936.7952] <4> onlfi_thaw: INF - do_thaw return value: 0 02:58:44.189 [9936.7952] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:58:44.189 [9936.7952] <2> onlfi_thaw: INF - fim=VMware 02:58:44.189 [9936.7952] <2> onlfi_thaw: INF - fim=NAS_Snapshot 02:58:44.189 [9936.7952] <2> onlfi_thaw: INF - fim=VSS_Writer 02:58:44.189 [9936.7952] <2> onlfi_thaw: INF - resyncing VSS object 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fs_name=C:\ 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fs_type=NTFS 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume1 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - raw_device= 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - volume_group= 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fi_mnt_device= 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fi_raw_device= 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - mnt_flag=0 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fi_flag=1 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - fm_flag=0 02:58:44.189 [9936.7952] <2> log_fs_dev_rec: INF - cs_flag=0 02:58:44.205 [9936.7952] <4> bpfis main: INF - snapshot creation failed, status 156 02:58:44.221 [9936.7952] <2> onlfi_thaw: INF - fim=auto 02:58:44.221 [9936.7952] <2> onlfi_thaw: INF - fim=Hyper-V 02:58:44.221 [9936.7952] <2> onlfi_thaw: INF - fim=VSS 02:58:44.221 [9936.7952] <4> onlfi_thaw: INF - Thawing C:\ using snapshot method VSS. 02:58:44.252 [9936.7952] <8> onlfi_thaw: WRN - C:\ is not frozen 02:58:44.252 [9936.7952] <2> onlfi_thaw: INF - fim=VMware 02:58:44.252 [9936.7952] <2> onlfi_thaw: INF - fim=NAS_Snapshot 02:58:44.252 [9936.7952] <2> onlfi_thaw: INF - fim=VSS_Writer 02:58:44.268 [9936.7952] <8> bpfis Exit: WRN - snapshot delete returned status 20 02:58:44.268 [9936.7952] <2> onlfi_get_fsdev_info: INF - Looking up C:\ 02:58:44.268 [9936.7952] <2> onlfi_get_fsdev_info: INF - comparing C:\ to C:\ 02:58:44.268 [9936.7952] <2> onlfi_get_fsdev_info: INF - Match found for C:\ 02:58:44.268 [9936.7952] <2> bpfis remap_name: fs_name=C:\ 02:58:44.268 [9936.7952] <2> bpfis remap_name: mapped fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__9936 02:58:44.268 [9936.7952] <4> remap_file_name: INF - REMAP FILE BACKUP C:\ 02:58:44.283 [9936.7952] <2> ol_cleanup: INF - removing C:\Program Files\Veritas\NetBackup\temp\unknown+9936+1.std_filelist 02:58:44.314 [9936.7952] <2> onlfi_vfms_logf: INF - snapshot services: improper disposition parameter. 02:58:44.611 [9936.7952] <32> onlfi_cleanup: FTL - VfMS error 11; see following messages: 02:58:44.611 [9936.7952] <32> onlfi_cleanup: FTL - Fatal method error was reported 02:58:44.611 [9936.7952] <32> onlfi_cleanup: FTL - vfm_fi_terminate: method: VxFI, type: FIM, function: VxFI_detach 02:58:44.611 [9936.7952] <32> onlfi_cleanup: FTL - VfMS method error 0; see following message: 02:58:44.611 [9936.7952] <32> onlfi_cleanup: FTL - snapshot services: snapshot detach failed 1 times: invalid argument(s). 02:58:44.955 [9936.7952] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed 02:58:44.955 [9936.7952] <2> stop_keep_alive_thread: INF - Stop keep_alive thread 02:58:46.955 [9936.7952] <2> bpfis Exit: INF - Close of stdout complete 02:58:47.033 [9936.7952] <2> onlfi_vfms_logf: INF - INF - free_VSS_slot() Entering. 02:58:47.033 [9936.7952] <2> onlfi_vfms_logf: INF - release_VSS_slot: Entering. 02:58:47.033 [9936.7952] <2> onlfi_vfms_logf: INF - release_VSS_slot: INF - Semaphore handle received with NULL value. 02:58:47.033 [9936.7952] <2> onlfi_vfms_logf: INF - release_VSS_slot: Exiting. 02:58:47.033 [9936.7952] <2> onlfi_vfms_logf: INF - INF - free_VSS_slot() Exiting. 03:02:01.409 [9592.4984] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id client.domain.com_1331793898 D:\ 03:02:01.425 [9592.4984] <2> bpfis main: received FIM as [3] VSS 03:02:01.441 [9592.4984] <4> bpfis main: INF - BACKUP START 9592 03:02:01.441 [9592.4984] <2> bpfis main: filelist: 03:02:01.441 [9592.4984] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS= 03:02:01.441 [9592.4984] <2> ol_initialize: INF - stream_in_control = unknown+9592+1, stream_name = unknown+9592+1, streams = 1 03:02:01.534 [9592.4984] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.232: 0: starting cache size: 200 0x000000c8 03:02:01.550 [9592.4984] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:02:01.550 [9592.4984] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:02:01.550 [9592.4984] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: client.domain.com 03:02:01.550 [9592.4984] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:02:01.550 [9592.4984] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\vfm.conf 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 7: FIM auto "C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll" 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 27: FIM Hyper-V "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" nameuse=%dd[hyperv_client_name_type_list]#Client Name Selection,Virtual_machine_backup=%dd[hyperv_backup_type_list]#Virtual Machine Backup,prov_type=%dd[vss_provider_type_list]#Provider Type,snap_attr=%dd[snapshot_attribute_list]#Snapshot Attribute,allow_offline_backup=%b[1]#Allow Offline Backup of VM,csv_timeout=%d[0-:0]#CSV Timeout in Minutes 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 50: FIM VSS "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" prov_type=%d[0-3:0]#Provider Type (0-auto 1-system 2-software 3-hardware),snap_attr=%d[0-2:0]#Snapshot Attribute(0-unspecified 1-differential 2-plex),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 72: FIM FlashSnap "C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll" keep_fi=%b[0]#Keep snapshot after backup (not Instant Recovery),async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),dg_split_retry_times=%d[1]#Number of times to retry disk group split,dg_split_retry_wait_period=%d[60-:60]#Delay in seconds between disk group split retries,num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 93: FIM vxvm "C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll" async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),sync_wait=%b[1]#Wait for mirror sync completion (Data Mover backups only),num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 113: FIM VMware "C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll" nameuse=%dd[vmware_client_name_type_list]#Client name selection,Virtual_machine_backup=%dd[vmware_backup_type_list]#Virtual machine backup,trantype=%dd[VMware_transfer_list]#Transfer type,disable_quiesce=%dd[enabled_disable_quiesce_list]#Virtual machine quiesce,file_system_optimization=%dd[enabled_disabled_type_list]#Exclude unused and deleted blocks,snapact=%dd[snapshot_handling_list]#Existing snapshot handling,vmmono=%dd[disabled_enabled_type_list]#Monolithic export (VCB only),mntpt=%s#Snapshot mount point (VCB only) 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 135: FIM NAS_Snapshot "C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll" max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:02:01.550 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 156: FIM VSS_Writer "C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll" 03:02:01.550 [9592.4984] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\vfm.conf 03:02:01.566 [9592.4984] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $ 03:02:01.566 [9592.4984] <2> onlfi_add_to_fim_list: INF - snapshot method=auto 03:02:01.566 [9592.4984] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI 03:02:01.581 [9592.4984] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V 03:02:01.581 [9592.4984] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 03:02:01.581 [9592.4984] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:02:01.581 [9592.4984] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:02:01.581 [9592.4984] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 03:02:01.581 [9592.4984] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:02:01.581 [9592.4984] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed. 03:02:01.597 [9592.4984] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:02:01.597 [9592.4984] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:02:01.597 [9592.4984] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 03:02:01.597 [9592.4984] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:02:01.597 [9592.4984] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed. 03:02:01.675 [9592.4984] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:02:01.675 [9592.4984] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $ 03:02:01.675 [9592.4984] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $ 03:02:01.675 [9592.4984] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:02:01.675 [9592.4984] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $ 03:02:01.675 [9592.4984] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:02:01.675 [9592.4984] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware 03:02:01.691 [9592.4984] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 03:02:01.691 [9592.4984] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:02:01.691 [9592.4984] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:02:01.691 [9592.4984] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot 03:02:01.738 [9592.4984] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $ 03:02:01.738 [9592.4984] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:02:01.738 [9592.4984] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:02:01.738 [9592.4984] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer 03:02:01.738 [9592.4984] <4> bpfis main: INF - FIS_ID=client.domain.com_1331793898 03:02:01.738 [9592.4984] <2> onlfi_process_fs_list: INF - processing file=C:\Program Files\Veritas\NetBackup\temp\unknown+9592+1.std_filelist 03:02:01.738 [9592.4984] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\temp\unknown+9592+1.std_filelist 03:02:01.738 [9592.4984] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\temp\unknown+9592+1.std_filelist, line 1: D:\ 03:02:01.738 [9592.4984] <2> devtomp: INF - matched GUID \\?\Volume{006737fc-e0a8-11dd-98df-806e6f6e6963} to device \Device\HarddiskVolume2 03:02:01.738 [9592.4984] <2> make_fsmount_name: INF - name C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592, path D:\, stream_id 9592 03:02:01.738 [9592.4984] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 03:02:01.738 [9592.4984] <2> onlfi_process_fs_list_fs_entry: INF - Adding file system D:\ to list 03:02:01.738 [9592.4984] <2> onlfi_add_to_fsmap_list2: INF - Adding entry: 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - raw_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - volume_group= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_raw_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - mnt_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fm_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - cs_flag=0 03:02:01.738 [9592.4984] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 03:02:01.738 [9592.4984] <2> make_fsmount_name: INF - name C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592, path D:\, stream_id 9592 03:02:01.738 [9592.4984] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\temp\unknown+9592+1.std_filelist 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - Have 6 snapshot list entries and 1 filesystem device list entries. 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[0].fim_name=auto 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=Hyper-V 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[2].fim_name=VSS 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fs_devlist_rec[0]= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - raw_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - volume_group= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_raw_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - mnt_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fm_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - cs_flag=0 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=VMware 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=NAS_Snapshot 03:02:01.738 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=VSS_Writer 03:02:01.738 [9592.4984] <2> onlfi_runtime_check: INF - snapshot method=auto 03:02:01.738 [9592.4984] <2> onlfi_runtime_check: INF - snapshot method=Hyper-V 03:02:01.738 [9592.4984] <2> onlfi_runtime_check: INF - snapshot method=VSS 03:02:01.738 [9592.4984] <2> onlfi_runtime_check: INF - snapshot method=VMware 03:02:01.738 [9592.4984] <2> onlfi_runtime_check: INF - snapshot method=NAS_Snapshot 03:02:01.738 [9592.4984] <2> onlfi_runtime_check: INF - snapshot method=VSS_Writer 03:02:01.738 [9592.4984] <2> onlfi_freeze_prepare: INF - snapshot method=auto 03:02:01.738 [9592.4984] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V 03:02:01.738 [9592.4984] <2> onlfi_freeze_prepare: INF - snapshot method=VSS 03:02:01.738 [9592.4984] <4> onlfi_fim_dev_info: INF - Preparing freeze of D:\ using snapshot method VSS. 03:02:01.738 [9592.4984] <2> onlfi_fim_dev_info: INF - Before freeze 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - raw_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - volume_group= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_raw_device= 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - mnt_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fi_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - fm_flag=0 03:02:01.738 [9592.4984] <2> log_fs_dev_rec: INF - cs_flag=0 03:02:01.738 [9592.4984] <4> create_mount_point: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:01.738 [9592.4984] <2> start_keep_alive_thread: INF - Start keep_alive thread 03:02:01.738 [9592.10460] <2> send_keep_alive: INF - sending keep alive 03:02:01.753 [9592.4984] <2> read_vfm_conf: options=0x2 03:02:01.753 [9592.4984] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 03:02:01.753 [9592.4984] <2> read_vfm_conf: Found FIM: auto 03:02:01.753 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 03:02:01.753 [9592.4984] <2> read_vfm_conf: Found FIM: Hyper-V 03:02:01.753 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:02:01.753 [9592.4984] <2> parse_ssm_parms: Found keyword nameuse 03:02:01.753 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.753 [9592.4984] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 03:02:01.753 [9592.4984] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 03:02:01.753 [9592.4984] <2> parse_value_specification: Found default hyperv_client_name_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Client Name Selection 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default hyperv_backup_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword prov_type 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification vss_provider_type_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='vss_provider_type_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default vss_provider_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Provider Type 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword snap_attr 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='snapshot_attribute_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default snapshot_attribute_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Snapshot Attribute 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword allow_offline_backup 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %b 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword csv_timeout 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0-:0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0-:0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 03:02:01.769 [9592.4984] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found FIM: VSS 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword prov_type 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0-3:0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0-3:0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword snap_attr 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0-2:0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0-2:0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword max_snapshots 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found FIM: FlashSnap 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword keep_fi 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %b 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword async_resync 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %b 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword max_snapshots 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword dg_split_retry_times 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 60-:60 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='60-:60' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 60 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword num_sync_vols 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-32:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-32:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword iosize 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-128:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-128:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword slow 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0-:0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0-:0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found FIM: vxvm 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword async_resync 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %b 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword max_snapshots 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword sync_wait 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %b 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword num_sync_vols 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-32:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-32:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword iosize 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-128:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-128:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword slow 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 0-:0 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='0-:0' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 0 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found FIM: VMware 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword nameuse 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default vmware_client_name_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Client name selection 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='vmware_backup_type_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default vmware_backup_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Virtual machine backup 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword trantype 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification VMware_transfer_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='VMware_transfer_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default VMware_transfer_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Transfer type 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword disable_quiesce 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default enabled_disable_quiesce_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword file_system_optimization 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default enabled_disabled_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword snapact 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification snapshot_handling_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='snapshot_handling_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default snapshot_handling_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Existing snapshot handling 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword vmmono 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %dd 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default disabled_enabled_type_list 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword mntpt 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %s 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 03:02:01.769 [9592.4984] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found FIM: NAS_Snapshot 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found keyword max_snapshots 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found data type %d 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found value specification 1-:1 03:02:01.769 [9592.4984] <2> parse_value_specification: Value specification='1-:1' 03:02:01.769 [9592.4984] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:02:01.769 [9592.4984] <2> parse_value_specification: Found default 1 03:02:01.769 [9592.4984] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found FIM: VSS_Writer 03:02:01.769 [9592.4984] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 03:02:01.769 [9592.4984] <2> onlfi_fim_dev_info: INF - After freeze 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - raw_device= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - volume_group= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_raw_device= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - mnt_flag=0 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_flag=1 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fm_flag=0 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - cs_flag=0 03:02:01.769 [9592.4984] <2> onlfi_freeze_prepare: INF - snapshot method=VMware 03:02:01.769 [9592.4984] <2> onlfi_freeze_prepare: INF - snapshot method=NAS_Snapshot 03:02:01.769 [9592.4984] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_Writer 03:02:01.769 [9592.4984] <2> onlfi_freeze: INF - snapshot method=auto 03:02:01.769 [9592.4984] <2> onlfi_freeze: INF - snapshot method=Hyper-V 03:02:01.769 [9592.4984] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V 03:02:01.769 [9592.4984] <2> onlfi_freeze: INF - snapshot method=VSS 03:02:01.769 [9592.4984] <2> onlfi_freeze_commit: INF - snapshot method=VSS 03:02:01.769 [9592.4984] <2> onlfi_fim_split: INF - before split... 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - raw_device= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - volume_group= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_raw_device= 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - mnt_flag=0 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fi_flag=1 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - fm_flag=0 03:02:01.769 [9592.4984] <2> log_fs_dev_rec: INF - cs_flag=0 03:02:01.769 [9592.4984] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: config= 03:02:01.878 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:02:01.878000 WARNING: No credentials found for EMC CLARIION. 03:02:01.878 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:02:01.878000 Unable to import any login credentials for any appliances. 03:02:01.878 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcclariionfi : corresponding supported PTP not found. 03:02:02.034 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: emcsymfi: CLI tool C:\Program Files\EMC\SYMCLI\bin\ installation path symcfg.exe is invalid. 03:02:02.034 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcsymfi : corresponding supported PTP not found. 03:02:02.081 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:02:02.081000 CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found. 03:02:02.081 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:02:02.081000 CHitachiPlugin::init : Library RMLIB init failed 03:02:02.081 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hitachifi : corresponding supported PTP not found. 03:02:02.144 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi: CLI tool C:\Program Files\Hewlett-Packard\Sanworks\Element Manager for StorageWorks HSV\Bridge\ installation path sssu_win_ia64.exe is invalid. 03:02:02.144 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Thu Mar 15 2012 03:02:02.144000 CHpEvaPlugin::init: CLI tool is not installed. 03:02:02.144 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpevafi : corresponding supported PTP not found. 03:02:02.191 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Thu Mar 15 2012 03:02:02.191000 No array management credentials are available in configuration file. 03:02:02.191 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi: CLI tool C:\Program Files\Compaq\Hpacucli\Bin\ installation path hpacucli.exe is invalid. 03:02:02.191 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpmsafi : corresponding supported PTP not found. 03:02:02.253 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Thu Mar 15 2012 03:02:02.253000 DSCLI not found at path=[C:\Program Files\IBM\DSCLI\] 03:02:02.253 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: 03:02:02.253 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin ibmtsfi : corresponding supported PTP not found. 03:02:02.628 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: netappfi:Thu Mar 15 2012 03:02:02.628000 WARNING: No credentials found for NETAPP LUN. 03:02:02.628 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: netappfi: 03:02:02.628 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin netappfi : corresponding supported PTP not found. 03:02:02.659 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:02:02.659000 check_service_status - STATUS=[4] 03:02:03.128 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Thu Mar 15 2012 03:02:03.128000 CWmiPlugin::init WMI Provider is suppored on windows 2008 server only 03:02:03.128 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin wmifi : corresponding supported PTP not found. 03:02:03.175 [9592.9832] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:02:03.175000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 03:02:03.175 [9592.9832] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:02:03.175000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 03:02:03.175 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot] 03:02:03.175 [9592.4984] <2> onlfi_vfms_logf: INF - 03:02:03.550 [9592.9832] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:02:03.550000 VSS API ERROR:- API [QueryStatus] status = 42309 [VSS_S_ASYNC_PENDING] 03:02:08.925 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:02:08.925000 VssNode::AdjustUnboundedAssociations: Ignoring volume [\\?\Volume{006737fc-e0a8-11dd-98df-806e6f6e6963}] 03:02:08.925 [9592.4984] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[0] 03:02:08.925 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_build_alttree 03:02:08.925 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_build_alttree with 0 03:02:09.019 [9592.4984] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_fiid =[root2050D:\2050C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592D:\winfsys<vss>\\?\Volume{006737fc-e0a8-11dd-98df-806e6f6e6963}40989472<ShadowCopyID>{E4DF3A69-23D2-4BA8-AD65-6CF5E803310D}\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83D:\\\?\Volume{006737fc-e0a8-11dd-98df-806e6f6e6963}<vss>\\?\Volume{006737fc-e0a8-11dd-98df-806e6f6e6963}vssPABTAE4AQQBQAFMASABPAFQAXwBJAEQAPgB7AEUANABEAEYAMwBBADYAOQAt 03:02:09.019 [9592.4984] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:02:09.019 [9592.4984] <2> onlfi_fim_split: INF - After split.... 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - raw_device= 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - volume_group= 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fi_fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fi_raw_device= 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - mnt_flag=0 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fi_flag=1 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - fm_flag=0 03:02:09.019 [9592.4984] <2> log_fs_dev_rec: INF - cs_flag=0 03:02:09.019 [9592.4984] <2> onlfi_fim_clone: INF - cloning object \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83 on 03:02:09.019 [9592.4984] <2> onlfi_fim_clone: INF - clone done 03:02:09.019 [9592.4984] <2> onlfi_freeze: INF - snapshot method=VMware 03:02:09.019 [9592.4984] <2> onlfi_freeze: INF - snapshot method=NAS_Snapshot 03:02:09.019 [9592.4984] <2> onlfi_freeze_commit: INF - snapshot method=NAS_Snapshot 03:02:09.019 [9592.4984] <2> onlfi_freeze: INF - snapshot method=VSS_Writer 03:02:09.019 [9592.4984] <2> onlfi_freeze_commit: INF - snapshot method=VSS_Writer 03:02:09.019 [9592.4984] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed 03:02:09.019 [9592.4984] <2> determine_vss_provider_type: Get the VSS provider type used for the snapshot 03:02:09.019 [9592.4984] <2> onlfi_vfms_logf: INF - VXMS ctl: get VSS provider type, vss_prov_type = 0 03:02:09.019 [9592.4984] <2> determine_vss_provider_type: VSS provider type was not given to bpfis, and could not be determined. 03:02:09.019 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[0].fim_name=auto 03:02:09.019 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=Hyper-V 03:02:09.019 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=VMware 03:02:09.019 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=NAS_Snapshot 03:02:09.019 [9592.4984] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=VSS_Writer 03:02:09.019 [9592.4984] <2> onlfi_get_fsdev_info: INF - Looking up D:\ 03:02:09.019 [9592.4984] <2> onlfi_get_fsdev_info: INF - comparing D:\ to D:\ 03:02:09.019 [9592.4984] <2> onlfi_get_fsdev_info: INF - Match found for D:\ 03:02:09.019 [9592.4984] <2> bpfis remap_name: fs_name=D:\ 03:02:09.019 [9592.4984] <2> bpfis remap_name: mapped fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83 03:02:09.019 [9592.4984] <2> bpfis remap_name: name: D:\ :maps to: \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83\ 03:02:09.019 [9592.4984] <4> remap_file_name: INF - REMAP FILE BACKUP D:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_9592,FITYPE=MIRROR,MNTPOINT=D:\,FSTYPE=NTFS 03:02:09.019 [9592.4984] <2> ol_cleanup: INF - removing C:\Program Files\Veritas\NetBackup\temp\unknown+9592+1.std_filelist 03:02:09.081 [9592.4984] <2> ol_cleanup: INF - removing all log files 03:02:09.081 [9592.4984] <2> bpfis_state_on_master_xfer_level: bpfis state file transfer is disabled (level = 0), touch file exists: 1, -noserverstate: 0, -nbu: 0 03:02:09.081 [9592.4984] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed 03:02:09.081 [9592.4984] <2> stop_keep_alive_thread: INF - Stop keep_alive thread 03:02:11.081 [9592.4984] <2> bpfis Exit: INF - Close of stdout complete 03:08:56.053 [7016.4296] <2> logparams: bpfis delete -id client.domain.com_1331793898 03:08:56.053 [7016.4296] <2> bpfis_state_on_master_xfer_level: bpfis state file transfer is disabled (level = 0), touch file exists: 1, -noserverstate: 0, -nbu: 0 03:08:56.068 [7016.4296] <4> bpfis main: INF - BACKUP START 7016 03:08:56.068 [7016.4296] <2> ol_initialize: INF - stream_in_control = unknown+d9592+1, stream_name = unknown+d9592+1, streams = 1 03:08:56.068 [7016.4296] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.232: 0: starting cache size: 200 0x000000c8 03:08:56.068 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:08:56.068 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:08:56.068 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: client.domain.com 03:08:56.068 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:08:56.068 [7016.4296] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\vfm.conf 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 7: FIM auto "C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll" 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 27: FIM Hyper-V "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" nameuse=%dd[hyperv_client_name_type_list]#Client Name Selection,Virtual_machine_backup=%dd[hyperv_backup_type_list]#Virtual Machine Backup,prov_type=%dd[vss_provider_type_list]#Provider Type,snap_attr=%dd[snapshot_attribute_list]#Snapshot Attribute,allow_offline_backup=%b[1]#Allow Offline Backup of VM,csv_timeout=%d[0-:0]#CSV Timeout in Minutes 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 50: FIM VSS "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" prov_type=%d[0-3:0]#Provider Type (0-auto 1-system 2-software 3-hardware),snap_attr=%d[0-2:0]#Snapshot Attribute(0-unspecified 1-differential 2-plex),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 72: FIM FlashSnap "C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll" keep_fi=%b[0]#Keep snapshot after backup (not Instant Recovery),async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),dg_split_retry_times=%d[1]#Number of times to retry disk group split,dg_split_retry_wait_period=%d[60-:60]#Delay in seconds between disk group split retries,num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 93: FIM vxvm "C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll" async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),sync_wait=%b[1]#Wait for mirror sync completion (Data Mover backups only),num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 113: FIM VMware "C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll" nameuse=%dd[vmware_client_name_type_list]#Client name selection,Virtual_machine_backup=%dd[vmware_backup_type_list]#Virtual machine backup,trantype=%dd[VMware_transfer_list]#Transfer type,disable_quiesce=%dd[enabled_disable_quiesce_list]#Virtual machine quiesce,file_system_optimization=%dd[enabled_disabled_type_list]#Exclude unused and deleted blocks,snapact=%dd[snapshot_handling_list]#Existing snapshot handling,vmmono=%dd[disabled_enabled_type_list]#Monolithic export (VCB only),mntpt=%s#Snapshot mount point (VCB only) 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 135: FIM NAS_Snapshot "C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll" max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:08:56.068 [7016.4296] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 156: FIM VSS_Writer "C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll" 03:08:56.068 [7016.4296] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\vfm.conf 03:08:56.068 [7016.4296] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $ 03:08:56.068 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=auto 03:08:56.068 [7016.4296] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI 03:08:56.178 [7016.4296] <2> read_vfm_conf: options=0x2 03:08:56.178 [7016.4296] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: auto 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: Hyper-V 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword nameuse 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default hyperv_client_name_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Client Name Selection 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default hyperv_backup_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword prov_type 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification vss_provider_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='vss_provider_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default vss_provider_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Provider Type 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword snap_attr 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='snapshot_attribute_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default snapshot_attribute_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Snapshot Attribute 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword allow_offline_backup 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %b 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword csv_timeout 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0-:0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0-:0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 03:08:56.178 [7016.4296] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: VSS 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword prov_type 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0-3:0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0-3:0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword snap_attr 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0-2:0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0-2:0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword max_snapshots 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: FlashSnap 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword keep_fi 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %b 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword async_resync 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %b 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword max_snapshots 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword dg_split_retry_times 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 60-:60 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='60-:60' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 60 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword num_sync_vols 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-32:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-32:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword iosize 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-128:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-128:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword slow 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0-:0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0-:0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: vxvm 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword async_resync 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %b 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword max_snapshots 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword sync_wait 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %b 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword num_sync_vols 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-32:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-32:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword iosize 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-128:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-128:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword slow 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 0-:0 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='0-:0' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 0 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: VMware 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword nameuse 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default vmware_client_name_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Client name selection 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='vmware_backup_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default vmware_backup_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Virtual machine backup 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword trantype 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification VMware_transfer_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='VMware_transfer_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default VMware_transfer_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Transfer type 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword disable_quiesce 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default enabled_disable_quiesce_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword file_system_optimization 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default enabled_disabled_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword snapact 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification snapshot_handling_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='snapshot_handling_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default snapshot_handling_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Existing snapshot handling 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword vmmono 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %dd 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default disabled_enabled_type_list 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword mntpt 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %s 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 03:08:56.178 [7016.4296] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: NAS_Snapshot 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found keyword max_snapshots 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found data type %d 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found value specification 1-:1 03:08:56.178 [7016.4296] <2> parse_value_specification: Value specification='1-:1' 03:08:56.178 [7016.4296] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:08:56.178 [7016.4296] <2> parse_value_specification: Found default 1 03:08:56.178 [7016.4296] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found FIM: VSS_Writer 03:08:56.178 [7016.4296] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 03:08:56.178 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V 03:08:56.178 [7016.4296] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: configuring VxFI credentials 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: usaroc9050as015.domain.com 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 13.118.33.36 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 127.0.0.1 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: veritas_pbx 03:08:56.193 [7016.4296] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 1 0x00000001 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 13.115.4.39 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:08:56.193 [7016.4296] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4882: 0: using interface : ANY 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: vnetd 03:08:56.193 [7016.4296] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 1 0x00000001 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 13.115.4.39 03:08:56.193 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:08:56.193 [7016.4296] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4882: 0: using interface : ANY 03:08:56.193 [7016.4296] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6635: 0: fopen() failed: 2 0x00000002 03:08:56.193 [7016.4296] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6636: 0: fopen() failed: C:\Program Files\Veritas\NetBackup\var\host_cache\176\4b10a376+bprd,1,0,2,1,0+usaroc9050as015.domain.com.txt 03:08:56.193 [7016.4296] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.874: 0: using service: bprd 03:08:56.193 [7016.4296] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.892: 0: using port: 13720 0x00003598 03:08:56.193 [7016.4296] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.896: 0: adjusted service name: 13720 03:08:56.209 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1572: 0: found via getaddrinfo name: usaroc9050as015.domain.com 03:08:56.209 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1574: 0: found via getaddrinfo service: bprd 03:08:56.225 [7016.4296] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 1 0x00000001 03:08:56.225 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 13.115.4.39 03:08:56.225 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:08:56.225 [7016.4296] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4882: 0: using interface : ANY 03:08:56.225 [7016.4296] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1200: 0: connect in progress: 1 0x00000001 03:08:56.225 [7016.4296] <2> vnet_pbxConnect: pbxConnectEx Succeeded 03:08:56.225 [7016.4296] <2> do_pbx_service: ../../libvlibs/vnet_connect.c.1784: 0: via PBX: bprd CONNECT FROM 13.118.33.36.2319 TO 13.115.4.39.1556 fd = 1592 03:08:56.225 [7016.4296] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1367: 0: connect: async CONNECT FROM 13.118.33.36.2319 TO 13.115.4.39.1556 fd = 1592 03:08:56.225 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: usaroc9050as015.domain.com 03:08:56.225 [7016.4296] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:08:56.225 [7016.4296] <2> logconnections: BPRD CONNECT FROM 13.118.33.36.2319 TO 13.115.4.39.1556 fd = 1592 03:08:56.240 [7016.4296] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.871: 0: Ignoring VxSS authentication: 2 0x00000002 03:08:56.240 [7016.4296] <2> bprd_read_text_file: Starting to read data 03:08:56.803 [7016.4296] <2> bprd_read_text_file: Received status 131 03:08:56.803 [7016.4296] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: read credentials file failed 03:08:56.803 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed. 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:08:56.803 [7016.4296] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed. 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:08:56.818 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:08:56.818 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:08:56.818 [7016.4296] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:08:56.818 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - rebuild from file: C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FIS-VERSION=1.0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FIS-TIME=1331794921 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FIS-OWNER=NBU 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FIS-RHOST=LOCALHOST 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FIS-IS_PRIMARY 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FAILED_DELETES=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is STREAM_IN_CONTROL=unknown+9592+1 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is STREAM_NAME=unknown+9592+1 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is STREAM_ID=unknown+9592 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is STREAM_NUM=1 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is STREAM_PID=9592 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is STREAMS=1 1 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is PFI_BACKUP=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is NBU_CATALOG=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is ALT_CLIENT=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is POLICY_NAME=unknown 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is POLICY_TYPE=13 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is SCHED_NAME=unknown 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is SCHED_TYPE=unknown 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is GRAN_BACKUP=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is VSS_REPLICA=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is DATABASE_SOURCE=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is EXCHANGE_DAG_NAME= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is NO_SNAP_LICENSE=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FILELIST=FILENAME=D:\ 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FIM_NAME=VSS 03:08:56.818 [7016.4296] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 03:08:56.818 [7016.4296] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-type=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fs_name=D:\ 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fs_type=NTFS 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-mnt_device=\Device\HarddiskVolume2 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-raw_device= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fi_mnt_device= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fi_raw_device= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fi_args= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-mnt_flag=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fi_flag=1 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fm_flag=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-cs_flag=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV-fi_fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is PATH_INFO= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is PLUGIN=START 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is HDEF-afs=D:\ 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is HDEF-fifs=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_D__9592 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is HDEF-vss_prov_type=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is HDEF-is_alt=0 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is HDEF-fstype=NTFS 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is HDEF-fidev= 03:08:56.818 [7016.4296] <2> rebuild_fim_list: INF - line is PLUGIN=END 03:08:56.834 [7016.4296] <2> rebuild_fim_list: INF - line is FSDEV=NEXT 03:08:56.834 [7016.4296] <2> rebuild_fim_list: INF - line is FIM_NAME_END 03:08:56.834 [7016.4296] <2> onlfi_thaw: INF - fim=VSS 03:08:56.834 [7016.4296] <4> onlfi_thaw: INF - Thawing D:\ using snapshot method VSS. 03:08:56.834 [7016.4296] <2> start_keep_alive_thread: INF - Start keep_alive thread 03:08:56.834 [7016.4296] <2> do_thaw: INF - do_thaw: parent: thaw flag on 03:08:56.834 [7016.4296] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ... 03:08:56.834 [7016.7688] <2> send_keep_alive: INF - sending keep alive 03:08:56.943 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_reaccess_tree 03:08:57.537 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:08:57.537000 WARNING: No credentials found for EMC CLARIION. 03:08:57.537 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:08:57.537000 Unable to import any login credentials for any appliances. 03:08:57.537 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcclariionfi : corresponding supported PTP not found. 03:08:57.709 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: emcsymfi: CLI tool C:\Program Files\EMC\SYMCLI\bin\ installation path symcfg.exe is invalid. 03:08:57.709 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcsymfi : corresponding supported PTP not found. 03:08:57.881 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:08:57.881000 CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found. 03:08:57.881 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:08:57.881000 CHitachiPlugin::init : Library RMLIB init failed 03:08:57.881 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hitachifi : corresponding supported PTP not found. 03:08:58.006 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi: CLI tool C:\Program Files\Hewlett-Packard\Sanworks\Element Manager for StorageWorks HSV\Bridge\ installation path sssu_win_ia64.exe is invalid. 03:08:58.006 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Thu Mar 15 2012 03:08:58.006000 CHpEvaPlugin::init: CLI tool is not installed. 03:08:58.006 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpevafi : corresponding supported PTP not found. 03:08:58.100 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Thu Mar 15 2012 03:08:58.100000 No array management credentials are available in configuration file. 03:08:58.115 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi: CLI tool C:\Program Files\Compaq\Hpacucli\Bin\ installation path hpacucli.exe is invalid. 03:08:58.115 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpmsafi : corresponding supported PTP not found. 03:08:58.209 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Thu Mar 15 2012 03:08:58.209000 DSCLI not found at path=[C:\Program Files\IBM\DSCLI\] 03:08:58.209 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: 03:08:58.209 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin ibmtsfi : corresponding supported PTP not found. 03:08:58.334 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: netappfi:Thu Mar 15 2012 03:08:58.334000 WARNING: No credentials found for NETAPP LUN. 03:08:58.334 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: netappfi: 03:08:58.334 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin netappfi : corresponding supported PTP not found. 03:08:58.412 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:08:58.412000 check_service_status - STATUS=[4] 03:08:58.818 [7016.4296] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_reaccess_tree with 0 03:08:58.818 [7016.4296] <4> onlfi_thaw: INF - do_thaw return value: 0 03:08:58.818 [7016.4296] <2> onlfi_thaw: INF - resyncing VSS object 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fs_name=D:\ 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - raw_device= 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - volume_group= 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fi_fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy83 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fi_raw_device= 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fi_handle=0000000000E91620 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - mnt_flag=0 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fi_flag=1 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - fm_flag=0 03:08:58.818 [7016.4296] <2> log_fs_dev_rec: INF - cs_flag=0 03:08:58.818 [7016.4296] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0 03:08:58.818 [7016.4296] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0.fiid 03:08:58.818 [7016.4296] <2> ol_cleanup: INF - removing C:\Program Files\Veritas\NetBackup\temp\unknown+d9592+1.std_filelist 03:08:58.881 [7016.4296] <2> ol_cleanup: INF - removing all log files 03:08:58.881 [7016.4296] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed 03:08:58.881 [7016.4296] <2> stop_keep_alive_thread: INF - Stop keep_alive thread 03:09:00.881 [7016.4296] <2> bpfis Exit: INF - Close of stdout complete 03:09:05.959 [900.10464] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id client.domain.com_1331793898 I:\ 03:09:05.959 [900.10464] <2> bpfis main: received FIM as [3] VSS 03:09:05.975 [900.10464] <4> bpfis main: INF - BACKUP START 900 03:09:05.975 [900.10464] <2> bpfis main: filelist: 03:09:05.975 [900.10464] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS= 03:09:05.975 [900.10464] <2> ol_initialize: INF - stream_in_control = unknown+900+1, stream_name = unknown+900+1, streams = 1 03:09:05.975 [900.10464] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.232: 0: starting cache size: 200 0x000000c8 03:09:05.975 [900.10464] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:09:05.975 [900.10464] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:09:05.975 [900.10464] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: client.domain.com 03:09:05.975 [900.10464] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:09:05.975 [900.10464] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\vfm.conf 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 7: FIM auto "C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll" 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 27: FIM Hyper-V "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" nameuse=%dd[hyperv_client_name_type_list]#Client Name Selection,Virtual_machine_backup=%dd[hyperv_backup_type_list]#Virtual Machine Backup,prov_type=%dd[vss_provider_type_list]#Provider Type,snap_attr=%dd[snapshot_attribute_list]#Snapshot Attribute,allow_offline_backup=%b[1]#Allow Offline Backup of VM,csv_timeout=%d[0-:0]#CSV Timeout in Minutes 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 50: FIM VSS "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" prov_type=%d[0-3:0]#Provider Type (0-auto 1-system 2-software 3-hardware),snap_attr=%d[0-2:0]#Snapshot Attribute(0-unspecified 1-differential 2-plex),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 72: FIM FlashSnap "C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll" keep_fi=%b[0]#Keep snapshot after backup (not Instant Recovery),async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),dg_split_retry_times=%d[1]#Number of times to retry disk group split,dg_split_retry_wait_period=%d[60-:60]#Delay in seconds between disk group split retries,num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 93: FIM vxvm "C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll" async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),sync_wait=%b[1]#Wait for mirror sync completion (Data Mover backups only),num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 113: FIM VMware "C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll" nameuse=%dd[vmware_client_name_type_list]#Client name selection,Virtual_machine_backup=%dd[vmware_backup_type_list]#Virtual machine backup,trantype=%dd[VMware_transfer_list]#Transfer type,disable_quiesce=%dd[enabled_disable_quiesce_list]#Virtual machine quiesce,file_system_optimization=%dd[enabled_disabled_type_list]#Exclude unused and deleted blocks,snapact=%dd[snapshot_handling_list]#Existing snapshot handling,vmmono=%dd[disabled_enabled_type_list]#Monolithic export (VCB only),mntpt=%s#Snapshot mount point (VCB only) 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 135: FIM NAS_Snapshot "C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll" max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:09:05.975 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 156: FIM VSS_Writer "C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll" 03:09:05.975 [900.10464] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\vfm.conf 03:09:05.975 [900.10464] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $ 03:09:05.975 [900.10464] <2> onlfi_add_to_fim_list: INF - snapshot method=auto 03:09:05.975 [900.10464] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI 03:09:05.975 [900.10464] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V 03:09:05.975 [900.10464] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed. 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:09:05.990 [900.10464] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed. 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $ 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $ 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $ 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:09:05.990 [900.10464] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:09:05.990 [900.10464] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:09:05.990 [900.10464] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot 03:09:06.006 [900.10464] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $ 03:09:06.006 [900.10464] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:09:06.006 [900.10464] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:09:06.006 [900.10464] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer 03:09:06.006 [900.10464] <4> bpfis main: INF - FIS_ID=client.domain.com_1331793898 03:09:06.006 [900.10464] <2> onlfi_process_fs_list: INF - processing file=C:\Program Files\Veritas\NetBackup\temp\unknown+900+1.std_filelist 03:09:06.006 [900.10464] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\temp\unknown+900+1.std_filelist 03:09:06.006 [900.10464] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\temp\unknown+900+1.std_filelist, line 1: I:\ 03:09:06.006 [900.10464] <2> devtomp: INF - matched GUID \\?\Volume{e6cc3e19-39b8-44a6-bc57-30fc8788ecda} to device \Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:06.006 [900.10464] <2> make_fsmount_name: INF - name C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900, path I:\, stream_id 900 03:09:06.006 [900.10464] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 03:09:06.006 [900.10464] <2> onlfi_process_fs_list_fs_entry: INF - Adding file system I:\ to list 03:09:06.006 [900.10464] <2> onlfi_add_to_fsmap_list2: INF - Adding entry: 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - volume_group= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fm_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - cs_flag=0 03:09:06.006 [900.10464] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 03:09:06.006 [900.10464] <2> make_fsmount_name: INF - name C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900, path I:\, stream_id 900 03:09:06.006 [900.10464] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\temp\unknown+900+1.std_filelist 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - Have 6 snapshot list entries and 1 filesystem device list entries. 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[0].fim_name=auto 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=Hyper-V 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[2].fim_name=VSS 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fs_devlist_rec[0]= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - volume_group= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fm_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - cs_flag=0 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=VMware 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=NAS_Snapshot 03:09:06.006 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=VSS_Writer 03:09:06.006 [900.10464] <2> onlfi_runtime_check: INF - snapshot method=auto 03:09:06.006 [900.10464] <2> onlfi_runtime_check: INF - snapshot method=Hyper-V 03:09:06.006 [900.10464] <2> onlfi_runtime_check: INF - snapshot method=VSS 03:09:06.006 [900.10464] <2> onlfi_runtime_check: INF - snapshot method=VMware 03:09:06.006 [900.10464] <2> onlfi_runtime_check: INF - snapshot method=NAS_Snapshot 03:09:06.006 [900.10464] <2> onlfi_runtime_check: INF - snapshot method=VSS_Writer 03:09:06.006 [900.10464] <2> onlfi_freeze_prepare: INF - snapshot method=auto 03:09:06.006 [900.10464] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V 03:09:06.006 [900.10464] <2> onlfi_freeze_prepare: INF - snapshot method=VSS 03:09:06.006 [900.10464] <4> onlfi_fim_dev_info: INF - Preparing freeze of I:\ using snapshot method VSS. 03:09:06.006 [900.10464] <2> onlfi_fim_dev_info: INF - Before freeze 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - volume_group= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_handle=0000000000000000 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fm_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - cs_flag=0 03:09:06.006 [900.10464] <4> create_mount_point: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:06.006 [900.10464] <2> start_keep_alive_thread: INF - Start keep_alive thread 03:09:06.006 [900.10464] <2> read_vfm_conf: options=0x2 03:09:06.006 [900.10804] <2> send_keep_alive: INF - sending keep alive 03:09:06.006 [900.10464] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: auto 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: Hyper-V 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword nameuse 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default hyperv_client_name_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Client Name Selection 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default hyperv_backup_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword prov_type 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification vss_provider_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='vss_provider_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default vss_provider_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Provider Type 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword snap_attr 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='snapshot_attribute_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default snapshot_attribute_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Snapshot Attribute 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword allow_offline_backup 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %b 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword csv_timeout 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0-:0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0-:0' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 03:09:06.006 [900.10464] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: VSS 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword prov_type 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0-3:0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0-3:0' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword snap_attr 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0-2:0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0-2:0' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword max_snapshots 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: FlashSnap 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword keep_fi 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %b 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword async_resync 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %b 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword max_snapshots 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword dg_split_retry_times 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 60-:60 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='60-:60' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 60 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword num_sync_vols 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-32:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-32:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword iosize 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-128:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-128:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword slow 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0-:0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0-:0' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: vxvm 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword async_resync 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %b 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword max_snapshots 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword sync_wait 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %b 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword num_sync_vols 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-32:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-32:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword iosize 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-128:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-128:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword slow 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 0-:0 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='0-:0' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 0 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: VMware 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword nameuse 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default vmware_client_name_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Client name selection 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='vmware_backup_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default vmware_backup_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Virtual machine backup 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword trantype 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification VMware_transfer_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='VMware_transfer_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default VMware_transfer_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Transfer type 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword disable_quiesce 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default enabled_disable_quiesce_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword file_system_optimization 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default enabled_disabled_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword snapact 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification snapshot_handling_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='snapshot_handling_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default snapshot_handling_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Existing snapshot handling 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword vmmono 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %dd 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 03:09:06.006 [900.10464] <2> parse_value_specification: Found default disabled_enabled_type_list 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword mntpt 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %s 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 03:09:06.006 [900.10464] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: NAS_Snapshot 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found keyword max_snapshots 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found data type %d 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found value specification 1-:1 03:09:06.006 [900.10464] <2> parse_value_specification: Value specification='1-:1' 03:09:06.006 [900.10464] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:09:06.006 [900.10464] <2> parse_value_specification: Found default 1 03:09:06.006 [900.10464] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:09:06.006 [900.10464] <2> read_vfm_conf: Found FIM: VSS_Writer 03:09:06.006 [900.10464] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 03:09:06.006 [900.10464] <2> onlfi_fim_dev_info: INF - After freeze 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - volume_group= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_flag=1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fm_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - cs_flag=0 03:09:06.006 [900.10464] <2> onlfi_freeze_prepare: INF - snapshot method=VMware 03:09:06.006 [900.10464] <2> onlfi_freeze_prepare: INF - snapshot method=NAS_Snapshot 03:09:06.006 [900.10464] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_Writer 03:09:06.006 [900.10464] <2> onlfi_freeze: INF - snapshot method=auto 03:09:06.006 [900.10464] <2> onlfi_freeze: INF - snapshot method=Hyper-V 03:09:06.006 [900.10464] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V 03:09:06.006 [900.10464] <2> onlfi_freeze: INF - snapshot method=VSS 03:09:06.006 [900.10464] <2> onlfi_freeze_commit: INF - snapshot method=VSS 03:09:06.006 [900.10464] <2> onlfi_fim_split: INF - before split... 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - volume_group= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_raw_device= 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - mnt_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fi_flag=1 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - fm_flag=0 03:09:06.006 [900.10464] <2> log_fs_dev_rec: INF - cs_flag=0 03:09:06.006 [900.10464] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: config= 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:09:06.022000 WARNING: No credentials found for EMC CLARIION. 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:09:06.022000 Unable to import any login credentials for any appliances. 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcclariionfi : corresponding supported PTP not found. 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: emcsymfi: CLI tool C:\Program Files\EMC\SYMCLI\bin\ installation path symcfg.exe is invalid. 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcsymfi : corresponding supported PTP not found. 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:09:06.022000 CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found. 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:09:06.022000 CHitachiPlugin::init : Library RMLIB init failed 03:09:06.022 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hitachifi : corresponding supported PTP not found. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi: CLI tool C:\Program Files\Hewlett-Packard\Sanworks\Element Manager for StorageWorks HSV\Bridge\ installation path sssu_win_ia64.exe is invalid. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Thu Mar 15 2012 03:09:06.037000 CHpEvaPlugin::init: CLI tool is not installed. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpevafi : corresponding supported PTP not found. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Thu Mar 15 2012 03:09:06.037000 No array management credentials are available in configuration file. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi: CLI tool C:\Program Files\Compaq\Hpacucli\Bin\ installation path hpacucli.exe is invalid. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpmsafi : corresponding supported PTP not found. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Thu Mar 15 2012 03:09:06.037000 DSCLI not found at path=[C:\Program Files\IBM\DSCLI\] 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin ibmtsfi : corresponding supported PTP not found. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: netappfi:Thu Mar 15 2012 03:09:06.037000 WARNING: No credentials found for NETAPP LUN. 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: netappfi: 03:09:06.037 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin netappfi : corresponding supported PTP not found. 03:09:06.053 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:06.037000 check_service_status - STATUS=[4] 03:09:06.240 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: wmifi:Thu Mar 15 2012 03:09:06.240000 CWmiPlugin::init WMI Provider is suppored on windows 2008 server only 03:09:06.240 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin wmifi : corresponding supported PTP not found. 03:09:06.272 [900.9816] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:06.272000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 03:09:06.272 [900.9816] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:06.272000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 03:09:06.272 [900.9816] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:06.272000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 03:09:06.272 [900.9816] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:06.272000 VSS API ERROR:- API [IsVolumeSupported] return val = 80042308 [VSS_E_OBJECT_NOT_FOUND] 03:09:06.272 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot] 03:09:06.272 [900.10464] <2> onlfi_vfms_logf: INF - 03:09:06.475 [900.9816] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:06.475000 VSS API ERROR:- API [QueryStatus] status = 42309 [VSS_S_ASYNC_PENDING] 03:09:10.709 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:09:10.709000 VssNode::AdjustUnboundedAssociations: Ignoring volume [\\?\Volume{e6cc3e19-39b8-44a6-bc57-30fc8788ecda}] 03:09:10.709 [900.10464] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[0] 03:09:10.709 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_build_alttree 03:09:10.725 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_build_alttree with 0 03:09:10.725 [900.10464] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_fiid =[root2050I:\2050C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900I:\winfsys<vss>\\?\Volume{e6cc3e19-39b8-44a6-bc57-30fc8788ecda}40989472<ShadowCopyID>{32CD0E25-F939-4E8E-8175-D0D598335770}\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84I:\\\?\Volume{e6cc3e19-39b8-44a6-bc57-30fc8788ecda}<vss>\\?\Volume{e6cc3e19-39b8-44a6-bc57-30fc8788ecda}vssPABTAE4AQQBQAFMASABPAFQAXwBJAEQAPgB7ADMAMgBDAEQAMABFADIANQAt 03:09:10.725 [900.10464] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:09:10.725 [900.10464] <2> onlfi_fim_split: INF - After split.... 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - raw_device= 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - volume_group= 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fi_fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fi_raw_device= 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fi_handle=0000000000E64500 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - mnt_flag=0 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fi_flag=1 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - fm_flag=0 03:09:10.725 [900.10464] <2> log_fs_dev_rec: INF - cs_flag=0 03:09:10.725 [900.10464] <2> onlfi_fim_clone: INF - cloning object \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84 on 03:09:10.725 [900.10464] <2> onlfi_fim_clone: INF - clone done 03:09:10.725 [900.10464] <2> onlfi_freeze: INF - snapshot method=VMware 03:09:10.725 [900.10464] <2> onlfi_freeze: INF - snapshot method=NAS_Snapshot 03:09:10.725 [900.10464] <2> onlfi_freeze_commit: INF - snapshot method=NAS_Snapshot 03:09:10.725 [900.10464] <2> onlfi_freeze: INF - snapshot method=VSS_Writer 03:09:10.725 [900.10464] <2> onlfi_freeze_commit: INF - snapshot method=VSS_Writer 03:09:10.725 [900.10464] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed 03:09:10.725 [900.10464] <2> determine_vss_provider_type: Get the VSS provider type used for the snapshot 03:09:10.725 [900.10464] <2> onlfi_vfms_logf: INF - VXMS ctl: get VSS provider type, vss_prov_type = 0 03:09:10.725 [900.10464] <2> determine_vss_provider_type: VSS provider type was not given to bpfis, and could not be determined. 03:09:10.725 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[0].fim_name=auto 03:09:10.725 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=Hyper-V 03:09:10.725 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=VMware 03:09:10.725 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=NAS_Snapshot 03:09:10.725 [900.10464] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=VSS_Writer 03:09:10.725 [900.10464] <2> onlfi_get_fsdev_info: INF - Looking up I:\ 03:09:10.725 [900.10464] <2> onlfi_get_fsdev_info: INF - comparing I:\ to I:\ 03:09:10.725 [900.10464] <2> onlfi_get_fsdev_info: INF - Match found for I:\ 03:09:10.725 [900.10464] <2> bpfis remap_name: fs_name=I:\ 03:09:10.725 [900.10464] <2> bpfis remap_name: mapped fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84 03:09:10.725 [900.10464] <2> bpfis remap_name: name: I:\ :maps to: \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84\ 03:09:10.725 [900.10464] <4> remap_file_name: INF - REMAP FILE BACKUP I:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_900,FITYPE=MIRROR,MNTPOINT=I:\,FSTYPE=NTFS 03:09:10.725 [900.10464] <2> ol_cleanup: INF - removing C:\Program Files\Veritas\NetBackup\temp\unknown+900+1.std_filelist 03:09:10.772 [900.10464] <2> ol_cleanup: INF - removing all log files 03:09:10.772 [900.10464] <2> bpfis_state_on_master_xfer_level: bpfis state file transfer is disabled (level = 0), touch file exists: 1, -noserverstate: 0, -nbu: 0 03:09:10.772 [900.10464] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed 03:09:10.772 [900.10464] <2> stop_keep_alive_thread: INF - Stop keep_alive thread 03:09:12.772 [900.10464] <2> bpfis Exit: INF - Close of stdout complete 03:15:49.259 [10244.8576] <2> logparams: bpfis delete -id client.domain.com_1331793898 03:15:49.259 [10244.8576] <2> bpfis_state_on_master_xfer_level: bpfis state file transfer is disabled (level = 0), touch file exists: 1, -noserverstate: 0, -nbu: 0 03:15:49.259 [10244.8576] <4> bpfis main: INF - BACKUP START 10244 03:15:49.259 [10244.8576] <2> ol_initialize: INF - stream_in_control = unknown+d900+1, stream_name = unknown+d900+1, streams = 1 03:15:49.274 [10244.8576] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.232: 0: starting cache size: 200 0x000000c8 03:15:49.274 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:15:49.274 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:15:49.274 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: client.domain.com 03:15:49.274 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:15:49.274 [10244.8576] <2> parse_open: INF - Opening file C:\Program Files\Veritas\NetBackup\vfm.conf 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 7: FIM auto "C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll" 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 27: FIM Hyper-V "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" nameuse=%dd[hyperv_client_name_type_list]#Client Name Selection,Virtual_machine_backup=%dd[hyperv_backup_type_list]#Virtual Machine Backup,prov_type=%dd[vss_provider_type_list]#Provider Type,snap_attr=%dd[snapshot_attribute_list]#Snapshot Attribute,allow_offline_backup=%b[1]#Allow Offline Backup of VM,csv_timeout=%d[0-:0]#CSV Timeout in Minutes 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 50: FIM VSS "C:\Program Files\Veritas\NetBackup\bin\libvfms.dll" prov_type=%d[0-3:0]#Provider Type (0-auto 1-system 2-software 3-hardware),snap_attr=%d[0-2:0]#Snapshot Attribute(0-unspecified 1-differential 2-plex),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 72: FIM FlashSnap "C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll" keep_fi=%b[0]#Keep snapshot after backup (not Instant Recovery),async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),dg_split_retry_times=%d[1]#Number of times to retry disk group split,dg_split_retry_wait_period=%d[60-:60]#Delay in seconds between disk group split retries,num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 93: FIM vxvm "C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll" async_resync=%b[0]#Resynchronize mirror in background (not Instant Recovery),max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only),sync_wait=%b[1]#Wait for mirror sync completion (Data Mover backups only),num_sync_vols=%d[1-32:1]#Maximum number of volumes to resynchronize concurrently,iosize=%d[1-128:1]#Sync region size in MB (UNIX only),slow=%d[0-:0]#Sync I/O delay in milliseconds (UNIX only) 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 113: FIM VMware "C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll" nameuse=%dd[vmware_client_name_type_list]#Client name selection,Virtual_machine_backup=%dd[vmware_backup_type_list]#Virtual machine backup,trantype=%dd[VMware_transfer_list]#Transfer type,disable_quiesce=%dd[enabled_disable_quiesce_list]#Virtual machine quiesce,file_system_optimization=%dd[enabled_disabled_type_list]#Exclude unused and deleted blocks,snapact=%dd[snapshot_handling_list]#Existing snapshot handling,vmmono=%dd[disabled_enabled_type_list]#Monolithic export (VCB only),mntpt=%s#Snapshot mount point (VCB only) 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 135: FIM NAS_Snapshot "C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll" max_snapshots=%d[1-:1]#Maximum Snapshots (Instant Recovery only) 03:15:49.274 [10244.8576] <2> parse_getline: INF - Read file C:\Program Files\Veritas\NetBackup\vfm.conf, line 156: FIM VSS_Writer "C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll" 03:15:49.274 [10244.8576] <2> parse_close: INF - Closing file C:\Program Files\Veritas\NetBackup\vfm.conf 03:15:49.274 [10244.8576] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $ 03:15:49.274 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=auto 03:15:49.274 [10244.8576] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI 03:15:49.337 [10244.8576] <2> read_vfm_conf: options=0x2 03:15:49.337 [10244.8576] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: auto 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: Hyper-V 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword nameuse 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default hyperv_client_name_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Client Name Selection 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default hyperv_backup_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword prov_type 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification vss_provider_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='vss_provider_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default vss_provider_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Provider Type 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword snap_attr 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='snapshot_attribute_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default snapshot_attribute_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Snapshot Attribute 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword allow_offline_backup 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %b 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword csv_timeout 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0-:0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0-:0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 03:15:49.337 [10244.8576] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: VSS 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword prov_type 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0-3:0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0-3:0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword snap_attr 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0-2:0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0-2:0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword max_snapshots 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: FlashSnap 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword keep_fi 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %b 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword async_resync 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %b 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword max_snapshots 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword dg_split_retry_times 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 60-:60 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='60-:60' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 60 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword num_sync_vols 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-32:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-32:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword iosize 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-128:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-128:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword slow 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0-:0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0-:0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: vxvm 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword async_resync 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %b 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword max_snapshots 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword sync_wait 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %b 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword num_sync_vols 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-32:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-32:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword iosize 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-128:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-128:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword slow 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 0-:0 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='0-:0' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 0 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: VMware 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword nameuse 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default vmware_client_name_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Client name selection 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='vmware_backup_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default vmware_backup_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Virtual machine backup 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword trantype 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification VMware_transfer_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='VMware_transfer_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default VMware_transfer_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Transfer type 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword disable_quiesce 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default enabled_disable_quiesce_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword file_system_optimization 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default enabled_disabled_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword snapact 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification snapshot_handling_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='snapshot_handling_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default snapshot_handling_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Existing snapshot handling 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword vmmono 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %dd 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default disabled_enabled_type_list 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword mntpt 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %s 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 03:15:49.337 [10244.8576] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: NAS_Snapshot 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found keyword max_snapshots 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found data type %d 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found value specification 1-:1 03:15:49.337 [10244.8576] <2> parse_value_specification: Value specification='1-:1' 03:15:49.337 [10244.8576] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 03:15:49.337 [10244.8576] <2> parse_value_specification: Found default 1 03:15:49.337 [10244.8576] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found FIM: VSS_Writer 03:15:49.337 [10244.8576] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 03:15:49.337 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V 03:15:49.337 [10244.8576] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: configuring VxFI credentials 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: usaroc9050as015.domain.com 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 13.118.33.36 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 127.0.0.1 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: veritas_pbx 03:15:49.352 [10244.8576] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 1 0x00000001 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 13.115.4.39 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 03:15:49.352 [10244.8576] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4882: 0: using interface : ANY 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: vnetd 03:15:49.352 [10244.8576] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 1 0x00000001 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 13.115.4.39 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:15:49.352 [10244.8576] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4882: 0: using interface : ANY 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: usaroc9050as015.domain.com 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: bprd 03:15:49.352 [10244.8576] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 1 0x00000001 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 13.115.4.39 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:15:49.352 [10244.8576] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4882: 0: using interface : ANY 03:15:49.352 [10244.8576] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1200: 0: connect in progress: 1 0x00000001 03:15:49.352 [10244.8576] <2> vnet_pbxConnect: pbxConnectEx Succeeded 03:15:49.352 [10244.8576] <2> do_pbx_service: ../../libvlibs/vnet_connect.c.1784: 0: via PBX: bprd CONNECT FROM 13.118.33.36.2406 TO 13.115.4.39.1556 fd = 1592 03:15:49.352 [10244.8576] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1367: 0: connect: async CONNECT FROM 13.118.33.36.2406 TO 13.115.4.39.1556 fd = 1592 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: usaroc9050as015.domain.com 03:15:49.352 [10244.8576] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 03:15:49.352 [10244.8576] <2> logconnections: BPRD CONNECT FROM 13.118.33.36.2406 TO 13.115.4.39.1556 fd = 1592 03:15:49.352 [10244.8576] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.871: 0: Ignoring VxSS authentication: 2 0x00000002 03:15:49.352 [10244.8576] <2> bprd_read_text_file: Starting to read data 03:15:49.915 [10244.8576] <2> bprd_read_text_file: Received status 131 03:15:49.915 [10244.8576] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: read credentials file failed 03:15:49.915 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed. 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages: 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message: 03:15:49.915 [10244.8576] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed. 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:15:49.930 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:15:49.930 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $ 03:15:49.930 [10244.8576] <2> onlfi_vfms_logf: INF - virt_test_log: log function is called 03:15:49.930 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - rebuild from file: C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIS-VERSION=1.0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIS-TIME=1331795345 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIS-OWNER=NBU 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIS-RHOST=LOCALHOST 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIS-IS_PRIMARY 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FAILED_DELETES=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is STREAM_IN_CONTROL=unknown+900+1 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is STREAM_NAME=unknown+900+1 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is STREAM_ID=unknown+900 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is STREAM_NUM=1 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is STREAM_PID=900 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is STREAMS=1 1 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is PFI_BACKUP=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is NBU_CATALOG=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is ALT_CLIENT=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is POLICY_NAME=unknown 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is POLICY_TYPE=13 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is SCHED_NAME=unknown 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is SCHED_TYPE=unknown 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is GRAN_BACKUP=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is VSS_REPLICA=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is DATABASE_SOURCE=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is EXCHANGE_DAG_NAME= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is NO_SNAP_LICENSE=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FILELIST=FILENAME=I:\ 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIM_NAME=VSS 03:15:49.946 [10244.8576] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS 03:15:49.946 [10244.8576] <2> onlfi_check_fim_list: INF - comparing VSS to VSS 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-type=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fs_name=I:\ 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fs_type=NTFS 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-raw_device= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fi_mnt_device= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fi_raw_device= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fi_args= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-mnt_flag=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fi_flag=1 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fm_flag=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-cs_flag=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV-fi_fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is PATH_INFO= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is PLUGIN=START 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is HDEF-afs=I:\ 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is HDEF-fifs=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_I__900 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is HDEF-vss_prov_type=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is HDEF-is_alt=0 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is HDEF-fstype=NTFS 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is HDEF-fidev= 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is PLUGIN=END 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FSDEV=NEXT 03:15:49.946 [10244.8576] <2> rebuild_fim_list: INF - line is FIM_NAME_END 03:15:49.946 [10244.8576] <2> onlfi_thaw: INF - fim=VSS 03:15:49.946 [10244.8576] <4> onlfi_thaw: INF - Thawing I:\ using snapshot method VSS. 03:15:49.946 [10244.8576] <2> start_keep_alive_thread: INF - Start keep_alive thread 03:15:49.946 [10244.8576] <2> do_thaw: INF - do_thaw: parent: thaw flag on 03:15:49.946 [10244.8576] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ... 03:15:49.946 [10244.7860] <2> send_keep_alive: INF - sending keep alive 03:15:50.009 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_reaccess_tree 03:15:50.212 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:15:50.212000 WARNING: No credentials found for EMC CLARIION. 03:15:50.212 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Thu Mar 15 2012 03:15:50.212000 Unable to import any login credentials for any appliances. 03:15:50.212 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcclariionfi : corresponding supported PTP not found. 03:15:50.259 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: emcsymfi: CLI tool C:\Program Files\EMC\SYMCLI\bin\ installation path symcfg.exe is invalid. 03:15:50.274 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin emcsymfi : corresponding supported PTP not found. 03:15:50.321 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:15:50.321000 CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found. 03:15:50.321 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Thu Mar 15 2012 03:15:50.321000 CHitachiPlugin::init : Library RMLIB init failed 03:15:50.321 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hitachifi : corresponding supported PTP not found. 03:15:50.368 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi: CLI tool C:\Program Files\Hewlett-Packard\Sanworks\Element Manager for StorageWorks HSV\Bridge\ installation path sssu_win_ia64.exe is invalid. 03:15:50.384 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Thu Mar 15 2012 03:15:50.384000 CHpEvaPlugin::init: CLI tool is not installed. 03:15:50.384 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpevafi : corresponding supported PTP not found. 03:15:50.430 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Thu Mar 15 2012 03:15:50.430000 No array management credentials are available in configuration file. 03:15:50.430 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi: CLI tool C:\Program Files\Compaq\Hpacucli\Bin\ installation path hpacucli.exe is invalid. 03:15:50.430 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin hpmsafi : corresponding supported PTP not found. 03:15:50.477 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Thu Mar 15 2012 03:15:50.477000 DSCLI not found at path=[C:\Program Files\IBM\DSCLI\] 03:15:50.477 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: 03:15:50.477 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin ibmtsfi : corresponding supported PTP not found. 03:15:50.509 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: netappfi:Thu Mar 15 2012 03:15:50.509000 WARNING: No credentials found for NETAPP LUN. 03:15:50.509 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: netappfi: 03:15:50.509 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: fi_pgninit: Plugin netappfi : corresponding supported PTP not found. 03:15:50.555 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: vss:Thu Mar 15 2012 03:15:50.555000 check_service_status - STATUS=[4] 03:15:50.759 [10244.8576] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_reaccess_tree with 0 03:15:50.759 [10244.8576] <4> onlfi_thaw: INF - do_thaw return value: 0 03:15:50.759 [10244.8576] <2> onlfi_thaw: INF - resyncing VSS object 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - f_type=0 (F_REG) 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fs_name=I:\ 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fs_type=NTFS 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskDmVolumes\ClientDg0\Volume1 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - raw_device= 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - volume_group= 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fi_fs_name=\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy84 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fi_mnt_device= 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fi_raw_device= 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fi_args= [size = 0] 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fi_handle=0000000000E91620 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - mnt_flag=0 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fi_flag=1 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - fm_flag=0 03:15:50.759 [10244.8576] <2> log_fs_dev_rec: INF - cs_flag=0 03:15:50.759 [10244.8576] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0 03:15:50.759 [10244.8576] <2> fis_delete_id: INF - removing C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.client.domain.com_1331793898.0.fiid 03:15:50.759 [10244.8576] <2> ol_cleanup: INF - removing C:\Program Files\Veritas\NetBackup\temp\unknown+d900+1.std_filelist 03:15:50.837 [10244.8576] <2> ol_cleanup: INF - removing all log files 03:15:50.837 [10244.8576] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed 03:15:50.837 [10244.8576] <2> stop_keep_alive_thread: INF - Stop keep_alive thread 03:15:52.837 [10244.8576] <2> bpfis Exit: INF - Close of stdout complete