Single VCB backup failing (snapshot error encountered - 156)

My environment: NBU 7.0.1  on server 2k8 sp2  - vSphere and vCenter 4.1.0

 

bpfis for one backup attempt:

 

08:54:11.245 [7972.6976] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id rex.xxxxxxx.xxxxxx_1316534050 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 29 -vm_type 2 -vm_client rex.xxxxx.xxxx -clnt XXXXXXXX -st FULL -rg other -fim VMware:nameuse=0,Virtual_machine_backup=2,trantype=0,disable_quiesce=0,file_system_optimization=1,snapact=0,vmmono=1,mntpt=i:\mount,nameuse=0 -class VCB_Linux_SDisk3 -sched Full -fso -S XXXXXXXX
08:54:11.245 [7972.6976] <2> bpfis main: received FIM as [141] VMware:nameuse=0,Virtual_machine_backup=2,trantype=0,disable_quiesce=0,file_system_optimization=1,snapact=0,vmmono=1,mntpt=i:\mount,nameuse=0
08:54:11.245 [7972.6976] <4> bpfis: INF - BACKUP START 7972
08:54:11.245 [7972.6976] <2> bpfis main: receive filelist:<NEW_STREAM>
08:54:11.245 [7972.6976] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
08:54:11.245 [7972.6976] <2> bpfis main: receive filelist:<CONTINUE>
08:54:11.261 [7972.6976] <2> read_vfm_conf: options=0x2
08:54:11.261 [7972.6976] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: auto
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: Hyper-V
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword nameuse
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification client_name_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='client_name_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default client_name_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Client Name Selection
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='hyperv_backup_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default hyperv_backup_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Virtual Machine Backup
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword prov_type
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification vss_provider_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='vss_provider_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default vss_provider_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Provider Type
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword snap_attr
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification snapshot_attribute_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='snapshot_attribute_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default snapshot_attribute_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Snapshot Attribute
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword allow_offline_backup
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %b
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword csv_timeout
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0-:0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0-:0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes
08:54:11.261 [7972.6976] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: VSS
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword prov_type
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0-3:0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0-3:0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword snap_attr
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0-2:0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0-2:0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword max_snapshots
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: FlashSnap
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword keep_fi
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %b
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword async_resync
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %b
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword max_snapshots
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword dg_split_retry_times
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 60-:60
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='60-:60'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 60
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword num_sync_vols
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-32:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-32:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword iosize
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-128:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-128:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword slow
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0-:0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0-:0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: vxvm
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword async_resync
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %b
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword max_snapshots
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword sync_wait
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %b
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword num_sync_vols
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-32:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-32:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword iosize
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-128:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-128:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword slow
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 0-:0
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='0-:0'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 0
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: VMware
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword nameuse
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification client_name_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='client_name_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default client_name_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Client name selection
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification vmware_backup_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='vmware_backup_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default vmware_backup_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Virtual machine backup
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword trantype
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification VMware_transfer_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='VMware_transfer_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default VMware_transfer_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Transfer type
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword disable_quiesce
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default enabled_disable_quiesce_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Virtual machine quiesce
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword file_system_optimization
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='enabled_disabled_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default enabled_disabled_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword snapact
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification snapshot_handling_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='snapshot_handling_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default snapshot_handling_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Existing snapshot handling
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword vmmono
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %dd
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='disabled_enabled_type_list'
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default disabled_enabled_type_list
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword mntpt
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %s
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)
08:54:11.261 [7972.6976] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: NAS_Snapshot
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found keyword max_snapshots
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found data type %d
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found value specification 1-:1
08:54:11.261 [7972.6976] <2> parse_value_specification: Value specification='1-:1'
08:54:11.261 [7972.6976] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
08:54:11.261 [7972.6976] <2> parse_value_specification: Found default 1
08:54:11.261 [7972.6976] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found FIM: VSS_Writer
08:54:11.261 [7972.6976] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll
08:54:11.261 [7972.6976] <2> vnet_pbxConnect: pbxConnectEx Succeeded
08:54:11.261 [7972.6976] <2> logconnections: BPRD CONNECT FROM 10.1.254.39.56086 TO 10.1.254.39.1556 fd = 548
08:54:11.339 [7972.6976] <8> bpfis: WRN - VfMS error 10; see following messages:
08:54:11.339 [7972.6976] <8> bpfis: WRN - Non-fatal method error was reported
08:54:11.339 [7972.6976] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init
08:54:11.339 [7972.6976] <8> bpfis: WRN - VfMS method error 3; see following message:
08:54:11.339 [7972.6976] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.
08:54:11.339 [7972.6976] <8> bpfis: WRN - VfMS error 10; see following messages:
08:54:11.339 [7972.6976] <8> bpfis: WRN - Non-fatal method error was reported
08:54:11.339 [7972.6976] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init
08:54:11.339 [7972.6976] <8> bpfis: WRN - VfMS method error 3; see following message:
08:54:11.339 [7972.6976] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.
08:54:11.354 [7972.6976] <4> bpfis: INF - FIS_ID=rex.xxxxx.xxxxx_1316534050
08:54:11.354 [7972.6976] <4> bpfis: INF - Freezing ALL_LOCAL_DRIVES using snapshot method VMware.
08:54:11.354 [7972.6976] <4> bpfis: INF - ACT=rex.xxxxxxxx.xxxxxxxx
08:54:11.354 [7972.6976] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_7972
08:54:11.354 [7972.6976] <2> vnet_pbxConnect: pbxConnectEx Succeeded
08:54:11.354 [7972.6976] <2> logconnections: BPRD CONNECT FROM 10.1.254.39.56091 TO 10.1.254.39.1556 fd = 628
08:54:11.885 [7972.6976] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_7972
08:54:11.885 [7972.6976] <32> bpfis: FTL - VfMS error 11; see following messages:
08:54:11.885 [7972.6976] <32> bpfis: FTL - Fatal method error was reported
08:54:11.885 [7972.6976] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
08:54:11.885 [7972.6976] <32> bpfis: FTL - VfMS method error 7; see following message:
08:54:11.885 [7972.6976] <32> bpfis: FTL - VMware_freeze: Unable to locate VM rex.xxxxxxxx.xxxxxxxx
08:54:11.885 [7972.6976] <32> bpfis: FTL - VfMS error 11; see following messages:
08:54:11.885 [7972.6976] <32> bpfis: FTL - Fatal method error was reported
08:54:11.885 [7972.6976] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
08:54:11.885 [7972.6976] <32> bpfis: FTL - VfMS method error 7; see following message:
08:54:11.885 [7972.6976] <32> bpfis: FTL - VMware_freeze: Unable to locate VM rex.xxxxxxxx.xxxxxxxx
08:54:11.885 [7972.6976] <16> bpfis: FTL - snapshot creation failed, status 156
08:54:11.885 [7972.6976] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method VMware.
08:54:11.885 [7972.6976] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen
08:54:11.885 [7972.6976] <8> bpfis: WRN - snapshot delete returned status 20
08:54:13.897 [7972.6976] <2> vnet_pbxConnect: pbxConnectEx Succeeded
08:54:13.897 [7972.6976] <2> logconnections: BPRD CONNECT FROM 10.1.254.39.56100 TO 10.1.254.39.1556 fd = 604
08:54:13.991 [7972.6976] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered
08:54:14.334 [7876.6948] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id rex.xxxxxxxx.xxxxxxxx
_1316534050 -bpstart_to 300 -bpend_to 300 -clnt xxxxxx -S xxxxxx
08:54:14.350 [7876.6948] <2> vnet_pbxConnect: pbxConnectEx Succeeded
08:54:14.350 [7876.6948] <2> logconnections: BPRD CONNECT FROM 10.1.254.39.56136 TO 10.1.254.39.1556 fd = 512
08:54:14.428 [7876.6948] <4> bpfis: INF - BACKUP START 7876
08:54:14.428 [7876.6948] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.rex.xxxxxxxx.xxxxxxxx
_1316534050.0
08:54:14.443 [7876.6948] <2> vnet_pbxConnect: pbxConnectEx Succeeded
08:54:14.443 [7876.6948] <2> logconnections: BPRD CONNECT FROM 10.1.254.39.56144 TO 10.1.254.39.1556 fd = 8
08:54:14.537 [7876.6948] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed

 

 

 

 

 

I think my issue is probably in one of these 2 lines:

08:54:11.885 [7972.6976] <32> bpfis: FTL - VMware_freeze: Unable to locate VM rex.xxxxxxxx.xxxxxxxx
or
08:54:11.885 [7972.6976] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen
 

But I am unsure. I have recently inhereted the backup system and am just trying to keep my head above water!

Ive tried:
Remove client from policy and re-add, refreshing VM host before and after
RE-installing VMware tools
I am able to take a manual snapshot without issue

The guest is a debian box if it matters Smiley Happy Any help or suggestions would be great!

3 Replies

Resolved. I added its fqdn/IP

Resolved. I added its fqdn/IP to the hosts file and that seemed to resolve the issue.

...

use the displayname instead of the hostname so you do not have to work with DNS issues anymore!

Close this thread, if

Close this thread, if resolved