13:54:50.726 [7200.10016] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id GSN0-DAG1_1352296457 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 16 -clnt G1GSN0BMS014 -rg other -fim VSS:prov_type=3,snap_attr=2,max_snapshots=4 -class FT_BC_TEST_SP_DAG1_VSSHW_SNAP_DG -sched test -st FULL -ds 0 -dag GSN0-DAG1 -fso -S odismbkpmastcl1 13:54:50.726 [7200.10016] <2> bpfis main: received FIM as [43] VSS:prov_type=3,snap_attr=2,max_snapshots=4 13:54:50.726 [7200.10016] <2> bpfis main: VSS provider type: 3 13:54:50.727 [7200.10016] <4> bpfis: INF - BACKUP START 7200 13:54:50.727 [7200.10016] <2> bpfis main: receive filelist: 13:54:50.727 [7200.10016] <2> bpfis main: receive filelist: 13:54:50.727 [7200.10016] <2> bpfis main: receive filelist: 13:54:50.773 [7200.10016] <2> read_vfm_conf: options=0x2 13:54:50.773 [7200.10016] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: auto 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: Hyper-V 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword nameuse 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='hyperv_client_name_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default hyperv_client_name_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Client Name Selection 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default hyperv_backup_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword prov_type 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification vss_provider_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='vss_provider_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default vss_provider_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Provider Type 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword snap_attr 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='snapshot_attribute_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default snapshot_attribute_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Snapshot Attribute 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword allow_offline_backup 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %b 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword csv_timeout 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0-:0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0-:0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 13:54:50.773 [7200.10016] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: VSS 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword prov_type 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0-3:0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0-3:0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword snap_attr 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0-2:0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0-2:0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword max_snapshots 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: FlashSnap 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword keep_fi 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %b 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword async_resync 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %b 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword max_snapshots 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword dg_split_retry_times 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 60-:60 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='60-:60' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 60 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword num_sync_vols 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-32:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-32:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword iosize 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-128:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-128:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword slow 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0-:0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0-:0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: vxvm 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword async_resync 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %b 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword max_snapshots 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword sync_wait 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %b 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword num_sync_vols 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-32:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-32:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword iosize 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-128:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-128:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword slow 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 0-:0 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='0-:0' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 0 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: VMware 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword nameuse 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='vmware_client_name_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default vmware_client_name_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Client name selection 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='vmware_backup_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default vmware_backup_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Virtual machine backup 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword trantype 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification VMware_transfer_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='VMware_transfer_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default VMware_transfer_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Transfer type 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword disable_quiesce 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default enabled_disable_quiesce_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword file_system_optimization 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default enabled_disabled_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword snapact 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification snapshot_handling_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='snapshot_handling_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default snapshot_handling_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Existing snapshot handling 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword vmmono 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %dd 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default disabled_enabled_type_list 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword mntpt 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %s 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 13:54:50.773 [7200.10016] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: NAS_Snapshot 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found keyword max_snapshots 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found data type %d 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found value specification 1-:1 13:54:50.773 [7200.10016] <2> parse_value_specification: Value specification='1-:1' 13:54:50.773 [7200.10016] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 13:54:50.773 [7200.10016] <2> parse_value_specification: Found default 1 13:54:50.773 [7200.10016] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found FIM: VSS_Writer 13:54:50.773 [7200.10016] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 13:54:50.820 [7200.10016] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:54:50.820 [7200.10016] <2> logconnections: BPRD CONNECT FROM 10.61.7.114.32880 TO 192.168.96.66.1556 fd = 688 13:54:51.772 [7200.10016] <8> bpfis: WRN - VfMS error 10; see following messages: 13:54:51.772 [7200.10016] <8> bpfis: WRN - Non-fatal method error was reported 13:54:51.772 [7200.10016] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 13:54:51.772 [7200.10016] <8> bpfis: WRN - VfMS method error 3; see following message: 13:54:51.772 [7200.10016] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 13:54:51.772 [7200.10016] <8> bpfis: WRN - VfMS error 10; see following messages: 13:54:51.772 [7200.10016] <8> bpfis: WRN - Non-fatal method error was reported 13:54:51.772 [7200.10016] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 13:54:51.772 [7200.10016] <8> bpfis: WRN - VfMS method error 3; see following message: 13:54:51.772 [7200.10016] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 13:54:51.975 [7200.10016] <4> bpfis: INF - FIS_ID=GSN0-DAG1_1352296457 13:54:51.975 [7200.10016] <4> bpfis: INF - Preparing freeze of NEW_STREAM0 using snapshot method VSS_Writer. 13:54:51.975 [7200.10016] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_NEW_STREAM0_7200 13:54:51.975 [7200.10016] <4> bpfis: INF - Preparing freeze of Microsoft Information Store:\GSN0-LPC-N-MailDB11 using snapshot method VSS_Writer. 13:54:51.975 [7200.10016] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_Microsoft Information Store_GSN0-LPC-N-MailDB11_7200 13:56:18.841 [7200.10016] <32> bpfis: FTL - VfMS error 11; see following messages: 13:56:18.856 [7200.10016] <32> bpfis: FTL - Fatal method error was reported 13:56:18.856 [7200.10016] <32> bpfis: FTL - vfm_freeze_commit: method: VSS_Writer, type: FIM, function: VSS_Writer_freeze_commit 13:56:18.856 [7200.10016] <32> bpfis: FTL - VfMS method error 4; see following message: 13:56:18.856 [7200.10016] <32> bpfis: FTL - VSS_Writer_freeze_commit Pass up backup status for - GSN0-LPC-N-MailDB11 13:56:18.856 [7200.10016] <32> bpfis: FTL - VfMS error 11; see following messages: 13:56:18.856 [7200.10016] <32> bpfis: FTL - Fatal method error was reported 13:56:18.856 [7200.10016] <32> bpfis: FTL - vfm_freeze_commit: method: VSS_Writer, type: FIM, function: VSS_Writer_freeze_commit 13:56:18.856 [7200.10016] <32> bpfis: FTL - VfMS method error 4; see following message: 13:56:18.856 [7200.10016] <32> bpfis: FTL - VSS_Writer_freeze_commit Pass up backup status for - GSN0-LPC-N-MailDB11 13:56:18.856 [7200.10016] <4> bpfis: INF - Thawing NEW_STREAM0 using snapshot method VSS_Writer. 13:56:18.856 [7200.10016] <4> bpfis: INF - do_thaw return value: 0 13:56:18.856 [7200.10016] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_NEW_STREAM0_7200 13:56:18.856 [7200.10016] <4> bpfis: INF - Thawing Microsoft Information Store:\GSN0-LPC-N-MailDB11 using snapshot method VSS_Writer. 13:56:18.856 [7200.10016] <4> bpfis: INF - do_thaw return value: 0 13:56:18.856 [7200.10016] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_Microsoft Information Store_GSN0-LPC-N-MailDB11_7200 13:56:18.856 [7200.10016] <16> bpfis: FTL - snapshot creation failed - VSS volume was not prepared for snap., status 130 13:56:18.856 [7200.10016] <4> bpfis: INF - DB_BACKUP_STATUS:DB_status:EX_DB 19 GSN0-LPC-N-MailDB11:EX_SRVR 12 G1GSN0BMS014:EX_STATUS 156 13:56:18.856 [7200.10016] <16> bpfis: FTL - snapshot creation failed, status 130 13:56:18.856 [7200.10016] <4> bpfis: INF - Thawing NEW_STREAM0 using snapshot method VSS_Writer. 13:56:18.856 [7200.10016] <8> bpfis: WRN - NEW_STREAM0 is not frozen 13:56:18.856 [7200.10016] <4> bpfis: INF - Thawing Microsoft Information Store:\GSN0-LPC-N-MailDB11 using snapshot method VSS_Writer. 13:56:18.856 [7200.10016] <8> bpfis: WRN - Microsoft Information Store:\GSN0-LPC-N-MailDB11 is not frozen 13:56:18.856 [7200.10016] <8> bpfis: WRN - snapshot delete returned status 20 13:56:18.856 [7200.10016] <4> bpfis: INF - EXIT STATUS 130: system error occurred 13:56:23.802 [2676.7356] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id GSN0-DAG1_1352296457 -bpstart_to 300 -bpend_to 300 -clnt G1GSN0BMS014 -pt 16 -st FULL -status 130 -S odismbkpmastcl1 13:56:23.865 [2676.7356] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:56:23.865 [2676.7356] <2> logconnections: BPRD CONNECT FROM 10.61.7.114.10986 TO 192.168.96.66.1556 fd = 652 13:56:25.222 [2676.7356] <4> bpfis: INF - BACKUP START 2676 13:56:25.222 [2676.7356] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.GSN0-DAG1_1352296457.0 13:56:25.222 [2676.7356] <4> bpfis: INF - Attempt to send status to the bpfis create process 13:56:26.236 [2676.7356] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed