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