VM backup is failing continously with 156 error within start

[RajkumarBaniwal]

VM backup is failing continously with 156 error within start

PFB bpfis logs:

17:51:00.342 [11788.8532] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id gnnmis41-bkp_1357993258 -bpstart_to 30000 -bpend_to 3000 -backup_copy 0 -ru root -pt 29 -vm_type 2 -vm_client gnnmis41-bkp -clnt gnnbkp04 -st FULL -rg other -fim VMware:nameuse=0,Virtual_machine_backup=2,trantype=6,disable_quiesce=0,file_system_optimization=1,snapact=0,vmmono=0,nameuse=0 -class VM_Windows -sched Full -fso -S gnnbkp01
17:51:00.342 [11788.8532] <2> bpfis main: received FIM as [126] VMware:nameuse=0,Virtual_machine_backup=2,trantype=6,disable_quiesce=0,file_system_optimization=1,snapact=0,vmmono=0,nameuse=0
17:51:00.342 [11788.8532] <4> bpfis: INF - BACKUP START 11788
17:51:00.342 [11788.8532] <2> bpfis main: receive filelist:<NEW_STREAM>
17:51:00.342 [11788.8532] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
17:51:00.342 [11788.8532] <2> bpfis main: receive filelist:<CONTINUE>
17:51:00.373 [11788.8532] <2> read_vfm_conf: options=0x2
17:51:00.373 [11788.8532] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found FIM: auto
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found FIM: Hyper-V
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword nameuse
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='hyperv_client_name_type_list'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default hyperv_client_name_type_list
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Client Name Selection
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='hyperv_backup_type_list'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default hyperv_backup_type_list
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Virtual Machine Backup
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword prov_type
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification vss_provider_type_list
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='vss_provider_type_list'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default vss_provider_type_list
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Provider Type
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword snap_attr
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification snapshot_attribute_list
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='snapshot_attribute_list'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default snapshot_attribute_list
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Snapshot Attribute
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword allow_offline_backup
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %b
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword csv_timeout
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0-:0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0-:0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes
17:51:00.373 [11788.8532] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found FIM: VSS
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword prov_type
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0-3:0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0-3:0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword snap_attr
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0-2:0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0-2:0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword max_snapshots
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1-:1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1-:1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found FIM: FlashSnap
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword keep_fi
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %b
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword async_resync
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %b
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword max_snapshots
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1-:1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1-:1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword dg_split_retry_times
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 60-:60
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='60-:60'
17:51:00.373 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 60
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword num_sync_vols
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1-32:1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1-32:1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword iosize
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1-128:1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1-128:1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword slow
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0-:0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0-:0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found FIM: vxvm
17:51:00.373 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword async_resync
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %b
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 0
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='0'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword max_snapshots
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1-:1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1-:1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found keyword sync_wait
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found data type %b
17:51:00.373 [11788.8532] <2> parse_ssm_parms: Found value specification 1
17:51:00.373 [11788.8532] <2> parse_value_specification: Value specification='1'
17:51:00.373 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword num_sync_vols
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification 1-32:1
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='1-32:1'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword iosize
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification 1-128:1
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='1-128:1'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword slow
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification 0-:0
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='0-:0'
17:51:00.389 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default 0
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
17:51:00.389 [11788.8532] <2> read_vfm_conf: Found FIM: VMware
17:51:00.389 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword nameuse
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='vmware_client_name_type_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default vmware_client_name_type_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Client name selection
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification vmware_backup_type_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='vmware_backup_type_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default vmware_backup_type_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Virtual machine backup
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword trantype
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification VMware_transfer_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='VMware_transfer_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default VMware_transfer_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Transfer type
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword disable_quiesce
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default enabled_disable_quiesce_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Virtual machine quiesce
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword file_system_optimization
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='enabled_disabled_type_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default enabled_disabled_type_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword snapact
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification snapshot_handling_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='snapshot_handling_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default snapshot_handling_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Existing snapshot handling
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword vmmono
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %dd
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='disabled_enabled_type_list'
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default disabled_enabled_type_list
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword mntpt
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %s
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)
17:51:00.389 [11788.8532] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1
17:51:00.389 [11788.8532] <2> read_vfm_conf: Found FIM: NAS_Snapshot
17:51:00.389 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found keyword max_snapshots
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found data type %d
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found value specification 1-:1
17:51:00.389 [11788.8532] <2> parse_value_specification: Value specification='1-:1'
17:51:00.389 [11788.8532] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:51:00.389 [11788.8532] <2> parse_value_specification: Found default 1
17:51:00.389 [11788.8532] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:51:00.389 [11788.8532] <2> read_vfm_conf: Found FIM: VSS_Writer
17:51:00.389 [11788.8532] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll
17:51:00.405 [11788.8532] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:51:00.405 [11788.8532] <2> logconnections: BPRD CONNECT FROM 172.30.27.4.1335 TO 172.30.27.1.1556 fd = 544
17:51:00.498 [11788.8532] <8> bpfis: WRN - VfMS error 10; see following messages:
17:51:00.498 [11788.8532] <8> bpfis: WRN - Non-fatal method error was reported
17:51:00.498 [11788.8532] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init
17:51:00.498 [11788.8532] <8> bpfis: WRN - VfMS method error 3; see following message:
17:51:00.498 [11788.8532] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.
17:51:00.498 [11788.8532] <8> bpfis: WRN - VfMS error 10; see following messages:
17:51:00.498 [11788.8532] <8> bpfis: WRN - Non-fatal method error was reported
17:51:00.498 [11788.8532] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init
17:51:00.498 [11788.8532] <8> bpfis: WRN - VfMS method error 3; see following message:
17:51:00.498 [11788.8532] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.
17:51:00.514 [11788.8532] <4> bpfis: INF - FIS_ID=gnnmis41-bkp_1357993258
17:51:00.514 [11788.8532] <4> bpfis: INF - Freezing ALL_LOCAL_DRIVES using snapshot method VMware.
17:51:00.514 [11788.8532] <4> bpfis: INF - ACT=gnnmis41-bkp
17:51:00.514 [11788.8532] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_11788
17:51:00.530 [11788.8532] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:51:00.530 [11788.8532] <2> logconnections: BPRD CONNECT FROM 172.30.27.4.1338 TO 172.30.27.1.1556 fd = 336
17:51:04.311 [11788.8532] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_11788
17:51:04.311 [11788.8532] <32> bpfis: FTL - VfMS error 11; see following messages:
17:51:04.311 [11788.8532] <32> bpfis: FTL - Fatal method error was reported
17:51:04.311 [11788.8532] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
17:51:04.311 [11788.8532] <32> bpfis: FTL - VfMS method error 7; see following message:
17:51:04.311 [11788.8532] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed unknown client gnnmis41-bkp
17:51:04.311 [11788.8532] <32> bpfis: FTL - VfMS error 11; see following messages:
17:51:04.311 [11788.8532] <32> bpfis: FTL - Fatal method error was reported
17:51:04.311 [11788.8532] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
17:51:04.311 [11788.8532] <32> bpfis: FTL - VfMS method error 7; see following message:
17:51:04.311 [11788.8532] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed unknown client gnnmis41-bkp
17:51:04.311 [11788.8532] <16> bpfis: FTL - snapshot creation failed, status 156
17:51:04.311 [11788.8532] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method VMware.
17:51:04.311 [11788.8532] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen
17:51:04.311 [11788.8532] <8> bpfis: WRN - snapshot delete returned status 20
17:51:04.311 [11788.8532] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered
17:51:05.623 [5080.9172] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id gnnmis41-bkp_1357993258 -bpstart_to 30000 -bpend_to 3000 -clnt gnnbkp04 -S gnnbkp01
17:51:05.655 [5080.9172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:51:05.655 [5080.9172] <2> logconnections: BPRD CONNECT FROM 172.30.27.4.1377 TO 172.30.27.1.1556 fd = 604
17:51:06.233 [5080.9172] <4> bpfis: INF - BACKUP START 5080
17:51:06.233 [5080.9172] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.gnnmis41-bkp_1357993258.0
17:51:06.233 [5080.9172] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
 

Please provide solution

[RamNagalla]

17:51:04.311 [11788.8532] <32> bpfis: FTL - VfMS error 11; see following messages:
17:51:04.311 [11788.8532] <32> bpfis: FTL - Fatal method error was reported
17:51:04.311 [11788.8532] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
17:51:04.311 [11788.8532] <32> bpfis: FTL - VfMS method error 7; see following message:
17:51:04.311 [11788.8532] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed unknown client gnnmis41-bkp

its saying that the gnnmis41-bkp is unknow client.

check below tech notes

http://www.symantec.com/business/support/index?page=content&id=TECH175943

http://www.symantec.com/business/support/index?page=content&id=TECH136385

https://www-secure.symantec.com/connect/forums/netbackup-654-70-vmware-backups-fail

What is your current version of Netbackup?

[Dan4]

1. Try deleting gnnmis41-bkp client from configurd policy. Re-add the client from the list of VM's.

2. Run a test backup.

3. if it still fails, try to create a manual snapshot for the VM.

4. ensure enough disk space exist in diskstore for the VM.

That should resolve the issue.

+ Dan