Video Screencast Help
Symantec to Separate Into Two Focused, Industry-Leading Technology Companies. Learn more.

socket read failed, An existing connection was forcibly closed by the remote host. (10054)

Created: 08 Nov 2013 | 2 comments

We have an client with the specidication mentiond below is showing such error:

Client: VM - Windows server 2008 R2 

Netbackup log:

11/9/2013 8:12:56 AM - Info nbjm(pid=3416) starting backup job (jobid=69244) for client spcp-gihmap3.egis.hksarg, policy spcp-capp1-vm-backup-policy01, schedule Daily_Backup  
11/9/2013 8:12:56 AM - estimated 0 Kbytes needed
11/9/2013 8:12:56 AM - Info nbjm(pid=3416) started backup (backupid=spcp-gihmap3.egis.hksarg_1383955976) job for client spcp-gihmap3.egis.hksarg, policy spcp-capp1-vm-backup-policy01, schedule Daily_Backup on storage unit spmg-pbkup3-hcart2-robot-tld-1
11/9/2013 8:12:58 AM - started process bpbrm (9456)
11/9/2013 8:13:05 AM - Info bpbrm(pid=9456) spcp-gihmap3.egis.hksarg is the host to backup data from     
11/9/2013 8:13:05 AM - Info bpbrm(pid=9456) reading file list from client        
11/9/2013 8:13:05 AM - connecting
11/9/2013 8:13:06 AM - Info bpbrm(pid=9456) starting bpbkar32 on client         
11/9/2013 8:13:06 AM - connected; connect time: 00:00:01
11/9/2013 8:13:23 AM - Info bpbkar32(pid=9444) Backup started           
11/9/2013 8:13:23 AM - Info bptm(pid=6356) start            
11/9/2013 8:13:23 AM - Info bptm(pid=6356) using 262144 data buffer size        
11/9/2013 8:13:23 AM - Info bptm(pid=6356) setting receive network buffer to 1049600 bytes      
11/9/2013 8:13:23 AM - Info bptm(pid=6356) using 256 data buffers         
11/9/2013 8:13:23 AM - Info bptm(pid=6356) start backup           
11/9/2013 8:13:23 AM - Info bptm(pid=6356) backup child process is pid 8212.8420       
11/9/2013 8:13:23 AM - Info bptm(pid=6356) media id 240034 mounted on drive index 56, drivepath {3,0,5,6}, drivename Drive016, copy 1
11/9/2013 8:13:23 AM - Info bptm(pid=8212) start            
11/9/2013 8:13:23 AM - mounted
11/9/2013 8:13:23 AM - positioning 240034 to file 37
11/9/2013 8:13:24 AM - Info bpbkar32(pid=9444) CONTINUE BACKUP received.          
11/9/2013 8:13:25 AM - positioned 240034; position time: 00:00:02
11/9/2013 8:13:25 AM - begin writing
11/9/2013 8:13:47 AM - Info bpbkar32(pid=9444) INF - Transport Type = san       
11/9/2013 8:13:49 AM - Error bpbrm(pid=9456) socket read failed, An existing connection was forcibly closed by the remote host.  (10054)
11/9/2013 8:13:49 AM - Error bpbrm(pid=9456) could not send server status message       
11/9/2013 8:13:53 AM - end writing; write time: 00:00:28
file read failed(13)
 
VM Log: 
 
013-11-08T17:39:22.054Z| vcpu-0| I120: SNAPSHOT: SnapshotDumperOpenFromInfo: Creating checkpoint file /vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-Snapshot11.vmsn
2013-11-08T17:39:22.065Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005-delta.vmdk" : open successful (29) size = 33837056, hd = 0. Type 8
2013-11-08T17:39:22.067Z| vcpu-0| I120: DISKLIB-LIB   : DiskLibCreateCreateParam: vmfssparse grain size set to : 0
2013-11-08T17:39:22.067Z| vcpu-0| I120: DISKLIB-LIB   : CREATE CHILD: "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk" -- vmfsSparse cowGran=0 allocType=0
2013-11-08T17:39:22.067Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004-delta.vmdk" : open successful (23) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.068Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003-delta.vmdk" : open successful (23) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.069Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002-delta.vmdk" : open successful (23) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.070Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001-delta.vmdk" : open successful (23) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.070Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-flat.vmdk" : open successful (23) size = 146697879552, hd = 0. Type 3
2013-11-08T17:39:22.071Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004-delta.vmdk" : closed.
2013-11-08T17:39:22.071Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003-delta.vmdk" : closed.
2013-11-08T17:39:22.071Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002-delta.vmdk" : closed.
2013-11-08T17:39:22.071Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001-delta.vmdk" : closed.
2013-11-08T17:39:22.071Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-flat.vmdk" : closed.
2013-11-08T17:39:22.071Z| vcpu-0| I120: CREATE-CHILD: Creating disk backed by 'default'
2013-11-08T17:39:22.085Z| vcpu-0| I120: DISKLIB-VMFS_SPARSE : VmfsSparseExtentCreate: "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006-delta.vmdk" : success
2013-11-08T17:39:22.091Z| vcpu-0| I120: DISKLIB-DSCPTR: "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk" : creation successful.
2013-11-08T17:39:22.106Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006-delta.vmdk" : open successful (17) size = 282624, hd = 0. Type 8
2013-11-08T17:39:22.147Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006-delta.vmdk" : closed.
2013-11-08T17:39:22.147Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005-delta.vmdk" : closed.
2013-11-08T17:39:22.175Z| vcpu-0| I120: SnapshotVMXConsolidateOnlineCB: nextState = 1 uid 11
2013-11-08T17:39:22.176Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006-delta.vmdk" : open successful (21) size = 282624, hd = 0. Type 8
2013-11-08T17:39:22.176Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006-delta.vmdk" : closed.
2013-11-08T17:39:22.177Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005-delta.vmdk" : open successful (21) size = 33837056, hd = 0. Type 8
2013-11-08T17:39:22.177Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005-delta.vmdk" : closed.
2013-11-08T17:39:22.178Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004-delta.vmdk" : open successful (21) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.178Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004-delta.vmdk" : closed.
2013-11-08T17:39:22.179Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003-delta.vmdk" : open successful (21) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.179Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003-delta.vmdk" : closed.
2013-11-08T17:39:22.179Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002-delta.vmdk" : open successful (21) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.179Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002-delta.vmdk" : closed.
2013-11-08T17:39:22.180Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001-delta.vmdk" : open successful (21) size = 17059840, hd = 0. Type 8
2013-11-08T17:39:22.180Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001-delta.vmdk" : closed.
2013-11-08T17:39:22.181Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-flat.vmdk" : open successful (21) size = 146697879552, hd = 0. Type 3
2013-11-08T17:39:22.181Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-flat.vmdk" : closed.
2013-11-08T17:39:22.181Z| vcpu-0| I120: Checkpoint_Unstun: vm stopped for 279595 us
2013-11-08T17:39:22.181Z| vcpu-0| I120: SCSI: switching scsi0 to push completion mode
2013-11-08T17:39:22.182Z| vcpu-0| I120: DISK: OPEN scsi0:0 '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk' persistent R[]
2013-11-08T17:39:22.185Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006-delta.vmdk" : open successful (8) size = 282624, hd = 119188198. Type 8
2013-11-08T17:39:22.185Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-000006-delta.vmdk" (0x8)
2013-11-08T17:39:22.185Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk' (0x8): vmfsSparse, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.185Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 1, numSubChains = 1
2013-11-08T17:39:22.185Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 119188198, extentType = 0
2013-11-08T17:39:22.185Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk" (flags 0x8, type vmfsSparse).
2013-11-08T17:39:22.188Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005-delta.vmdk" : open successful (8) size = 33837056, hd = 113093352. Type 8
2013-11-08T17:39:22.188Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-000005-delta.vmdk" (0x8)
2013-11-08T17:39:22.188Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005.vmdk' (0x8): vmfsSparse, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.188Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 1, numSubChains = 1
2013-11-08T17:39:22.189Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 113093352, extentType = 0
2013-11-08T17:39:22.189Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000005.vmdk" (flags 0x8, type vmfsSparse).
2013-11-08T17:39:22.190Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 2, numSubChains = 1
2013-11-08T17:39:22.190Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 113093352, extentType = 0
2013-11-08T17:39:22.190Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 119188198, extentType = 0
2013-11-08T17:39:22.206Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004-delta.vmdk" : open successful (8) size = 17059840, hd = 106146538. Type 8
2013-11-08T17:39:22.206Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-000004-delta.vmdk" (0x8)
2013-11-08T17:39:22.206Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004.vmdk' (0x8): vmfsSparse, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.206Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 1, numSubChains = 1
2013-11-08T17:39:22.206Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 106146538, extentType = 0
2013-11-08T17:39:22.207Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000004.vmdk" (flags 0x8, type vmfsSparse).
2013-11-08T17:39:22.208Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 3, numSubChains = 1
2013-11-08T17:39:22.208Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 106146538, extentType = 0
2013-11-08T17:39:22.208Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 113093352, extentType = 0
2013-11-08T17:39:22.208Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(2) fid = 119188198, extentType = 0
2013-11-08T17:39:22.222Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003-delta.vmdk" : open successful (8) size = 17059840, hd = 59484908. Type 8
2013-11-08T17:39:22.222Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-000003-delta.vmdk" (0x8)
2013-11-08T17:39:22.222Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003.vmdk' (0x8): vmfsSparse, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.222Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 1, numSubChains = 1
2013-11-08T17:39:22.222Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 59484908, extentType = 0
2013-11-08T17:39:22.223Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000003.vmdk" (flags 0x8, type vmfsSparse).
2013-11-08T17:39:22.224Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 4, numSubChains = 1
2013-11-08T17:39:22.224Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 59484908, extentType = 0
2013-11-08T17:39:22.224Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 106146538, extentType = 0
2013-11-08T17:39:22.224Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(2) fid = 113093352, extentType = 0
2013-11-08T17:39:22.224Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(3) fid = 119188198, extentType = 0
2013-11-08T17:39:22.239Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002-delta.vmdk" : open successful (8) size = 17059840, hd = 181644014. Type 8
2013-11-08T17:39:22.239Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-000002-delta.vmdk" (0x8)
2013-11-08T17:39:22.240Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002.vmdk' (0x8): vmfsSparse, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.240Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 1, numSubChains = 1
2013-11-08T17:39:22.240Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 181644014, extentType = 0
2013-11-08T17:39:22.240Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000002.vmdk" (flags 0x8, type vmfsSparse).
2013-11-08T17:39:22.241Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 5, numSubChains = 1
2013-11-08T17:39:22.241Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 181644014, extentType = 0
2013-11-08T17:39:22.241Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 59484908, extentType = 0
2013-11-08T17:39:22.241Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(2) fid = 106146538, extentType = 0
2013-11-08T17:39:22.241Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(3) fid = 113093352, extentType = 0
2013-11-08T17:39:22.241Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(4) fid = 119188198, extentType = 0
2013-11-08T17:39:22.260Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001-delta.vmdk" : open successful (8) size = 17059840, hd = 105032432. Type 8
2013-11-08T17:39:22.260Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-000001-delta.vmdk" (0x8)
2013-11-08T17:39:22.260Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001.vmdk' (0x8): vmfsSparse, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.260Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 1, numSubChains = 1
2013-11-08T17:39:22.260Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 105032432, extentType = 0
2013-11-08T17:39:22.261Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000001.vmdk" (flags 0x8, type vmfsSparse).
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 6, numSubChains = 1
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 105032432, extentType = 0
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 181644014, extentType = 0
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(2) fid = 59484908, extentType = 0
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(3) fid = 106146538, extentType = 0
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(4) fid = 113093352, extentType = 0
2013-11-08T17:39:22.262Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(5) fid = 119188198, extentType = 0
2013-11-08T17:39:22.274Z| vcpu-0| I120: DISKLIB-VMFS  : "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-flat.vmdk" : open successful (522) size = 146697879552, hd = 75541234. Type 3
2013-11-08T17:39:22.274Z| vcpu-0| I120: DISKLIB-DSCPTR: Opened [0]: "spcp-gihmap3.egis.hksarg-flat.vmdk" (0x20a)
2013-11-08T17:39:22.274Z| vcpu-0| I120: DISKLIB-LINK  : Opened '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg.vmdk' (0x20a): vmfs, 286519296 sectors / 136.6 GB.
2013-11-08T17:39:22.274Z| vcpu-0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg.vmdk" (flags 0x20a, type vmfs).
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain: numLinks = 7, numSubChains = 1
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 75541234, extentType = 2
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 105032432, extentType = 0
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(2) fid = 181644014, extentType = 0
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(3) fid = 59484908, extentType = 0
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(4) fid = 106146538, extentType = 0
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(5) fid = 113093352, extentType = 0
2013-11-08T17:39:22.275Z| vcpu-0| I120: DISKLIB-CHAINESX : ChainESXOpenSubChain:(6) fid = 119188198, extentType = 0
2013-11-08T17:39:22.279Z| vcpu-0| I120: DISK: Disk '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk' has UUID '60 00 c2 95 b5 f7 54 d8-e1 b5 4e 85 d9 8e ef e0'
2013-11-08T17:39:22.279Z| vcpu-0| I120: DISK: OPEN '/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk' Geo (17835/255/63) BIOS Geo (17835/255/63)
2013-11-08T17:39:22.279Z| vcpu-0| I120: Creating virtual dev for scsi0:0
2013-11-08T17:39:22.279Z| vcpu-0| I120: DumpDiskInfo: scsi0:0 createType=11, capacity = 286519296, numLinks = 7, allocationType = 0
2013-11-08T17:39:22.279Z| vcpu-0| I120: SCSIDiskESXPopulateVDevDesc: Using FS backend
2013-11-08T17:39:22.279Z| vcpu-0| I120: DISKUTIL: scsi0:0 : geometry=17835/255/63
2013-11-08T17:39:22.281Z| vcpu-0| I120: VMXNET3 user: Ethernet0 Driver Info: version = 761693 gosBits = 2 gosType = 2, gosVer = 24848, gosMisc = 212
2013-11-08T17:39:22.281Z| vcpu-0| I120: VMXNET3 user: Ethernet1 Driver Info: version = 761693 gosBits = 2 gosType = 2, gosVer = 24848, gosMisc = 212
2013-11-08T17:39:22.282Z| vcpu-0| I120: SnapshotVMXConsolidateOnlineCB: nextState = 2 uid 11
2013-11-08T17:39:22.282Z| SnapshotVMXCombiner| I120: VTHREAD start thread 10 "SnapshotVMXCombiner" pid 5555823
2013-11-08T17:39:22.282Z| SnapshotVMXCombiner| I120: SnapshotVMXConsolidateOnlineCB: nextState = 3 uid 11
2013-11-08T17:39:22.282Z| SnapshotVMXCombiner| I120: SnapshotVMXConsolidateOnlineCB: Starting combine of scsi0:0 /vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk. 6 links, starting from 1
2013-11-08T17:39:22.283Z| vcpu-0| I120: SnapshotVMXConsolidateOnlineCB: Created thread 10 for scsi0:0
2013-11-08T17:39:22.306Z| SnapshotVMXCombiner| I120: DISKLIB-LIB   : Upward Combine 6 links at 1. Need 0 MB of free space (314713 MB available)
2013-11-08T17:39:22.306Z| SnapshotVMXCombiner| I120: DDB: "longContentID" = "56d79919585df60aa173e3e7e9c4e82e" (was "9d9305e6d60d9bf4ff932b40ba14d545")
2013-11-08T17:39:22.320Z| SnapshotVMXCombiner| I120: DDB: "longContentID" = "6ecfa7666770fd40dfed8770f2e95aad" (was "56d79919585df60aa173e3e7e9c4e82e")
2013-11-08T17:39:22.321Z| vcpu-0| I120: Guest: toolbox-dnd: Version: build-782409
2013-11-08T17:39:22.321Z| vcpu-0| I120: GuestRpc: Channel 3, conflict: guest application toolbox-dnd tried to register, but it is still registered on channel 2
2013-11-08T17:39:22.321Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:22.321Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:22.322Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:22.333Z| SnapshotVMXCombiner| I120: DDB: "longContentID" = "5269d155f8568c345006c9ce98e303e1" (was "6ecfa7666770fd40dfed8770f2e95aad")
2013-11-08T17:39:22.346Z| SnapshotVMXCombiner| I120: DDB: "longContentID" = "0133fa5fe8014ca41da830a628d871da" (was "5269d155f8568c345006c9ce98e303e1")
2013-11-08T17:39:22.361Z| SnapshotVMXCombiner| I120: DDB: "longContentID" = "1e89ffe8ca127e6944b61e5dff643d1b" (was "0133fa5fe8014ca41da830a628d871da")
2013-11-08T17:39:23.147Z| vcpu-0| I120: HBACommon: First write on scsi0:0.fileName='/vmfs/volumes/51c14874-dce6640e-e0f9-90b11c3084d1/spcp-gihmap3.egis.hksarg/spcp-gihmap3.egis.hksarg-000006.vmdk'
2013-11-08T17:39:23.148Z| vcpu-0| I120: DDB: "longContentID" = "08709b3aa5f4ca08a3096af361305b11" (was "1e89ffe8ca127e6944b61e5dff643d1b")
2013-11-08T17:39:23.162Z| vcpu-0| I120: DISKLIB-CHAIN : DiskChainUpdateContentID: old=0xff643d1b, new=0x61305b11 (08709b3aa5f4ca08a3096af361305b11)
2013-11-08T17:39:23.335Z| vcpu-0| I120: Guest: toolbox-dnd: Version: build-782409
2013-11-08T17:39:23.335Z| vcpu-0| I120: GuestRpc: Channel 3, conflict: guest application toolbox-dnd tried to register, but it is still registered on channel 2
2013-11-08T17:39:23.335Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:23.335Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:23.336Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:24.350Z| vcpu-0| I120: Guest: toolbox-dnd: Version: build-782409
2013-11-08T17:39:24.350Z| vcpu-0| I120: GuestRpc: Channel 3, conflict: guest application toolbox-dnd tried to register, but it is still registered on channel 2
2013-11-08T17:39:24.350Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:24.350Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.
2013-11-08T17:39:24.351Z| vcpu-0| I120: GuestRpc: Channel 3 reinitialized.

 

Operating Systems:

Comments 2 CommentsJump to latest comment

Marianne's picture

GuestRpc: Channel 3, conflict: guest application toolbox-dnd tried to register, but it is still registered on channel 2

This looks like an issue with vmtools on the VM.

Which NBU version and patch level on backup host?

Is backup host also the media server? 

What is ESX/vSphere version?

Supporting Storage Foundation and VCS on Unix and Windows as well as NetBackup on Unix and Windows
Handy NBU Links

pep-e's picture

Hi,

No updates about this? It's a pity.

We are seeing the same errors, though not often. When they appear, usually NBU does the backup successfully on the second try. It's a hotadd backup performed by NBU. The backup host, which is also the media server, runs NBU 7.5.0.7. Master Server is still 7.5.0.5, but this is a supported combination of versions as per Symantec's documentation. I believe this issue has been active since before we upgraded the backup host from 7.5.0.5 to 7.5.0.7.