cancel
Showing results for 
Search instead for 
Did you mean: 

VM backup failing with 156

Aman_Sidana
Level 3

My environment: NBU 7 - windows server 2008 x64
                              vSphere 5.0

14:57:06.612 [12852.3776] <2> logparams: E:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id glseccprd03.ag.ad.local_1385391426 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 29 -vm_type 2 -vm_client glseccprd03.ag.ad.local -clnt gbstbck01.ag.ad.local -st FULL -rg other -fim VMware:nameuse=0,Virtual_machine_backup=2,trantype=0,disable_quiesce=1,file_system_optimization=1,snapact=2,vmmono=0,nameuse=0 -class ST1_WSYVCBT21_DLY -sched ST1_VCB2100_DLY -fso -S gbstbck01.ag.ad.local 

14:57:06.612 [12852.3776] <2> bpfis main: received FIM as [126] VMware:nameuse=0,Virtual_machine_backup=2,trantype=0,disable_quiesce=1,file_system_optimization=1,snapact=2,vmmono=0,nameuse=0
14:57:06.612 [12852.3776] <4> bpfis: INF - BACKUP START 12852
14:57:06.612 [12852.3776] <2> bpfis main: receive filelist:<NEW_STREAM> 
14:57:06.612 [12852.3776] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES> 
14:57:06.612 [12852.3776] <2> bpfis main: receive filelist:<CONTINUE> 
14:57:06.612 [12852.3776] <2> read_vfm_conf: options=0x2
14:57:06.612 [12852.3776] <2> read_vfm_conf: Reading file: E:\Program Files\Veritas\NetBackup\vfm.conf
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found FIM: auto
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found FIM: Hyper-V
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libvfms.dll
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword nameuse
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='hyperv_client_name_type_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default hyperv_client_name_type_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Client Name Selection
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='hyperv_backup_type_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default hyperv_backup_type_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Virtual Machine Backup
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword prov_type
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification vss_provider_type_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='vss_provider_type_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default vss_provider_type_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Provider Type
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword snap_attr
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification snapshot_attribute_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='snapshot_attribute_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default snapshot_attribute_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Snapshot Attribute
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword allow_offline_backup
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %b
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword csv_timeout
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0-:0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0-:0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes
14:57:06.612 [12852.3776] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found FIM: VSS
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libvfms.dll
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword prov_type
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0-3:0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0-3:0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword snap_attr
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0-2:0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0-2:0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword max_snapshots
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found FIM: FlashSnap
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword keep_fi
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %b
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword async_resync
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %b
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword max_snapshots
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword dg_split_retry_times
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 60-:60
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='60-:60'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 60
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword num_sync_vols
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-32:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-32:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword iosize
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-128:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-128:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword slow
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0-:0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0-:0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found FIM: vxvm
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword async_resync
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %b
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword max_snapshots
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword sync_wait
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %b
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword num_sync_vols
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-32:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-32:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword iosize
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 1-128:1
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='1-128:1'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword slow
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification 0-:0
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='0-:0'
14:57:06.612 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default 0
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found FIM: VMware
14:57:06.612 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword nameuse
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='vmware_client_name_type_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default vmware_client_name_type_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Client name selection
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification vmware_backup_type_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='vmware_backup_type_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default vmware_backup_type_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Virtual machine backup
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword trantype
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification VMware_transfer_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='VMware_transfer_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default VMware_transfer_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Transfer type
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword disable_quiesce
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default enabled_disable_quiesce_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Virtual machine quiesce
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword file_system_optimization
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='enabled_disabled_type_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default enabled_disabled_type_list
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found keyword snapact
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.612 [12852.3776] <2> parse_ssm_parms: Found value specification snapshot_handling_list
14:57:06.612 [12852.3776] <2> parse_value_specification: Value specification='snapshot_handling_list'
14:57:06.612 [12852.3776] <2> parse_value_specification: Found default snapshot_handling_list
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found prompt Existing snapshot handling
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found keyword vmmono
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found data type %dd
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list
14:57:06.628 [12852.3776] <2> parse_value_specification: Value specification='disabled_enabled_type_list'
14:57:06.628 [12852.3776] <2> parse_value_specification: Found default disabled_enabled_type_list
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found keyword mntpt
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found data type %s
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)
14:57:06.628 [12852.3776] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1
14:57:06.628 [12852.3776] <2> read_vfm_conf: Found FIM: NAS_Snapshot
14:57:06.628 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found keyword max_snapshots
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found data type %d
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found value specification 1-:1
14:57:06.628 [12852.3776] <2> parse_value_specification: Value specification='1-:1'
14:57:06.628 [12852.3776] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
14:57:06.628 [12852.3776] <2> parse_value_specification: Found default 1
14:57:06.628 [12852.3776] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
14:57:06.628 [12852.3776] <2> read_vfm_conf: Found FIM: VSS_Writer
14:57:06.628 [12852.3776] <2> read_vfm_conf: Found library: E:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll
14:57:06.643 [12852.3776] <2> logconnections: BPRD CONNECT FROM 172.30.131.22.57431 TO 172.30.131.22.13720 fd = 736
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS error 10; see following messages:
14:57:06.690 [12852.3776] <8> bpfis: WRN - Non-fatal method error was reported
14:57:06.690 [12852.3776] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS method error 3; see following message:
14:57:06.690 [12852.3776] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS error 10; see following messages:
14:57:06.690 [12852.3776] <8> bpfis: WRN - Non-fatal method error was reported
14:57:06.690 [12852.3776] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS method error 3; see following message:
14:57:06.690 [12852.3776] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.
14:57:06.690 [12852.3776] <4> bpfis: INF - FIS_ID=glseccprd03.ag.ad.local_1385391426
14:57:06.690 [12852.3776] <4> bpfis: INF - Freezing ALL_LOCAL_DRIVES using snapshot method VMware.
14:57:06.690 [12852.3776] <4> bpfis: INF - ACT=glseccprd03.ag.ad.local
14:57:06.690 [12852.3776] <4> bpfis: INF - Created mount point E:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_12852
14:57:06.690 [12852.3776] <2> logconnections: BPRD CONNECT FROM 172.30.131.22.57435 TO 172.30.131.22.13720 fd = 812
14:59:09.842 [12852.3776] <4> bpfis: INF - Deleted mount point E:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_12852
14:59:09.842 [12852.3776] <32> bpfis: FTL - VfMS error 11; see following messages:
14:59:09.842 [12852.3776] <32> bpfis: FTL - Fatal method error was reported
14:59:09.842 [12852.3776] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
14:59:09.842 [12852.3776] <32> bpfis: FTL - VfMS method error 7; see following message:
14:59:09.842 [12852.3776] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed with 6
14:59:09.842 [12852.3776] <32> bpfis: FTL - VfMS error 11; see following messages:
14:59:09.842 [12852.3776] <32> bpfis: FTL - Fatal method error was reported
14:59:09.842 [12852.3776] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
14:59:09.842 [12852.3776] <32> bpfis: FTL - f; see following message:
14:59:09.842 [12852.3776] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed with 6
14:59:09.842 [12852.3776] <16> bpfis: FTL - snapshot creation failed, status 156
14:59:09.842 [12852.3776] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method VMware.
14:59:09.842 [12852.3776] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen
14:59:09.842 [12852.3776] <8> bpfis: WRN - snapshot delete returned status 20
14:59:09.842 [12852.3776] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered
14:59:10.076 [5504.7964] <2> logparams: E:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id glseccprd03.ag.ad.local_1385391426 -bpstart_to 300 -bpend_to 300 -clnt gbstbck01.ag.ad.local -S gbstbck01.ag.ad.local 
14:59:10.076 [5504.7964] <2> logconnections: BPRD CONNECT FROM 172.30.131.22.54329 TO 172.30.131.22.13720 fd = 700
14:59:10.154 [5504.7964] <4> bpfis: INF - BACKUP START 5504
14:59:10.154 [5504.7964] <32> bpfis: FTL - cannot open E:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.glseccprd03.ag.ad.local_1385391426.0
14:59:10.154 [5504.7964] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
 
1 ACCEPTED SOLUTION

Accepted Solutions

RohitNayal
Level 5
Certified

Hi Aman ,

 

 
If this backup is previously running fine , Can you take snapshot on this VM through V center if yes than , reinstall the VMware Tool on the machine and during installation de select the VSS option , please have a look on the below link as suggested by VMWare .

 

http://kb.vmware.com/kb/1018194

View solution in original post

10 REPLIES 10

RamNagalla
Moderator
Moderator
Partner    VIP    Certified
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS error 10; see following messages:
14:57:06.690 [12852.3776] <8> bpfis: WRN - Non-fatal method error was reported
14:57:06.690 [12852.3776] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS method error 3; see following message:
14:57:06.690 [12852.3776] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS error 10; see following messages:
14:57:06.690 [12852.3776] <8> bpfis: WRN - Non-fatal method error was reported
14:57:06.690 [12852.3776] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init
14:57:06.690 [12852.3776] <8> bpfis: WRN - VfMS method error 3; see following message:
14:57:06.690 [12852.3776] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed
 
see the below tech note that is exactly refering the same error message, see if that is related to your issue...
 
 
 
13:58:11.976 [6300.6616] <8> bpfis: WRN - VfMS error 10; see following messages: 
13:58:11.976 [6300.6616] <8> bpfis: WRN - Non-fatal method error was reported 
13:58:11.976 [6300.6616] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 
13:58:11.976 [6300.6616] <8> bpfis: WRN - VfMS method error 3; see following message: 
13:58:11.976 [6300.6616] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 
13:58:11.992 [6300.6616] <8> bpfis: WRN - VfMS error 10; see following messages: 
13:58:11.992 [6300.6616] <8> bpfis: WRN - Non-fatal method error was reported 
13:58:11.992 [6300.6616] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 
13:58:11.992 [6300.6616] <8> bpfis: WRN - VfMS method error 3; see following message: 
13:58:11.992 [6300.6616] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.
Cause

When a virtual machine has multiple IP addresses, sometimes the vStorage API might not return the IP address expected.  Thus the snapshot will fail because the IP address does not translate to the name of the virtual machine in the policy.


Solution



Provided engineering binary for Etrack 2024766 and created the appropriate registry key to workaround this issue.  The fix can also be found in later versions of the binary bundles.

Enumeration and backups will check for the existence of a registry entry 
\HKEY_LOCAL_MACHINE\SOFTWARE\NetBackup\VERITAS\CurrentVersion\Config\BACKUP\useVMhostname. 

If found, the VM hostname returned from the vADP will be used instead of a reverse IP lookup in the IP address returned. After adding the registry entry, the policy clients will have to be redone after a rebuild of the VM cache. 

 

SymTerry
Level 6
Employee Accredited

Hello Aman,

What version of NetBackup are you running? It could also be related to this bug as well: http://www.symantec.com/docs/TECH173857

Aman_Sidana
Level 3

Nagalla, I am not too sure that this is related. The error seems to differ in the link 

 

13:58:15.164 [6300.6616] <32> bpfis: FTL - VfMS method error 7; see following message: 
13:58:15.164 [6300.6616] <32> bpfis: FTL - VMware_freeze: Unable to locate VM vmclient.symantec.com 

 

Where as my issue has a different error 

14:59:09.842 [12852.3776] <32> bpfis: FTL - VfMS error 11; see following messages:
14:59:09.842 [12852.3776] <32> bpfis: FTL - Fatal method error was reported
14:59:09.842 [12852.3776] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
14:59:09.842 [12852.3776] <32> bpfis: FTL - VfMS method error 7; see following message:
14:59:09.842 [12852.3776] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed with 6
14:59:09.842 [12852.3776] <32> bpfis: FTL - VfMS error 11; see following messages:
14:59:09.842 [12852.3776] <32> bpfis: FTL - Fatal method error was reported
14:59:09.842 [12852.3776] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
14:59:09.842 [12852.3776] <32> bpfis: FTL - f; see following message:
14:59:09.842 [12852.3776] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed with 6
14:59:09.842 [12852.3776] <16> bpfis: FTL - snapshot creation failed, status 156

Aman_Sidana
Level 3

Hello SymTerry,

Both client and backup host are running on Netbackup 7.1.0.4.

 

sri_vani
Level 6
Partner

Hello Aman,

It may help you please verify this below link :

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

Cause

Client was migrated from Physical to Virtual.

The IP address was set to dynamic.

Solution

Change the IP address to Static

Please keep us posted..

 

RamNagalla
Moderator
Moderator
Partner    VIP    Certified

Does this backup ever worked....?

if not, you need to check the credentials and permissions...

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

if you are not sure about the permissions, try to create a snaphost by loggin into Vcenter using the same user id that is used in netbackup configurations.

RohitNayal
Level 5
Certified

Hi Aman ,

 

 
If this backup is previously running fine , Can you take snapshot on this VM through V center if yes than , reinstall the VMware Tool on the machine and during installation de select the VSS option , please have a look on the below link as suggested by VMWare .

 

http://kb.vmware.com/kb/1018194

RohitNayal
Level 5
Certified

Hi Aman ,

Also delete the VM from the backup policy and add it again in the policy .and test the backup .

 

Jung
Not applicable

Hi Aman,

Another method to see what error actually happen for code 156 is to login directly to vm host via vsphere client before you run backup job. There you can see what error occur that make the job fail with error 156.

bpdown
Level 4

My usual troubleshooting list for a 156 error...

 

- Are any of the drives on the VM out of space?

- Does the VM have high IO at the time of backup?

- Does the VM have any raw disks?

- Are there VSS errors on the VM? - vssadmin list writers

- Are there excessive delta files in the datastore?

- Is the datastore out of space?

- Turn off quiescing and take a backup, does the backup work?

- Take a snapshot from vCentre, does it work?

- Migrate the VM and backup again

- Reboot the VM