another problem for image reservation

["Huang,Lei" <lh...@PVAMU.EDU>]

Hi All, 

  I have encountered another problem after upgraded to 2.2.1. Per Aaron's
suggestion, I changed my Vmguests provisioning engine to Vmware from
Vmware Server GSX. However, my Windows XP image failed to reserve after
the change. From the log, I found that it looks for
'C:/Windows/System32/qwinsta.exe' and 'C:/Windows/System32/sc.exe config
sshd start= auto'. However, both them are not found on my XP image. Do I
need to update my XP image? The image works fine on Vmware Server GSX
provision engine. The following is the log.

Thanks,
Lei

======================

|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:21|15314|780:780|new|Windows.pm:is_64_bit(8095)|unable to find
registry value line in reg.exe output:
|15314|780:780|new| bash: reg.exe: command not found
|15314|780:780|new| ( 0) Windows.pm, is_64_bit (line: 8095)
|15314|780:780|new| (-1) Windows.pm, get_system32_path (line: 8146)
|15314|780:780|new| (-2) Windows.pm, user_logged_in (line: 8342)
|15314|780:780|new| (-3) Windows.pm, wait_for_logoff (line: 8440)
|15314|780:780|new| (-4) Windows.pm, post_load (line: 602)
|15314|780:780|new| (-5) VMware.pm, load (line: 415)

2011-11-02 
12:58:21|15314|780:780|new|Windows.pm:get_system32_path(8152)|32-bit
Windows OS installed on vmguest-3, using C:/Windows/System32
2011-11-02 
12:58:21|15314|780:780|new|Windows.pm:user_logged_in(8356)|checking if
root is logged in to vmguest-3
2011-11-02 
12:58:21|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
command on vmguest-3:
|15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
'C:/Windows/System32/qwinsta.exe' 2>&1
2011-11-02 
12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 5:
waiting for vmguest-1 to respond to SSH
2011-11-02 
12:58:25|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
responding to SSH, ports 22 or 24 are both closed
2011-11-02 
12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 5:
code returned false, seconds elapsed/remaining: 34/866, sleeping for 8
seconds
2011-11-02 12:58:26|12831|vcld:main(167)|lastcheckin time updated for
management node 1: 2011-11-02 12:58:26
2011-11-02 
12:58:26|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
1093)|reservation remote IP is not defined
2011-11-02 
12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
output:
|15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
directory
2011-11-02 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
command executed on vmguest-3, command:
|15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
'C:/Windows/System32/qwinsta.exe' 2>&1
|15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")

|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:27|15314|780:780|new|Windows.pm:user_logged_in(8361)|failed to run
qwinsta.exe on vmguest-3, exit status: 127, output:
|15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
directory
|15314|780:780|new| ( 0) Windows.pm, user_logged_in (line: 8361)
|15314|780:780|new| (-1) Windows.pm, wait_for_logoff (line: 8440)
|15314|780:780|new| (-2) Windows.pm, post_load (line: 602)
|15314|780:780|new| (-3) VMware.pm, load (line: 415)
|15314|780:780|new| (-4) new.pm, reload_image (line: 618)
|15314|780:780|new| (-5) new.pm, process (line: 248)

2011-11-02 
12:58:27|15314|780:780|new|Windows.pm:wait_for_logoff(8441)|root is NOT
logged in to vmguest-3, returning 1
2011-11-02 
12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
command on vmguest-3:
|15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
'C:/Windows/System32/qwinsta.exe' 2>&1
2011-11-02 12:58:31|12831|vcld:main(167)|lastcheckin time updated for
management node 1: 2011-11-02 12:58:31
2011-11-02 
12:58:31|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
1093)|reservation remote IP is not defined
2011-11-02 
12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
output:
|15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
directory
2011-11-02 12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
command executed on vmguest-3, command:
|15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
'C:/Windows/System32/qwinsta.exe' 2>&1
|15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")

|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:32|15314|780:780|new|Windows.pm:logoff_users(1437)|failed to run
qwinsta.exe on vmguest-3, exit status: 127, output:
|15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
directory
|15314|780:780|new| ( 0) Windows.pm, logoff_users (line: 1437)
|15314|780:780|new| (-1) Windows.pm, post_load (line: 615)
|15314|780:780|new| (-2) VMware.pm, load (line: 415)
|15314|780:780|new| (-3) new.pm, reload_image (line: 618)
|15314|780:780|new| (-4) new.pm, process (line: 248)
|15314|780:780|new| (-5) vcld, make_new_child (line: 568)


|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:32|15314|780:780|new|Windows.pm:post_load(615)|failed to log off all
currently logged in users
|15314|780:780|new| ( 0) Windows.pm, post_load (line: 615)
|15314|780:780|new| (-1) VMware.pm, load (line: 415)
|15314|780:780|new| (-2) new.pm, reload_image (line: 618)
|15314|780:780|new| (-3) new.pm, process (line: 248)
|15314|780:780|new| (-4) vcld, make_new_child (line: 568)
|15314|780:780|new| (-5) vcld, main (line: 346)

2011-11-02 
12:58:33|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 6:
waiting for vmguest-1 to respond to SSH
2011-11-02 
12:58:34|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
responding to SSH, ports 22 or 24 are both closed
2011-11-02 
12:58:34|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 6:
code returned false, seconds elapsed/remaining: 43/857, sleeping for 8
seconds
2011-11-02 12:58:36|12831|vcld:main(167)|lastcheckin time updated for
management node 1: 2011-11-02 12:58:36
2011-11-02 
12:58:36|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
1093)|reservation remote IP is not defined

|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:37|15314|780:780|new|Windows.pm:set_file_owner(1398)|error occurred
setting root as the owner of /home/root, error output:
|15314|780:780|new| bash: /usr/bin/chown.exe: No such file or directory
|15314|780:780|new| ( 0) Windows.pm, set_file_owner (line: 1398)
|15314|780:780|new| (-1) Windows.pm, post_load (line: 625)
|15314|780:780|new| (-2) VMware.pm, load (line: 415)
|15314|780:780|new| (-3) new.pm, reload_image (line: 618)
|15314|780:780|new| (-4) new.pm, process (line: 248)
|15314|780:780|new| (-5) vcld, make_new_child (line: 568)


|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:37|15314|780:780|new|Windows.pm:post_load(625)|unable to set root as
the owner of /home/root
|15314|780:780|new| ( 0) Windows.pm, post_load (line: 625)
|15314|780:780|new| (-1) VMware.pm, load (line: 415)
|15314|780:780|new| (-2) new.pm, reload_image (line: 618)
|15314|780:780|new| (-3) new.pm, process (line: 248)
|15314|780:780|new| (-4) vcld, make_new_child (line: 568)
|15314|780:780|new| (-5) vcld, main (line: 346)

2011-11-02 
12:58:37|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
command on vmguest-3:
|15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
2011-11-02 12:58:41|12831|vcld:main(167)|lastcheckin time updated for
management node 1: 2011-11-02 12:58:41
2011-11-02 
12:58:41|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
1093)|reservation remote IP is not defined
2011-11-02 
12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 7:
waiting for vmguest-1 to respond to SSH
2011-11-02 
12:58:42|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
responding to SSH, ports 22 or 24 are both closed
2011-11-02 
12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 7:
code returned false, seconds elapsed/remaining: 51/849, sleeping for 8
seconds
2011-11-02 
12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
output:
|15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
directory
2011-11-02 12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
command executed on vmguest-3, command:
|15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
|15314|780:780|new| returning (127, "bash: C:/Windows/System32/sc.e...")

|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:43|15314|780:780|new|Windows.pm:set_service_startup_mode(3644)|failed
 to set sshd service startup mode to auto, exit status: 127, output:
|15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
directory
|15314|780:780|new| ( 0) Windows.pm, set_service_startup_mode (line: 3644)
|15314|780:780|new| (-1) Windows.pm, post_load (line: 639)
|15314|780:780|new| (-2) VMware.pm, load (line: 415)
|15314|780:780|new| (-3) new.pm, reload_image (line: 618)
|15314|780:780|new| (-4) new.pm, process (line: 248)
|15314|780:780|new| (-5) vcld, make_new_child (line: 568)


|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:43|15314|780:780|new|Windows.pm:post_load(640)|unable to set sshd
service startup mode to auto
|15314|780:780|new| ( 0) Windows.pm, post_load (line: 640)
|15314|780:780|new| (-1) VMware.pm, load (line: 415)
|15314|780:780|new| (-2) new.pm, reload_image (line: 618)
|15314|780:780|new| (-3) new.pm, process (line: 248)
|15314|780:780|new| (-4) vcld, make_new_child (line: 568)
|15314|780:780|new| (-5) vcld, main (line: 346)


|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:43|15314|780:780|new|VMware.pm:load(419)|failed to perform OS
post-load tasks on VM vmguest-3 on VM host: CSB303
|15314|780:780|new| ( 0) VMware.pm, load (line: 419)
|15314|780:780|new| (-1) new.pm, reload_image (line: 618)
|15314|780:780|new| (-2) new.pm, process (line: 248)
|15314|780:780|new| (-3) vcld, make_new_child (line: 568)
|15314|780:780|new| (-4) vcld, main (line: 346)


|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:43|15314|780:780|new|new.pm:reload_image(623)|vmwarewinxp-WindowsXPwi
thVisualStudio32-v0 failed to load on vmguest-3, returning
|15314|780:780|new| ( 0) new.pm, reload_image (line: 623)
|15314|780:780|new| (-1) new.pm, process (line: 248)
|15314|780:780|new| (-2) vcld, make_new_child (line: 568)
|15314|780:780|new| (-3) vcld, main (line: 346)

2011-11-02 
12:58:43|15314|780:780|new|utils.pm:insertloadlog(3875)|inserted
computer=4, loadimagefailed, vmwarewinxp-WindowsXPwithVisualStudio32-v0
failed to load on vmguest-3

|15314|780:780|new| ---- WARNING ----
|15314|780:780|new| 2011-11-02
12:58:43|15314|780:780|new|new.pm:process(295)|failed to load vmguest-3
with vmwarewinxp-WindowsXPwithVisualStudio32-v0
|15314|780:780|new| ( 0) new.pm, process (line: 295)
|15314|780:780|new| (-1) vcld, make_new_child (line: 568)
|15314|780:780|new| (-2) vcld, main (line: 346)

2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2305)|a
ttempting to retrieve current state of computer vmguest-3 from the database
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2336)|r
etrieved current state of computer vmguest-3 from the database: reloading
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:_automethod(834)|data
structure updated: 
$self->request_data->{reservation}{780}{computer}{state}{name}
|15314|780:780|new| computer_state_name = reloading
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_address
(1581)|attempting to retrieve private IP address for computer: vmguest-3
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_address
(1585)|retrieved contents of /etc/hosts on this management node, contains
44 lines
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_address
(1645)|returning IP address from /etc/hosts file: 10.10.10.4
2011-11-02 
12:58:43|15314|780:780|new|utils.pm:is_inblockrequest(6163)|zero rows were
returned from database select
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:get_image_affiliation_name(2035
)|image owner id: 1
2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:localhost)
2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1352)|attemp
ting to retrieve and store data for user: user.id = '1'
2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:localhost)
2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-11-02 
12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1415)|data
has been retrieved for user: admin (id: 1)
2011-11-02 12:58:43|15314|780:780|new|utils.pm:mail(1268)|SUCCESS --
Sending mail To: 0, PROBLEM --
780:780|new|State.pm|vmguest-3>CSB303|vmwarewinxp-WindowsXPwithVisualStudio
32-v0|admin





|15314|780:780|new| ---- CRITICAL ----
|15314|780:780|new| 2011-11-02
12:58:43|15314|780:780|new|State.pm:reservation_failed(213)|reservation
failed on vmguest-3: process failed after trying to load or make available
|15314|780:780|new| ( 0) State.pm, reservation_failed (line: 213)
|15314|780:780|new| (-1) new.pm, process (line: 298)
|15314|780:780|new| (-2) vcld, make_new_child (line: 568)
|15314|780:780|new| (-3) vcld, main (line: 346)

Re: another problem for image reservation

["Huang,Lei" <lh...@PVAMU.EDU>]

Aaron,

   I tested it again using the two modules. However, both of reservations
worked this time. I will do more tests to see in what situation the
failure happens. I am still attaching the log to let you see the
differences. The blade name is CSB303, and the vmguest is vmguest-3.

Thanks,
Lei




On 11/2/11 2:17 PM, "Aaron Peeler" <aa...@ncsu.edu> wrote:

>Can you send log output of a reservation flow working with the old
>module and a 2nd reservation flow of it not working using the
>recommended vmware module?
>
>I'd have to trace through it to see what is going on. We want to move
>away from the older GSX module.
>
>It's also strange that is works for one and not the other. Both
>modules using ssh <nodename> to get on the node.
>
>Thanks,
>Aaron
>
>
>On Wed, Nov 2, 2011 at 3:10 PM, Huang,Lei <lh...@pvamu.edu> wrote:
>> Aaron,
>>
>>  Thanks. The question is why the old module using Vmware Server GSX
>>works.
>> If I switch back to Vmware server GSX, windows image works fine.In this
>> case, ssh can log into the image. Do I need to switch back?
>>
>> Thanks,
>> Lei
>>
>> On 11/2/11 1:37 PM, "Aaron Peeler" <fa...@ncsu.edu> wrote:
>>
>>>Hi Lei,
>>>
>>>The issue in the log output is related to ssh is not responding.
>>>
>>>Double check that you can log into vmguest-1 or vmguest-3 via ssh (you
>>>have a couple of different reservations in your log output).
>>>
>>>Things to check are to;
>>>make sure your vmguest-X machines are getting the correct dhcp IP
>>>address.
>>>make sure cygwin sshd service is started.
>>>
>>>Aaron
>>>
>>>
>>>
>>>On Wed, Nov 2, 2011 at 2:22 PM, Huang,Lei <lh...@pvamu.edu> wrote:
>>>> Hi All,
>>>>
>>>>  I have encountered another problem after upgraded to 2.2.1. Per
>>>>Aaron's
>>>> suggestion, I changed my Vmguests provisioning engine to Vmware from
>>>> Vmware Server GSX. However, my Windows XP image failed to reserve
>>>>after
>>>> the change. From the log, I found that it looks for
>>>> 'C:/Windows/System32/qwinsta.exe' and 'C:/Windows/System32/sc.exe
>>>>config
>>>> sshd start= auto'. However, both them are not found on my XP image.
>>>>Do I
>>>> need to update my XP image? The image works fine on Vmware Server GSX
>>>> provision engine. The following is the log.
>>>>
>>>> Thanks,
>>>> Lei
>>>>
>>>> ======================
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:21|15314|780:780|new|Windows.pm:is_64_bit(8095)|unable to find
>>>> registry value line in reg.exe output:
>>>> |15314|780:780|new| bash: reg.exe: command not found
>>>> |15314|780:780|new| ( 0) Windows.pm, is_64_bit (line: 8095)
>>>> |15314|780:780|new| (-1) Windows.pm, get_system32_path (line: 8146)
>>>> |15314|780:780|new| (-2) Windows.pm, user_logged_in (line: 8342)
>>>> |15314|780:780|new| (-3) Windows.pm, wait_for_logoff (line: 8440)
>>>> |15314|780:780|new| (-4) Windows.pm, post_load (line: 602)
>>>> |15314|780:780|new| (-5) VMware.pm, load (line: 415)
>>>>
>>>> 2011-11-02
>>>> 12:58:21|15314|780:780|new|Windows.pm:get_system32_path(8152)|32-bit
>>>> Windows OS installed on vmguest-3, using C:/Windows/System32
>>>> 2011-11-02
>>>> 12:58:21|15314|780:780|new|Windows.pm:user_logged_in(8356)|checking if
>>>> root is logged in to vmguest-3
>>>> 2011-11-02
>>>> 12:58:21|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing
>>>>SSH
>>>> command on vmguest-3:
>>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>>> 2011-11-02
>>>> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 5:
>>>> waiting for vmguest-1 to respond to SSH
>>>> 2011-11-02
>>>> 12:58:25|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is
>>>>NOT
>>>> responding to SSH, ports 22 or 24 are both closed
>>>> 2011-11-02
>>>> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 5:
>>>> code returned false, seconds elapsed/remaining: 34/866, sleeping for 8
>>>> seconds
>>>> 2011-11-02 12:58:26|12831|vcld:main(167)|lastcheckin time updated for
>>>> management node 1: 2011-11-02 12:58:26
>>>> 2011-11-02
>>>>
>>>>12:58:26|15410|778:778|reserved|DataStructure.pm:get_reservation_remote
>>>>_i
>>>>p(
>>>> 1093)|reservation remote IP is not defined
>>>> 2011-11-02
>>>>
>>>>12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_comma
>>>>nd
>>>> output:
>>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such
>>>>file
>>>>or
>>>> directory
>>>> 2011-11-02
>>>>12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>>>> command executed on vmguest-3, command:
>>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>>> |15314|780:780|new| returning (127, "bash:
>>>>C:/Windows/System32/qwin...")
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:27|15314|780:780|new|Windows.pm:user_logged_in(8361)|failed to
>>>>run
>>>> qwinsta.exe on vmguest-3, exit status: 127, output:
>>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such
>>>>file
>>>>or
>>>> directory
>>>> |15314|780:780|new| ( 0) Windows.pm, user_logged_in (line: 8361)
>>>> |15314|780:780|new| (-1) Windows.pm, wait_for_logoff (line: 8440)
>>>> |15314|780:780|new| (-2) Windows.pm, post_load (line: 602)
>>>> |15314|780:780|new| (-3) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-4) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-5) new.pm, process (line: 248)
>>>>
>>>> 2011-11-02
>>>> 12:58:27|15314|780:780|new|Windows.pm:wait_for_logoff(8441)|root is
>>>>NOT
>>>> logged in to vmguest-3, returning 1
>>>> 2011-11-02
>>>> 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing
>>>>SSH
>>>> command on vmguest-3:
>>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>>> 2011-11-02 12:58:31|12831|vcld:main(167)|lastcheckin time updated for
>>>> management node 1: 2011-11-02 12:58:31
>>>> 2011-11-02
>>>>
>>>>12:58:31|15410|778:778|reserved|DataStructure.pm:get_reservation_remote
>>>>_i
>>>>p(
>>>> 1093)|reservation remote IP is not defined
>>>> 2011-11-02
>>>>
>>>>12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_comma
>>>>nd
>>>> output:
>>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such
>>>>file
>>>>or
>>>> directory
>>>> 2011-11-02
>>>>12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>>>> command executed on vmguest-3, command:
>>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>>> |15314|780:780|new| returning (127, "bash:
>>>>C:/Windows/System32/qwin...")
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:32|15314|780:780|new|Windows.pm:logoff_users(1437)|failed to run
>>>> qwinsta.exe on vmguest-3, exit status: 127, output:
>>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such
>>>>file
>>>>or
>>>> directory
>>>> |15314|780:780|new| ( 0) Windows.pm, logoff_users (line: 1437)
>>>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 615)
>>>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>>>
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:32|15314|780:780|new|Windows.pm:post_load(615)|failed to log off
>>>>all
>>>> currently logged in users
>>>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 615)
>>>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>>>
>>>> 2011-11-02
>>>> 12:58:33|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 6:
>>>> waiting for vmguest-1 to respond to SSH
>>>> 2011-11-02
>>>> 12:58:34|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is
>>>>NOT
>>>> responding to SSH, ports 22 or 24 are both closed
>>>> 2011-11-02
>>>> 12:58:34|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 6:
>>>> code returned false, seconds elapsed/remaining: 43/857, sleeping for 8
>>>> seconds
>>>> 2011-11-02 12:58:36|12831|vcld:main(167)|lastcheckin time updated for
>>>> management node 1: 2011-11-02 12:58:36
>>>> 2011-11-02
>>>>
>>>>12:58:36|15410|778:778|reserved|DataStructure.pm:get_reservation_remote
>>>>_i
>>>>p(
>>>> 1093)|reservation remote IP is not defined
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:37|15314|780:780|new|Windows.pm:set_file_owner(1398)|error
>>>>occurred
>>>> setting root as the owner of /home/root, error output:
>>>> |15314|780:780|new| bash: /usr/bin/chown.exe: No such file or
>>>>directory
>>>> |15314|780:780|new| ( 0) Windows.pm, set_file_owner (line: 1398)
>>>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 625)
>>>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>>>
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:37|15314|780:780|new|Windows.pm:post_load(625)|unable to set
>>>>root
>>>>as
>>>> the owner of /home/root
>>>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 625)
>>>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>>>
>>>> 2011-11-02
>>>> 12:58:37|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing
>>>>SSH
>>>> command on vmguest-3:
>>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>>> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
>>>> 2011-11-02 12:58:41|12831|vcld:main(167)|lastcheckin time updated for
>>>> management node 1: 2011-11-02 12:58:41
>>>> 2011-11-02
>>>>
>>>>12:58:41|15410|778:778|reserved|DataStructure.pm:get_reservation_remote
>>>>_i
>>>>p(
>>>> 1093)|reservation remote IP is not defined
>>>> 2011-11-02
>>>> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 7:
>>>> waiting for vmguest-1 to respond to SSH
>>>> 2011-11-02
>>>> 12:58:42|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is
>>>>NOT
>>>> responding to SSH, ports 22 or 24 are both closed
>>>> 2011-11-02
>>>> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 7:
>>>> code returned false, seconds elapsed/remaining: 51/849, sleeping for 8
>>>> seconds
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_comma
>>>>nd
>>>> output:
>>>> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
>>>> directory
>>>> 2011-11-02
>>>>12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>>>> command executed on vmguest-3, command:
>>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>>> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
>>>> |15314|780:780|new| returning (127, "bash:
>>>>C:/Windows/System32/sc.e...")
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|Windows.pm:set_service_startup_mode(3644)|fa
>>>>il
>>>>ed
>>>>  to set sshd service startup mode to auto, exit status: 127, output:
>>>> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
>>>> directory
>>>> |15314|780:780|new| ( 0) Windows.pm, set_service_startup_mode (line:
>>>>3644)
>>>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 639)
>>>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>>>
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:43|15314|780:780|new|Windows.pm:post_load(640)|unable to set
>>>>sshd
>>>> service startup mode to auto
>>>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 640)
>>>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>>>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>>>
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:43|15314|780:780|new|VMware.pm:load(419)|failed to perform OS
>>>> post-load tasks on VM vmguest-3 on VM host: CSB303
>>>> |15314|780:780|new| ( 0) VMware.pm, load (line: 419)
>>>> |15314|780:780|new| (-1) new.pm, reload_image (line: 618)
>>>> |15314|780:780|new| (-2) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-3) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-4) vcld, main (line: 346)
>>>>
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|new.pm:reload_image(623)|vmwarewinxp-Windows
>>>>XP
>>>>wi
>>>> thVisualStudio32-v0 failed to load on vmguest-3, returning
>>>> |15314|780:780|new| ( 0) new.pm, reload_image (line: 623)
>>>> |15314|780:780|new| (-1) new.pm, process (line: 248)
>>>> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-3) vcld, main (line: 346)
>>>>
>>>> 2011-11-02
>>>> 12:58:43|15314|780:780|new|utils.pm:insertloadlog(3875)|inserted
>>>> computer=4, loadimagefailed,
>>>>vmwarewinxp-WindowsXPwithVisualStudio32-v0
>>>> failed to load on vmguest-3
>>>>
>>>> |15314|780:780|new| ---- WARNING ----
>>>> |15314|780:780|new| 2011-11-02
>>>> 12:58:43|15314|780:780|new|new.pm:process(295)|failed to load
>>>>vmguest-3
>>>> with vmwarewinxp-WindowsXPwithVisualStudio32-v0
>>>> |15314|780:780|new| ( 0) new.pm, process (line: 295)
>>>> |15314|780:780|new| (-1) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-2) vcld, main (line: 346)
>>>>
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(230
>>>>5)
>>>>|a
>>>> ttempting to retrieve current state of computer vmguest-3 from the
>>>>database
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(233
>>>>6)
>>>>|r
>>>> etrieved current state of computer vmguest-3 from the database:
>>>>reloading
>>>> 2011-11-02
>>>> 12:58:43|15314|780:780|new|DataStructure.pm:_automethod(834)|data
>>>> structure updated:
>>>> $self->request_data->{reservation}{780}{computer}{state}{name}
>>>> |15314|780:780|new| computer_state_name = reloading
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_add
>>>>re
>>>>ss
>>>> (1581)|attempting to retrieve private IP address for computer:
>>>>vmguest-3
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_add
>>>>re
>>>>ss
>>>> (1585)|retrieved contents of /etc/hosts on this management node,
>>>>contains
>>>> 44 lines
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_add
>>>>re
>>>>ss
>>>> (1645)|returning IP address from /etc/hosts file: 10.10.10.4
>>>> 2011-11-02
>>>> 12:58:43|15314|780:780|new|utils.pm:is_inblockrequest(6163)|zero rows
>>>>were
>>>> returned from database select
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:get_image_affiliation_name(
>>>>20
>>>>35
>>>> )|image owner id: 1
>>>> 2011-11-02
>>>>12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
>>>> requested (information_schema) does not match handle stored in
>>>>$ENV{dbh}
>>>> (vcl:localhost)
>>>> 2011-11-02
>>>>12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
>>>> handle stored in $ENV{dbh}
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1352)|at
>>>>te
>>>>mp
>>>> ting to retrieve and store data for user: user.id = '1'
>>>> 2011-11-02
>>>>12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
>>>> requested (vcl) does not match handle stored in $ENV{dbh}
>>>> (information_schema:localhost)
>>>> 2011-11-02
>>>>12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
>>>> handle stored in $ENV{dbh}
>>>> 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1415)|da
>>>>ta
>>>> has been retrieved for user: admin (id: 1)
>>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:mail(1268)|SUCCESS --
>>>> Sending mail To: 0, PROBLEM --
>>>>
>>>>780:780|new|State.pm|vmguest-3>CSB303|vmwarewinxp-WindowsXPwithVisualSt
>>>>ud
>>>>io
>>>> 32-v0|admin
>>>>
>>>>
>>>>
>>>>
>>>>
>>>> |15314|780:780|new| ---- CRITICAL ----
>>>> |15314|780:780|new| 2011-11-02
>>>>
>>>>12:58:43|15314|780:780|new|State.pm:reservation_failed(213)|reservation
>>>> failed on vmguest-3: process failed after trying to load or make
>>>>available
>>>> |15314|780:780|new| ( 0) State.pm, reservation_failed (line: 213)
>>>> |15314|780:780|new| (-1) new.pm, process (line: 298)
>>>> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
>>>> |15314|780:780|new| (-3) vcld, main (line: 346)
>>>>
>>>>
>>>
>>>
>>>
>>>--
>>>Aaron Peeler
>>>Program Manager
>>>Virtual Computing Lab
>>>NC State University
>>>
>>>All electronic mail messages in connection with State business which
>>>are sent to or received by this account are subject to the NC Public
>>>Records Law and may be disclosed to third parties.
>>
>>
>
>
>
>--
>Aaron Peeler
>Program Manager
>Virtual Computing Lab
>NC State University
>
>All electronic mail messages in connection with State business which
>are sent to or received by this account are subject to the NC Public
>Records Law and may be disclosed to third parties.

Re: another problem for image reservation

[Aaron Peeler <aa...@ncsu.edu>]

Can you send log output of a reservation flow working with the old
module and a 2nd reservation flow of it not working using the
recommended vmware module?

I'd have to trace through it to see what is going on. We want to move
away from the older GSX module.

It's also strange that is works for one and not the other. Both
modules using ssh <nodename> to get on the node.

Thanks,
Aaron


On Wed, Nov 2, 2011 at 3:10 PM, Huang,Lei <lh...@pvamu.edu> wrote:
> Aaron,
>
>  Thanks. The question is why the old module using Vmware Server GSX works.
> If I switch back to Vmware server GSX, windows image works fine.In this
> case, ssh can log into the image. Do I need to switch back?
>
> Thanks,
> Lei
>
> On 11/2/11 1:37 PM, "Aaron Peeler" <fa...@ncsu.edu> wrote:
>
>>Hi Lei,
>>
>>The issue in the log output is related to ssh is not responding.
>>
>>Double check that you can log into vmguest-1 or vmguest-3 via ssh (you
>>have a couple of different reservations in your log output).
>>
>>Things to check are to;
>>make sure your vmguest-X machines are getting the correct dhcp IP address.
>>make sure cygwin sshd service is started.
>>
>>Aaron
>>
>>
>>
>>On Wed, Nov 2, 2011 at 2:22 PM, Huang,Lei <lh...@pvamu.edu> wrote:
>>> Hi All,
>>>
>>>  I have encountered another problem after upgraded to 2.2.1. Per Aaron's
>>> suggestion, I changed my Vmguests provisioning engine to Vmware from
>>> Vmware Server GSX. However, my Windows XP image failed to reserve after
>>> the change. From the log, I found that it looks for
>>> 'C:/Windows/System32/qwinsta.exe' and 'C:/Windows/System32/sc.exe config
>>> sshd start= auto'. However, both them are not found on my XP image. Do I
>>> need to update my XP image? The image works fine on Vmware Server GSX
>>> provision engine. The following is the log.
>>>
>>> Thanks,
>>> Lei
>>>
>>> ======================
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:21|15314|780:780|new|Windows.pm:is_64_bit(8095)|unable to find
>>> registry value line in reg.exe output:
>>> |15314|780:780|new| bash: reg.exe: command not found
>>> |15314|780:780|new| ( 0) Windows.pm, is_64_bit (line: 8095)
>>> |15314|780:780|new| (-1) Windows.pm, get_system32_path (line: 8146)
>>> |15314|780:780|new| (-2) Windows.pm, user_logged_in (line: 8342)
>>> |15314|780:780|new| (-3) Windows.pm, wait_for_logoff (line: 8440)
>>> |15314|780:780|new| (-4) Windows.pm, post_load (line: 602)
>>> |15314|780:780|new| (-5) VMware.pm, load (line: 415)
>>>
>>> 2011-11-02
>>> 12:58:21|15314|780:780|new|Windows.pm:get_system32_path(8152)|32-bit
>>> Windows OS installed on vmguest-3, using C:/Windows/System32
>>> 2011-11-02
>>> 12:58:21|15314|780:780|new|Windows.pm:user_logged_in(8356)|checking if
>>> root is logged in to vmguest-3
>>> 2011-11-02
>>> 12:58:21|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
>>> command on vmguest-3:
>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>> 2011-11-02
>>> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 5:
>>> waiting for vmguest-1 to respond to SSH
>>> 2011-11-02
>>> 12:58:25|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
>>> responding to SSH, ports 22 or 24 are both closed
>>> 2011-11-02
>>> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 5:
>>> code returned false, seconds elapsed/remaining: 34/866, sleeping for 8
>>> seconds
>>> 2011-11-02 12:58:26|12831|vcld:main(167)|lastcheckin time updated for
>>> management node 1: 2011-11-02 12:58:26
>>> 2011-11-02
>>>
>>>12:58:26|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>>p(
>>> 1093)|reservation remote IP is not defined
>>> 2011-11-02
>>>
>>>12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
>>> output:
>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>>or
>>> directory
>>> 2011-11-02 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>>> command executed on vmguest-3, command:
>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:27|15314|780:780|new|Windows.pm:user_logged_in(8361)|failed to run
>>> qwinsta.exe on vmguest-3, exit status: 127, output:
>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>>or
>>> directory
>>> |15314|780:780|new| ( 0) Windows.pm, user_logged_in (line: 8361)
>>> |15314|780:780|new| (-1) Windows.pm, wait_for_logoff (line: 8440)
>>> |15314|780:780|new| (-2) Windows.pm, post_load (line: 602)
>>> |15314|780:780|new| (-3) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-4) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-5) new.pm, process (line: 248)
>>>
>>> 2011-11-02
>>> 12:58:27|15314|780:780|new|Windows.pm:wait_for_logoff(8441)|root is NOT
>>> logged in to vmguest-3, returning 1
>>> 2011-11-02
>>> 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
>>> command on vmguest-3:
>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>> 2011-11-02 12:58:31|12831|vcld:main(167)|lastcheckin time updated for
>>> management node 1: 2011-11-02 12:58:31
>>> 2011-11-02
>>>
>>>12:58:31|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>>p(
>>> 1093)|reservation remote IP is not defined
>>> 2011-11-02
>>>
>>>12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
>>> output:
>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>>or
>>> directory
>>> 2011-11-02 12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>>> command executed on vmguest-3, command:
>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>>> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:32|15314|780:780|new|Windows.pm:logoff_users(1437)|failed to run
>>> qwinsta.exe on vmguest-3, exit status: 127, output:
>>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>>or
>>> directory
>>> |15314|780:780|new| ( 0) Windows.pm, logoff_users (line: 1437)
>>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 615)
>>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>>
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:32|15314|780:780|new|Windows.pm:post_load(615)|failed to log off
>>>all
>>> currently logged in users
>>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 615)
>>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>>
>>> 2011-11-02
>>> 12:58:33|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 6:
>>> waiting for vmguest-1 to respond to SSH
>>> 2011-11-02
>>> 12:58:34|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
>>> responding to SSH, ports 22 or 24 are both closed
>>> 2011-11-02
>>> 12:58:34|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 6:
>>> code returned false, seconds elapsed/remaining: 43/857, sleeping for 8
>>> seconds
>>> 2011-11-02 12:58:36|12831|vcld:main(167)|lastcheckin time updated for
>>> management node 1: 2011-11-02 12:58:36
>>> 2011-11-02
>>>
>>>12:58:36|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>>p(
>>> 1093)|reservation remote IP is not defined
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:37|15314|780:780|new|Windows.pm:set_file_owner(1398)|error
>>>occurred
>>> setting root as the owner of /home/root, error output:
>>> |15314|780:780|new| bash: /usr/bin/chown.exe: No such file or directory
>>> |15314|780:780|new| ( 0) Windows.pm, set_file_owner (line: 1398)
>>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 625)
>>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>>
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:37|15314|780:780|new|Windows.pm:post_load(625)|unable to set root
>>>as
>>> the owner of /home/root
>>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 625)
>>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>>
>>> 2011-11-02
>>> 12:58:37|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
>>> command on vmguest-3:
>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
>>> 2011-11-02 12:58:41|12831|vcld:main(167)|lastcheckin time updated for
>>> management node 1: 2011-11-02 12:58:41
>>> 2011-11-02
>>>
>>>12:58:41|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>>p(
>>> 1093)|reservation remote IP is not defined
>>> 2011-11-02
>>> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 7:
>>> waiting for vmguest-1 to respond to SSH
>>> 2011-11-02
>>> 12:58:42|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
>>> responding to SSH, ports 22 or 24 are both closed
>>> 2011-11-02
>>> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 7:
>>> code returned false, seconds elapsed/remaining: 51/849, sleeping for 8
>>> seconds
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
>>> output:
>>> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
>>> directory
>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>>> command executed on vmguest-3, command:
>>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>>> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
>>> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/sc.e...")
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|Windows.pm:set_service_startup_mode(3644)|fail
>>>ed
>>>  to set sshd service startup mode to auto, exit status: 127, output:
>>> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
>>> directory
>>> |15314|780:780|new| ( 0) Windows.pm, set_service_startup_mode (line:
>>>3644)
>>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 639)
>>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>>
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:43|15314|780:780|new|Windows.pm:post_load(640)|unable to set sshd
>>> service startup mode to auto
>>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 640)
>>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>>
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:43|15314|780:780|new|VMware.pm:load(419)|failed to perform OS
>>> post-load tasks on VM vmguest-3 on VM host: CSB303
>>> |15314|780:780|new| ( 0) VMware.pm, load (line: 419)
>>> |15314|780:780|new| (-1) new.pm, reload_image (line: 618)
>>> |15314|780:780|new| (-2) new.pm, process (line: 248)
>>> |15314|780:780|new| (-3) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-4) vcld, main (line: 346)
>>>
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|new.pm:reload_image(623)|vmwarewinxp-WindowsXP
>>>wi
>>> thVisualStudio32-v0 failed to load on vmguest-3, returning
>>> |15314|780:780|new| ( 0) new.pm, reload_image (line: 623)
>>> |15314|780:780|new| (-1) new.pm, process (line: 248)
>>> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-3) vcld, main (line: 346)
>>>
>>> 2011-11-02
>>> 12:58:43|15314|780:780|new|utils.pm:insertloadlog(3875)|inserted
>>> computer=4, loadimagefailed, vmwarewinxp-WindowsXPwithVisualStudio32-v0
>>> failed to load on vmguest-3
>>>
>>> |15314|780:780|new| ---- WARNING ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:43|15314|780:780|new|new.pm:process(295)|failed to load vmguest-3
>>> with vmwarewinxp-WindowsXPwithVisualStudio32-v0
>>> |15314|780:780|new| ( 0) new.pm, process (line: 295)
>>> |15314|780:780|new| (-1) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-2) vcld, main (line: 346)
>>>
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2305)
>>>|a
>>> ttempting to retrieve current state of computer vmguest-3 from the
>>>database
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2336)
>>>|r
>>> etrieved current state of computer vmguest-3 from the database:
>>>reloading
>>> 2011-11-02
>>> 12:58:43|15314|780:780|new|DataStructure.pm:_automethod(834)|data
>>> structure updated:
>>> $self->request_data->{reservation}{780}{computer}{state}{name}
>>> |15314|780:780|new| computer_state_name = reloading
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_addre
>>>ss
>>> (1581)|attempting to retrieve private IP address for computer: vmguest-3
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_addre
>>>ss
>>> (1585)|retrieved contents of /etc/hosts on this management node,
>>>contains
>>> 44 lines
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_addre
>>>ss
>>> (1645)|returning IP address from /etc/hosts file: 10.10.10.4
>>> 2011-11-02
>>> 12:58:43|15314|780:780|new|utils.pm:is_inblockrequest(6163)|zero rows
>>>were
>>> returned from database select
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:get_image_affiliation_name(20
>>>35
>>> )|image owner id: 1
>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
>>> requested (information_schema) does not match handle stored in $ENV{dbh}
>>> (vcl:localhost)
>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
>>> handle stored in $ENV{dbh}
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1352)|atte
>>>mp
>>> ting to retrieve and store data for user: user.id = '1'
>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
>>> requested (vcl) does not match handle stored in $ENV{dbh}
>>> (information_schema:localhost)
>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
>>> handle stored in $ENV{dbh}
>>> 2011-11-02
>>>
>>>12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1415)|data
>>> has been retrieved for user: admin (id: 1)
>>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:mail(1268)|SUCCESS --
>>> Sending mail To: 0, PROBLEM --
>>>
>>>780:780|new|State.pm|vmguest-3>CSB303|vmwarewinxp-WindowsXPwithVisualStud
>>>io
>>> 32-v0|admin
>>>
>>>
>>>
>>>
>>>
>>> |15314|780:780|new| ---- CRITICAL ----
>>> |15314|780:780|new| 2011-11-02
>>> 12:58:43|15314|780:780|new|State.pm:reservation_failed(213)|reservation
>>> failed on vmguest-3: process failed after trying to load or make
>>>available
>>> |15314|780:780|new| ( 0) State.pm, reservation_failed (line: 213)
>>> |15314|780:780|new| (-1) new.pm, process (line: 298)
>>> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
>>> |15314|780:780|new| (-3) vcld, main (line: 346)
>>>
>>>
>>
>>
>>
>>--
>>Aaron Peeler
>>Program Manager
>>Virtual Computing Lab
>>NC State University
>>
>>All electronic mail messages in connection with State business which
>>are sent to or received by this account are subject to the NC Public
>>Records Law and may be disclosed to third parties.
>
>



-- 
Aaron Peeler
Program Manager
Virtual Computing Lab
NC State University

All electronic mail messages in connection with State business which
are sent to or received by this account are subject to the NC Public
Records Law and may be disclosed to third parties.

Re: another problem for image reservation

["Huang,Lei" <lh...@PVAMU.EDU>]

Aaron,

 Thanks. The question is why the old module using Vmware Server GSX works.
If I switch back to Vmware server GSX, windows image works fine.In this
case, ssh can log into the image. Do I need to switch back?

Thanks,
Lei

On 11/2/11 1:37 PM, "Aaron Peeler" <fa...@ncsu.edu> wrote:

>Hi Lei,
>
>The issue in the log output is related to ssh is not responding.
>
>Double check that you can log into vmguest-1 or vmguest-3 via ssh (you
>have a couple of different reservations in your log output).
>
>Things to check are to;
>make sure your vmguest-X machines are getting the correct dhcp IP address.
>make sure cygwin sshd service is started.
>
>Aaron
>
>
>
>On Wed, Nov 2, 2011 at 2:22 PM, Huang,Lei <lh...@pvamu.edu> wrote:
>> Hi All,
>>
>>  I have encountered another problem after upgraded to 2.2.1. Per Aaron's
>> suggestion, I changed my Vmguests provisioning engine to Vmware from
>> Vmware Server GSX. However, my Windows XP image failed to reserve after
>> the change. From the log, I found that it looks for
>> 'C:/Windows/System32/qwinsta.exe' and 'C:/Windows/System32/sc.exe config
>> sshd start= auto'. However, both them are not found on my XP image. Do I
>> need to update my XP image? The image works fine on Vmware Server GSX
>> provision engine. The following is the log.
>>
>> Thanks,
>> Lei
>>
>> ======================
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:21|15314|780:780|new|Windows.pm:is_64_bit(8095)|unable to find
>> registry value line in reg.exe output:
>> |15314|780:780|new| bash: reg.exe: command not found
>> |15314|780:780|new| ( 0) Windows.pm, is_64_bit (line: 8095)
>> |15314|780:780|new| (-1) Windows.pm, get_system32_path (line: 8146)
>> |15314|780:780|new| (-2) Windows.pm, user_logged_in (line: 8342)
>> |15314|780:780|new| (-3) Windows.pm, wait_for_logoff (line: 8440)
>> |15314|780:780|new| (-4) Windows.pm, post_load (line: 602)
>> |15314|780:780|new| (-5) VMware.pm, load (line: 415)
>>
>> 2011-11-02
>> 12:58:21|15314|780:780|new|Windows.pm:get_system32_path(8152)|32-bit
>> Windows OS installed on vmguest-3, using C:/Windows/System32
>> 2011-11-02
>> 12:58:21|15314|780:780|new|Windows.pm:user_logged_in(8356)|checking if
>> root is logged in to vmguest-3
>> 2011-11-02
>> 12:58:21|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
>> command on vmguest-3:
>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>> 2011-11-02
>> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 5:
>> waiting for vmguest-1 to respond to SSH
>> 2011-11-02
>> 12:58:25|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
>> responding to SSH, ports 22 or 24 are both closed
>> 2011-11-02
>> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 5:
>> code returned false, seconds elapsed/remaining: 34/866, sleeping for 8
>> seconds
>> 2011-11-02 12:58:26|12831|vcld:main(167)|lastcheckin time updated for
>> management node 1: 2011-11-02 12:58:26
>> 2011-11-02
>>
>>12:58:26|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>p(
>> 1093)|reservation remote IP is not defined
>> 2011-11-02
>>
>>12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
>> output:
>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>or
>> directory
>> 2011-11-02 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>> command executed on vmguest-3, command:
>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:27|15314|780:780|new|Windows.pm:user_logged_in(8361)|failed to run
>> qwinsta.exe on vmguest-3, exit status: 127, output:
>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>or
>> directory
>> |15314|780:780|new| ( 0) Windows.pm, user_logged_in (line: 8361)
>> |15314|780:780|new| (-1) Windows.pm, wait_for_logoff (line: 8440)
>> |15314|780:780|new| (-2) Windows.pm, post_load (line: 602)
>> |15314|780:780|new| (-3) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-4) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-5) new.pm, process (line: 248)
>>
>> 2011-11-02
>> 12:58:27|15314|780:780|new|Windows.pm:wait_for_logoff(8441)|root is NOT
>> logged in to vmguest-3, returning 1
>> 2011-11-02
>> 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
>> command on vmguest-3:
>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>> 2011-11-02 12:58:31|12831|vcld:main(167)|lastcheckin time updated for
>> management node 1: 2011-11-02 12:58:31
>> 2011-11-02
>>
>>12:58:31|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>p(
>> 1093)|reservation remote IP is not defined
>> 2011-11-02
>>
>>12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
>> output:
>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>or
>> directory
>> 2011-11-02 12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>> command executed on vmguest-3, command:
>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>> 'C:/Windows/System32/qwinsta.exe' 2>&1
>> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:32|15314|780:780|new|Windows.pm:logoff_users(1437)|failed to run
>> qwinsta.exe on vmguest-3, exit status: 127, output:
>> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file
>>or
>> directory
>> |15314|780:780|new| ( 0) Windows.pm, logoff_users (line: 1437)
>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 615)
>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:32|15314|780:780|new|Windows.pm:post_load(615)|failed to log off
>>all
>> currently logged in users
>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 615)
>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>
>> 2011-11-02
>> 12:58:33|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 6:
>> waiting for vmguest-1 to respond to SSH
>> 2011-11-02
>> 12:58:34|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
>> responding to SSH, ports 22 or 24 are both closed
>> 2011-11-02
>> 12:58:34|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 6:
>> code returned false, seconds elapsed/remaining: 43/857, sleeping for 8
>> seconds
>> 2011-11-02 12:58:36|12831|vcld:main(167)|lastcheckin time updated for
>> management node 1: 2011-11-02 12:58:36
>> 2011-11-02
>>
>>12:58:36|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>p(
>> 1093)|reservation remote IP is not defined
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:37|15314|780:780|new|Windows.pm:set_file_owner(1398)|error
>>occurred
>> setting root as the owner of /home/root, error output:
>> |15314|780:780|new| bash: /usr/bin/chown.exe: No such file or directory
>> |15314|780:780|new| ( 0) Windows.pm, set_file_owner (line: 1398)
>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 625)
>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:37|15314|780:780|new|Windows.pm:post_load(625)|unable to set root
>>as
>> the owner of /home/root
>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 625)
>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>
>> 2011-11-02
>> 12:58:37|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
>> command on vmguest-3:
>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
>> 2011-11-02 12:58:41|12831|vcld:main(167)|lastcheckin time updated for
>> management node 1: 2011-11-02 12:58:41
>> 2011-11-02
>>
>>12:58:41|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_i
>>p(
>> 1093)|reservation remote IP is not defined
>> 2011-11-02
>> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 7:
>> waiting for vmguest-1 to respond to SSH
>> 2011-11-02
>> 12:58:42|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
>> responding to SSH, ports 22 or 24 are both closed
>> 2011-11-02
>> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 7:
>> code returned false, seconds elapsed/remaining: 51/849, sleeping for 8
>> seconds
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
>> output:
>> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
>> directory
>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
>> command executed on vmguest-3, command:
>> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
>> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
>> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
>> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/sc.e...")
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>>
>>12:58:43|15314|780:780|new|Windows.pm:set_service_startup_mode(3644)|fail
>>ed
>>  to set sshd service startup mode to auto, exit status: 127, output:
>> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
>> directory
>> |15314|780:780|new| ( 0) Windows.pm, set_service_startup_mode (line:
>>3644)
>> |15314|780:780|new| (-1) Windows.pm, post_load (line: 639)
>> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-4) new.pm, process (line: 248)
>> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>>
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:43|15314|780:780|new|Windows.pm:post_load(640)|unable to set sshd
>> service startup mode to auto
>> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 640)
>> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
>> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-3) new.pm, process (line: 248)
>> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-5) vcld, main (line: 346)
>>
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:43|15314|780:780|new|VMware.pm:load(419)|failed to perform OS
>> post-load tasks on VM vmguest-3 on VM host: CSB303
>> |15314|780:780|new| ( 0) VMware.pm, load (line: 419)
>> |15314|780:780|new| (-1) new.pm, reload_image (line: 618)
>> |15314|780:780|new| (-2) new.pm, process (line: 248)
>> |15314|780:780|new| (-3) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-4) vcld, main (line: 346)
>>
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>>
>>12:58:43|15314|780:780|new|new.pm:reload_image(623)|vmwarewinxp-WindowsXP
>>wi
>> thVisualStudio32-v0 failed to load on vmguest-3, returning
>> |15314|780:780|new| ( 0) new.pm, reload_image (line: 623)
>> |15314|780:780|new| (-1) new.pm, process (line: 248)
>> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-3) vcld, main (line: 346)
>>
>> 2011-11-02
>> 12:58:43|15314|780:780|new|utils.pm:insertloadlog(3875)|inserted
>> computer=4, loadimagefailed, vmwarewinxp-WindowsXPwithVisualStudio32-v0
>> failed to load on vmguest-3
>>
>> |15314|780:780|new| ---- WARNING ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:43|15314|780:780|new|new.pm:process(295)|failed to load vmguest-3
>> with vmwarewinxp-WindowsXPwithVisualStudio32-v0
>> |15314|780:780|new| ( 0) new.pm, process (line: 295)
>> |15314|780:780|new| (-1) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-2) vcld, main (line: 346)
>>
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2305)
>>|a
>> ttempting to retrieve current state of computer vmguest-3 from the
>>database
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2336)
>>|r
>> etrieved current state of computer vmguest-3 from the database:
>>reloading
>> 2011-11-02
>> 12:58:43|15314|780:780|new|DataStructure.pm:_automethod(834)|data
>> structure updated:
>> $self->request_data->{reservation}{780}{computer}{state}{name}
>> |15314|780:780|new| computer_state_name = reloading
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_addre
>>ss
>> (1581)|attempting to retrieve private IP address for computer: vmguest-3
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_addre
>>ss
>> (1585)|retrieved contents of /etc/hosts on this management node,
>>contains
>> 44 lines
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_addre
>>ss
>> (1645)|returning IP address from /etc/hosts file: 10.10.10.4
>> 2011-11-02
>> 12:58:43|15314|780:780|new|utils.pm:is_inblockrequest(6163)|zero rows
>>were
>> returned from database select
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:get_image_affiliation_name(20
>>35
>> )|image owner id: 1
>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
>> requested (information_schema) does not match handle stored in $ENV{dbh}
>> (vcl:localhost)
>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
>> handle stored in $ENV{dbh}
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1352)|atte
>>mp
>> ting to retrieve and store data for user: user.id = '1'
>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
>> requested (vcl) does not match handle stored in $ENV{dbh}
>> (information_schema:localhost)
>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
>> handle stored in $ENV{dbh}
>> 2011-11-02
>>
>>12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1415)|data
>> has been retrieved for user: admin (id: 1)
>> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:mail(1268)|SUCCESS --
>> Sending mail To: 0, PROBLEM --
>>
>>780:780|new|State.pm|vmguest-3>CSB303|vmwarewinxp-WindowsXPwithVisualStud
>>io
>> 32-v0|admin
>>
>>
>>
>>
>>
>> |15314|780:780|new| ---- CRITICAL ----
>> |15314|780:780|new| 2011-11-02
>> 12:58:43|15314|780:780|new|State.pm:reservation_failed(213)|reservation
>> failed on vmguest-3: process failed after trying to load or make
>>available
>> |15314|780:780|new| ( 0) State.pm, reservation_failed (line: 213)
>> |15314|780:780|new| (-1) new.pm, process (line: 298)
>> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
>> |15314|780:780|new| (-3) vcld, main (line: 346)
>>
>>
>
>
>
>--
>Aaron Peeler
>Program Manager
>Virtual Computing Lab
>NC State University
>
>All electronic mail messages in connection with State business which
>are sent to or received by this account are subject to the NC Public
>Records Law and may be disclosed to third parties.

Re: another problem for image reservation

[Aaron Peeler <fa...@ncsu.edu>]

Hi Lei,

The issue in the log output is related to ssh is not responding.

Double check that you can log into vmguest-1 or vmguest-3 via ssh (you
have a couple of different reservations in your log output).

Things to check are to;
make sure your vmguest-X machines are getting the correct dhcp IP address.
make sure cygwin sshd service is started.

Aaron



On Wed, Nov 2, 2011 at 2:22 PM, Huang,Lei <lh...@pvamu.edu> wrote:
> Hi All,
>
>  I have encountered another problem after upgraded to 2.2.1. Per Aaron's
> suggestion, I changed my Vmguests provisioning engine to Vmware from
> Vmware Server GSX. However, my Windows XP image failed to reserve after
> the change. From the log, I found that it looks for
> 'C:/Windows/System32/qwinsta.exe' and 'C:/Windows/System32/sc.exe config
> sshd start= auto'. However, both them are not found on my XP image. Do I
> need to update my XP image? The image works fine on Vmware Server GSX
> provision engine. The following is the log.
>
> Thanks,
> Lei
>
> ======================
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:21|15314|780:780|new|Windows.pm:is_64_bit(8095)|unable to find
> registry value line in reg.exe output:
> |15314|780:780|new| bash: reg.exe: command not found
> |15314|780:780|new| ( 0) Windows.pm, is_64_bit (line: 8095)
> |15314|780:780|new| (-1) Windows.pm, get_system32_path (line: 8146)
> |15314|780:780|new| (-2) Windows.pm, user_logged_in (line: 8342)
> |15314|780:780|new| (-3) Windows.pm, wait_for_logoff (line: 8440)
> |15314|780:780|new| (-4) Windows.pm, post_load (line: 602)
> |15314|780:780|new| (-5) VMware.pm, load (line: 415)
>
> 2011-11-02
> 12:58:21|15314|780:780|new|Windows.pm:get_system32_path(8152)|32-bit
> Windows OS installed on vmguest-3, using C:/Windows/System32
> 2011-11-02
> 12:58:21|15314|780:780|new|Windows.pm:user_logged_in(8356)|checking if
> root is logged in to vmguest-3
> 2011-11-02
> 12:58:21|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
> command on vmguest-3:
> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
> 'C:/Windows/System32/qwinsta.exe' 2>&1
> 2011-11-02
> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 5:
> waiting for vmguest-1 to respond to SSH
> 2011-11-02
> 12:58:25|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
> responding to SSH, ports 22 or 24 are both closed
> 2011-11-02
> 12:58:25|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 5:
> code returned false, seconds elapsed/remaining: 34/866, sleeping for 8
> seconds
> 2011-11-02 12:58:26|12831|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-11-02 12:58:26
> 2011-11-02
> 12:58:26|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
> 1093)|reservation remote IP is not defined
> 2011-11-02
> 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
> output:
> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
> directory
> 2011-11-02 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
> command executed on vmguest-3, command:
> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
> 'C:/Windows/System32/qwinsta.exe' 2>&1
> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:27|15314|780:780|new|Windows.pm:user_logged_in(8361)|failed to run
> qwinsta.exe on vmguest-3, exit status: 127, output:
> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
> directory
> |15314|780:780|new| ( 0) Windows.pm, user_logged_in (line: 8361)
> |15314|780:780|new| (-1) Windows.pm, wait_for_logoff (line: 8440)
> |15314|780:780|new| (-2) Windows.pm, post_load (line: 602)
> |15314|780:780|new| (-3) VMware.pm, load (line: 415)
> |15314|780:780|new| (-4) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-5) new.pm, process (line: 248)
>
> 2011-11-02
> 12:58:27|15314|780:780|new|Windows.pm:wait_for_logoff(8441)|root is NOT
> logged in to vmguest-3, returning 1
> 2011-11-02
> 12:58:27|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
> command on vmguest-3:
> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
> 'C:/Windows/System32/qwinsta.exe' 2>&1
> 2011-11-02 12:58:31|12831|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-11-02 12:58:31
> 2011-11-02
> 12:58:31|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
> 1093)|reservation remote IP is not defined
> 2011-11-02
> 12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
> output:
> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
> directory
> 2011-11-02 12:58:32|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
> command executed on vmguest-3, command:
> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
> 'C:/Windows/System32/qwinsta.exe' 2>&1
> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/qwin...")
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:32|15314|780:780|new|Windows.pm:logoff_users(1437)|failed to run
> qwinsta.exe on vmguest-3, exit status: 127, output:
> |15314|780:780|new| bash: C:/Windows/System32/qwinsta.exe: No such file or
> directory
> |15314|780:780|new| ( 0) Windows.pm, logoff_users (line: 1437)
> |15314|780:780|new| (-1) Windows.pm, post_load (line: 615)
> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-4) new.pm, process (line: 248)
> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:32|15314|780:780|new|Windows.pm:post_load(615)|failed to log off all
> currently logged in users
> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 615)
> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-3) new.pm, process (line: 248)
> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-5) vcld, main (line: 346)
>
> 2011-11-02
> 12:58:33|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 6:
> waiting for vmguest-1 to respond to SSH
> 2011-11-02
> 12:58:34|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
> responding to SSH, ports 22 or 24 are both closed
> 2011-11-02
> 12:58:34|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 6:
> code returned false, seconds elapsed/remaining: 43/857, sleeping for 8
> seconds
> 2011-11-02 12:58:36|12831|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-11-02 12:58:36
> 2011-11-02
> 12:58:36|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
> 1093)|reservation remote IP is not defined
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:37|15314|780:780|new|Windows.pm:set_file_owner(1398)|error occurred
> setting root as the owner of /home/root, error output:
> |15314|780:780|new| bash: /usr/bin/chown.exe: No such file or directory
> |15314|780:780|new| ( 0) Windows.pm, set_file_owner (line: 1398)
> |15314|780:780|new| (-1) Windows.pm, post_load (line: 625)
> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-4) new.pm, process (line: 248)
> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:37|15314|780:780|new|Windows.pm:post_load(625)|unable to set root as
> the owner of /home/root
> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 625)
> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-3) new.pm, process (line: 248)
> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-5) vcld, main (line: 346)
>
> 2011-11-02
> 12:58:37|15314|780:780|new|utils.pm:run_ssh_command(5380)|executing SSH
> command on vmguest-3:
> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
> 2011-11-02 12:58:41|12831|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-11-02 12:58:41
> 2011-11-02
> 12:58:41|15410|778:778|reserved|DataStructure.pm:get_reservation_remote_ip(
> 1093)|reservation remote IP is not defined
> 2011-11-02
> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(759)|attempt 7:
> waiting for vmguest-1 to respond to SSH
> 2011-11-02
> 12:58:42|15241|779:779|new|OS.pm:is_ssh_responding(386)|vmguest-1 is NOT
> responding to SSH, ports 22 or 24 are both closed
> 2011-11-02
> 12:58:42|15241|779:779|new|Module.pm:code_loop_timeout(755)|attempt 7:
> code returned false, seconds elapsed/remaining: 51/849, sleeping for 8
> seconds
> 2011-11-02
> 12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5464)|run_ssh_command
> output:
> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
> directory
> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:run_ssh_command(5474)|SSH
> command executed on vmguest-3, command:
> |15314|780:780|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o
> StrictHostKeyChecking=no -l root -p 22 -x vmguest-3
> 'C:/Windows/System32/sc.exe config sshd start= auto' 2>&1
> |15314|780:780|new| returning (127, "bash: C:/Windows/System32/sc.e...")
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:43|15314|780:780|new|Windows.pm:set_service_startup_mode(3644)|failed
>  to set sshd service startup mode to auto, exit status: 127, output:
> |15314|780:780|new| bash: C:/Windows/System32/sc.exe: No such file or
> directory
> |15314|780:780|new| ( 0) Windows.pm, set_service_startup_mode (line: 3644)
> |15314|780:780|new| (-1) Windows.pm, post_load (line: 639)
> |15314|780:780|new| (-2) VMware.pm, load (line: 415)
> |15314|780:780|new| (-3) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-4) new.pm, process (line: 248)
> |15314|780:780|new| (-5) vcld, make_new_child (line: 568)
>
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:43|15314|780:780|new|Windows.pm:post_load(640)|unable to set sshd
> service startup mode to auto
> |15314|780:780|new| ( 0) Windows.pm, post_load (line: 640)
> |15314|780:780|new| (-1) VMware.pm, load (line: 415)
> |15314|780:780|new| (-2) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-3) new.pm, process (line: 248)
> |15314|780:780|new| (-4) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-5) vcld, main (line: 346)
>
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:43|15314|780:780|new|VMware.pm:load(419)|failed to perform OS
> post-load tasks on VM vmguest-3 on VM host: CSB303
> |15314|780:780|new| ( 0) VMware.pm, load (line: 419)
> |15314|780:780|new| (-1) new.pm, reload_image (line: 618)
> |15314|780:780|new| (-2) new.pm, process (line: 248)
> |15314|780:780|new| (-3) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-4) vcld, main (line: 346)
>
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:43|15314|780:780|new|new.pm:reload_image(623)|vmwarewinxp-WindowsXPwi
> thVisualStudio32-v0 failed to load on vmguest-3, returning
> |15314|780:780|new| ( 0) new.pm, reload_image (line: 623)
> |15314|780:780|new| (-1) new.pm, process (line: 248)
> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-3) vcld, main (line: 346)
>
> 2011-11-02
> 12:58:43|15314|780:780|new|utils.pm:insertloadlog(3875)|inserted
> computer=4, loadimagefailed, vmwarewinxp-WindowsXPwithVisualStudio32-v0
> failed to load on vmguest-3
>
> |15314|780:780|new| ---- WARNING ----
> |15314|780:780|new| 2011-11-02
> 12:58:43|15314|780:780|new|new.pm:process(295)|failed to load vmguest-3
> with vmwarewinxp-WindowsXPwithVisualStudio32-v0
> |15314|780:780|new| ( 0) new.pm, process (line: 295)
> |15314|780:780|new| (-1) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-2) vcld, main (line: 346)
>
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2305)|a
> ttempting to retrieve current state of computer vmguest-3 from the database
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:get_computer_state_name(2336)|r
> etrieved current state of computer vmguest-3 from the database: reloading
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:_automethod(834)|data
> structure updated:
> $self->request_data->{reservation}{780}{computer}{state}{name}
> |15314|780:780|new| computer_state_name = reloading
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_address
> (1581)|attempting to retrieve private IP address for computer: vmguest-3
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_address
> (1585)|retrieved contents of /etc/hosts on this management node, contains
> 44 lines
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:get_computer_private_ip_address
> (1645)|returning IP address from /etc/hosts file: 10.10.10.4
> 2011-11-02
> 12:58:43|15314|780:780|new|utils.pm:is_inblockrequest(6163)|zero rows were
> returned from database select
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:get_image_affiliation_name(2035
> )|image owner id: 1
> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
> requested (information_schema) does not match handle stored in $ENV{dbh}
> (vcl:localhost)
> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1352)|attemp
> ting to retrieve and store data for user: user.id = '1'
> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2709)|database
> requested (vcl) does not match handle stored in $ENV{dbh}
> (information_schema:localhost)
> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-11-02
> 12:58:43|15314|780:780|new|DataStructure.pm:retrieve_user_data(1415)|data
> has been retrieved for user: admin (id: 1)
> 2011-11-02 12:58:43|15314|780:780|new|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: 0, PROBLEM --
> 780:780|new|State.pm|vmguest-3>CSB303|vmwarewinxp-WindowsXPwithVisualStudio
> 32-v0|admin
>
>
>
>
>
> |15314|780:780|new| ---- CRITICAL ----
> |15314|780:780|new| 2011-11-02
> 12:58:43|15314|780:780|new|State.pm:reservation_failed(213)|reservation
> failed on vmguest-3: process failed after trying to load or make available
> |15314|780:780|new| ( 0) State.pm, reservation_failed (line: 213)
> |15314|780:780|new| (-1) new.pm, process (line: 298)
> |15314|780:780|new| (-2) vcld, make_new_child (line: 568)
> |15314|780:780|new| (-3) vcld, main (line: 346)
>
>



-- 
Aaron Peeler
Program Manager
Virtual Computing Lab
NC State University

All electronic mail messages in connection with State business which
are sent to or received by this account are subject to the NC Public
Records Law and may be disclosed to third parties.