cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
Veritas.com
Support

Hyper V backup failed with error code 156

dellas
Level 2

‎11-25-2015 10:48 AM

Hi Team,

Hyper v backup failed with error code 156. Hyper v backup failed for LINUX VM client

Netbackup Version : 7.7

OS of master server:  5.11 11.1 sun4v sparc sun4v

 

Job Details:

 

11/25/2015 23:53:26 - Info nbjm (pid=24140) starting backup job (jobid=5591522) for client NVMBD01VSR37, policy Hyper-V_NBU_NVMBD01PSRHYP18, schedule Daily_Incr
11/25/2015 23:53:26 - Info nbjm (pid=24140) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=5591522, request id:{9EA97698-93A1-11E5-BF27-90E2BA5A2974})
11/25/2015 23:53:26 - requesting resource LTO_STD1
11/25/2015 23:53:26 - requesting resource NVMD1BLJ130D00.prod.rjil.net.NBU_CLIENT.MAXJOBS.NVMBD01VSR37
11/25/2015 23:53:26 - requesting resource NVMD1BLJ130D00.prod.rjil.net.NBU_POLICY.MAXJOBS.Hyper-V_NBU_NVMBD01PSRHYP18
11/25/2015 23:53:26 - granted resource  NVMD1BLJ130D00.prod.rjil.net.NBU_CLIENT.MAXJOBS.NVMBD01VSR37
11/25/2015 23:53:26 - granted resource  NVMD1BLJ130D00.prod.rjil.net.NBU_POLICY.MAXJOBS.Hyper-V_NBU_NVMBD01PSRHYP18
11/25/2015 23:53:26 - granted resource  M00550
11/25/2015 23:53:26 - granted resource  IBM.ULT3580-TD6.013
11/25/2015 23:53:26 - granted resource  LTO_STD1
11/25/2015 23:53:27 - estimated 0 kbytes needed
11/25/2015 23:53:27 - begin Parent Job
11/25/2015 23:53:27 - begin Hyper-V: Start Notify Script
11/25/2015 23:53:27 - Info RUNCMD (pid=20949) started
11/25/2015 23:53:27 - Info RUNCMD (pid=20949) exiting with status: 0
Operation Status: 0
11/25/2015 23:53:27 - end Hyper-V: Start Notify Script; elapsed time 0:00:00
11/25/2015 23:53:27 - begin Hyper-V: Step By Condition
Operation Status: 0
11/25/2015 23:53:27 - end Hyper-V: Step By Condition; elapsed time 0:00:00
Operation Status: 0
11/25/2015 23:53:27 - end Parent Job; elapsed time 0:00:00
11/25/2015 23:53:27 - begin Hyper-V: Create Snapshot
11/25/2015 23:53:27 - started process bpbrm (pid=26203)
11/25/2015 23:53:31 - Info bpbrm (pid=26203) NVMBD01VSR37 is the host to backup data from
11/25/2015 23:53:31 - Info bpbrm (pid=26203) reading file list for client
11/25/2015 23:53:31 - Info bpbrm (pid=26203) start bpfis on client
11/25/2015 23:53:32 - Info bpbrm (pid=26203) Starting create snapshot processing
11/25/2015 23:53:33 - Info bpfis (pid=8656) Backup started
11/25/2015 23:54:00 - Critical bpbrm (pid=26203) from client NVMBD01VSR37: FTL - vfm_freeze_commit: method: Hyper-V_v2, type: FIM, function: Hyper-V_v2_make
11/25/2015 23:54:00 - Critical bpbrm (pid=26203) from client NVMBD01VSR37: FTL - snapshot services: snapshot creation failed: unknown error.
11/25/2015 23:54:00 - Critical bpbrm (pid=26203) from client NVMBD01VSR37: FTL - vfm_freeze_commit: method: Hyper-V_v2, type: FIM, function: Hyper-V_v2_make
11/25/2015 23:54:01 - Critical bpbrm (pid=26203) from client NVMBD01VSR37: FTL - snapshot services: snapshot creation failed: unknown error.
11/25/2015 23:54:01 - Critical bpbrm (pid=26203) from client NVMBD01VSR37: FTL - snapshot processing failed, status 156

11/25/2015 23:54:01 - Critical bpbrm (pid=26203) from client NVMBD01VSR37: FTL - snapshot creation failed, status 156

11/25/2015 23:54:01 - Warning bpbrm (pid=26203) from client NVMBD01VSR37: WRN - ALL_LOCAL_DRIVES is not frozen
11/25/2015 23:54:01 - Info bpfis (pid=8656) done. status: 156

11/25/2015 23:54:01 - end Hyper-V: Create Snapshot; elapsed time 0:00:34
11/25/2015 23:54:01 - Info bpfis (pid=8656) done. status: 156: snapshot error encountered
11/25/2015 23:54:01 - end writing
Operation Status: 156

11/25/2015 23:54:01 - begin Hyper-V: Stop On Error
Operation Status: 0
11/25/2015 23:54:01 - end Hyper-V: Stop On Error; elapsed time 0:00:00
11/25/2015 23:54:01 - begin Hyper-V: Delete Snapshot
11/25/2015 23:54:01 - started process bpbrm (pid=26207)
11/25/2015 23:54:06 - Info bpbrm (pid=26207) Starting delete snapshot processing
11/25/2015 23:54:08 - Info bpfis (pid=8796) Backup started
11/25/2015 23:54:08 - Critical bpbrm (pid=26207) from client NVMBD01VSR37: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.NVMBD01VSR37_1448475806.1.0
11/25/2015 23:54:09 - Info bpfis (pid=8796) done. status: 4207

11/25/2015 23:54:09 - end Hyper-V: Delete Snapshot; elapsed time 0:00:08
11/25/2015 23:54:09 - Info bpfis (pid=8796) done. status: 4207: Could not fetch snapshot metadata or state files
11/25/2015 23:54:09 - end writing
Operation Status: 4207

11/25/2015 23:54:09 - begin Hyper-V: End Notify Script
11/25/2015 23:54:09 - Info RUNCMD (pid=21267) started
11/25/2015 23:54:09 - Info RUNCMD (pid=21267) exiting with status: 0
Operation Status: 0
11/25/2015 23:54:09 - end Hyper-V: End Notify Script; elapsed time 0:00:00
Operation Status: 156

snapshot error encountered  (156)

 

0 Kudos
2 REPLIES 2

dellas
Level 2

‎11-25-2015 10:50 AM

BPFIS error from base machine:

 

on returning :<ALL_LOCAL_DRIVES>
23:52:53.982 [10224.9336] <2> bpfis main: receive filelist:<CONTINUE>
23:52:53.982 [10224.9336] <2> check_special_names: got path entry as :<CONTINUE>
23:52:53.982 [10224.9336] <2> check_special_names: after conversion returning :<CONTINUE>
23:52:53.982 [10224.9336] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:52:53.982 [10224.9336] <4> bpfis main: VSS Snapshot Mutex lock acquired
23:52:53.997 [10224.9336] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:52:53.997 [10224.9336] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56204 TO 10.137.8.29.1556 fd = 684
23:52:54.278 [10224.9336] <8> bpfis: WRN - VfMS error 10; see following messages:
23:52:54.278 [10224.9336] <8> bpfis: WRN - Non-fatal method error was reported
23:52:54.278 [10224.9336] <8> bpfis: WRN -
23:52:54.278 [10224.9336] <8> bpfis: WRN - VfMS method error 0; see following message:
23:52:54.278 [10224.9336] <8> bpfis: WRN -
23:52:54.278 [10224.9336] <8> bpfis: WRN - VfMS error 10; see following messages:
23:52:54.278 [10224.9336] <8> bpfis: WRN - Non-fatal method error was reported
23:52:54.278 [10224.9336] <8> bpfis: WRN -
23:52:54.278 [10224.9336] <8> bpfis: WRN - VfMS method error 0; see following message:
23:52:54.278 [10224.9336] <8> bpfis: WRN -
23:52:54.528 [10224.9336] <4> bpfis: INF - FIS_ID=1448475773
23:52:54.528 [10224.9336] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:52:54.528 [10224.9336] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_10224_-1
23:52:54.543 [10224.9336] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:52:54.543 [10224.9336] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56205 TO 10.137.8.29.1556 fd = 836
23:52:55.573 [10224.9336] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_10224_-1
23:52:55.573 [10224.9336] <4> bpfis: Starting keep alive thread.
23:52:55.589 [10224.9336] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
23:52:57.305 [8300.5568] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe verify -nbu -S NVMD1BLJ130D00 -pt 41 -backup_copy 0 -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0 -vm_client NVMBD01VSR37
23:52:57.305 [8300.5568] <2> bpfis main: received FIM as [108] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:52:57.336 [8300.5568] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:52:57.336 [8300.5568] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56210 TO 10.137.8.29.1556 fd = 584
23:52:57.383 [8300.5568] <4> bpfis: Starting keep alive thread.
23:52:57.383 [8300.5568] <4> bpfis: INF - BACKUP START 8300
23:52:57.383 [8300.5568] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
23:52:57.383 [8300.5568] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>
23:52:57.383 [8300.5568] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>
23:52:57.383 [8300.5568] <2> bpfis main: receive filelist:<CONTINUE>
23:52:57.383 [8300.5568] <2> check_special_names: got path entry as :<CONTINUE>
23:52:57.383 [8300.5568] <2> check_special_names: after conversion returning :<CONTINUE>
23:52:57.383 [8300.5568] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:52:57.601 [8300.5568] <4> bpfis main: VSS Snapshot Mutex lock acquired
k
23:52:57.617 [8300.5568] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:52:57.617 [8300.5568] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56211 TO 10.137.8.29.1556 fd = 660
23:52:57.882 [8300.5568] <8> bpfis: WRN - VfMS error 10; see following messages:
23:52:57.882 [8300.5568] <8> bpfis: WRN - Non-fatal method error was reported
23:52:57.882 [8300.5568] <8> bpfis: WRN -
23:52:57.882 [8300.5568] <8> bpfis: WRN - VfMS method error 0; see following message:
23:52:57.882 [8300.5568] <8> bpfis: WRN -
23:52:57.882 [8300.5568] <8> bpfis: WRN - VfMS error 10; see following messages:
23:52:57.882 [8300.5568] <8> bpfis: WRN - Non-fatal method error was reported
23:52:57.882 [8300.5568] <8> bpfis: WRN -
23:52:57.882 [8300.5568] <8> bpfis: WRN - VfMS method error 0; see following message:
23:52:57.882 [8300.5568] <8> bpfis: WRN -
23:52:58.397 [8300.5568] <4> bpfis: INF - FIS_ID=1448475777
23:52:58.397 [8300.5568] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:52:58.397 [8300.5568] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8300_-1
23:52:58.412 [8300.5568] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:52:58.412 [8300.5568] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56212 TO 10.137.8.29.1556 fd = 816
23:52:59.676 [8300.5568] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8300_-1
23:52:59.676 [8300.5568] <4> bpfis: Starting keep alive thread.
23:52:59.707 [8300.5568] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
23:53:01.173 [8612.1464] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe verify -nbu -S NVMD1BLJ130D00 -pt 41 -backup_copy 0 -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0 -vm_client NVMBD01VSR43
23:53:01.173 [8612.1464] <2> bpfis main: received FIM as [108] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:53:01.189 [8612.1464] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:01.189 [8612.1464] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56217 TO 10.137.8.29.1556 fd = 592
23:53:01.220 [8612.1464] <4> bpfis: Starting keep alive thread.
23:53:01.220 [8612.1464] <4> bpfis: INF - BACKUP START 8612
23:53:01.220 [8612.1464] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
23:53:01.220 [8612.1464] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>
23:53:01.220 [8612.1464] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>
23:53:01.220 [8612.1464] <2> bpfis main: receive filelist:<CONTINUE>
23:53:01.220 [8612.1464] <2> check_special_names: got path entry as :<CONTINUE>
23:53:01.220 [8612.1464] <2> check_special_names: after conversion returning :<CONTINUE>
23:53:01.220 [8612.1464] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:53:01.719 [8300.5568] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:53:01.735 [8612.1464] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:01.735 [8612.1464] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56218 TO 10.137.8.29.1556 fd = 668
23:53:02.000 [8612.1464] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:02.000 [8612.1464] <8> bpfis: WRN - Non-fatal method error was reported
23:53:02.000 [8612.1464] <8> bpfis: WRN -
23:53:02.000 [8612.1464] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:02.000 [8612.1464] <8> bpfis: WRN -
23:53:02.000 [8612.1464] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:02.000 [8612.1464] <8> bpfis: WRN - Non-fatal method error was reported
23:53:02.000 [8612.1464] <8> bpfis: WRN -
23:53:02.000 [8612.1464] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:02.000 [8612.1464] <8> bpfis: WRN -
23:53:02.265 [8612.1464] <4> bpfis: INF - FIS_ID=1448475781
23:53:02.265 [8612.1464] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:53:02.265 [8612.1464] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8612_-1
23:53:02.281 [8612.1464] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:02.281 [8612.1464] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56219 TO 10.137.8.29.1556 fd = 824
23:53:03.545 [8612.1464] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8612_-1
23:53:03.545 [8612.1464] <4> bpfis: Starting keep alive thread.
23:53:03.560 [8612.1464] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
23:53:05.354 [5616.8464] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe verify -nbu -S NVMD1BLJ130D00 -pt 41 -backup_copy 0 -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0 -vm_client NVMBD01VSR42
23:53:05.354 [5616.8464] <2> bpfis main: received FIM as [108] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:53:05.386 [5616.8464] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:05.386 [5616.8464] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56224 TO 10.137.8.29.1556 fd = 584
23:53:05.417 [5616.8464] <4> bpfis: Starting keep alive thread.
23:53:05.417 [5616.8464] <4> bpfis: INF - BACKUP START 5616
23:53:05.417 [5616.8464] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
23:53:05.417 [5616.8464] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>
23:53:05.417 [5616.8464] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>
23:53:05.417 [5616.8464] <2> bpfis main: receive filelist:<CONTINUE>
23:53:05.417 [5616.8464] <2> check_special_names: got path entry as :<CONTINUE>
23:53:05.417 [5616.8464] <2> check_special_names: after conversion returning :<CONTINUE>
23:53:05.417 [5616.8464] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:53:05.573 [8612.1464] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:53:05.573 [5616.8464] <4> bpfis main: VSS Snapshot Mutex lock acquired
23:53:05.588 [5616.8464] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:05.588 [5616.8464] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56225 TO 10.137.8.29.1556 fd = 660
23:53:05.854 [5616.8464] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:05.854 [5616.8464] <8> bpfis: WRN - Non-fatal method error was reported
23:53:05.854 [5616.8464] <8> bpfis: WRN -
23:53:05.854 [5616.8464] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:05.854 [5616.8464] <8> bpfis: WRN -
23:53:05.869 [5616.8464] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:05.869 [5616.8464] <8> bpfis: WRN - Non-fatal method error was reported
23:53:05.869 [5616.8464] <8> bpfis: WRN -
23:53:05.869 [5616.8464] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:05.869 [5616.8464] <8> bpfis: WRN -
23:53:06.368 [5616.8464] <4> bpfis: INF - FIS_ID=1448475785
23:53:06.368 [5616.8464] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:53:06.368 [5616.8464] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_5616_-1
23:53:06.400 [5616.8464] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:06.400 [5616.8464] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56226 TO 10.137.8.29.1556 fd = 820
23:53:08.022 [5616.8464] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_5616_-1
23:53:08.022 [5616.8464] <4> bpfis: Starting keep alive thread.
23:53:08.053 [5616.8464] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
23:53:09.488 [6732.9088] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe verify -nbu -S NVMD1BLJ130D00 -pt 41 -backup_copy 0 -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0 -vm_client NVMBD01VSR41
23:53:09.488 [6732.9088] <2> bpfis main: received FIM as [108] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:53:09.504 [6732.9088] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:09.504 [6732.9088] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56231 TO 10.137.8.29.1556 fd = 592
23:53:09.535 [6732.9088] <4> bpfis: Starting keep alive thread.
23:53:09.535 [6732.9088] <4> bpfis: INF - BACKUP START 6732
23:53:09.535 [6732.9088] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
23:53:09.535 [6732.9088] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>
23:53:09.535 [6732.9088] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>
23:53:09.535 [6732.9088] <2> bpfis main: receive filelist:<CONTINUE>
23:53:09.535 [6732.9088] <2> check_special_names: got path entry as :<CONTINUE>
23:53:09.535 [6732.9088] <2> check_special_names: after conversion returning :<CONTINUE>
23:53:09.535 [6732.9088] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:53:10.066 [5616.8464] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:53:10.066 [6732.9088] <4> bpfis main: VSS Snapshot Mutex lock acquired
23:53:10.066 [6732.9088] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:10.066 [6732.9088] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56232 TO 10.137.8.29.1556 fd = 656
23:53:10.346 [6732.9088] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:10.346 [6732.9088] <8> bpfis: WRN - Non-fatal method error was reported
23:53:10.346 [6732.9088] <8> bpfis: WRN -
23:53:10.346 [6732.9088] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:10.346 [6732.9088] <8> bpfis: WRN -
23:53:10.346 [6732.9088] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:10.346 [6732.9088] <8> bpfis: WRN - Non-fatal method error was reported
23:53:10.346 [6732.9088] <8> bpfis: WRN -
23:53:10.346 [6732.9088] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:10.346 [6732.9088] <8> bpfis: WRN -
23:53:10.596 [6732.9088] <4> bpfis: INF - FIS_ID=1448475789
23:53:10.596 [6732.9088] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:53:10.596 [6732.9088] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_6732_-1
23:53:10.612 [6732.9088] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:10.612 [6732.9088] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56233 TO 10.137.8.29.1556 fd = 820
23:53:11.392 [6732.9088] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_6732_-1
23:53:11.392 [6732.9088] <4> bpfis: Starting keep alive thread.
23:53:11.407 [6732.9088] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
23:53:13.186 [6276.3580] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe verify -nbu -S NVMD1BLJ130D00 -pt 41 -backup_copy 0 -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0 -vm_client NVMBD01VSR39
23:53:13.186 [6276.3580] <2> bpfis main: received FIM as [108] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:53:13.217 [6276.3580] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:13.217 [6276.3580] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56238 TO 10.137.8.29.1556 fd = 584
23:53:13.264 [6276.3580] <4> bpfis: Starting keep alive thread.
23:53:13.264 [6276.3580] <4> bpfis: INF - BACKUP START 6276
23:53:13.264 [6276.3580] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
23:53:13.264 [6276.3580] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>
23:53:13.264 [6276.3580] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>
23:53:13.264 [6276.3580] <2> bpfis main: receive filelist:<CONTINUE>
23:53:13.264 [6276.3580] <2> check_special_names: got path entry as :<CONTINUE>
23:53:13.264 [6276.3580] <2> check_special_names: after conversion returning :<CONTINUE>
23:53:13.264 [6276.3580] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:53:13.420 [6732.9088] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:53:13.420 [6276.3580] <4> bpfis main: VSS Snapshot Mutex lock acquired
23:53:13.451 [6276.3580] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:13.451 [6276.3580] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56239 TO 10.137.8.29.1556 fd = 664
23:53:13.716 [6276.3580] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:13.716 [6276.3580] <8> bpfis: WRN - Non-fatal method error was reported
23:53:13.716 [6276.3580] <8> bpfis: WRN -
23:53:13.716 [6276.3580] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:13.716 [6276.3580] <8> bpfis: WRN -
23:53:13.716 [6276.3580] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:13.716 [6276.3580] <8> bpfis: WRN - Non-fatal method error was reported
23:53:13.716 [6276.3580] <8> bpfis: WRN -
23:53:13.716 [6276.3580] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:13.716 [6276.3580] <8> bpfis: WRN -
23:53:14.278 [6276.3580] <4> bpfis: INF - FIS_ID=1448475793
23:53:14.278 [6276.3580] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:53:14.278 [6276.3580] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_6276_-1
23:53:14.293 [6276.3580] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:14.293 [6276.3580] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56240 TO 10.137.8.29.1556 fd = 824
23:53:15.510 [6276.3580] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_6276_-1
23:53:15.510 [6276.3580] <4> bpfis: Starting keep alive thread.
23:53:15.541 [6276.3580] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed
23:53:17.554 [6276.3580] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:53:34.027 [8656.9576] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id NVMBD01VSR37_1448475806 -bpstart_to 2000 -bpend_to 300 -backup_copy 0 -ru root -pt 41 -vm_type 2 -vm_client NVMBD01VSR37 -clnt NVMBD01PSRHYP18 -st INCR -rg root -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0,nameuse=1 -class Hyper-V_NBU_NVMBD01PSRHYP18 -sched Daily_Incr -fso -S NVMD1BLJ130D00.prod.rjil.net -jobid 5591522 -nbu_version 124780544 -application_consistent 1
23:53:34.027 [8656.9576] <2> bpfis main: received FIM as [118] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0,nameuse=1
23:53:34.059 [8656.9576] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:34.059 [8656.9576] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56251 TO 10.137.8.29.1556 fd = 584
23:53:34.090 [8656.9576] <4> bpfis: Starting keep alive thread.
23:53:34.090 [8656.9576] <4> bpfis: INF - BACKUP START 8656
23:53:34.090 [8656.9576] <2> bpfis main: receive filelist:<NEW_STREAM>
23:53:34.090 [8656.9576] <2> check_special_names: got path entry as :<NEW_STREAM>
23:53:34.090 [8656.9576] <2> check_special_names: after conversion returning :<NEW_STREAM>
23:53:34.090 [8656.9576] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>
23:53:34.090 [8656.9576] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>
23:53:34.090 [8656.9576] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>
23:53:34.090 [8656.9576] <2> bpfis main: receive filelist:<CONTINUE>
23:53:34.090 [8656.9576] <2> check_special_names: got path entry as :<CONTINUE>
23:53:34.090 [8656.9576] <2> check_special_names: after conversion returning :<CONTINUE>
23:53:34.121 [8656.9576] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:34.121 [8656.9576] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56252 TO 10.137.8.29.1556 fd = 652
23:53:34.386 [8656.9576] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:34.386 [8656.9576] <8> bpfis: WRN - Non-fatal method error was reported
23:53:34.386 [8656.9576] <8> bpfis: WRN -
23:53:34.386 [8656.9576] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:34.386 [8656.9576] <8> bpfis: WRN -
23:53:34.386 [8656.9576] <8> bpfis: WRN - VfMS error 10; see following messages:
23:53:34.386 [8656.9576] <8> bpfis: WRN - Non-fatal method error was reported
23:53:34.386 [8656.9576] <8> bpfis: WRN -
23:53:34.386 [8656.9576] <8> bpfis: WRN - VfMS method error 0; see following message:
23:53:34.386 [8656.9576] <8> bpfis: WRN -
23:53:34.917 [8656.9576] <4> bpfis: INF - FIS_ID=NVMBD01VSR37_1448475806
23:53:34.917 [8656.9576] <4> bpfis: parameter client_type 41, fis_accl 0, fis_accl_cksv -1, SNAPSHOT_IS_HERE 1
23:53:34.917 [8656.9576] <4> bpfis: Volume guid list is empty, will not pause and flush NetBackup Change Journal databases
23:53:34.917 [8656.9576] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:53:34.917 [8656.9576] <4> bpfis main: VSS Snapshot create Mutex lock acquired
23:53:34.917 [8656.9576] <4> bpfis: INF - Preparing freeze of ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:53:34.917 [8656.9576] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8656_1
23:53:34.932 [8656.9576] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:53:34.932 [8656.9576] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56253 TO 10.137.8.29.1556 fd = 816
23:54:01.172 [8656.9576] <32> bpfis: FTL - VfMS error 11; see following messages:
23:54:01.172 [8656.9576] <32> bpfis: FTL - Fatal method error was reported
23:54:01.172 [8656.9576] <32> bpfis: FTL - vfm_freeze_commit: method: Hyper-V_v2, type: FIM, function: Hyper-V_v2_make
23:54:01.172 [8656.9576] <32> bpfis: FTL - VfMS method error 38; see following message:
23:54:01.172 [8656.9576] <32> bpfis: FTL - snapshot services: snapshot creation failed: unknown error.
23:54:01.172 [8656.9576] <32> bpfis: FTL - VfMS error 11; see following messages:
23:54:01.172 [8656.9576] <32> bpfis: FTL - Fatal method error was reported
23:54:01.172 [8656.9576] <32> bpfis: FTL - vfm_freeze_commit: method: Hyper-V_v2, type: FIM, function: Hyper-V_v2_make
23:54:01.172 [8656.9576] <32> bpfis: FTL - VfMS method error 38; see following message:
23:54:01.172 [8656.9576] <32> bpfis: FTL - snapshot services: snapshot creation failed: unknown error.
23:54:01.172 [8656.9576] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:54:01.187 [8656.9576] <4> bpfis: INF - do_thaw return value: 0
23:54:01.187 [8656.9576] <4> bpfis: INF - Deleted mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_8656_1
23:54:01.187 [8656.9576] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
23:54:01.187 [8656.9576] <16> bpfis: FTL - snapshot creation failed, status 156
23:54:01.187 [8656.9576] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method Hyper-V_v2.
23:54:01.187 [8656.9576] <8> bpfis: WRN - ALL_LOCAL_DRIVES is not frozen
23:54:01.187 [8656.9576] <8> bpfis: WRN - snapshot delete returned status 20
23:54:01.187 [8656.9576] <4> bpfis: INF - EXIT STATUS 156: snapshot error encountered
23:54:04.838 [8656.9576] <8> bpfis: WRN - set backup status returned status 156
23:54:04.838 [8656.9576] <4> bpfis: Starting keep alive thread.
23:54:04.931 [8656.9576] <32> bpfis: FTL - VfMS error 11; see following messages:
23:54:04.931 [8656.9576] <32> bpfis: FTL - Fatal method error was reported
23:54:04.931 [8656.9576] <32> bpfis: FTL - vfm_fi_terminate: method: VxFI, type: FIM, function: VxFI_detach
23:54:04.931 [8656.9576] <16> bpfis: Error -1 sending <vfm_fi_terminate: method: VxFI, type: FIM, function: VxFI_detach> to server
23:54:04.931 [8656.9576] <32> bpfis: FTL - VfMS method error 0; see following message:
23:54:04.931 [8656.9576] <32> bpfis: FTL - snapshot services: snapshot detach failed 1 times: invalid argument(s).
23:54:04.931 [8656.9576] <16> bpfis: Error -1 sending <snapshot services: snapshot detach failed 1 times: invalid argument(s).> to server
23:54:08.051 [8796.4848] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id NVMBD01VSR37_1448475806 -bpstart_to 2000 -bpend_to 300 -clnt NVMBD01PSRHYP18 -jobgrpid 5591522 -jobid 5591522 -copy 1 -pt 41 -st INCR -status 156 -S NVMD1BLJ130D00.prod.rjil.net -fim Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:54:08.051 [8796.4848] <2> bpfis main: received FIM as [108] Hyper-V_v2:allow_offline_backup=1,prov_type=0,csv_timeout=180,nameuse=1,Virtual_machine_backup=2,snap_attr=0
23:54:08.051 [8796.4848] <4> bpfis: Starting keep alive thread.
23:54:08.051 [8796.4848] <4> bpfis: Acquiring snapshot lock:retry count [0]
23:54:08.051 [8796.4848] <2> getFileName:  FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock]
23:54:08.051 [8796.4848] <2> getFileName:  FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock]
23:54:08.083 [8796.4848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:54:08.083 [8796.4848] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56274 TO 10.137.8.29.1556 fd = 580
23:54:08.691 [8796.4848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
23:54:08.691 [8796.4848] <2> logconnections: BPRD CONNECT FROM 10.135.8.57.56277 TO 10.137.8.29.1556 fd = 628
23:54:08.722 [8796.4848] <4> bpfis: INF - BACKUP START 8796
23:54:08.722 [8796.4848] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0
23:54:08.722 [8796.4848] <4> bpfis main: VSS Snapshot Mutex lock acquired
23:54:08.722 [8796.4848] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.NVMBD01VSR37_1448475806.1.0
23:54:08.722 [8796.4848] <4> bpfis:  INF - Attempt to send status to the bpfis create process
23:54:08.722 [8796.4848] <4> bpfis: Starting keep alive thread.
23:54:08.722 [8796.4848] <2> deleteReference:    reference not found in the list
23:54:08.722 [8796.4848] <4> bpfis: Delete reference: status [-4]
23:54:08.722 [8796.4848] <2> writeSnapshotLockFile:    Writing type[2] refcount[0]
23:54:08.722 [8796.4848] <2> writeSnapshotLockFile:    Writing Flags [0] [0]
23:54:08.722 [8796.4848] <2> writeSnapshotLockFile:   Writing Last addReffernt Time [Thu Jan 01 05:30:00 1970
]
23:54:08.722 [8796.4848] <2> writeSnapshotLockFile:   Writing Last Hostname []
23:54:08.722 [8796.4848] <2> getFileName:  FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock]
23:54:08.722 [8796.4848] <2> getFileName:  FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock]
23:54:08.722 [8796.4848] <2> unlockSnapshot:    file[C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock] : zero reference count
23:54:08.722 [8796.4848] <4> bpfis: Released snapshot lock: status [0]
23:54:09.736 [8796.4848] <4> bpfis: Starting keep alive thread.
23:54:09.736 [8796.4848] <2> deleteReference:     Snapshot lock info NULL
23:54:09.736 [8796.4848] <4> bpfis: Delete reference: status [-4]
23:54:09.736 [8796.4848] <2> unlockSnapshot:     Snapshot lock info NULL
23:54:09.736 [8796.4848] <4> bpfis: Released snapshot lock: status [0]
23:54:09.736 [8796.4848] <4> do_post_processing: Deleting snapshot lock files from client for id [NVMBD01VSR37_1448475806] and copy_number [1]
23:54:09.736 [8796.4848] <2> getFileName:  FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock]
23:54:09.736 [8796.4848] <2> getFileName:  FileName is [C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock]
23:54:09.736 [8796.4848] <2> deleteSnapshotLockFile:    file[C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\NVMBD01VSR37_1448475806_copy1.lock] deleted: status[0]
23:54:09.736 [8796.4848] <4> bpfis: INF - EXIT STATUS 4207: Could not fetch snapshot metadata or state files
23:54:09.736 [8796.4848] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock

 

0 Kudos

Marianne
Level 6
Partner    VIP    Accredited Certified

‎11-25-2015 11:28 PM

Have you confirmed that the Linux version on the VM is supported?

See Supported Hyper-V guest operating systems in Statement of Support for NetBackup 7.x in a Virtual Environment:    http://www.veritas.com/docs/000006177 

 

PS: please post logs in future as Attachments.
Copy the log file to .txt (e.g. bpfis) and use 'Attach Files' at the bottom of the screen to upload the log. 


Handy NetBackup Links
0 Kudos