VMware Communities > VMTN > VMware Infrastructure™ > VI: ESX 3.5 > Discussions
Up to Discussions in VI: ESX 3.5

This Question is Answered

1 "correct" answer available (10 pts) 1 "helpful" answer available (6 pts)
1 2 Previous Next
16 Replies Last post: Nov 9, 2008 1:40 AM by ManuFan007
Reply

VCB backup Failure ESX 3.5, VC 2.5

Nov 4, 2008 3:13 AM

Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008

Hi,

I have been trying to take VCB backups but it is not working. Here is the log output. Port used is 443. I would greatly appreciate any help here.

2008/11/04-10:51:54.32299 stdout avspawnpipe::body appending 104 '2008-11-04 16:21:52.776 'App' 1352 info Current working directory: C:\PROGRA~1\VMware\VMWARE~1\generic'
2008/11/04-10:51:54.32299 stdout avspawnpipe::body stdout waiting for I/O
2008/11/04-10:51:54.32299 stdout avspawnpipe::body appending 112 '2008-11-04 16:21:52.776 'BaseLibs' 1352 info HOSTINFO: Seeing Intel CPU, numCoresPerCPU 1 numThreadsPerCore 2.'
2008/11/04-10:51:54.32299 stdout avspawnpipe::body stdout waiting for I/O
2008/11/04-10:51:54.32299 stdout avspawnpipe::body appending 125 '2008-11-04 16:21:52.776 'BaseLibs' 1352 info HOSTINFO: This machine has 1 physical CPUS, 1 total cores, and 2 logical CPUs.'
2008/11/04-10:51:54.32299 stdout avspawnpipe::body stdout waiting for I/O
2008/11/04-10:51:54.32299 stdout avspawnpipe::body appending 85 '2008-11-04 16:21:53.308 'BaseLibs' 1352 info Using system libcrypto, version 90709F'
2008/11/04-10:51:54.32299 stdout avspawnpipe::body stdout waiting for I/O
2008/11/04-10:51:54.32299 stdout avspawnpipe::body appending 155 '2008-11-04 16:21:53.323 'App' 1352 error SSLStreamImpl::BIOWrite ( 16FA088) Write failed: An existing connection was forcibly closed by the remote host. '
2008/11/04-10:51:54.32299 stdout avspawnpipe::body stdout waiting for I/O
2008/11/04-10:51:54.32299 stdout avspawnpipe::body appending 136 '2008-11-04 16:21:53.323 'vcbMounter' 1352 error Error: Other error encountered: SSL Exception: error:00000000:lib(0):func(0):reason(0)'
2008/11/04-10:51:54.32299 stdout avspawnpipe::body stdout waiting for I/O
2008/11/04-10:51:54.43300 stderr avspawnpipe::body appending 41 'External command failed. See error above.'

Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 4, 2008 5:00 AM
Click to view immad70's profile Novice immad70 9 posts since
Oct 28, 2008
What is the command that you are using? I see some of the logs, but what is the command that you ran to get this output?
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 1:20 AM
in response to: immad70
Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008

I am calling an Avamar script which inturn runs this command

cscript pre-command.wsf "C:\Progra~1\VMware\VMware~1\" %1 fullvm

the argument represents the VM to be backed up

Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 1:39 AM
in response to: ManuFan007
Click to view depping's profile Champion depping 2,798 posts since
Jan 17, 2005 VMware Moderator
Can you backup via vcbmounter?



Duncan
Blogging: http://www.yellow-bricks.com

If you find this information useful, please award points for "correct" or "helpful".
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 1:50 AM
in response to: depping
Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008

It is VCB Mounter itself which is failing.. Here is the log details

2008-11-05 14:41:48.710 'App' 2884 info Current working directory: C:\PROGRA~1\VMware\VMWARE~1\generic
2008-11-05 14:41:48.710 'BaseLibs' 2884 info HOSTINFO: Seeing Intel CPU, numCoresPerCPU 1 numThreadsPerCore 2.
2008-11-05 14:41:48.710 'BaseLibs' 2884 info HOSTINFO: This machine has 1 physical CPUS, 1 total cores, and 2 logical CPUs.
2008-11-05 14:41:49.257 'BaseLibs' 2884 info Using system libcrypto, version 90709F
2008-11-05 14:41:49.273 'App' 2884 error SSLStreamImpl::BIOWrite ( 16FA088) Write failed: An existing connection was forcibly closed by the remote host.
2008-11-05 14:41:49.273 'vcbMounter' 2884 error Error: Other error encountered: SSL Exception: error:00000000:lib(0):func(0):reason(0)
2008-11-05 14:41:50 avtar Info <6032>: End of STDOUT

Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 1:57 AM
in response to: ManuFan007
Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008

I tried once again and this time i got a slightly different error.. here are the logs

C:\Program Files\VMware\VMware Consolidated Backup Framework>vcbmounter -h svcta
g-4z3nl1s.iis.com -u administrator -p password!@# -a name:VM-Fista -r f:\ave-vcb
\Fista -t fullvm -m san
2008-11-05 15:21:13.369 'App' 472 info Current working directory: C:\Program F
iles\VMware\VMware Consolidated Backup Framework
2008-11-05 15:21:13.369 'BaseLibs' 472 info HOSTINFO: Seeing Intel CPU, numCor
esPerCPU 1 numThreadsPerCore 2.
2008-11-05 15:21:13.369 'BaseLibs' 472 info HOSTINFO: This machine has 1 physi
cal CPUS, 1 total cores, and 2 logical CPUs.
2008-11-05 15:21:13.697 'BaseLibs' 472 info Using system libcrypto, version 90
709F
2008-11-05 15:21:13.712 'App' 472 error SSLStreamImpl::BIORead ( 1979088) Time
dAsyncRead failed: The specified network name is no longer available.
2008-11-05 15:21:13.712 'vcbMounter' 472 error Error: Other error encountered:
SSL Exception: error:00000000:lib(0):func(0):reason(0)

Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 3:07 AM
in response to: ManuFan007
Click to view depping's profile Champion depping 2,798 posts since
Jan 17, 2005 VMware Moderator
there's not much info to go on here. can you please post the outcome of : vcbsandbg.exe, the vcbmounter command you used to check if the concepts works including the command you used. And run the vcbmounter command with the -L 6 option! This way we get more info in the log...



Duncan
Blogging: http://www.yellow-bricks.com

If you find this information useful, please award points for "correct" or "helpful".
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 3:47 AM
in response to: ManuFan007
Click to view immad70's profile Novice immad70 9 posts since
Oct 28, 2008

This is a dumb question, but for your -h option are you using your vc or the host? When I run the vcbmounter command I don't use the -t or the -m options. Also, I am wondering if you have your config.js file set up properly.


Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 5:29 AM
in response to: immad70
Click to view depping's profile Champion depping 2,798 posts since
Jan 17, 2005 VMware Moderator
vcbmounter as a stand alone tool doesn't care about your config.js, that's why I would like to see the command he used and the -L 6 log info.



Duncan
Blogging: http://www.yellow-bricks.com

If you find this information useful, please award points for "correct" or "helpful".
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 6:06 AM
in response to: depping
Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008
Here is the output and all the details..I have also attached this info in a file for convenience.. Also, I am using a Virtualcenter and config.js has been configured with the same details as given in the command


Command used
vcbmounter -h svctag-4z3nl1s.iis.com -u administrator -p password!@# -a name:VM-Fista -r f:\ave-vcb\Fista -t fullvm -m san -L 6

VCBmounter output:
C:\Program Files\VMware\VMware Consolidated Backup Framework>vcbmounter -h svcta
g-4z3nl1s.iis.com -u administrator -p password!@# -a name:VM-Fista -r f:\ave-vcb
\Fista -t fullvm -m san -L 6
--2008-11-05 19:17:01.895 'App' 960 info-- Current working directory: C:\Program F
iles\VMware\VMware Consolidated Backup Framework
--2008-11-05 19:17:01.895 'BaseLibs' 960 info-- HOSTINFO: Seeing Intel CPU, numCor
esPerCPU 1 numThreadsPerCore 2.
--2008-11-05 19:17:01.910 'BaseLibs' 960 info-- HOSTINFO: This machine has 1 physi
cal CPUS, 1 total cores, and 2 logical CPUs.
--2008-11-05 19:17:02.254 'vcbMounter' 960 info-- Connecting to host svctag-4z3nl1
s.iis.com on port 443 using protocol https

--2008-11-05 19:17:02.254 'vcbMounter' 960 trivia-- Initializing SSL context
--2008-11-05 19:17:02.254 'BaseLibs' 960 info-- Using system libcrypto, version 90
709F
--2008-11-05 19:17:02.254 'App' 960 info-- Vmacore::InitSSL: doVersionCheck = true
, handshakeTimeoutUs = 120000000
--2008-11-05 19:17:02.254 'SOAP' 960 trivia-- Sending soap request to [TCP:svctag-
4z3nl1s.iis.com:443]: retrieveContent
--2008-11-05 19:17:03.363 'vcbMounter' 960 error-- Error: Other error encountered:
No connection could be made because the target machine actively refused it.

vcbSanDbg
C:\Program Files\VMware\VMware Consolidated Backup Framework>vcbSanDbg.exe
--2008-11-05 19:25:40.492 'App' 3372 info-- Current working directory: C:\Program
Files\VMware\VMware Consolidated Backup Framework
--2008-11-05 19:25:40.492 'BaseLibs' 3372 info-- HOSTINFO: Seeing Intel CPU, numCo
resPerCPU 1 numThreadsPerCore 2.
--2008-11-05 19:25:40.492 'BaseLibs' 3372 info-- HOSTINFO: This machine has 1 phys
ical CPUS, 1 total cores, and 2 logical CPUs.
--2008-11-05 19:25:40.507 'App' 3372 verbose-- Building SCSI Device List...
--2008-11-05 19:25:40.585 'App' 3372 warning-- Could not obtain inquiry page 131 f
or device on path 0, target 0, lun 0
--2008-11-05 19:25:40.585 'App' 3372 warning-- Sending SCSI inquiry failed: The re
quest could not be performed because of an I/O device error.
(Error code 45d)
--2008-11-05 19:25:40.585 'App' 3372 warning-- Could not scan this device. The dev
ice will be ignored. This will only cause the operation to fail if the device co
ntained relevant data.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Evaluating 1 paths.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Trying to open path \\?\scsi#disk&ve
n_seagate&prod_st373207lc&rev_d704#6&35b122ac&0&000#{53f56307-b6bf-11d0-94f2-00a
0c91efb8b}.
--2008-11-05 19:25:40.601 'App' 3372 info-- Now using Path \\?\scsi#disk&ven_seaga
te&prod_st373207lc&rev_d704#6&35b122ac&0&000#{53f56307-b6bf-11d0-94f2-00a0c91efb
8b}.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Reading 32256 bytes from offset 0.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Found 1 partition(s) on this device.

--2008-11-05 19:25:40.601 'App' 3372 trivia-- Evaluating 1 paths.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Trying to open path \\?\scsi#disk&ve
n_dgc&prod_raid_0&rev_0326#6&178d75&0&000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}
.
--2008-11-05 19:25:40.601 'App' 3372 info-- Now using Path \\?\scsi#disk&ven_dgc&p
rod_raid_0&rev_0326#6&178d75&0&000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Reading 32256 bytes from offset 0.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Found 1 partition(s) on this device.

--2008-11-05 19:25:40.601 'App' 3372 trivia-- Evaluating 1 paths.
--2008-11-05 19:25:40.601 'App' 3372 trivia-- Trying to open path \\?\scsi#disk&ve
n_emc&prod_symmetrix&rev_5670#6&178d75&0&023e#{53f56307-b6bf-11d0-94f2-00a0c91ef
b8b}.
--2008-11-05 19:25:40.601 'App' 3372 info-- Now using Path \\?\scsi#disk&ven_emc&p
rod_symmetrix&rev_5670#6&178d75&0&023e#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}.
--2008-11-05 19:25:40.617 'App' 3372 trivia-- Reading 32256 bytes from offset 0.
--2008-11-05 19:25:40.617 'App' 3372 trivia-- Found 1 partition(s) on this device.

--2008-11-05 19:25:40.617 'App' 3372 info-- Partition #1 is a VMFS partition.
--2008-11-05 19:25:40.617 'App' 3372 trivia-- Reading 1574400 bytes from offset 11
14112.
--2008-11-05 19:25:40.632 'App' 3372 trivia-- Found an ESX LVM v3 signature on par
tition 1.
--2008-11-05 19:25:40.632 'App' 3372 info-- Found logical volume 48c91696-8daba330
-8991-001d0905dc59.
--2008-11-05 19:25:40.632 'App' 3372 error-- Dumping SCSI Device/LUN List.
--2008-11-05 19:25:40.632 'App' 3372 info-- **** Begin SCSI Device LIst ****
--2008-11-05 19:25:40.648 'App' 3372 info-- Found SCSI Device: SERIAL_NUM:334b5435
35573445535433373332/00000
--2008-11-05 19:25:40.648 'App' 3372 info-- Visible on 1 paths:
--2008-11-05 19:25:40.648 'App' 3372 info-- Device Name: \\?\scsi#disk&ven_seagate
&prod_st373207lc&rev_d704#6&35b122ac&0&000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b
}, Bus: 0 Target: 0 Lun: 0
--2008-11-05 19:25:40.648 'App' 3372 info-- Lun does not contain any VMFS/LVM sign
atures.
--2008-11-05 19:25:40.648 'App' 3372 info-- Found SCSI Device: NAA:6006016040501e0
09e3c5f052f53dd11524149442030
--2008-11-05 19:25:40.648 'App' 3372 info-- Visible on 1 paths:
--2008-11-05 19:25:40.648 'App' 3372 info-- Device Name: \\?\scsi#disk&ven_dgc&pro
d_raid_0&rev_0326#6&178d75&0&000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}, Bus: 0
Target: 0 Lun: 0
--2008-11-05 19:25:40.648 'App' 3372 info-- Lun does not contain any VMFS/LVM sign
atures.
--2008-11-05 19:25:40.648 'App' 3372 info-- Found SCSI Device: NAA:600604800001874
6164153594d4d323053594d4d4554
--2008-11-05 19:25:40.648 'App' 3372 info-- Visible on 1 paths:
--2008-11-05 19:25:40.648 'App' 3372 info-- Device Name: \\?\scsi#disk&ven_emc&pro
d_symmetrix&rev_5670#6&178d75&0&023e#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}, Bus
: 0 Target: 2 Lun: 62
--2008-11-05 19:25:40.648 'App' 3372 info-- Lun contains VMFS/LVM signatures:
--2008-11-05 19:25:40.648 'App' 3372 info-- VMFS LVM signature.
--2008-11-05 19:25:40.648 'App' 3372 info-- ID: LVID:48c91697-ba7d8690-cdc2-001d09
05dc59/48c91696-8daba330-8991-001d0905dc59/1 Name: 48c91696-8daba330-8991-001d09
05dc59
--2008-11-05 19:25:40.648 'App' 3372 info-- **** End SCSI Device LIst ****

Attachments:
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 11:38 AM
in response to: ManuFan007
Click to view depping's profile Champion depping 2,798 posts since
Jan 17, 2005 VMware Moderator
So your SAN is setup correctly and masked/zoned to the VCB proxy correctly but the host you are trying to connect to doesn't respond the way it should. Are you using VCB 1.5? Can you ping the VC host correctly from the VCB Proxy?



Duncan
Blogging: http://www.yellow-bricks.com

If you find this information useful, please award points for "correct" or "helpful".
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 5, 2008 8:43 PM
in response to: depping
Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008
Thts correct, the SAN is setup correctly and masking is also done.. I am actually using VCB 1.1 and i am also able to ping the VC host from the VCB Proxy.
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 6, 2008 4:21 AM
in response to: ManuFan007
Click to view depping's profile Champion depping 2,798 posts since
Jan 17, 2005 VMware Moderator
try a telnet to the VC on port 443 and see what it comes up with. This should just work fine, maybe a firewall is blocking it or the default port has changed from 443 to ?



Duncan
Blogging: http://www.yellow-bricks.com

If you find this information useful, please award points for "correct" or "helpful".
Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 6, 2008 7:31 AM
in response to: depping
Click to view ManuFan007's profile Novice ManuFan007 12 posts since
Oct 23, 2008
I tried the telnet and surprisingly it didnt work.. There are no firewalls installed on the system and windows firewall has been turned off temporarily.. the port was closed. I opened the port again and the backup started. However, the backup failed after some time and now its back to square zero. I am getting the same error messages.

With Telnet, I get the following errors: " Connection closed by remote host" and "Network error: Connection refused"

Would changing the VC to a different machine help?

Reply Re: VCB backup Failure ESX 3.5, VC 2.5 Nov 7, 2008 2:25 AM
in response to: ManuFan007
Click to view depping's profile Champion depping 2,798 posts since
Jan 17, 2005 VMware Moderator
You could try that... Could it be that there's a virus scanning the port? Or you're enforcing it via a policy...

Duncan
Blogging: http://www.yellow-bricks.com

If you find this information useful, please award points for "correct" or "helpful".
1 2 Previous Next
Actions
Up to Discussions in VI: ESX 3.5