Re: image capture issue
I'm trying to find the code that actually makes the ssh call to see what its doing and to figure out where its going wrong. Any ideas? On 2/25/2011 9:26 AM, Jay Diener-Brazelle wrote: I also tried giving the machine a dns name (node01.vcu.edu) and adding it to the /etc/hosts file for looup, but I got a very simular error: |27494|26:25|image| 2011-02-25 08:52:20|27494|26:25|image|VMware.pm:capture(403)|unable to capture image, VM node01 is not responding to SSH again I am able to ssh directly into the machine using both the IP and the dns name from the management node On 2/24/2011 3:38 PM, Jay Diener-Brazelle wrote: I believe I got my datastore configured correctly now, but when I go to capture an image I get the following in my log: 2011-02-24 15:29:00|7040|utils.pm:setup_get_array_choice(9986)|choices argument: 2011-02-24 15:29:00|7040|utils.pm:setup_get_array_choice(9986)|choices argument: 2011-02-24 15:29:08|7040|utils.pm:insert_request(8443)|inserted new reload request into request table, request id=25 2011-02-24 15:29:08|7040|utils.pm:insert_request(8474)|inserted new reload request into reservation table, reservation id=24 2011-02-24 15:29:11|2522|25:24|image|utils.pm:update_request_state(2049)|request 25 state updated to: pending, laststate to: image 2011-02-24 15:29:11|2522|25:24|image|utils.pm:insertloadlog(3933)|inserted computer=2, begin, beginning to process, state is image 2011-02-24 15:29:11|7107|25:24|image|utils.pm:rename_vcld_process(7221)|renamed process to 'vcld 25:24 image 192 vmwarewin7-testcap2826-v0 admin' 2011-02-24 15:29:11|7107|25:24|image|vSphere_SDK.pm:is_restricted(1079)|access to the VM host via the vSphere SDK is NOT restricted due to the license 2011-02-24 15:29:18|7107|25:24|image|State.pm:initialize(128)|VCL::Module::Provisioning::VMware::VMware provisioner object created 2011-02-24 15:29:18|7107|25:24|image|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_6::7 OS object created 2011-02-24 15:29:18|7107|25:24|image|vcld:make_new_child(592)|VCL::image object created and initialized 2011-02-24 15:29:18|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: jabraze...@vcu.edu, VCL IMAGE Creation Started: vmwarewin7-testcap2826-v0 2011-02-24 15:29:24|7107|25:24|image|image.pm:process(148)|image vmwarewin7-testcap2826-v0 does not exist in the repository 2011-02-24 15:29:24|7107|25:24|image|image.pm:process(164)|calling provisioning module's capture() subroutine |7107|25:24|image| WARNING |7107|25:24|image| 2011-02-24 15:29:24|7107|25:24|image|VMware.pm:capture(403)|unable to capture image, VM 192 is not responding to SSH |7107|25:24|image| ( 0) utils.pm, notify (line: 630) |7107|25:24|image| (-1) VMware.pm, capture (line: 403) |7107|25:24|image| (-2) image.pm, process (line: 165) |7107|25:24|image| (-3) vcld, make_new_child (line: 595) |7107|25:24|image| (-4) vcld, main (line: 342) |7107|25:24|image| WARNING |7107|25:24|image| 2011-02-24 15:29:24|7107|25:24|image|image.pm:process(169)|vmwarewin7-testcap2826-v0 image failed to be captured by provisioning module |7107|25:24|image| ( 0) utils.pm, notify (line: 630) |7107|25:24|image| (-1) image.pm, process (line: 169) |7107|25:24|image| (-2) vcld, make_new_child (line: 595) |7107|25:24|image| (-3) vcld, main (line: 342) 2011-02-24 15:29:24|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: jabraze...@vcu.edu, PROBLEM -- image.pm |7107|25:24|image| CRITICAL |7107|25:24|image| 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(393)|vmwarewin7-testcap2826-v0 image creation failed |7107|25:24|image| ( 0) utils.pm, notify (line: 630) |7107|25:24|image| (-1) image.pm, reservation_failed (line: 393) |7107|25:24|image| (-2) image.pm, process (line: 170) |7107|25:24|image| (-3) vcld, make_new_child (line: 595) |7107|25:24|image| (-4) vcld, main (line: 342) 2011-02-24 15:29:24|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation testcap28 2011-02-24 15:29:24|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: jabraze...@vcu.edu, VCL -- NOTICE FAILED Image Creation testcap28 2011-02-24 15:29:24|7107|25:24|image|utils.pm:update_request_state(2049)|request 25 state updated to: maintenance, laststate to: image 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image 2011-02-24 15:29:24|7107|25:24|image|utils.pm:update_computer_state(2091)|computer 2 state updated to: maintenance 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(453)|192 state set to maintenance 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(460)|exiting 2011-02-24 15:29:24|7107|25:24|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for
image capture issue
I believe I got my datastore configured correctly now, but when I go to capture an image I get the following in my log: 2011-02-24 15:29:00|7040|utils.pm:setup_get_array_choice(9986)|choices argument: 2011-02-24 15:29:00|7040|utils.pm:setup_get_array_choice(9986)|choices argument: 2011-02-24 15:29:08|7040|utils.pm:insert_request(8443)|inserted new reload request into request table, request id=25 2011-02-24 15:29:08|7040|utils.pm:insert_request(8474)|inserted new reload request into reservation table, reservation id=24 2011-02-24 15:29:11|2522|25:24|image|utils.pm:update_request_state(2049)|request 25 state updated to: pending, laststate to: image 2011-02-24 15:29:11|2522|25:24|image|utils.pm:insertloadlog(3933)|inserted computer=2, begin, beginning to process, state is image 2011-02-24 15:29:11|7107|25:24|image|utils.pm:rename_vcld_process(7221)|renamed process to 'vcld 25:24 image 192 vmwarewin7-testcap2826-v0 admin' 2011-02-24 15:29:11|7107|25:24|image|vSphere_SDK.pm:is_restricted(1079)|access to the VM host via the vSphere SDK is NOT restricted due to the license 2011-02-24 15:29:18|7107|25:24|image|State.pm:initialize(128)|VCL::Module::Provisioning::VMware::VMware provisioner object created 2011-02-24 15:29:18|7107|25:24|image|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_6::7 OS object created 2011-02-24 15:29:18|7107|25:24|image|vcld:make_new_child(592)|VCL::image object created and initialized 2011-02-24 15:29:18|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: jabraze...@vcu.edu, VCL IMAGE Creation Started: vmwarewin7-testcap2826-v0 2011-02-24 15:29:24|7107|25:24|image|image.pm:process(148)|image vmwarewin7-testcap2826-v0 does not exist in the repository 2011-02-24 15:29:24|7107|25:24|image|image.pm:process(164)|calling provisioning module's capture() subroutine |7107|25:24|image| WARNING |7107|25:24|image| 2011-02-24 15:29:24|7107|25:24|image|VMware.pm:capture(403)|unable to capture image, VM 192 is not responding to SSH |7107|25:24|image| ( 0) utils.pm, notify (line: 630) |7107|25:24|image| (-1) VMware.pm, capture (line: 403) |7107|25:24|image| (-2) image.pm, process (line: 165) |7107|25:24|image| (-3) vcld, make_new_child (line: 595) |7107|25:24|image| (-4) vcld, main (line: 342) |7107|25:24|image| WARNING |7107|25:24|image| 2011-02-24 15:29:24|7107|25:24|image|image.pm:process(169)|vmwarewin7-testcap2826-v0 image failed to be captured by provisioning module |7107|25:24|image| ( 0) utils.pm, notify (line: 630) |7107|25:24|image| (-1) image.pm, process (line: 169) |7107|25:24|image| (-2) vcld, make_new_child (line: 595) |7107|25:24|image| (-3) vcld, main (line: 342) 2011-02-24 15:29:24|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: jabraze...@vcu.edu, PROBLEM -- image.pm |7107|25:24|image| CRITICAL |7107|25:24|image| 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(393)|vmwarewin7-testcap2826-v0 image creation failed |7107|25:24|image| ( 0) utils.pm, notify (line: 630) |7107|25:24|image| (-1) image.pm, reservation_failed (line: 393) |7107|25:24|image| (-2) image.pm, process (line: 170) |7107|25:24|image| (-3) vcld, make_new_child (line: 595) |7107|25:24|image| (-4) vcld, main (line: 342) 2011-02-24 15:29:24|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation testcap28 2011-02-24 15:29:24|7107|25:24|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: jabraze...@vcu.edu, VCL -- NOTICE FAILED Image Creation testcap28 2011-02-24 15:29:24|7107|25:24|image|utils.pm:update_request_state(2049)|request 25 state updated to: maintenance, laststate to: image 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image 2011-02-24 15:29:24|7107|25:24|image|utils.pm:update_computer_state(2091)|computer 2 state updated to: maintenance 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(453)|192 state set to maintenance 2011-02-24 15:29:24|7107|25:24|image|image.pm:reservation_failed(460)|exiting 2011-02-24 15:29:24|7107|25:24|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=24 2011-02-24 15:29:24|7107|25:24|image|State.pm:DESTROY(946)|VCL::image process 7107 exiting 2011-02-24 15:29:00|7040|utils.pm:setup_get_array_choice(9986)|choices argument: 2011-02-24 15:29:00|7040|utils.pm:setup_get_array_choice(9986)|choices argument: 2011-02-24 15:29:08|7040|utils.pm:insert_request(8443)|inserted new reload request into request table, request id=25 2011-02-24 15:29:08|7040|utils.pm:insert_request(8474)|inserted new reload request into reservation table, reservation id=24 2011-02-24 15:29:11|2522|25:24|image|utils.pm:update_request_state(2049)|request 25 state updated to: pending, laststate to: image
RE: Image Capture issue
| (-3) vcld, make_new_child (line: 595)
|4026|1:1|image| (-4) vcld, main (line: 342)
2011-01-28 11:32:04|4026|1:1|image|utils.pm:mail(1247)|SUCCESS -- Sending mail
To: root@localhost, VCL -- NOTICE DELAY Image Creation xpbase2
/usr/lib/perl5/site_perl/5.8.8/Mail/Mailer/rfc822.pm line 22 (#1)
|4026|1:1|image| WARNING
|4026|1:1|image| 2011-01-28
11:32:04|4026|1:1|image|vcld:warning_handler(637)|Use of uninitialized value in
substitution (s///) at /usr/lib/perl5/site_perl/5.8.8/Mail/Mailer/rfc822.pm
line 22.
|4026|1:1|image| ( 0) utils.pm, notify (line: 630)
|4026|1:1|image| (-1) vcld, warning_handler (line: 637)
|4026|1:1|image| (-2) rfc822.pm, set_headers (line: 22)
|4026|1:1|image| (-3) Mailer.pm, open (line: 162)
|4026|1:1|image| (-4) utils.pm, mail (line: 1241)
|4026|1:1|image| (-5) image.pm, reservation_failed (line: 442)
|4026|1:1|image| (-6) image.pm, process (line: 170)
|4026|1:1|image| WARNING
|4026|1:1|image| 2011-01-28
11:32:04|4026|1:1|image|vcld:warning_handler(637)|Use of uninitialized value in
concatenation (.) or string at
/usr/lib/perl5/site_perl/5.8.8/Mail/Mailer/rfc822.pm line 23.
|4026|1:1|image| ( 0) utils.pm, notify (line: 630)
|4026|1:1|image| (-1) vcld, warning_handler (line: 637)
|4026|1:1|image| (-2) rfc822.pm, set_headers (line: 23)
|4026|1:1|image| (-3) Mailer.pm, open (line: 162)
|4026|1:1|image| (-4) utils.pm, mail (line: 1241)
|4026|1:1|image| (-5) image.pm, reservation_failed (line: 442)
|4026|1:1|image| (-6) image.pm, process (line: 170)
|4026|1:1|image| WARNING
|4026|1:1|image| 2011-01-28
11:32:04|4026|1:1|image|vcld:warning_handler(637)|Use of uninitialized value in
concatenation (.) or string at /usr/local/vcl/bin/../lib/VCL/utils.pm line 1247.
|4026|1:1|image| ( 0) utils.pm, notify (line: 630)
|4026|1:1|image| (-1) vcld, warning_handler (line: 637)
|4026|1:1|image| (-2) utils.pm, mail (line: 1247)
|4026|1:1|image| (-3) image.pm, reservation_failed (line: 442)
|4026|1:1|image| (-4) image.pm, process (line: 170)
|4026|1:1|image| (-5) vcld, make_new_child (line: 595)
|4026|1:1|image| (-6) vcld, main (line: 342)
2011-01-28 11:32:04|4026|1:1|image|utils.pm:mail(1247)|SUCCESS -- Sending mail
To: , VCL -- NOTICE FAILED Image Creation xpbase2
2011-01-28 11:32:04|4026|1:1|image|utils.pm:update_request_state(2049)|request
1 state updated to: maintenance, laststate to: image
2011-01-28 11:32:04|4026|1:1|image|image.pm:reservation_failed(445)|request
state set to maintenance, laststate to image
2011-01-28
11:32:04|4026|1:1|image|utils.pm:update_computer_state(2091)|computer 9 state
updated to: maintenance
2011-01-28 11:32:04|4026|1:1|image|image.pm:reservation_failed(453)|xphost2
state set to maintenance
2011-01-28 11:32:04|4026|1:1|image|image.pm:reservation_failed(460)|exiting
2011-01-28 11:32:04|4026|1:1|image|State.pm:DESTROY(902)|destructor called,
ref($self)=VCL::image
2011-01-28
11:32:04|4026|1:1|image|utils.pm:delete_computerloadlog_reservation(6829)|removing
computerloadlog entries matching loadstate = begin
2011-01-28
11:32:04|4026|1:1|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted
rows from computerloadlog for reservation id=1
2011-01-28 11:32:04|4026|1:1|image|State.pm:DESTROY(909)|removed
computerloadlog rows with loadstate=begin for reservation
2011-01-28 11:32:04|4026|1:1|image|State.pm:DESTROY(921)|number of database
handles state process created: 1
2011-01-28 11:32:04|4026|1:1|image|State.pm:DESTROY(930)|process has a database
handle stored in $ENV{dbh}, attempting disconnect
2011-01-28 11:32:04|4026|1:1|image|State.pm:DESTROY(932)|$ENV{dbh}: database
disconnect successful
2011-01-28 11:32:04|4026|1:1|image|State.pm:DESTROY(946)|VCL::image process
4026 exiting
2011-01-28 11:32:04|3520|vcld:REAPER(745)|VCL process exited for reservation 1,
PID: 4026, signal: CHLD
2011-01-28 11:32:04|3520|vcld:main(166)|lastcheckin time updated for management
node 1: 2011-01-28 11:32:04
-Original Message-
From: Andy Kurth [mailto:andy_ku...@ncsu.edu]
Sent: Friday, January 28, 2011 11:32 AM
To: vcl-user@incubator.apache.org
Subject: Re: Image Capture issue
It looks like your VM computer xpbase1 hasn't been assigned to a VM
host. Try assigning it via the Virtual Hosts link on the VCL website.
-Andy
