Video Screencast Help
Give us your opinion and win with Symantec! Please help us by taking this survey to tell us about your experience with Symantec Connect, so that we can continue to grow and improve.  Take the survey.

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.