16:09:47.892 [2528.7672] <2> logparams: c:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id acdevboxi1_1376489225 C:\ 16:09:47.892 [2528.7672] <2> bpfis main: received FIM as [3] VSS 16:09:47.892 [2528.7672] <4> bpfis: INF - BACKUP START 2528 16:09:47.939 [2528.7672] <8> bpfis: WRN - VfMS error 10; see following messages: 16:09:47.939 [2528.7672] <8> bpfis: WRN - Non-fatal method error was reported 16:09:47.939 [2528.7672] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 16:09:47.939 [2528.7672] <8> bpfis: WRN - VfMS method error 3; see following message: 16:09:47.939 [2528.7672] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 16:09:47.939 [2528.7672] <8> bpfis: WRN - VfMS error 10; see following messages: 16:09:47.939 [2528.7672] <8> bpfis: WRN - Non-fatal method error was reported 16:09:47.939 [2528.7672] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 16:09:47.939 [2528.7672] <8> bpfis: WRN - VfMS method error 3; see following message: 16:09:47.939 [2528.7672] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 16:09:48.001 [2528.7672] <4> bpfis: INF - FIS_ID=acdevboxi1_1376489225 16:09:48.001 [2528.7672] <4> bpfis: INF - Preparing freeze of C:\ using snapshot method VSS. 16:09:48.001 [2528.7672] <4> bpfis: INF - Created mount point c:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_C__2528 16:09:48.001 [2528.7672] <2> read_vfm_conf: options=0x2 16:09:48.001 [2528.7672] <2> read_vfm_conf: Reading file: c:\Program Files\VERITAS\NetBackup\vfm.conf 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found FIM: auto 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_auto.dll 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found FIM: Hyper-V 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword nameuse 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default hyperv_client_name_type_list 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Client Name Selection 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default hyperv_backup_type_list 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword prov_type 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification vss_provider_type_list 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='vss_provider_type_list' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default vss_provider_type_list 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Provider Type 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword snap_attr 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='snapshot_attribute_list' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default snapshot_attribute_list 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Snapshot Attribute 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword allow_offline_backup 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %b 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 1 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='1' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword csv_timeout 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 0-:0 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='0-:0' 16:09:48.001 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 16:09:48.001 [2528.7672] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found FIM: VSS 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword prov_type 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 0-3:0 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='0-3:0' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword snap_attr 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 0-2:0 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='0-2:0' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword max_snapshots 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 1-:1 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='1-:1' 16:09:48.001 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found FIM: FlashSnap 16:09:48.001 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_FlashSnap.dll 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword keep_fi 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %b 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 0 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='0' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword async_resync 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %b 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 0 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='0' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword max_snapshots 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 1-:1 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='1-:1' 16:09:48.001 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword dg_split_retry_times 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 1 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='1' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 60-:60 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='60-:60' 16:09:48.001 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 60 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found keyword num_sync_vols 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.001 [2528.7672] <2> parse_ssm_parms: Found value specification 1-32:1 16:09:48.001 [2528.7672] <2> parse_value_specification: Value specification='1-32:1' 16:09:48.001 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword iosize 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 1-128:1 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='1-128:1' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword slow 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 0-:0 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='0-:0' 16:09:48.017 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found FIM: vxvm 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vxvm.dll 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword async_resync 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %b 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 0 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='0' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword max_snapshots 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 1-:1 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='1-:1' 16:09:48.017 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword sync_wait 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %b 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 1 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='1' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword num_sync_vols 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 1-32:1 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='1-32:1' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword iosize 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 1-128:1 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='1-128:1' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword slow 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 0-:0 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='0-:0' 16:09:48.017 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 0 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found FIM: VMware 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_VMware.dll 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword nameuse 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default vmware_client_name_type_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Client name selection 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='vmware_backup_type_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default vmware_backup_type_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Virtual machine backup 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword trantype 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification VMware_transfer_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='VMware_transfer_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default VMware_transfer_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Transfer type 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword disable_quiesce 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default enabled_disable_quiesce_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword file_system_optimization 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default enabled_disabled_type_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword snapact 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification snapshot_handling_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='snapshot_handling_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default snapshot_handling_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Existing snapshot handling 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword vmmono 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %dd 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default disabled_enabled_type_list 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword mntpt 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %s 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 16:09:48.017 [2528.7672] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found FIM: NAS_Snapshot 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_NAS_Snapshot.dll 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found keyword max_snapshots 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found data type %d 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found value specification 1-:1 16:09:48.017 [2528.7672] <2> parse_value_specification: Value specification='1-:1' 16:09:48.017 [2528.7672] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:09:48.017 [2528.7672] <2> parse_value_specification: Found default 1 16:09:48.017 [2528.7672] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found FIM: VSS_Writer 16:09:48.017 [2528.7672] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vss_writer.dll 16:09:51.017 [2528.7672] <4> bpfis: INF - Deleted mount point c:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_C__2528 16:09:51.017 [2528.7672] <2> determine_vss_provider_type: Get the VSS provider type used for the snapshot 16:09:51.017 [2528.7672] <2> determine_vss_provider_type: VSS provider type was not given to bpfis, and could not be determined. 16:09:51.033 [2528.7672] <4> bpfis: INF - REMAP FILE BACKUP C:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy203\ OPTIONS:ALT_PATH_PREFIX=c:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_2528,FITYPE=MIRROR,MNTPOINT=C:\,FSTYPE=NTFS 16:09:51.158 [2528.7672] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed 16:11:26.799 [7408.3516] <2> logparams: bpfis delete -id acdevboxi1_1376489225 16:11:26.815 [7408.3516] <4> bpfis: INF - BACKUP START 7408 16:11:26.831 [7408.3516] <2> read_vfm_conf: options=0x2 16:11:26.831 [7408.3516] <2> read_vfm_conf: Reading file: c:\Program Files\VERITAS\NetBackup\vfm.conf 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: auto 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_auto.dll 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: Hyper-V 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword nameuse 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default hyperv_client_name_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Client Name Selection 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default hyperv_backup_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword prov_type 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification vss_provider_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='vss_provider_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default vss_provider_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Provider Type 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword snap_attr 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='snapshot_attribute_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default snapshot_attribute_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Snapshot Attribute 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword allow_offline_backup 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %b 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword csv_timeout 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0-:0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0-:0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 16:11:26.831 [7408.3516] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: VSS 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword prov_type 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0-3:0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0-3:0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword snap_attr 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0-2:0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0-2:0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: FlashSnap 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_FlashSnap.dll 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword keep_fi 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %b 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword async_resync 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %b 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword dg_split_retry_times 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 60-:60 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='60-:60' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 60 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword num_sync_vols 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-32:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-32:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword iosize 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-128:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-128:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword slow 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0-:0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0-:0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: vxvm 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vxvm.dll 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword async_resync 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %b 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword sync_wait 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %b 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword num_sync_vols 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-32:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-32:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword iosize 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-128:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-128:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword slow 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 0-:0 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='0-:0' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 0 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: VMware 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_VMware.dll 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword nameuse 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default vmware_client_name_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Client name selection 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='vmware_backup_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default vmware_backup_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Virtual machine backup 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword trantype 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification VMware_transfer_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='VMware_transfer_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default VMware_transfer_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Transfer type 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword disable_quiesce 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default enabled_disable_quiesce_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword file_system_optimization 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default enabled_disabled_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword snapact 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification snapshot_handling_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='snapshot_handling_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default snapshot_handling_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Existing snapshot handling 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword vmmono 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %dd 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default disabled_enabled_type_list 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword mntpt 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %s 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 16:11:26.831 [7408.3516] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: NAS_Snapshot 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_NAS_Snapshot.dll 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found data type %d 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found value specification 1-:1 16:11:26.831 [7408.3516] <2> parse_value_specification: Value specification='1-:1' 16:11:26.831 [7408.3516] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:26.831 [7408.3516] <2> parse_value_specification: Found default 1 16:11:26.831 [7408.3516] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found FIM: VSS_Writer 16:11:26.831 [7408.3516] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vss_writer.dll 16:11:26.862 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6702: 0: fopen() failed: 2 0x00000002 16:11:26.862 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6703: 0: fopen() failed: c:\Program Files\VERITAS\NetBackup\var\host_cache\16d\fa2fcb6d+veritas_pbx,1,0,2,1,0+ac000911.txt 16:11:26.878 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6702: 0: fopen() failed: 2 0x00000002 16:11:26.878 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6703: 0: fopen() failed: c:\Program Files\VERITAS\NetBackup\var\host_cache\080\8a024480+0,1,2,0,1,0+164.131.87.241.txt 16:11:26.893 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6702: 0: fopen() failed: 2 0x00000002 16:11:26.893 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6703: 0: fopen() failed: c:\Program Files\VERITAS\NetBackup\var\host_cache\16d\fa2fcb6d+vnetd,1,0,2,1,0+ac000911.txt 16:11:26.909 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6702: 0: fopen() failed: 2 0x00000002 16:11:26.909 [7408.3516] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6703: 0: fopen() failed: c:\Program Files\VERITAS\NetBackup\var\host_cache\16d\fa2fcb6d+bprd,1,0,2,1,0+ac000911.txt 16:11:26.924 [7408.3516] <2> vnet_pbxConnect: pbxConnectEx Succeeded 16:11:26.924 [7408.3516] <2> logconnections: BPRD CONNECT FROM 192.168.87.211.58770 TO 164.131.87.241.1556 fd = 588 16:11:27.034 [7408.3516] <8> bpfis: WRN - VfMS error 10; see following messages: 16:11:27.034 [7408.3516] <8> bpfis: WRN - Non-fatal method error was reported 16:11:27.034 [7408.3516] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 16:11:27.034 [7408.3516] <8> bpfis: WRN - VfMS method error 3; see following message: 16:11:27.034 [7408.3516] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 16:11:27.049 [7408.3516] <8> bpfis: WRN - VfMS error 10; see following messages: 16:11:27.049 [7408.3516] <8> bpfis: WRN - Non-fatal method error was reported 16:11:27.049 [7408.3516] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 16:11:27.049 [7408.3516] <8> bpfis: WRN - VfMS method error 3; see following message: 16:11:27.049 [7408.3516] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 16:11:27.081 [7408.3516] <4> bpfis: INF - Thawing C:\ using snapshot method VSS. 16:11:27.424 [7408.3516] <4> bpfis: INF - do_thaw return value: 0 16:11:27.596 [7408.3516] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed 16:11:34.753 [6752.7532] <2> logparams: c:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id acdevboxi1_1376489225 E:\ 16:11:34.753 [6752.7532] <2> bpfis main: received FIM as [3] VSS 16:11:34.753 [6752.7532] <4> bpfis: INF - BACKUP START 6752 16:11:34.768 [6752.7532] <8> bpfis: WRN - VfMS error 10; see following messages: 16:11:34.768 [6752.7532] <8> bpfis: WRN - Non-fatal method error was reported 16:11:34.768 [6752.7532] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 16:11:34.768 [6752.7532] <8> bpfis: WRN - VfMS method error 3; see following message: 16:11:34.768 [6752.7532] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 16:11:34.768 [6752.7532] <8> bpfis: WRN - VfMS error 10; see following messages: 16:11:34.768 [6752.7532] <8> bpfis: WRN - Non-fatal method error was reported 16:11:34.768 [6752.7532] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 16:11:34.768 [6752.7532] <8> bpfis: WRN - VfMS method error 3; see following message: 16:11:34.768 [6752.7532] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 16:11:34.800 [6752.7532] <4> bpfis: INF - FIS_ID=acdevboxi1_1376489225 16:11:34.815 [6752.7532] <4> bpfis: INF - Preparing freeze of E:\ using snapshot method VSS. 16:11:34.815 [6752.7532] <4> bpfis: INF - Created mount point c:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_E__6752 16:11:34.815 [6752.7532] <2> read_vfm_conf: options=0x2 16:11:34.815 [6752.7532] <2> read_vfm_conf: Reading file: c:\Program Files\VERITAS\NetBackup\vfm.conf 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: auto 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_auto.dll 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: Hyper-V 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword nameuse 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default hyperv_client_name_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Client Name Selection 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default hyperv_backup_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword prov_type 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification vss_provider_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='vss_provider_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default vss_provider_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Provider Type 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword snap_attr 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='snapshot_attribute_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default snapshot_attribute_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Snapshot Attribute 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword allow_offline_backup 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %b 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword csv_timeout 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0-:0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0-:0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 16:11:34.815 [6752.7532] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: VSS 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword prov_type 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0-3:0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0-3:0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword snap_attr 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0-2:0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0-2:0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: FlashSnap 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_FlashSnap.dll 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword keep_fi 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %b 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword async_resync 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %b 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword dg_split_retry_times 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 60-:60 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='60-:60' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 60 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword num_sync_vols 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-32:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-32:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword iosize 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-128:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-128:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword slow 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0-:0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0-:0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: vxvm 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vxvm.dll 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword async_resync 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %b 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword sync_wait 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %b 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword num_sync_vols 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-32:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-32:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword iosize 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-128:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-128:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword slow 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 0-:0 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='0-:0' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 0 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: VMware 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_VMware.dll 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword nameuse 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default vmware_client_name_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Client name selection 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='vmware_backup_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default vmware_backup_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Virtual machine backup 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword trantype 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification VMware_transfer_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='VMware_transfer_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default VMware_transfer_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Transfer type 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword disable_quiesce 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default enabled_disable_quiesce_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword file_system_optimization 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default enabled_disabled_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword snapact 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification snapshot_handling_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='snapshot_handling_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default snapshot_handling_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Existing snapshot handling 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword vmmono 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %dd 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default disabled_enabled_type_list 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword mntpt 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %s 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 16:11:34.815 [6752.7532] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: NAS_Snapshot 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_NAS_Snapshot.dll 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found keyword max_snapshots 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found data type %d 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found value specification 1-:1 16:11:34.815 [6752.7532] <2> parse_value_specification: Value specification='1-:1' 16:11:34.815 [6752.7532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:11:34.815 [6752.7532] <2> parse_value_specification: Found default 1 16:11:34.815 [6752.7532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found FIM: VSS_Writer 16:11:34.815 [6752.7532] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vss_writer.dll 16:11:36.675 [6752.7532] <4> bpfis: INF - Deleted mount point c:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_E__6752 16:11:36.675 [6752.7532] <2> determine_vss_provider_type: Get the VSS provider type used for the snapshot 16:11:36.675 [6752.7532] <2> determine_vss_provider_type: VSS provider type was not given to bpfis, and could not be determined. 16:11:36.675 [6752.7532] <4> bpfis: INF - REMAP FILE BACKUP E:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy204\ OPTIONS:ALT_PATH_PREFIX=c:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_6752,FITYPE=MIRROR,MNTPOINT=E:\,FSTYPE=NTFS 16:11:36.831 [6752.7532] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed 16:24:43.357 [7520.6444] <2> logparams: bpfis delete -id acdevboxi1_1376489225 16:24:43.372 [7520.6444] <4> bpfis: INF - BACKUP START 7520 16:24:43.403 [7520.6444] <2> read_vfm_conf: options=0x2 16:24:43.403 [7520.6444] <2> read_vfm_conf: Reading file: c:\Program Files\VERITAS\NetBackup\vfm.conf 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found FIM: auto 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_auto.dll 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found FIM: Hyper-V 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword nameuse 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default hyperv_client_name_type_list 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Client Name Selection 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default hyperv_backup_type_list 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword prov_type 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification vss_provider_type_list 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='vss_provider_type_list' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default vss_provider_type_list 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Provider Type 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword snap_attr 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='snapshot_attribute_list' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default snapshot_attribute_list 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Snapshot Attribute 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword allow_offline_backup 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %b 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword csv_timeout 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0-:0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0-:0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 16:24:43.403 [7520.6444] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found FIM: VSS 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libvfms.dll 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword prov_type 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0-3:0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0-3:0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword snap_attr 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0-2:0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0-2:0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword max_snapshots 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1-:1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1-:1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found FIM: FlashSnap 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_FlashSnap.dll 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword keep_fi 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %b 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword async_resync 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %b 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword max_snapshots 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1-:1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1-:1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword dg_split_retry_times 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 60-:60 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='60-:60' 16:24:43.403 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 60 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword num_sync_vols 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1-32:1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1-32:1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword iosize 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1-128:1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1-128:1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword slow 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0-:0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0-:0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found FIM: vxvm 16:24:43.403 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vxvm.dll 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword async_resync 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %b 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 0 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='0' 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found keyword max_snapshots 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found value specification 1-:1 16:24:43.403 [7520.6444] <2> parse_value_specification: Value specification='1-:1' 16:24:43.403 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.403 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.403 [7520.6444] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword sync_wait 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %b 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification 1 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='1' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword num_sync_vols 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification 1-32:1 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='1-32:1' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword iosize 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification 1-128:1 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='1-128:1' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword slow 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification 0-:0 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='0-:0' 16:24:43.419 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default 0 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 16:24:43.419 [7520.6444] <2> read_vfm_conf: Found FIM: VMware 16:24:43.419 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_VMware.dll 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword nameuse 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default vmware_client_name_type_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Client name selection 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='vmware_backup_type_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default vmware_backup_type_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Virtual machine backup 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword trantype 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification VMware_transfer_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='VMware_transfer_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default VMware_transfer_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Transfer type 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword disable_quiesce 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default enabled_disable_quiesce_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword file_system_optimization 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default enabled_disabled_type_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword snapact 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification snapshot_handling_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='snapshot_handling_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default snapshot_handling_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Existing snapshot handling 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword vmmono 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %dd 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default disabled_enabled_type_list 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword mntpt 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %s 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 16:24:43.419 [7520.6444] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 16:24:43.419 [7520.6444] <2> read_vfm_conf: Found FIM: NAS_Snapshot 16:24:43.419 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_NAS_Snapshot.dll 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found keyword max_snapshots 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found data type %d 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found value specification 1-:1 16:24:43.419 [7520.6444] <2> parse_value_specification: Value specification='1-:1' 16:24:43.419 [7520.6444] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 16:24:43.419 [7520.6444] <2> parse_value_specification: Found default 1 16:24:43.419 [7520.6444] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 16:24:43.419 [7520.6444] <2> read_vfm_conf: Found FIM: VSS_Writer 16:24:43.419 [7520.6444] <2> read_vfm_conf: Found library: c:\Program Files\VERITAS\NetBackup\bin\libfi_vss_writer.dll 16:24:43.466 [7520.6444] <2> vnet_pbxConnect: pbxConnectEx Succeeded 16:24:43.466 [7520.6444] <2> logconnections: BPRD CONNECT FROM 192.168.87.211.60032 TO 164.131.87.241.1556 fd = 556 16:24:43.528 [7520.6444] <8> bpfis: WRN - VfMS error 10; see following messages: 16:24:43.528 [7520.6444] <8> bpfis: WRN - Non-fatal method error was reported 16:24:43.528 [7520.6444] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 16:24:43.528 [7520.6444] <8> bpfis: WRN - VfMS method error 3; see following message: 16:24:43.528 [7520.6444] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 16:24:43.528 [7520.6444] <8> bpfis: WRN - VfMS error 10; see following messages: 16:24:43.528 [7520.6444] <8> bpfis: WRN - Non-fatal method error was reported 16:24:43.528 [7520.6444] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 16:24:43.528 [7520.6444] <8> bpfis: WRN - VfMS method error 3; see following message: 16:24:43.528 [7520.6444] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 16:24:43.575 [7520.6444] <4> bpfis: INF - Thawing E:\ using snapshot method VSS. 16:24:43.872 [7520.6444] <4> bpfis: INF - do_thaw return value: 0 16:24:44.138 [7520.6444] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed