Forum Discussion

mrtba's avatar
mrtba
Level 4
13 years ago
Solved

Snapshots failing

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

 

  • 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.

  • 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.

  • 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. 

  • 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

  • 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.

  • 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
     

  • 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?

  • 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

     

     

  • 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.

  • 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.

  • 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.