Snapshots failing

[mrtba]

Netbackup Snapshots failing with this error.
Creating a quiesced snapshot failed because the created snapshot operation exceeded the time limit for holding off I/O in the frozen virtual machine.

Machine is Windows 2008 Server 64-bit. All other VM's in our environment backup without issues(About 40).

Were running netbackup server 7.1.0.4, using Windows Flash back policy.

From what I have read Symantec recommends that I contact Vmware for this issue.

Let me know if more info is needed

[Douglas_A]

For a VMware snapshot, i would test somethig simple first... Can you manually create a snapshot from Vsphere of the Guest? I would guess no.. Check for orphaned snapshots on the guest and if there are no direct errors from vsphere when it trys to snapshot then contact your VMware admin or Vmware to find out how to get more logging from the Vm Guest directly.

[mrtba]

Sorry for not putting this in my original message.  Yes you CAN create quiesce snapshots from the vsphere client.  There are also no errors that I can see in the application or system logs of the VM. 

[mrtba]

This is what I believe to be the issue.  I have checked VSS out and all seems well.  No errors inside the VM.

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

[Douglas_A]

Can you create the bpfis log on the Vmbackup host and post it? That log should have the details behind the 156 error and maybe it can be matched up to some other issue.

[mrtba]

Here are the logs.

15:47:57.757 [7460.8820] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id *VMHost*_1344890676 -bpstart_to 300 -bpend_to 300 -clnt *BackupProxyHost* -S *MasterServer -noserverstate
15:47:57.772 [7460.8820] <4> bpfis: INF - BACKUP START 7460
15:47:57.772 [7460.8820] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.*VMHost*_1344890676.0
15:47:57.772 [7460.8820] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
15:50:44.837 [8356.7396] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id *VMHost*_1344891038 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 29 -vm_type 2 -vm_client *VMHost* -clnt *BackupProxyHost* -st FULL -block_incr -prev_bli_incr 0 -last_bli_full 0 -dt 0 -rg other -fim VMware:nameuse=1,Virtual_machine_backup=2,trantype=6,disable_quiesce=0,file_system_optimization=1,snapact=2,vmmono=0,nameuse=1 -class *PolicyName* -sched Weekly -fso -S *MasterServer
15:50:44.837 [8356.7396] <2> bpfis main: received FIM as [126] VMware:nameuse=1,Virtual_machine_backup=2,trantype=6,disable_quiesce=0,file_system_optimization=1,snapact=2,vmmono=0,nameuse=1
15:50:44.837 [8356.7396] <4> bpfis: INF - BACKUP START 8356
15:50:44.993 [8356.7396] <2> bpfis main: receive filelist:<NEW_STREAM>
15:50:44.993 [8356.7396] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
15:50:44.993 [8356.7396] <2> bpfis main: receive filelist:<CONTINUE>
15:50:45.149 [8356.7396] <2> read_vfm_conf: options=0x2
15:50:45.149 [8356.7396] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: auto
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: Hyper-V
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword nameuse
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='hyperv_client_name_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default hyperv_client_name_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Client Name Selection
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='hyperv_backup_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default hyperv_backup_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Virtual Machine Backup
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword prov_type
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification vss_provider_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='vss_provider_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default vss_provider_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Provider Type
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword snap_attr
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification snapshot_attribute_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='snapshot_attribute_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default snapshot_attribute_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Snapshot Attribute
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword allow_offline_backup
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %b
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword csv_timeout
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0-:0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0-:0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes
15:50:45.149 [8356.7396] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: VSS
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword prov_type
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0-3:0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0-3:0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword snap_attr
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0-2:0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0-2:0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword max_snapshots
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: FlashSnap
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword keep_fi
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %b
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword async_resync
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %b
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword max_snapshots
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword dg_split_retry_times
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 60-:60
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='60-:60'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 60
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword num_sync_vols
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-32:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-32:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword iosize
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-128:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-128:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword slow
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0-:0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0-:0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: vxvm
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword async_resync
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %b
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword max_snapshots
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword sync_wait
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %b
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword num_sync_vols
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-32:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-32:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword iosize
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-128:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-128:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword slow
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 0-:0
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='0-:0'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 0
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: VMware
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword nameuse
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='vmware_client_name_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default vmware_client_name_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Client name selection
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification vmware_backup_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='vmware_backup_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default vmware_backup_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Virtual machine backup
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword trantype
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification VMware_transfer_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='VMware_transfer_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default VMware_transfer_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Transfer type
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword disable_quiesce
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default enabled_disable_quiesce_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Virtual machine quiesce
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword file_system_optimization
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='enabled_disabled_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default enabled_disabled_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword snapact
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification snapshot_handling_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='snapshot_handling_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default snapshot_handling_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Existing snapshot handling
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword vmmono
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %dd
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='disabled_enabled_type_list'
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default disabled_enabled_type_list
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword mntpt
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %s
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)
15:50:45.149 [8356.7396] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: NAS_Snapshot
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found keyword max_snapshots
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found data type %d
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found value specification 1-:1
15:50:45.149 [8356.7396] <2> parse_value_specification: Value specification='1-:1'
15:50:45.149 [8356.7396] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
15:50:45.149 [8356.7396] <2> parse_value_specification: Found default 1
15:50:45.149 [8356.7396] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found FIM: VSS_Writer
15:50:45.149 [8356.7396] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll
15:50:45.149 [8356.7396] <2> vnet_pbxConnect: pbxConnectEx Succeeded
15:50:45.149 [8356.7396] <2> logconnections: BPRD CONNECT FROM 151.154.107.20.61818 TO 151.154.56.96.1556 fd = 496
15:50:45.259 [8356.7396] <8> bpfis: WRN - VfMS error 10; see following messages:
15:50:45.259 [8356.7396] <8> bpfis: WRN - Non-fatal method error was reported
15:50:45.259 [8356.7396] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init
15:50:45.259 [8356.7396] <8> bpfis: WRN - VfMS method error 3; see following message:
15:50:45.259 [8356.7396] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.
15:50:45.259 [8356.7396] <8> bpfis: WRN - VfMS error 10; see following messages:
15:50:45.259 [8356.7396] <8> bpfis: WRN - Non-fatal method error was reported
15:50:45.259 [8356.7396] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init
15:50:45.259 [8356.7396] <8> bpfis: WRN - VfMS method error 3; see following message:
15:50:45.259 [8356.7396] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.
15:50:45.275 [8356.7396] <4> bpfis: INF - FIS_ID=*VMHost*_1344891038
15:50:45.275 [8356.7396] <4> bpfis: INF - Freezing ALL_LOCAL_DRIVES using snapshot method VMware.
15:50:45.275 [8356.7396] <4> bpfis: INF - ACT=*VMHost*
15:50:45.275 [8356.7396] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8356
15:50:45.275 [8356.7396] <2> vnet_pbxConnect: pbxConnectEx Succeeded
15:50:45.275 [8356.7396] <2> logconnections: BPRD CONNECT FROM 151.154.107.20.61819 TO 151.154.56.96.1556 fd = 568
15:53:51.433 [8356.7396] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8356
15:53:51.433 [8356.7396] <32> bpfis: FTL - VfMS error 11; see following messages:
15:53:51.433 [8356.7396] <32> bpfis: FTL - Fatal method error was reported
15:53:51.433 [8356.7396] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
15:53:51.433 [8356.7396] <32> bpfis: FTL - VfMS method error 7; see following message:
15:53:51.433 [8356.7396] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed with 36
15:53:51.433 [8356.7396] <32> bpfis: FTL - VfMS error 11; see following messages:
15:53:51.433 [8356.7396] <32> bpfis: FTL - Fatal method error was reported
15:53:51.433 [8356.7396] <32> bpfis: FTL - vfm_freeze: method: VMware, type: FIM, function: VMware_freeze
15:53:51.433 [8356.7396] <32> bpfis: FTL - VfMS method error 7; see following message:
15:53:51.433 [8356.7396] <32> bpfis: FTL - VMware_freeze: VIXAPI freeze failed with 36
15:53:51.433 [8356.7396] <16> bpfis: FTL - snapshot creation failed, status 156
15:53:51.433 [8356.7396] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method VMware.
15:53:51.433 [8356.7396] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen
15:53:51.433 [8356.7396] <8> bpfis: WRN - snapshot delete returned status 20
15:53:51.433 [8356.7396] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered
15:53:58.074 [452.8828] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id *VMHost*_1344891038 -bpstart_to 300 -bpend_to 300 -clnt *BackupProxyHost* -S *MasterServer -noserverstate
15:53:58.089 [452.8828] <4> bpfis: INF - BACKUP START 452
15:53:58.089 [452.8828] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.*VMHost*_1344891038.0
15:53:58.089 [452.8828] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
 

[Douglas_A]

Is the Vmbackup host the one thats you are trying to backup? VMHost?

I dont know that you can backup the vmbackup host with the VADP method.. just curious.

I dont see anything special in the log which is really odd.. Do you have anyone who can review the VSphere logs and see if anything is showing up there?

[mrtba]

I do have a support case opened with Vmware.  I will contact them tomorrow and see what they say.  Also I found out that whoever set this server up, set the IP address to DHCP.  So I'm changing that tonight.  I have to wait until they are off the server(obviously) before I switch it.

-I will post my findings

[mrtba]

I have changed the IP address but still no luck.  I noticed that the disk were created as thick disk so I cloned the machine and changed them to think but it will still not snapshot via NBU.  I called symantec and have created a trouble ticket.  I will post what we find.

[Douglas_A]

One thing that occured to me.. are you using VMhostname or VMDNS name for the lookup?

I only ask becuase if its by DNS name and the name is not VMHost.domain.com then you could get an error.. if you are doing by hostname and the VSphere name is no VMHost (case sensitive) this could also cause your error.

Just something else to look at. Hope someone can get you a good answer.

[mrtba · Accepted solution]

Finally!!!

I'm not 100% sure what the problem was.  It was one of two things.

I noticed there was a ghosted Disk inside of Disk management.  So I eventually removed it.  Unfortunetly I changed both disks to dymanic at the same time.  I know troubleshooting no no number one.  That solved the issue.