Hyper V backup failure EC156
Hi All Master Server : Linux RHEL 6, 7.6.0.2, MSDP Media Server : Linux RHEL 6, 7.6.0.2, MSDP Hyper V Server: Windows 2012 R2, 7.6.0.2 (only client) We have implemented backup of Hyper-V machines from Netbackup. we have 6 node cluster, Backups of some of the VMs completing successfully & backup for some machines are failing. below are the events which I can find in event viewer of the hyperV machine (CLuster Node) at the time of backup failure. I dont see any thing in event viewer of the machine whose backup has failed. Warning related to VSS writer: A VSS writer has rejected an event with error 0x800423f4, The writer experienced a non-transient error. If the backup process is retried, the error is likely to reoccur. . Changes that the writer made to the writer components while handling the event will not be available to the requester. Check the event log for related events from the application hosting the VSS writer. Operation: PrepareForSnapshot Event bpfis logs from cluster node are attached. Kindly suggest.Solved7.8KViews1like5CommentsVSS & Snapshots on virtual clients with Hyper-V on CSV
Hi everyone, I need a better understanding on snapshots and writers on a Hyper-V backup scenario (CSV with 4 nodes - single policy). As far as i know. Netbackup is installed on the Hyper-V Hosts (nodes) Logs are created on the Hyper-V hosts. Network traffic is between Master-Media and Hyper-V Host And according to the 7.5 Hyper-V Admin Guide - Phase 3: The VSS Hyper-V writer quiesces the Windows virtual machine and creates the snapshot on the host volume. If the Hyper-V writer cannot quiesce the virtual machine, the virtual machine is placed in the Saved state before creation of the snapshot. But, when you get an snapshot error (156)troubleshootingis on the virtual machine, according to this article: http://www.symantec.com/business/support/index?page=content&id=HOWTO44941#v18522639 To be specific, i mean: Enough space on each volume of the virtual machine. NTFS shadow storage on each/same volume of the virtual machine. Those 2issues must be checked on the virtual machine. Last week i made a recomendation - (before being included in our Hyper-V backup) - to check if our newvirtual machines had those configurations correct in order to avoid any CSV issues due the snapshots failing, and when our IT admin asked "why on the virtual machine, if everything happens in the host? makes no sense and nothing should be checked on the virtual machine, just the Hyper-V Hosts" Icouldn´t answer that question. I know for sure the technote is valid, because i´ve troubleshooted156 error many times and always is due to no space on the virtual machine orNTFS shadow storageon differentvolumes.On the other hand, when backup is running, you can only list writers on the Hyper-V hosts, I´m pretty sure this is something conceptual related to the VSS writer, and how snapshots are done. Would be nice if someone could explain me what i´m missing to understand. Thanks a lot! Pamela.Solved5.9KViews2likes4CommentsNetbackup snapshot creation failed, ALL_LOCAL_DRIVES is not frozen, status 156
dear expert, Can you help us please for this Snapshot problem My environment: Master server : netbackup 7.1 , windows 2008 Mediar server : netbackup 7.1 , windows 2008 (Hyper V ) VM hostname : Msproj , windows 2008 Log : 18/05/2014 20:11:06 - begin Create Snapshot 18/05/2014 20:11:09 - Info bpfis(pid=1648) Backup started 18/05/2014 20:11:22 - Critical bpbrm(pid=16332) from client srvmsproj.onepnet.intra: FTL - snapshot creation failed, status 156 18/05/2014 20:11:22 - Warning bpbrm(pid=16332) from client srvmsproj.onepnet.intra: WRN - ALL_LOCAL_DRIVES is not frozen 18/05/2014 20:11:24 - Info bpfis(pid=1648) done. status: 156 18/05/2014 20:11:24 - end Create Snapshot; elapsed time: 00:00:18 18/05/2014 20:11:26 - end writing Status 156 18/05/2014 20:11:26 - end Flash Backup Windows, Create Snapshot; elapsed time: 00:00:28 18/05/2014 20:11:26 - begin Flash Backup Windows, Stop On Error Status 0 18/05/2014 20:11:26 - end Flash Backup Windows, Stop On Error; elapsed time: 00:00:00 18/05/2014 20:11:26 - begin Flash Backup Windows, Delete Snapshot On Exit 18/05/2014 20:11:26 - begin Flash Backup Windows, Delete Snapshot On Exit 18/05/2014 20:11:27 - started process bpbrm (6684) 18/05/2014 20:11:31 - Info bpfis(pid=0) done. status: 156: snapshot error encountered 18/05/2014 20:11:42 - end writing Status 0 18/05/2014 20:11:42 - end Flash Backup Windows, Delete Snapshot On Exit; elapsed time: 00:00:16 18/05/2014 20:11:42 - begin Flash Backup Windows, End Notify Script 18/05/2014 20:11:43 - Info RUNCMD(pid=17164) started 18/05/2014 20:11:43 - Info RUNCMD(pid=17164) exiting with status: 0 Status 0 18/05/2014 20:11:43 - end Flash Backup Windows, End Notify Script; elapsed time: 00:00:01 Status 156 18/05/2014 20:11:43 - end Flash Backup Windows, Delete Snapshot On Exit; elapsed time: 00:00:17 snapshot error encountered(156) bpfis: 20:00:14.116 [5148.12880] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id srvmsproj.onepnet.intra_1401393601 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 29 -vm_type 2 -vm_client srvmsproj.onepnet.intra -clnt hyperv.onepnet.intra -st FULL -rg root -fim Hyper-V:nameuse=0,Virtual_machine_backup=2,prov_type=0,snap_attr=0,allow_offline_backup=1,csv_timeout=0,nameuse=0 -class Msproj -sched Full -fso -S bkp-srv1 20:00:14.116 [5148.12880] <2> bpfis main: received FIM as [113] Hyper-V:nameuse=0,Virtual_machine_backup=2,prov_type=0,snap_attr=0,allow_offline_backup=1,csv_timeout=0,nameuse=0 20:00:14.272 [5148.12880] <4> bpfis: INF - BACKUP START 5148 20:00:14.272 [5148.12880] <2> bpfis main: receive filelist:<NEW_STREAM> 20:00:14.272 [5148.12880] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES> 20:00:14.474 [5148.12880] <2> bpfis main: receive filelist:<CONTINUE> 20:00:14.646 [5148.12880] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:00:14.646 [5148.12880] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:00:14.864 [5148.12880] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54028 TO 10.100.100.142.13724 20:00:17.672 [5148.12880] <2> get_long: (2) premature end of file (byte 1) 20:00:17.672 [5148.12880] <2> bprd_read_text_file: get_string() failed, Access is denied. (5), premature end of file encountered 20:00:17.813 [5148.12880] <8> bpfis: WRN - VfMS error 10; see following messages: 20:00:17.813 [5148.12880] <8> bpfis: WRN - Non-fatal method error was reported 20:00:17.813 [5148.12880] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 20:00:17.813 [5148.12880] <8> bpfis: WRN - VfMS method error 3; see following message: 20:00:17.813 [5148.12880] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 20:00:17.875 [5148.12880] <8> bpfis: WRN - VfMS error 10; see following messages: 20:00:17.875 [5148.12880] <8> bpfis: WRN - Non-fatal method error was reported 20:00:17.875 [5148.12880] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 20:00:17.875 [5148.12880] <8> bpfis: WRN - VfMS method error 3; see following message: 20:00:17.875 [5148.12880] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 20:00:18.172 [5148.12880] <4> bpfis: INF - FIS_ID=srvmsproj.onepnet.intra_1401393601 20:00:18.172 [5148.12880] <4> bpfis: INF - Preparing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V. 20:00:18.172 [5148.12880] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_5148 20:00:18.172 [5148.12880] <2> read_vfm_conf: options=0x2 20:00:18.172 [5148.12880] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: auto 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: Hyper-V 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword nameuse 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification client_name_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='client_name_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default client_name_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Client Name Selection 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default hyperv_backup_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword prov_type 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification vss_provider_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='vss_provider_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default vss_provider_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Provider Type 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword snap_attr 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='snapshot_attribute_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default snapshot_attribute_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Snapshot Attribute 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword allow_offline_backup 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %b 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword csv_timeout 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0-:0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0-:0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 20:00:18.172 [5148.12880] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: VSS 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword prov_type 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0-3:0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0-3:0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword snap_attr 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0-2:0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0-2:0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword max_snapshots 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: FlashSnap 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword keep_fi 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %b 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword async_resync 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %b 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword max_snapshots 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword dg_split_retry_times 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 60-:60 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='60-:60' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 60 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword num_sync_vols 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-32:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-32:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword iosize 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-128:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-128:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword slow 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0-:0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0-:0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: vxvm 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword async_resync 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %b 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword max_snapshots 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword sync_wait 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %b 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword num_sync_vols 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-32:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-32:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword iosize 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-128:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-128:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword slow 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 0-:0 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='0-:0' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 0 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: VMware 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword nameuse 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification client_name_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='client_name_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default client_name_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Client name selection 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='vmware_backup_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default vmware_backup_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Virtual machine backup 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword trantype 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification VMware_transfer_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='VMware_transfer_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default VMware_transfer_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Transfer type 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword disable_quiesce 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default enabled_disable_quiesce_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword file_system_optimization 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default enabled_disabled_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword snapact 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification snapshot_handling_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='snapshot_handling_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default snapshot_handling_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Existing snapshot handling 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword vmmono 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %dd 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default disabled_enabled_type_list 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword mntpt 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %s 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 20:00:18.172 [5148.12880] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: NAS_Snapshot 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found keyword max_snapshots 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found data type %d 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found value specification 1-:1 20:00:18.172 [5148.12880] <2> parse_value_specification: Value specification='1-:1' 20:00:18.172 [5148.12880] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:00:18.172 [5148.12880] <2> parse_value_specification: Found default 1 20:00:18.172 [5148.12880] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found FIM: VSS_Writer 20:00:18.172 [5148.12880] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 20:00:25.067 [5148.12880] <32> bpfis: FTL - VfMS error 11; see following messages: 20:00:25.067 [5148.12880] <32> bpfis: FTL - Fatal method error was reported 20:00:25.067 [5148.12880] <32> bpfis: FTL - vfm_freeze_commit: method: Hyper-V, type: FIM, function: Hyper-V_gentree 20:00:25.067 [5148.12880] <32> bpfis: FTL - VfMS method error 6; see following message: 20:00:25.067 [5148.12880] <32> bpfis: FTL - snapshot services: snapshot target analysis failed: provider-private error. 20:00:25.067 [5148.12880] <32> bpfis: FTL - VfMS error 11; see following messages: 20:00:25.067 [5148.12880] <32> bpfis: FTL - Fatal method error was reported 20:00:25.067 [5148.12880] <32> bpfis: FTL - vfm_freeze_commit: method: Hyper-V, type: FIM, function: Hyper-V_gentree 20:00:25.067 [5148.12880] <32> bpfis: FTL - VfMS method error 6; see following message: 20:00:25.067 [5148.12880] <32> bpfis: FTL - snapshot services: snapshot target analysis failed: provider-private error. 20:00:25.067 [5148.12880] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method Hyper-V. 20:00:27.079 [5148.12880] <4> bpfis: INF - do_thaw return value: 5 20:00:27.079 [5148.12880] <32> bpfis: FTL - VfMS error 5; see following messages: 20:00:27.079 [5148.12880] <32> bpfis: FTL - File open failed 20:00:27.079 [5148.12880] <32> bpfis: FTL - do_reaccess: cannot open reaccess file C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.srvmsproj.onepnet.intra_1401393601.0.fiid 20:00:27.079 [5148.12880] <16> bpfis: FTL - snapshot creation failed, status 156 20:00:27.079 [5148.12880] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method Hyper-V. 20:00:27.079 [5148.12880] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen 20:00:27.079 [5148.12880] <8> bpfis: WRN - snapshot delete returned status 20 20:00:27.095 [5148.12880] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:00:27.095 [5148.12880] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:00:27.329 [5148.12880] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54034 TO 10.100.100.142.13724 20:00:28.811 [5148.12880] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered 20:00:40.168 [6996.6968] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id srvmsproj.onepnet.intra_1401393601 -bpstart_to 300 -bpend_to 300 -clnt hyperv.onepnet.intra -pt 29 -st FULL -status 156 -S bkp-srv1 20:00:40.199 [6996.6968] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:00:40.199 [6996.6968] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:00:40.417 [6996.6968] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54049 TO 10.100.100.142.13724 20:00:41.868 [6996.6968] <4> bpfis: INF - BACKUP START 6996 20:00:41.868 [6996.6968] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.srvmsproj.onepnet.intra_1401393601.0 20:00:41.899 [6996.6968] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:00:41.899 [6996.6968] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:00:42.134 [6996.6968] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54052 TO 10.100.100.142.13724 20:00:43.553 [6996.6968] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed 20:10:55.801 [6876.10932] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id srvmsproj.onepnet.intra_1401394246 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 29 -vm_type 2 -vm_client srvmsproj.onepnet.intra -clnt hyperv.onepnet.intra -st FULL -rg root -fim Hyper-V:nameuse=0,Virtual_machine_backup=2,prov_type=0,snap_attr=0,allow_offline_backup=1,csv_timeout=0,nameuse=0 -class Msproj -sched Full -fso -S bkp-srv1 20:10:55.801 [6876.10932] <2> bpfis main: received FIM as [113] Hyper-V:nameuse=0,Virtual_machine_backup=2,prov_type=0,snap_attr=0,allow_offline_backup=1,csv_timeout=0,nameuse=0 20:10:55.817 [6876.10932] <4> bpfis: INF - BACKUP START 6876 20:10:55.817 [6876.10932] <2> bpfis main: receive filelist:<NEW_STREAM> 20:10:55.817 [6876.10932] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES> 20:10:56.035 [6876.10932] <2> bpfis main: receive filelist:<CONTINUE> 20:10:56.066 [6876.10932] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:10:56.066 [6876.10932] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:10:56.300 [6876.10932] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54307 TO 10.100.100.142.13724 20:10:58.734 [6876.10932] <2> get_long: (2) premature end of file (byte 1) 20:10:58.734 [6876.10932] <2> bprd_read_text_file: get_string() failed, Access is denied. (5), premature end of file encountered 20:10:58.734 [6876.10932] <8> bpfis: WRN - VfMS error 10; see following messages: 20:10:58.734 [6876.10932] <8> bpfis: WRN - Non-fatal method error was reported 20:10:58.734 [6876.10932] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init 20:10:58.734 [6876.10932] <8> bpfis: WRN - VfMS method error 3; see following message: 20:10:58.734 [6876.10932] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed. 20:10:58.734 [6876.10932] <8> bpfis: WRN - VfMS error 10; see following messages: 20:10:58.734 [6876.10932] <8> bpfis: WRN - Non-fatal method error was reported 20:10:58.734 [6876.10932] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init 20:10:58.734 [6876.10932] <8> bpfis: WRN - VfMS method error 3; see following message: 20:10:58.734 [6876.10932] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed. 20:10:58.781 [6876.10932] <4> bpfis: INF - FIS_ID=srvmsproj.onepnet.intra_1401394246 20:10:58.781 [6876.10932] <4> bpfis: INF - Preparing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V. 20:10:58.781 [6876.10932] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_6876 20:10:58.781 [6876.10932] <2> read_vfm_conf: options=0x2 20:10:58.781 [6876.10932] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: auto 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: Hyper-V 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword nameuse 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification client_name_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='client_name_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default client_name_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Client Name Selection 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='hyperv_backup_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default hyperv_backup_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Virtual Machine Backup 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword prov_type 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification vss_provider_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='vss_provider_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default vss_provider_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Provider Type 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword snap_attr 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification snapshot_attribute_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='snapshot_attribute_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default snapshot_attribute_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Snapshot Attribute 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword allow_offline_backup 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %b 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword csv_timeout 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0-:0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0-:0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes 20:10:58.781 [6876.10932] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: VSS 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword prov_type 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0-3:0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0-3:0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword snap_attr 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0-2:0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0-2:0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword max_snapshots 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: FlashSnap 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword keep_fi 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %b 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword async_resync 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %b 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword max_snapshots 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword dg_split_retry_times 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 60-:60 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='60-:60' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 60 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword num_sync_vols 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-32:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-32:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword iosize 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-128:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-128:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword slow 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0-:0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0-:0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: vxvm 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword async_resync 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %b 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword max_snapshots 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword sync_wait 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %b 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword num_sync_vols 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-32:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-32:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword iosize 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-128:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-128:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword slow 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 0-:0 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='0-:0' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 0 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only) 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: VMware 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword nameuse 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification client_name_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='client_name_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default client_name_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Client name selection 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword Virtual_machine_backup 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification vmware_backup_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='vmware_backup_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default vmware_backup_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Virtual machine backup 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword trantype 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification VMware_transfer_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='VMware_transfer_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default VMware_transfer_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Transfer type 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword disable_quiesce 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default enabled_disable_quiesce_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Virtual machine quiesce 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword file_system_optimization 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='enabled_disabled_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default enabled_disabled_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword snapact 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification snapshot_handling_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='snapshot_handling_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default snapshot_handling_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Existing snapshot handling 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword vmmono 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %dd 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='disabled_enabled_type_list' 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default disabled_enabled_type_list 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only) 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword mntpt 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %s 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only) 20:10:58.781 [6876.10932] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: NAS_Snapshot 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found keyword max_snapshots 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found data type %d 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found value specification 1-:1 20:10:58.781 [6876.10932] <2> parse_value_specification: Value specification='1-:1' 20:10:58.781 [6876.10932] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647) 20:10:58.781 [6876.10932] <2> parse_value_specification: Found default 1 20:10:58.781 [6876.10932] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only) 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found FIM: VSS_Writer 20:10:58.781 [6876.10932] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll 20:11:04.319 [6876.10932] <32> bpfis: FTL - VfMS error 11; see following messages: 20:11:04.319 [6876.10932] <32> bpfis: FTL - Fatal method error was reported 20:11:04.319 [6876.10932] <32> bpfis: FTL - vfm_freeze_commit: method: Hyper-V, type: FIM, function: Hyper-V_gentree 20:11:04.319 [6876.10932] <32> bpfis: FTL - VfMS method error 6; see following message: 20:11:04.319 [6876.10932] <32> bpfis: FTL - snapshot services: snapshot target analysis failed: provider-private error. 20:11:04.319 [6876.10932] <32> bpfis: FTL - VfMS error 11; see following messages: 20:11:04.319 [6876.10932] <32> bpfis: FTL - Fatal method error was reported 20:11:04.319 [6876.10932] <32> bpfis: FTL - vfm_freeze_commit: method: Hyper-V, type: FIM, function: Hyper-V_gentree 20:11:04.319 [6876.10932] <32> bpfis: FTL - VfMS method error 6; see following message: 20:11:04.319 [6876.10932] <32> bpfis: FTL - snapshot services: snapshot target analysis failed: provider-private error. 20:11:04.319 [6876.10932] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method Hyper-V. 20:11:06.331 [6876.10932] <4> bpfis: INF - do_thaw return value: 5 20:11:06.331 [6876.10932] <32> bpfis: FTL - VfMS error 5; see following messages: 20:11:06.331 [6876.10932] <32> bpfis: FTL - File open failed 20:11:06.331 [6876.10932] <32> bpfis: FTL - do_reaccess: cannot open reaccess file C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.srvmsproj.onepnet.intra_1401394246.0.fiid 20:11:06.331 [6876.10932] <16> bpfis: FTL - snapshot creation failed, status 156 20:11:06.331 [6876.10932] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method Hyper-V. 20:11:06.331 [6876.10932] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen 20:11:06.331 [6876.10932] <8> bpfis: WRN - snapshot delete returned status 20 20:11:06.363 [6876.10932] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:11:06.363 [6876.10932] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:11:06.581 [6876.10932] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54310 TO 10.100.100.142.13724 20:11:08.016 [6876.10932] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered 20:11:19.560 [7632.11024] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id srvmsproj.onepnet.intra_1401394246 -bpstart_to 300 -bpend_to 300 -clnt hyperv.onepnet.intra -pt 29 -st FULL -status 156 -S bkp-srv1 20:11:19.592 [7632.11024] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:11:19.592 [7632.11024] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:11:19.826 [7632.11024] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54326 TO 10.100.100.142.13724 20:11:21.276 [7632.11024] <4> bpfis: INF - BACKUP START 7632 20:11:21.276 [7632.11024] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.srvmsproj.onepnet.intra_1401394246.0 20:11:21.308 [7632.11024] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 20:11:21.308 [7632.11024] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bprd 20:11:21.526 [7632.11024] <2> logconnections: BPRD CONNECT FROM 10.100.1.116.54329 TO 10.100.100.142.13724 20:11:22.977 [7632.11024] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completedSolved5.6KViews1like2CommentsPlease enter a valid Hyper-V machine name
I have a Hyper-V server running a number of VM's. Server is running 2012 R2 Hyper-V Core version; the powershell only one. Server has had the NBU v7.5.0.6 client installed and configured. Master server and Media server is also v7.5.0.6. When I try and configure a Hyper-V policy it fails when trying to validate the client as a Hyper-V host with the pop up box "Please enter a valid Hyper-V machine name". Examining the bpVMutil logs on the Hyper-V server shows the following: 15:19:22.263 [2680.3780] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 768 _NONE_ _NONE_ 15:19:22.263 [2680.3780] virt_test_log: log function is called 15:19:22.263 [2680.3780] <2> bpVMutil main: cmd = 3 ESXMount = _NONE_ ESXserver = _NONE_, flags = 0 15:19:22.263 [2680.3780] <2> bpVMutil main: ListHyperVVMs returned -1 15:19:22.263 [2680.3780] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed It is as if the ListHyperVVMs function within bpVMutil is returning no VM's found but there are; they are 6 and all are up and running! This is not the first Hyper-V policy I have; in fact I have a good number. One other policy is running Win2012 R2 so I know that all works but not this time around. For the purpose of the exercise windows firewall has been disabled. Any assistance would be greatly appreciated. Thanks, MalcolmSolved2.5KViews1like6CommentsNetBackup Hyper-V 2012 Backup moving Virtual Machine to Save State
Hi Techs, We are facing issue while a Hyper V Backup. When I start a backup of the HyperV of 2012 Server. Virtual Machine is moving to Saved State and backup will start. During the backup. We are unable to access the Virtual Machine and Application. Once backup Completes it will comes to normal State and even during the backup no vss shadows are creating. Any inputs. Rgds, punbs2.4KViews0likes17CommentsForce full backup to other day in backup window
Dear All, I have some full VM (hyper-V) backups which end on error 196 every week on the same day (Monday night). The easiest solution is to change the backup window but I have plenty of backup window over on other days. When the clients misses there backup window the full backup is automatically taken the next day (Tuesday night). I assumed that in the following week the full backup of these client would be taken on Tuesday night but instead it tries to take the full backup again on Monday night and fails again with error 196. I toke a manual full backup during the day on Friday with the hope the full backup scheduled would shift to Friday night but this week the backup policy tried again to take the full backup on Monday night. I read David Chapa's article. I understood that manual backup have influence on scheduled backups. I did take a manual backup to influence the scheduled backup but it didn't work. Does this article still apply? I'm running Netbackup 7.6.0.4 on Windows Server 2008 R2. How can I force the full backup of some clients taken on another day? Regards, ErwinSolved2.1KViews1like10Commentsbackup Performence
how to improve backup performance(For example I am running a backup operation it is taking 10 hours (consider ) then I need it to finish it within 5 hours or below mean as early as possible is it possible or not if possible means how Give me an example )Solved1.9KViews2likes8CommentsHyper-V 2012 R2 Backup Issue
Hi.. I have few Virtual Machines, recently we have migrated the Hyper-v From 2008 R2 to 2012 R2. Post Migration few of my windows 2003 Sp2 Virtual Machines Backups are getting failed. From Windows Logs I found below error messages. Any suggestion on this. Could not create backup checkpoint for virtual machine '': Element not found. (0x80070490). (Virtual machine ID 15CD4588-4910-4B4C-B9DB-C2BDD3B13E34) Checkpoint operation for ' ' was cancelled. (Virtual machine ID 15CD4588-4910-4B4C-B9DB-C2BDD3B13E34) Rgds, punbs1.8KViews0likes6CommentsSimultaneous backup of VMs in a single CSV from multiple nodes
Hi, I am wondering if any of you guys have tested backing up multiple VMs from one Clustered Shared Volume in Hyper-V 2008 R2? According to the NBU for Hyper-V guide: During the backup, the cluster-shared volume (CSV) enters the online state (“Backup in progress, Redirected access"). The cluster node that performs the backup becomes the owner of the CSV. Multiple nodes cannot back up a CSV simultaneously. When a node backs up a virtual machine on a CSV, the attempt by another node to back up the same virtual machine fails. You can use the CSV Timeout in Minutes option in the policy to adjust how long NetBackup waits for another backup of the same CSV to complete. A single node containing two virtual machines can back up both machines simultaneously, even if they use the same CSV. As long as both virtual machines reside on the same node, simultaneous backups are allowed. I have multiple nodes in a cluster and they host different VMs obviously. If i try to backup all the VMs from the cluster and it only has one CSV, will only one node be able to backup its VMs simultaneously and the other nodes wait until this node releases the CSV?1.8KViews0likes11Comments