You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@vcl.apache.org by Juan Jose Zamanillo <jj...@itesm.mx> on 2011/04/06 06:28:05 UTC
Fwd: problems with image windows 7
> De: Juan Jose Zamanillo <jj...@itesm.mx>
> Fecha: 5 de abril de 2011 11:16:22 p.m. GMT-05:00
> Para: vcl-user-info@incubator.apache.org
> Asunto: Fwd: problems with image windows 7
>
>
>
> Inicio del mensaje reenviado:
>
>> De: Juan Jose Zamanillo <jj...@itesm.mx>
>> Fecha: 5 de abril de 2011 11:04:05 p.m. GMT-05:00
>> Para: vcl-user-info@incubator.apache.org
>> Asunto: problems with image windows 7
>>
>> I generate aWindows 7 Image, all things works ok, but when the
>> virtual machine reboot, the console of the virtual machine begin
>> with a blue screen and then tell me that there is a problem with
>> the OS and start de windows recovery system and ask me if I want
>> to try to repair the image o start normaly windows, either options
>> did not work, if i select repair maybe 10 times always is the same
>> problem.
>>
>> I am copying the mails and logs
>>
>> thanks in advance
>>
>>
>> Your VCL image creation request for Windows Base P6 has
>> succeeded. Please visit http://cwiki.apache.org/VCL and
>> you should see an image called Windows Base P6.
>> Please test this image to confirm it works correctly.
>>
>> Thank You,
>> VCL Team
>>
>> ===================================================
>>
>> VCL Image Creation Completed
>>
>> Request ID: 5
>> Reservation ID: 5
>> PID: 6740
>>
>> Image ID: 9
>> Image name: vmwarewin7-WindowsBaseP69-v0
>> Image size change: 1450 --> 15360
>>
>> Revision ID: 9
>>
>> Management node: webvcl
>>
>> Username: admin
>> User ID: 1
>>
>> Computer ID: 2
>> Computer name: windowsbase1
>>
>> Use Sysprep: 0
>>
>> ================================================
>>
>> reservation failed on windowsbase1: process failed after trying to
>> load or make available
>> ------------------------------------------------------------------------
>> time: 2011-04-05 21:58:47
>> caller: State.pm:reservation_failed(213)
>> ( 0) State.pm, reservation_failed (line: 213)
>> (-1) new.pm, process (line: 298)
>> (-2) vcld, make_new_child (line: 568)
>> (-3) vcld, main (line: 346)
>> ------------------------------------------------------------------------
>> management node: webvcl.qro.itesm.mx
>> reservation PID: 9566
>> parent vcld PID: 31208
>>
>> request ID: 6
>> reservation ID: 6
>> request state/laststate: reload/image
>> request start time: 2011-04-05 21:48:11
>> request end time: 2011-04-05 22:18:11
>> for imaging: no
>> log ID: none
>>
>> computer: windowsbase1
>> computer id: 2
>> computer type: virtualmachine
>> computer eth0 MAC address: 00:50:56:00:10:00
>> computer eth1 MAC address: 00:50:56:00:10:01
>> computer private IP address: 192.168.113.242
>> computer public IP address: 10.25.19.166
>> computer in block allocation: no
>> provisioning module: VCL::Module::Provisioning::VMware::VMware
>>
>> vm host: esxi.qro.itesm.mx
>> vm host ID: 1
>> vm host computer ID: 1
>> vm profile: VMware ESX - network storage
>> vm profile VM path: /vmfs/volumes/datastore1
>> vm profile repository path: 0
>> vm profile datastore path: /vmfs/volumes/datastore1
>> vm profile disk type: networkdisk
>>
>> image: vmwarewin7-WindowsBaseP69-v0
>> image display name: Windows Base P6
>> image ID: 9
>> image revision ID: 9
>> image size: 15360 MB
>> use Sysprep: no
>> root access: yes
>> image owner ID: 1
>> image owner affiliation: Local
>> image revision date created: 2011-04-05 21:03:48
>> image revision production: yes
>> OS module: VCL::Module::OS::Windows::Version_6::7
>>
>> user: vclreload
>> user name: vcl reload
>> user ID: 2
>> user affiliation: Local
>> ------------------------------------------------------------------------
>> RECENT LOG ENTRIES FOR THIS PROCESS:
>> 2011-04-05 21:55:25|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 27: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:55:26|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:55:26|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 27: code returned false,
>> seconds elapsed/remaining: 403/197, sleeping for 15 seconds
>> 2011-04-05 21:55:41|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 28: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:55:41|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:55:41|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 28: code returned false,
>> seconds elapsed/remaining: 418/182, sleeping for 15 seconds
>> 2011-04-05 21:55:56|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 29: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:55:57|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:55:57|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 29: code returned false,
>> seconds elapsed/remaining: 434/166, sleeping for 15 seconds
>> 2011-04-05 21:56:12|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 30: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:56:12|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:56:12|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 30: code returned false,
>> seconds elapsed/remaining: 449/151, sleeping for 15 seconds
>> 2011-04-05 21:56:27|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 31: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:56:28|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:56:28|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 31: code returned false,
>> seconds elapsed/remaining: 465/135, sleeping for 15 seconds
>> 2011-04-05 21:56:43|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 32: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:56:43|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:56:43|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 32: code returned false,
>> seconds elapsed/remaining: 480/120, sleeping for 15 seconds
>> 2011-04-05 21:56:58|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 33: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:56:59|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:56:59|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 33: code returned false,
>> seconds elapsed/remaining: 496/104, sleeping for 15 seconds
>> 2011-04-05 21:57:14|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 34: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:57:14|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:57:14|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 34: code returned false,
>> seconds elapsed/remaining: 511/89, sleeping for 15 seconds
>> 2011-04-05 21:57:29|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 35: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:57:30|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:57:30|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 35: code returned false,
>> seconds elapsed/remaining: 527/73, sleeping for 15 seconds
>> 2011-04-05 21:57:45|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 36: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:57:45|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:57:45|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 36: code returned false,
>> seconds elapsed/remaining: 542/58, sleeping for 15 seconds
>> 2011-04-05 21:58:00|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 37: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:58:00|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:58:00|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 37: code returned false,
>> seconds elapsed/remaining: 557/43, sleeping for 15 seconds
>> 2011-04-05 21:58:15|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 38: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:58:16|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:58:16|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 38: code returned false,
>> seconds elapsed/remaining: 573/27, sleeping for 15 seconds
>> 2011-04-05 21:58:31|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 39: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:58:31|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:58:31|9566|6:6|reload|
>> Module.pm:code_loop_timeout(755)|attempt 39: code returned false,
>> seconds elapsed/remaining: 588/12, sleeping for 15 seconds
>> 2011-04-05 21:58:46|9566|6:6|reload|
>> Module.pm:code_loop_timeout(759)|attempt 40: waiting for
>> windowsbase1 to respond to SSH
>> 2011-04-05 21:58:47|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>> windowsbase1 is NOT responding to SSH, ports 22 or 24 are both closed
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> Module.pm:code_loop_timeout(767)|waiting for windowsbase1 to
>> respond to SSH, code did not return true after waiting 600 seconds
>> |9566|6:6|reload| ---- WARNING ----
>> |9566|6:6|reload| 2011-04-05 21:58:47|9566|6:6|reload|
>> OS.pm:wait_for_response(465)|failed to connect to windowsbase1 via
>> SSH after 600 seconds
>> |9566|6:6|reload| ( 0) OS.pm, wait_for_response (line: 465)
>> |9566|6:6|reload| (-1) Version_6.pm, wait_for_response (line: 1767)
>> |9566|6:6|reload| (-2) Windows.pm, post_load (line: 591)
>> |9566|6:6|reload| (-3) Version_6.pm, post_load (line: 194)
>> |9566|6:6|reload| (-4) VMware.pm, load (line: 415)
>> |9566|6:6|reload| (-5) new.pm, reload_image (line: 618)
>> |9566|6:6|reload| ---- WARNING ----
>> |9566|6:6|reload| 2011-04-05 21:58:47|9566|6:6|reload|
>> Windows.pm:post_load(592)|windowsbase1 never responded to SSH
>> |9566|6:6|reload| ( 0) Windows.pm, post_load (line: 592)
>> |9566|6:6|reload| (-1) Version_6.pm, post_load (line: 194)
>> |9566|6:6|reload| (-2) VMware.pm, load (line: 415)
>> |9566|6:6|reload| (-3) new.pm, reload_image (line: 618)
>> |9566|6:6|reload| (-4) new.pm, process (line: 248)
>> |9566|6:6|reload| (-5) vcld, make_new_child (line: 568)
>> |9566|6:6|reload| ---- WARNING ----
>> |9566|6:6|reload| 2011-04-05 21:58:47|9566|6:6|reload|
>> Version_6.pm:post_load(198)|failed to execute parent class
>> post_load() subroutine
>> |9566|6:6|reload| ( 0) Version_6.pm, post_load (line: 198)
>> |9566|6:6|reload| (-1) VMware.pm, load (line: 415)
>> |9566|6:6|reload| (-2) new.pm, reload_image (line: 618)
>> |9566|6:6|reload| (-3) new.pm, process (line: 248)
>> |9566|6:6|reload| (-4) vcld, make_new_child (line: 568)
>> |9566|6:6|reload| (-5) vcld, main (line: 346)
>> |9566|6:6|reload| ---- WARNING ----
>> |9566|6:6|reload| 2011-04-05 21:58:47|9566|6:6|reload|
>> VMware.pm:load(419)|failed to perform OS post-load tasks on VM
>> windowsbase1 on VM host: esxi.qro.itesm.mx
>> |9566|6:6|reload| ( 0) VMware.pm, load (line: 419)
>> |9566|6:6|reload| (-1) new.pm, reload_image (line: 618)
>> |9566|6:6|reload| (-2) new.pm, process (line: 248)
>> |9566|6:6|reload| (-3) vcld, make_new_child (line: 568)
>> |9566|6:6|reload| (-4) vcld, main (line: 346)
>> |9566|6:6|reload| ---- WARNING ----
>> |9566|6:6|reload| 2011-04-05 21:58:47|9566|6:6|reload|
>> new.pm:reload_image(623)|vmwarewin7-WindowsBaseP69-v0 failed to
>> load on windowsbase1, returning
>> |9566|6:6|reload| ( 0) new.pm, reload_image (line: 623)
>> |9566|6:6|reload| (-1) new.pm, process (line: 248)
>> |9566|6:6|reload| (-2) vcld, make_new_child (line: 568)
>> |9566|6:6|reload| (-3) vcld, main (line: 346)
>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:insertloadlog(3875)|
>> inserted computer=2, loadimagefailed, vmwarewin7-WindowsBaseP69-v0
>> failed to load on windowsbase1
>> |9566|6:6|reload| ---- WARNING ----
>> |9566|6:6|reload| 2011-04-05 21:58:47|9566|6:6|reload|
>> new.pm:process(295)|failed to load windowsbase1 with vmwarewin7-
>> WindowsBaseP69-v0
>> |9566|6:6|reload| ( 0) new.pm, process (line: 295)
>> |9566|6:6|reload| (-1) vcld, make_new_child (line: 568)
>> |9566|6:6|reload| (-2) vcld, main (line: 346)
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:get_computer_state_name(2305)|attempting to
>> retrieve current state of computer windowsbase1 from the database
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:get_computer_state_name(2336)|retrieved current
>> state of computer windowsbase1 from the database: reloading
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:_automethod(834)|data structure updated: $self-
>> >request_data->{reservation}{6}{computer}{state}{name}
>> |9566|6:6|reload| computer_state_name = reloading
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:get_computer_private_ip_address(1581)|attempting
>> to retrieve private IP address for computer: windowsbase1
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
>> contents of /etc/hosts on this management node, contains 7 lines
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:get_computer_private_ip_address(1645)|returning IP
>> address from /etc/hosts file: 192.168.113.242
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> utils.pm:is_inblockrequest(6163)|zero rows were returned from
>> database select
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:get_image_affiliation_name(2035)|image owner id: 1
>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|
>> database requested (information_schema) does not match handle
>> stored in $ENV{dbh} (vcl:10.25.19.220)
>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|
>> database handle stored in $ENV{dbh}
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:retrieve_user_data(1352)|attempting to retrieve
>> and store data for user: user.id = '1'
>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|
>> database requested (vcl) does not match handle stored in $ENV{dbh}
>> (information_schema:10.25.19.220)
>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|
>> database handle stored in $ENV{dbh}
>> 2011-04-05 21:58:47|9566|6:6|reload|
>> DataStructure.pm:retrieve_user_data(1415)|data has been retrieved
>> for user: admin (id: 1)
>>
>>
>> Juan Jose Zamanillo
>>
>> jjzamani@itesm.mx
>>
>
Re: problems with image windows 7
Posted by jj...@itesm.mx.
I resolve the problem, I reinstall the Cygwin and works fine
Thanks
Juan José Zamanillo
-----Original Message-----
From: Juan Jose Zamanillo <jj...@itesm.mx>
Date: Thu, 7 Apr 2011 20:11:29
To: <vc...@incubator.apache.org>
Reply-To: vcl-user@incubator.apache.org
Subject: Re: problems with image windows 7
That works fine :)
and naow I get a problem that was not presented , I think I repeat
correctly the same steps here is the log.
Before I make the generation of the base image the program secedit.exe
exists in the machine, where the creation of the base image, it did
not exists.
thanks in advance
Juan Jose Zamanillo
vmwarewin7-Windows7Basepr730-v0 image creation failed
------------------------------------------------------------------------
time: 2011-04-07 20:03:30
caller: image.pm:reservation_failed(385)
( 0) image.pm, reservation_failed (line: 385)
(-1) image.pm, process (line: 167)
(-2) vcld, make_new_child (line: 568)
(-3) vcld, main (line: 346)
------------------------------------------------------------------------
management node: webvcl.qro.itesm.mx
reservation PID: 871
parent vcld PID: 31208
request ID: 49
reservation ID: 49
request state/laststate: image/image
request start time: 2011-04-07 20:00:36
request end time: 2011-04-07 21:00:36
for imaging: no
log ID: none
computer: windows7base1
computer id: 5
computer type: virtualmachine
computer eth0 MAC address: 00:50:56:00:10:06
computer eth1 MAC address: 00:50:56:00:10:07
computer private IP address: 192.168.113.240
computer public IP address: 10.25.19.174
computer in block allocation: no
provisioning module: VCL::Module::Provisioning::VMware::VMware
vm host: esxi.qro.itesm.mx
vm host ID: 1
vm host computer ID: 1
vm profile: VMware ESX - network storage
vm profile VM path: /vmfs/volumes/datastore1
vm profile repository path: 0
vm profile datastore path: /vmfs/volumes/datastore1
vm profile disk type: networkdisk
image: vmwarewin7-Windows7Basepr730-v0
image display name: Windows 7 Base pr7
image ID: 30
image revision ID: 30
image size: 1450 MB
use Sysprep: no
root access: yes
image owner ID: 1
image owner affiliation: Local
image revision date created: 2011-04-07 20:00:43
image revision production: yes
OS module: VCL::Module::OS::Windows::Version_6::7
user: admin
user name: vcl admin
user ID: 1
user affiliation: Local
------------------------------------------------------------------------
RECENT LOG ENTRIES FOR THIS PROCESS:
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:02:49|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/2_Disable_UAC.inf
2011-04-07 20:02:49|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/Disable_UAC.inf' --> 'windows7base1:C:/
Cygwin/home/root/VCL/Security/2_Disable_UAC.inf'
2011-04-07 20:02:51|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/Disable_UAC.inf' --> 'windows7base1:C:/Cygwin/home/root/VCL/
Security/2_Disable_UAC.inf'
2011-04-07 20:02:51|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/2_Disable_UAC.inf
2011-04-07 20:02:56|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
2_Disable_UAC.log'
2011-04-07 20:02:56|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/2_Disable_UAC.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:02:59|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\\2_Disable_UAC.inf, exit
status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:02:59|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/3_My_Computer_Reg_Perms.inf
2011-04-07 20:02:59|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/My_Computer_Reg_Perms.inf' -->
'windows7base1:C:/Cygwin/home/root/VCL/Security/
3_My_Computer_Reg_Perms.inf'
2011-04-07 20:03:01|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/My_Computer_Reg_Perms.inf' --> 'windows7base1:C:/Cygwin/home/
root/VCL/Security/3_My_Computer_Reg_Perms.inf'
2011-04-07 20:03:01|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/
3_My_Computer_Reg_Perms.inf
2011-04-07 20:03:07|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
3_My_Computer_Reg_Perms.log'
2011-04-07 20:03:07|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/3_My_Computer_Reg_Perms.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:10|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\
\3_My_Computer_Reg_Perms.inf, exit status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:03:10|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/4_Security_Center_Reg_Perms.inf
2011-04-07 20:03:10|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/Security_Center_Reg_Perms.inf' -->
'windows7base1:C:/Cygwin/home/root/VCL/Security/
4_Security_Center_Reg_Perms.inf'
2011-04-07 20:03:12|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/Security_Center_Reg_Perms.inf' --> 'windows7base1:C:/Cygwin/
home/root/VCL/Security/4_Security_Center_Reg_Perms.inf'
2011-04-07 20:03:12|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/
4_Security_Center_Reg_Perms.inf
2011-04-07 20:03:18|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
4_Security_Center_Reg_Perms.log'
2011-04-07 20:03:18|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/4_Security_Center_Reg_Perms.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:21|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\
\4_Security_Center_Reg_Perms.inf, exit status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:03:21|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/5_Sysprep_Reg_Perms.inf
2011-04-07 20:03:21|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/Sysprep_Reg_Perms.inf' -->
'windows7base1:C:/Cygwin/home/root/VCL/Security/5_Sysprep_Reg_Perms.inf'
2011-04-07 20:03:23|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/Sysprep_Reg_Perms.inf' --> 'windows7base1:C:/Cygwin/home/root/
VCL/Security/5_Sysprep_Reg_Perms.inf'
2011-04-07 20:03:23|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/5_Sysprep_Reg_Perms.inf
2011-04-07 20:03:28|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
5_Sysprep_Reg_Perms.log'
2011-04-07 20:03:28|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/5_Sysprep_Reg_Perms.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\\5_Sysprep_Reg_Perms.inf,
exit status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
Windows.pm:pre_capture(296)|unable to apply security templates
|871|49:49|image| ( 0) Windows.pm, pre_capture (line: 296)
|871|49:49|image| (-1) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-2) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-3) VMware.pm, capture (line: 556)
|871|49:49|image| (-4) image.pm, process (line: 162)
|871|49:49|image| (-5) vcld, make_new_child (line: 568)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
Version_6.pm:pre_capture(118)|failed to execute parent class
pre_capture() subroutine
|871|49:49|image| ( 0) Version_6.pm, pre_capture (line: 118)
|871|49:49|image| (-1) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-2) VMware.pm, capture (line: 556)
|871|49:49|image| (-3) image.pm, process (line: 162)
|871|49:49|image| (-4) vcld, make_new_child (line: 568)
|871|49:49|image| (-5) vcld, main (line: 346)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
7.pm:pre_capture(104)|failed to execute parent class pre_capture()
subroutine
|871|49:49|image| ( 0) 7.pm, pre_capture (line: 104)
|871|49:49|image| (-1) VMware.pm, capture (line: 556)
|871|49:49|image| (-2) image.pm, process (line: 162)
|871|49:49|image| (-3) vcld, make_new_child (line: 568)
|871|49:49|image| (-4) vcld, main (line: 346)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
VMware.pm:capture(557)|failed to complete OS module's pre_capture tasks
|871|49:49|image| ( 0) VMware.pm, capture (line: 557)
|871|49:49|image| (-1) image.pm, process (line: 162)
|871|49:49|image| (-2) vcld, make_new_child (line: 568)
|871|49:49|image| (-3) vcld, main (line: 346)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
image.pm:process(166)|vmwarewin7-Windows7Basepr730-v0 image failed to
be captured by provisioning module
|871|49:49|image| ( 0) image.pm, process (line: 166)
|871|49:49|image| (-1) vcld, make_new_child (line: 568)
|871|49:49|image| (-2) vcld, main (line: 346)
2011-04-07 20:03:30|871|49:49|image|
DataStructure.pm:get_computer_private_ip_address(1557)|returning
private IP address previously retrieved: 192.168.113.240
2011-04-07 20:03:30|871|49:49|image|utils.pm:is_inblockrequest(6163)|
zero rows were returned from database select
2011-04-07 20:03:30|871|49:49|image|
DataStructure.pm:get_image_affiliation_name(2035)|image owner id: 1
2011-04-07 20:03:30|871|49:49|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in
$ENV{dbh} (vcl:10.25.19.220)
2011-04-07 20:03:30|871|49:49|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-04-07 20:03:31|871|49:49|image|
DataStructure.pm:retrieve_user_data(1352)|attempting to retrieve and
store data for user: user.id = '1'
2011-04-07 20:03:31|871|49:49|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:10.25.19.220)
2011-04-07 20:03:31|871|49:49|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-04-07 20:03:31|871|49:49|image|
DataStructure.pm:retrieve_user_data(1415)|data has been retrieved for
user: admin (id: 1)
El 06/04/2011, a las 01:19 p.m., Andy Kurth escribió:
> My guess is that it's a storage controller problem. Check the
> storage controller type that is assigned to the VM in the vSphere
> Client:
> -Select the VM
> -Edit Settings
> -SCSI controller 0
>
> Try changing the type to LSI Logic SAS and then power on the VM. If
> this allows the VM to boot then the original base image was saved
> using the SAS controller. VCL does not currently assign a SAS
> controller due to a VMware bug. It will be easiest to recreate your
> base image. When creating the initial VM, be sure to select LSI
> Logic Parallel.
>
> -Andy
>
> On 4/6/2011 12:28 AM, Juan Jose Zamanillo wrote:
>>
>>
>>> *De: *Juan Jose Zamanillo <jjzamani@itesm.mx <mailto:jjzamani@itesm.mx
>>> >>
>>> *Fecha: *5 de abril de 2011 11:16:22 p.m. GMT-05:00
>>> *Para: *vcl-user-info@incubator.apache.org
>>> <ma...@incubator.apache.org>
>>> *Asunto: **Fwd: problems with image windows 7*
>>>
>>>
>>>
>>> Inicio del mensaje reenviado:
>>>
>>>> *De: *Juan Jose Zamanillo <jjzamani@itesm.mx <mailto:jjzamani@itesm.mx
>>>> >>
>>>> *Fecha: *5 de abril de 2011 11:04:05 p.m. GMT-05:00
>>>> *Para: *vcl-user-info@incubator.apache.org
>>>> <ma...@incubator.apache.org>
>>>> *Asunto: **problems with image windows 7*
>>>>
>>>> I generate aWindows 7 Image, all things works ok, but when the
>>>> virtual machine reboot, the console of the virtual machine begin
>>>> with
>>>> a blue screen and then tell me that there is a problem with the OS
>>>> and start de windows recovery system and ask me if I want to try to
>>>> repair the image o start normaly windows, either options did not
>>>> work, if i select repair maybe 10 times always is the same problem.
>>>>
>>>> I am copying the mails and logs
>>>>
>>>> thanks in advance
>>>>
>>>>
>>>> Your VCL image creation request for Windows Base P6 has
>>>> succeeded. Please visit http://cwiki.apache.org/VCL and
>>>> you should see an image called Windows Base P6.
>>>> Please test this image to confirm it works correctly.
>>>>
>>>> Thank You,
>>>> VCL Team
>>>>
>>>> ===================================================
>>>>
>>>> VCL Image Creation Completed
>>>>
>>>> Request ID: 5
>>>> Reservation ID: 5
>>>> PID: 6740
>>>>
>>>> Image ID: 9
>>>> Image name: vmwarewin7-WindowsBaseP69-v0
>>>> Image size change: 1450 --> 15360
>>>>
>>>> Revision ID: 9
>>>>
>>>> Management node: webvcl
>>>>
>>>> Username: admin
>>>> User ID: 1
>>>>
>>>> Computer ID: 2
>>>> Computer name: windowsbase1
>>>>
>>>> Use Sysprep: 0
>>>>
>>>> ================================================
>>>>
>>>> reservation failed on windowsbase1: process failed after trying to
>>>> load or make available
>>>> ------------------------------------------------------------------------
>>>> time: 2011-04-05 21:58:47
>>>> caller: State.pm:reservation_failed(213)
>>>> ( 0) State.pm, reservation_failed (line: 213)
>>>> (-1) new.pm, process (line: 298)
>>>> (-2) vcld, make_new_child (line: 568)
>>>> (-3) vcld, main (line: 346)
>>>> ------------------------------------------------------------------------
>>>> management node: webvcl.qro.itesm.mx
>>>> reservation PID: 9566
>>>> parent vcld PID: 31208
>>>>
>>>> request ID: 6
>>>> reservation ID: 6
>>>> request state/laststate: reload/image
>>>> request start time: 2011-04-05 21:48:11
>>>> request end time: 2011-04-05 22:18:11
>>>> for imaging: no
>>>> log ID: none
>>>>
>>>> computer: windowsbase1
>>>> computer id: 2
>>>> computer type: virtualmachine
>>>> computer eth0 MAC address: 00:50:56:00:10:00
>>>> computer eth1 MAC address: 00:50:56:00:10:01
>>>> computer private IP address: 192.168.113.242
>>>> computer public IP address: 10.25.19.166
>>>> computer in block allocation: no
>>>> provisioning module: VCL::Module::Provisioning::VMware::VMware
>>>>
>>>> vm host: esxi.qro.itesm.mx
>>>> vm host ID: 1
>>>> vm host computer ID: 1
>>>> vm profile: VMware ESX - network storage
>>>> vm profile VM path: /vmfs/volumes/datastore1
>>>> vm profile repository path: 0
>>>> vm profile datastore path: /vmfs/volumes/datastore1
>>>> vm profile disk type: networkdisk
>>>>
>>>> image: vmwarewin7-WindowsBaseP69-v0
>>>> image display name: Windows Base P6
>>>> image ID: 9
>>>> image revision ID: 9
>>>> image size: 15360 MB
>>>> use Sysprep: no
>>>> root access: yes
>>>> image owner ID: 1
>>>> image owner affiliation: Local
>>>> image revision date created: 2011-04-05 21:03:48
>>>> image revision production: yes
>>>> OS module: VCL::Module::OS::Windows::Version_6::7
>>>>
>>>> user: vclreload
>>>> user name: vcl reload
>>>> user ID: 2
>>>> user affiliation: Local
>>>> ------------------------------------------------------------------------
>>>> RECENT LOG ENTRIES FOR THIS PROCESS:
>>>> 2011-04-05
>>>> 21:55:25|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 27:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:55:26|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:55:26|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 27:
>>>> code returned false, seconds elapsed/remaining: 403/197, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:55:41|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 28:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:55:41|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:55:41|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 28:
>>>> code returned false, seconds elapsed/remaining: 418/182, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:55:56|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 29:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:55:57|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:55:57|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 29:
>>>> code returned false, seconds elapsed/remaining: 434/166, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:12|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 30:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:12|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:12|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 30:
>>>> code returned false, seconds elapsed/remaining: 449/151, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:27|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 31:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:28|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:28|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 31:
>>>> code returned false, seconds elapsed/remaining: 465/135, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:43|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 32:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:43|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:43|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 32:
>>>> code returned false, seconds elapsed/remaining: 480/120, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:58|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 33:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:59|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:59|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 33:
>>>> code returned false, seconds elapsed/remaining: 496/104, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:57:14|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 34:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:57:14|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:57:14|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 34:
>>>> code returned false, seconds elapsed/remaining: 511/89, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:57:29|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 35:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:57:30|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:57:30|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 35:
>>>> code returned false, seconds elapsed/remaining: 527/73, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:57:45|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 36:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:57:45|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:57:45|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 36:
>>>> code returned false, seconds elapsed/remaining: 542/58, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:00|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 37:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:00|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:00|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 37:
>>>> code returned false, seconds elapsed/remaining: 557/43, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:15|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 38:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:16|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:16|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 38:
>>>> code returned false, seconds elapsed/remaining: 573/27, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:31|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 39:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:31|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:31|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 39:
>>>> code returned false, seconds elapsed/remaining: 588/12, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:46|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 40:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|Module.pm:code_loop_timeout(767)|waiting
>>>> for
>>>> windowsbase1 to respond to SSH, code did not return true after
>>>> waiting 600 seconds
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|OS.pm:wait_for_response(465)|failed to
>>>> connect to windowsbase1 via SSH after 600 seconds
>>>> |9566|6:6|reload| ( 0) OS.pm, wait_for_response (line: 465)
>>>> |9566|6:6|reload| (-1) Version_6.pm, wait_for_response (line: 1767)
>>>> |9566|6:6|reload| (-2) Windows.pm, post_load (line: 591)
>>>> |9566|6:6|reload| (-3) Version_6.pm, post_load (line: 194)
>>>> |9566|6:6|reload| (-4) VMware.pm, load (line: 415)
>>>> |9566|6:6|reload| (-5) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|Windows.pm:post_load(592)|windowsbase1
>>>> never
>>>> responded to SSH
>>>> |9566|6:6|reload| ( 0) Windows.pm, post_load (line: 592)
>>>> |9566|6:6|reload| (-1) Version_6.pm, post_load (line: 194)
>>>> |9566|6:6|reload| (-2) VMware.pm, load (line: 415)
>>>> |9566|6:6|reload| (-3) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| (-4) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-5) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|Version_6.pm:post_load(198)|failed to
>>>> execute parent class post_load() subroutine
>>>> |9566|6:6|reload| ( 0) Version_6.pm, post_load (line: 198)
>>>> |9566|6:6|reload| (-1) VMware.pm, load (line: 415)
>>>> |9566|6:6|reload| (-2) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| (-3) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-4) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-5) vcld, main (line: 346)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|VMware.pm:load(419)|failed to perform OS
>>>> post-load tasks on VM windowsbase1 on VM host: esxi.qro.itesm.mx
>>>> |9566|6:6|reload| ( 0) VMware.pm, load (line: 419)
>>>> |9566|6:6|reload| (-1) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| (-2) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-3) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-4) vcld, main (line: 346)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|new.pm:reload_image(623)|vmwarewin7-
>>>> WindowsBaseP69-v0
>>>> failed to load on windowsbase1, returning
>>>> |9566|6:6|reload| ( 0) new.pm, reload_image (line: 623)
>>>> |9566|6:6|reload| (-1) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-2) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-3) vcld, main (line: 346)
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|utils.pm:insertloadlog(3875)|inserted
>>>> computer=2, loadimagefailed, vmwarewin7-WindowsBaseP69-v0 failed to
>>>> load on windowsbase1
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|new.pm:process(295)|failed to load
>>>> windowsbase1 with vmwarewin7-WindowsBaseP69-v0
>>>> |9566|6:6|reload| ( 0) new.pm, process (line: 295)
>>>> |9566|6:6|reload| (-1) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-2) vcld, main (line: 346)
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_state_name(2305)|attempting
>>>> to retrieve current state of computer windowsbase1 from the
>>>> database
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_state_name(2336)|retrieved
>>>> current state of computer windowsbase1 from the database: reloading
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|DataStructure.pm:_automethod(834)|data
>>>> structure updated:
>>>> $self->request_data->{reservation}{6}{computer}{state}{name}
>>>> |9566|6:6|reload| computer_state_name = reloading
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_private_ip_address(1581)|attempting
>>>> to retrieve private IP address for computer: windowsbase1
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
>>>> contents of /etc/hosts on this management node, contains 7 lines
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_private_ip_address(1645)|returning
>>>> IP address from /etc/hosts file: 192.168.113.242
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|utils.pm:is_inblockrequest(6163)|zero rows
>>>> were returned from database select
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_image_affiliation_name(2035)|image
>>>> owner id: 1
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|
>>>> database
>>>> requested (information_schema) does not match handle stored in
>>>> $ENV{dbh} (vcl:10.25.19.220)
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|
>>>> database
>>>> handle stored in $ENV{dbh}
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:retrieve_user_data(1352)|attempting
>>>> to retrieve and store data for user: user.id = '1'
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|
>>>> database
>>>> requested (vcl) does not match handle stored in $ENV{dbh}
>>>> (information_schema:10.25.19.220)
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|
>>>> database
>>>> handle stored in $ENV{dbh}
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:retrieve_user_data(1415)|data
>>>> has been retrieved for user: admin (id: 1)
>>>>
>>>>
>>>> Juan Jose Zamanillo
>>>>
>>>> jjzamani@itesm.mx <ma...@itesm.mx>
>>>>
>>>
>>
Re: problems with image windows 7
Posted by Juan Jose Zamanillo <jj...@itesm.mx>.
That works fine :)
and naow I get a problem that was not presented , I think I repeat
correctly the same steps here is the log.
Before I make the generation of the base image the program secedit.exe
exists in the machine, where the creation of the base image, it did
not exists.
thanks in advance
Juan Jose Zamanillo
vmwarewin7-Windows7Basepr730-v0 image creation failed
------------------------------------------------------------------------
time: 2011-04-07 20:03:30
caller: image.pm:reservation_failed(385)
( 0) image.pm, reservation_failed (line: 385)
(-1) image.pm, process (line: 167)
(-2) vcld, make_new_child (line: 568)
(-3) vcld, main (line: 346)
------------------------------------------------------------------------
management node: webvcl.qro.itesm.mx
reservation PID: 871
parent vcld PID: 31208
request ID: 49
reservation ID: 49
request state/laststate: image/image
request start time: 2011-04-07 20:00:36
request end time: 2011-04-07 21:00:36
for imaging: no
log ID: none
computer: windows7base1
computer id: 5
computer type: virtualmachine
computer eth0 MAC address: 00:50:56:00:10:06
computer eth1 MAC address: 00:50:56:00:10:07
computer private IP address: 192.168.113.240
computer public IP address: 10.25.19.174
computer in block allocation: no
provisioning module: VCL::Module::Provisioning::VMware::VMware
vm host: esxi.qro.itesm.mx
vm host ID: 1
vm host computer ID: 1
vm profile: VMware ESX - network storage
vm profile VM path: /vmfs/volumes/datastore1
vm profile repository path: 0
vm profile datastore path: /vmfs/volumes/datastore1
vm profile disk type: networkdisk
image: vmwarewin7-Windows7Basepr730-v0
image display name: Windows 7 Base pr7
image ID: 30
image revision ID: 30
image size: 1450 MB
use Sysprep: no
root access: yes
image owner ID: 1
image owner affiliation: Local
image revision date created: 2011-04-07 20:00:43
image revision production: yes
OS module: VCL::Module::OS::Windows::Version_6::7
user: admin
user name: vcl admin
user ID: 1
user affiliation: Local
------------------------------------------------------------------------
RECENT LOG ENTRIES FOR THIS PROCESS:
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:02:49|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/2_Disable_UAC.inf
2011-04-07 20:02:49|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/Disable_UAC.inf' --> 'windows7base1:C:/
Cygwin/home/root/VCL/Security/2_Disable_UAC.inf'
2011-04-07 20:02:51|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/Disable_UAC.inf' --> 'windows7base1:C:/Cygwin/home/root/VCL/
Security/2_Disable_UAC.inf'
2011-04-07 20:02:51|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/2_Disable_UAC.inf
2011-04-07 20:02:56|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
2_Disable_UAC.log'
2011-04-07 20:02:56|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/2_Disable_UAC.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:02:59|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\\2_Disable_UAC.inf, exit
status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:02:59|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/3_My_Computer_Reg_Perms.inf
2011-04-07 20:02:59|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/My_Computer_Reg_Perms.inf' -->
'windows7base1:C:/Cygwin/home/root/VCL/Security/
3_My_Computer_Reg_Perms.inf'
2011-04-07 20:03:01|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/My_Computer_Reg_Perms.inf' --> 'windows7base1:C:/Cygwin/home/
root/VCL/Security/3_My_Computer_Reg_Perms.inf'
2011-04-07 20:03:01|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/
3_My_Computer_Reg_Perms.inf
2011-04-07 20:03:07|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
3_My_Computer_Reg_Perms.log'
2011-04-07 20:03:07|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/3_My_Computer_Reg_Perms.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:10|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\
\3_My_Computer_Reg_Perms.inf, exit status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:03:10|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/4_Security_Center_Reg_Perms.inf
2011-04-07 20:03:10|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/Security_Center_Reg_Perms.inf' -->
'windows7base1:C:/Cygwin/home/root/VCL/Security/
4_Security_Center_Reg_Perms.inf'
2011-04-07 20:03:12|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/Security_Center_Reg_Perms.inf' --> 'windows7base1:C:/Cygwin/
home/root/VCL/Security/4_Security_Center_Reg_Perms.inf'
2011-04-07 20:03:12|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/
4_Security_Center_Reg_Perms.inf
2011-04-07 20:03:18|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
4_Security_Center_Reg_Perms.log'
2011-04-07 20:03:18|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/4_Security_Center_Reg_Perms.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:21|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\
\4_Security_Center_Reg_Perms.inf, exit status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
2011-04-07 20:03:21|871|49:49|image|
Windows.pm:apply_security_templates(7125)|attempting to copy file to:
C:/Cygwin/home/root/VCL/Security/5_Sysprep_Reg_Perms.inf
2011-04-07 20:03:21|871|49:49|image|utils.pm:run_scp_command(5600)|
attempting to copy file via SCP: '/usr/local/vcl/bin/../tools/
Windows_Version_6/Security/Sysprep_Reg_Perms.inf' -->
'windows7base1:C:/Cygwin/home/root/VCL/Security/5_Sysprep_Reg_Perms.inf'
2011-04-07 20:03:23|871|49:49|image|utils.pm:run_scp_command(5636)|
copied file via SCP: '/usr/local/vcl/bin/../tools/Windows_Version_6/
Security/Sysprep_Reg_Perms.inf' --> 'windows7base1:C:/Cygwin/home/root/
VCL/Security/5_Sysprep_Reg_Perms.inf'
2011-04-07 20:03:23|871|49:49|image|
Windows.pm:apply_security_templates(7127)|copied file:
windows7base1:C:/Cygwin/home/root/VCL/Security/5_Sysprep_Reg_Perms.inf
2011-04-07 20:03:28|871|49:49|image|Windows.pm:file_exists(1336)|file
does NOT exist on windows7base1: '$SYSTEMROOT/security/Logs/
5_Sysprep_Reg_Perms.log'
2011-04-07 20:03:28|871|49:49|image|Windows.pm:delete_file(1095)|
failed not deleted because it does not exist: '$SYSTEMROOT/security/
Logs/5_Sysprep_Reg_Perms.log'
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
Windows.pm:apply_security_templates(7159)|failed to run secedit.exe to
apply C:\\Cygwin\\home\\root\\VCL\\Security\\5_Sysprep_Reg_Perms.inf,
exit status: 1, output:
|871|49:49|image| ( 0) Windows.pm, apply_security_templates (line: 7159)
|871|49:49|image| (-1) Windows.pm, pre_capture (line: 295)
|871|49:49|image| (-2) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-3) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-4) VMware.pm, capture (line: 556)
|871|49:49|image| (-5) image.pm, process (line: 162)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
Windows.pm:pre_capture(296)|unable to apply security templates
|871|49:49|image| ( 0) Windows.pm, pre_capture (line: 296)
|871|49:49|image| (-1) Version_6.pm, pre_capture (line: 114)
|871|49:49|image| (-2) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-3) VMware.pm, capture (line: 556)
|871|49:49|image| (-4) image.pm, process (line: 162)
|871|49:49|image| (-5) vcld, make_new_child (line: 568)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
Version_6.pm:pre_capture(118)|failed to execute parent class
pre_capture() subroutine
|871|49:49|image| ( 0) Version_6.pm, pre_capture (line: 118)
|871|49:49|image| (-1) 7.pm, pre_capture (line: 100)
|871|49:49|image| (-2) VMware.pm, capture (line: 556)
|871|49:49|image| (-3) image.pm, process (line: 162)
|871|49:49|image| (-4) vcld, make_new_child (line: 568)
|871|49:49|image| (-5) vcld, main (line: 346)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
7.pm:pre_capture(104)|failed to execute parent class pre_capture()
subroutine
|871|49:49|image| ( 0) 7.pm, pre_capture (line: 104)
|871|49:49|image| (-1) VMware.pm, capture (line: 556)
|871|49:49|image| (-2) image.pm, process (line: 162)
|871|49:49|image| (-3) vcld, make_new_child (line: 568)
|871|49:49|image| (-4) vcld, main (line: 346)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
VMware.pm:capture(557)|failed to complete OS module's pre_capture tasks
|871|49:49|image| ( 0) VMware.pm, capture (line: 557)
|871|49:49|image| (-1) image.pm, process (line: 162)
|871|49:49|image| (-2) vcld, make_new_child (line: 568)
|871|49:49|image| (-3) vcld, main (line: 346)
|871|49:49|image| ---- WARNING ----
|871|49:49|image| 2011-04-07 20:03:30|871|49:49|image|
image.pm:process(166)|vmwarewin7-Windows7Basepr730-v0 image failed to
be captured by provisioning module
|871|49:49|image| ( 0) image.pm, process (line: 166)
|871|49:49|image| (-1) vcld, make_new_child (line: 568)
|871|49:49|image| (-2) vcld, main (line: 346)
2011-04-07 20:03:30|871|49:49|image|
DataStructure.pm:get_computer_private_ip_address(1557)|returning
private IP address previously retrieved: 192.168.113.240
2011-04-07 20:03:30|871|49:49|image|utils.pm:is_inblockrequest(6163)|
zero rows were returned from database select
2011-04-07 20:03:30|871|49:49|image|
DataStructure.pm:get_image_affiliation_name(2035)|image owner id: 1
2011-04-07 20:03:30|871|49:49|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in
$ENV{dbh} (vcl:10.25.19.220)
2011-04-07 20:03:30|871|49:49|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-04-07 20:03:31|871|49:49|image|
DataStructure.pm:retrieve_user_data(1352)|attempting to retrieve and
store data for user: user.id = '1'
2011-04-07 20:03:31|871|49:49|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:10.25.19.220)
2011-04-07 20:03:31|871|49:49|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-04-07 20:03:31|871|49:49|image|
DataStructure.pm:retrieve_user_data(1415)|data has been retrieved for
user: admin (id: 1)
El 06/04/2011, a las 01:19 p.m., Andy Kurth escribió:
> My guess is that it's a storage controller problem. Check the
> storage controller type that is assigned to the VM in the vSphere
> Client:
> -Select the VM
> -Edit Settings
> -SCSI controller 0
>
> Try changing the type to LSI Logic SAS and then power on the VM. If
> this allows the VM to boot then the original base image was saved
> using the SAS controller. VCL does not currently assign a SAS
> controller due to a VMware bug. It will be easiest to recreate your
> base image. When creating the initial VM, be sure to select LSI
> Logic Parallel.
>
> -Andy
>
> On 4/6/2011 12:28 AM, Juan Jose Zamanillo wrote:
>>
>>
>>> *De: *Juan Jose Zamanillo <jjzamani@itesm.mx <mailto:jjzamani@itesm.mx
>>> >>
>>> *Fecha: *5 de abril de 2011 11:16:22 p.m. GMT-05:00
>>> *Para: *vcl-user-info@incubator.apache.org
>>> <ma...@incubator.apache.org>
>>> *Asunto: **Fwd: problems with image windows 7*
>>>
>>>
>>>
>>> Inicio del mensaje reenviado:
>>>
>>>> *De: *Juan Jose Zamanillo <jjzamani@itesm.mx <mailto:jjzamani@itesm.mx
>>>> >>
>>>> *Fecha: *5 de abril de 2011 11:04:05 p.m. GMT-05:00
>>>> *Para: *vcl-user-info@incubator.apache.org
>>>> <ma...@incubator.apache.org>
>>>> *Asunto: **problems with image windows 7*
>>>>
>>>> I generate aWindows 7 Image, all things works ok, but when the
>>>> virtual machine reboot, the console of the virtual machine begin
>>>> with
>>>> a blue screen and then tell me that there is a problem with the OS
>>>> and start de windows recovery system and ask me if I want to try to
>>>> repair the image o start normaly windows, either options did not
>>>> work, if i select repair maybe 10 times always is the same problem.
>>>>
>>>> I am copying the mails and logs
>>>>
>>>> thanks in advance
>>>>
>>>>
>>>> Your VCL image creation request for Windows Base P6 has
>>>> succeeded. Please visit http://cwiki.apache.org/VCL and
>>>> you should see an image called Windows Base P6.
>>>> Please test this image to confirm it works correctly.
>>>>
>>>> Thank You,
>>>> VCL Team
>>>>
>>>> ===================================================
>>>>
>>>> VCL Image Creation Completed
>>>>
>>>> Request ID: 5
>>>> Reservation ID: 5
>>>> PID: 6740
>>>>
>>>> Image ID: 9
>>>> Image name: vmwarewin7-WindowsBaseP69-v0
>>>> Image size change: 1450 --> 15360
>>>>
>>>> Revision ID: 9
>>>>
>>>> Management node: webvcl
>>>>
>>>> Username: admin
>>>> User ID: 1
>>>>
>>>> Computer ID: 2
>>>> Computer name: windowsbase1
>>>>
>>>> Use Sysprep: 0
>>>>
>>>> ================================================
>>>>
>>>> reservation failed on windowsbase1: process failed after trying to
>>>> load or make available
>>>> ------------------------------------------------------------------------
>>>> time: 2011-04-05 21:58:47
>>>> caller: State.pm:reservation_failed(213)
>>>> ( 0) State.pm, reservation_failed (line: 213)
>>>> (-1) new.pm, process (line: 298)
>>>> (-2) vcld, make_new_child (line: 568)
>>>> (-3) vcld, main (line: 346)
>>>> ------------------------------------------------------------------------
>>>> management node: webvcl.qro.itesm.mx
>>>> reservation PID: 9566
>>>> parent vcld PID: 31208
>>>>
>>>> request ID: 6
>>>> reservation ID: 6
>>>> request state/laststate: reload/image
>>>> request start time: 2011-04-05 21:48:11
>>>> request end time: 2011-04-05 22:18:11
>>>> for imaging: no
>>>> log ID: none
>>>>
>>>> computer: windowsbase1
>>>> computer id: 2
>>>> computer type: virtualmachine
>>>> computer eth0 MAC address: 00:50:56:00:10:00
>>>> computer eth1 MAC address: 00:50:56:00:10:01
>>>> computer private IP address: 192.168.113.242
>>>> computer public IP address: 10.25.19.166
>>>> computer in block allocation: no
>>>> provisioning module: VCL::Module::Provisioning::VMware::VMware
>>>>
>>>> vm host: esxi.qro.itesm.mx
>>>> vm host ID: 1
>>>> vm host computer ID: 1
>>>> vm profile: VMware ESX - network storage
>>>> vm profile VM path: /vmfs/volumes/datastore1
>>>> vm profile repository path: 0
>>>> vm profile datastore path: /vmfs/volumes/datastore1
>>>> vm profile disk type: networkdisk
>>>>
>>>> image: vmwarewin7-WindowsBaseP69-v0
>>>> image display name: Windows Base P6
>>>> image ID: 9
>>>> image revision ID: 9
>>>> image size: 15360 MB
>>>> use Sysprep: no
>>>> root access: yes
>>>> image owner ID: 1
>>>> image owner affiliation: Local
>>>> image revision date created: 2011-04-05 21:03:48
>>>> image revision production: yes
>>>> OS module: VCL::Module::OS::Windows::Version_6::7
>>>>
>>>> user: vclreload
>>>> user name: vcl reload
>>>> user ID: 2
>>>> user affiliation: Local
>>>> ------------------------------------------------------------------------
>>>> RECENT LOG ENTRIES FOR THIS PROCESS:
>>>> 2011-04-05
>>>> 21:55:25|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 27:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:55:26|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:55:26|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 27:
>>>> code returned false, seconds elapsed/remaining: 403/197, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:55:41|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 28:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:55:41|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:55:41|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 28:
>>>> code returned false, seconds elapsed/remaining: 418/182, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:55:56|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 29:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:55:57|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:55:57|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 29:
>>>> code returned false, seconds elapsed/remaining: 434/166, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:12|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 30:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:12|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:12|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 30:
>>>> code returned false, seconds elapsed/remaining: 449/151, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:27|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 31:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:28|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:28|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 31:
>>>> code returned false, seconds elapsed/remaining: 465/135, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:43|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 32:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:43|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:43|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 32:
>>>> code returned false, seconds elapsed/remaining: 480/120, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:56:58|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 33:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:56:59|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:56:59|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 33:
>>>> code returned false, seconds elapsed/remaining: 496/104, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:57:14|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 34:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:57:14|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:57:14|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 34:
>>>> code returned false, seconds elapsed/remaining: 511/89, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:57:29|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 35:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:57:30|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:57:30|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 35:
>>>> code returned false, seconds elapsed/remaining: 527/73, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:57:45|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 36:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:57:45|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:57:45|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 36:
>>>> code returned false, seconds elapsed/remaining: 542/58, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:00|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 37:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:00|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:00|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 37:
>>>> code returned false, seconds elapsed/remaining: 557/43, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:15|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 38:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:16|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:16|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 38:
>>>> code returned false, seconds elapsed/remaining: 573/27, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:31|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 39:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:31|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:31|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt
>>>> 39:
>>>> code returned false, seconds elapsed/remaining: 588/12, sleeping
>>>> for
>>>> 15 seconds
>>>> 2011-04-05
>>>> 21:58:46|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt
>>>> 40:
>>>> waiting for windowsbase1 to respond to SSH
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|OS.pm:is_ssh_responding(386)|
>>>> windowsbase1 is
>>>> NOT responding to SSH, ports 22 or 24 are both closed
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|Module.pm:code_loop_timeout(767)|waiting
>>>> for
>>>> windowsbase1 to respond to SSH, code did not return true after
>>>> waiting 600 seconds
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|OS.pm:wait_for_response(465)|failed to
>>>> connect to windowsbase1 via SSH after 600 seconds
>>>> |9566|6:6|reload| ( 0) OS.pm, wait_for_response (line: 465)
>>>> |9566|6:6|reload| (-1) Version_6.pm, wait_for_response (line: 1767)
>>>> |9566|6:6|reload| (-2) Windows.pm, post_load (line: 591)
>>>> |9566|6:6|reload| (-3) Version_6.pm, post_load (line: 194)
>>>> |9566|6:6|reload| (-4) VMware.pm, load (line: 415)
>>>> |9566|6:6|reload| (-5) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|Windows.pm:post_load(592)|windowsbase1
>>>> never
>>>> responded to SSH
>>>> |9566|6:6|reload| ( 0) Windows.pm, post_load (line: 592)
>>>> |9566|6:6|reload| (-1) Version_6.pm, post_load (line: 194)
>>>> |9566|6:6|reload| (-2) VMware.pm, load (line: 415)
>>>> |9566|6:6|reload| (-3) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| (-4) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-5) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|Version_6.pm:post_load(198)|failed to
>>>> execute parent class post_load() subroutine
>>>> |9566|6:6|reload| ( 0) Version_6.pm, post_load (line: 198)
>>>> |9566|6:6|reload| (-1) VMware.pm, load (line: 415)
>>>> |9566|6:6|reload| (-2) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| (-3) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-4) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-5) vcld, main (line: 346)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|VMware.pm:load(419)|failed to perform OS
>>>> post-load tasks on VM windowsbase1 on VM host: esxi.qro.itesm.mx
>>>> |9566|6:6|reload| ( 0) VMware.pm, load (line: 419)
>>>> |9566|6:6|reload| (-1) new.pm, reload_image (line: 618)
>>>> |9566|6:6|reload| (-2) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-3) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-4) vcld, main (line: 346)
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|new.pm:reload_image(623)|vmwarewin7-
>>>> WindowsBaseP69-v0
>>>> failed to load on windowsbase1, returning
>>>> |9566|6:6|reload| ( 0) new.pm, reload_image (line: 623)
>>>> |9566|6:6|reload| (-1) new.pm, process (line: 248)
>>>> |9566|6:6|reload| (-2) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-3) vcld, main (line: 346)
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|utils.pm:insertloadlog(3875)|inserted
>>>> computer=2, loadimagefailed, vmwarewin7-WindowsBaseP69-v0 failed to
>>>> load on windowsbase1
>>>> |9566|6:6|reload| ---- WARNING ----
>>>> |9566|6:6|reload| 2011-04-05
>>>> 21:58:47|9566|6:6|reload|new.pm:process(295)|failed to load
>>>> windowsbase1 with vmwarewin7-WindowsBaseP69-v0
>>>> |9566|6:6|reload| ( 0) new.pm, process (line: 295)
>>>> |9566|6:6|reload| (-1) vcld, make_new_child (line: 568)
>>>> |9566|6:6|reload| (-2) vcld, main (line: 346)
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_state_name(2305)|attempting
>>>> to retrieve current state of computer windowsbase1 from the
>>>> database
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_state_name(2336)|retrieved
>>>> current state of computer windowsbase1 from the database: reloading
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|DataStructure.pm:_automethod(834)|data
>>>> structure updated:
>>>> $self->request_data->{reservation}{6}{computer}{state}{name}
>>>> |9566|6:6|reload| computer_state_name = reloading
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_private_ip_address(1581)|attempting
>>>> to retrieve private IP address for computer: windowsbase1
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
>>>> contents of /etc/hosts on this management node, contains 7 lines
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_computer_private_ip_address(1645)|returning
>>>> IP address from /etc/hosts file: 192.168.113.242
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|utils.pm:is_inblockrequest(6163)|zero rows
>>>> were returned from database select
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:get_image_affiliation_name(2035)|image
>>>> owner id: 1
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|
>>>> database
>>>> requested (information_schema) does not match handle stored in
>>>> $ENV{dbh} (vcl:10.25.19.220)
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|
>>>> database
>>>> handle stored in $ENV{dbh}
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:retrieve_user_data(1352)|attempting
>>>> to retrieve and store data for user: user.id = '1'
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|
>>>> database
>>>> requested (vcl) does not match handle stored in $ENV{dbh}
>>>> (information_schema:10.25.19.220)
>>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|
>>>> database
>>>> handle stored in $ENV{dbh}
>>>> 2011-04-05
>>>> 21:58:47|9566|6:6|reload|
>>>> DataStructure.pm:retrieve_user_data(1415)|data
>>>> has been retrieved for user: admin (id: 1)
>>>>
>>>>
>>>> Juan Jose Zamanillo
>>>>
>>>> jjzamani@itesm.mx <ma...@itesm.mx>
>>>>
>>>
>>
Re: Fwd: problems with image windows 7
Posted by Andy Kurth <an...@ncsu.edu>.
My guess is that it's a storage controller problem. Check the storage
controller type that is assigned to the VM in the vSphere Client:
-Select the VM
-Edit Settings
-SCSI controller 0
Try changing the type to LSI Logic SAS and then power on the VM. If
this allows the VM to boot then the original base image was saved using
the SAS controller. VCL does not currently assign a SAS controller due
to a VMware bug. It will be easiest to recreate your base image. When
creating the initial VM, be sure to select LSI Logic Parallel.
-Andy
On 4/6/2011 12:28 AM, Juan Jose Zamanillo wrote:
>
>
>> *De: *Juan Jose Zamanillo <jjzamani@itesm.mx <ma...@itesm.mx>>
>> *Fecha: *5 de abril de 2011 11:16:22 p.m. GMT-05:00
>> *Para: *vcl-user-info@incubator.apache.org
>> <ma...@incubator.apache.org>
>> *Asunto: **Fwd: problems with image windows 7*
>>
>>
>>
>> Inicio del mensaje reenviado:
>>
>>> *De: *Juan Jose Zamanillo <jjzamani@itesm.mx <ma...@itesm.mx>>
>>> *Fecha: *5 de abril de 2011 11:04:05 p.m. GMT-05:00
>>> *Para: *vcl-user-info@incubator.apache.org
>>> <ma...@incubator.apache.org>
>>> *Asunto: **problems with image windows 7*
>>>
>>> I generate aWindows 7 Image, all things works ok, but when the
>>> virtual machine reboot, the console of the virtual machine begin with
>>> a blue screen and then tell me that there is a problem with the OS
>>> and start de windows recovery system and ask me if I want to try to
>>> repair the image o start normaly windows, either options did not
>>> work, if i select repair maybe 10 times always is the same problem.
>>>
>>> I am copying the mails and logs
>>>
>>> thanks in advance
>>>
>>>
>>> Your VCL image creation request for Windows Base P6 has
>>> succeeded. Please visit http://cwiki.apache.org/VCL and
>>> you should see an image called Windows Base P6.
>>> Please test this image to confirm it works correctly.
>>>
>>> Thank You,
>>> VCL Team
>>>
>>> ===================================================
>>>
>>> VCL Image Creation Completed
>>>
>>> Request ID: 5
>>> Reservation ID: 5
>>> PID: 6740
>>>
>>> Image ID: 9
>>> Image name: vmwarewin7-WindowsBaseP69-v0
>>> Image size change: 1450 --> 15360
>>>
>>> Revision ID: 9
>>>
>>> Management node: webvcl
>>>
>>> Username: admin
>>> User ID: 1
>>>
>>> Computer ID: 2
>>> Computer name: windowsbase1
>>>
>>> Use Sysprep: 0
>>>
>>> ================================================
>>>
>>> reservation failed on windowsbase1: process failed after trying to
>>> load or make available
>>> ------------------------------------------------------------------------
>>> time: 2011-04-05 21:58:47
>>> caller: State.pm:reservation_failed(213)
>>> ( 0) State.pm, reservation_failed (line: 213)
>>> (-1) new.pm, process (line: 298)
>>> (-2) vcld, make_new_child (line: 568)
>>> (-3) vcld, main (line: 346)
>>> ------------------------------------------------------------------------
>>> management node: webvcl.qro.itesm.mx
>>> reservation PID: 9566
>>> parent vcld PID: 31208
>>>
>>> request ID: 6
>>> reservation ID: 6
>>> request state/laststate: reload/image
>>> request start time: 2011-04-05 21:48:11
>>> request end time: 2011-04-05 22:18:11
>>> for imaging: no
>>> log ID: none
>>>
>>> computer: windowsbase1
>>> computer id: 2
>>> computer type: virtualmachine
>>> computer eth0 MAC address: 00:50:56:00:10:00
>>> computer eth1 MAC address: 00:50:56:00:10:01
>>> computer private IP address: 192.168.113.242
>>> computer public IP address: 10.25.19.166
>>> computer in block allocation: no
>>> provisioning module: VCL::Module::Provisioning::VMware::VMware
>>>
>>> vm host: esxi.qro.itesm.mx
>>> vm host ID: 1
>>> vm host computer ID: 1
>>> vm profile: VMware ESX - network storage
>>> vm profile VM path: /vmfs/volumes/datastore1
>>> vm profile repository path: 0
>>> vm profile datastore path: /vmfs/volumes/datastore1
>>> vm profile disk type: networkdisk
>>>
>>> image: vmwarewin7-WindowsBaseP69-v0
>>> image display name: Windows Base P6
>>> image ID: 9
>>> image revision ID: 9
>>> image size: 15360 MB
>>> use Sysprep: no
>>> root access: yes
>>> image owner ID: 1
>>> image owner affiliation: Local
>>> image revision date created: 2011-04-05 21:03:48
>>> image revision production: yes
>>> OS module: VCL::Module::OS::Windows::Version_6::7
>>>
>>> user: vclreload
>>> user name: vcl reload
>>> user ID: 2
>>> user affiliation: Local
>>> ------------------------------------------------------------------------
>>> RECENT LOG ENTRIES FOR THIS PROCESS:
>>> 2011-04-05
>>> 21:55:25|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 27:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:55:26|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:55:26|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 27:
>>> code returned false, seconds elapsed/remaining: 403/197, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:55:41|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 28:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:55:41|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:55:41|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 28:
>>> code returned false, seconds elapsed/remaining: 418/182, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:55:56|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 29:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:55:57|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:55:57|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 29:
>>> code returned false, seconds elapsed/remaining: 434/166, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:56:12|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 30:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:56:12|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:56:12|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 30:
>>> code returned false, seconds elapsed/remaining: 449/151, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:56:27|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 31:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:56:28|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:56:28|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 31:
>>> code returned false, seconds elapsed/remaining: 465/135, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:56:43|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 32:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:56:43|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:56:43|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 32:
>>> code returned false, seconds elapsed/remaining: 480/120, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:56:58|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 33:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:56:59|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:56:59|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 33:
>>> code returned false, seconds elapsed/remaining: 496/104, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:57:14|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 34:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:57:14|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:57:14|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 34:
>>> code returned false, seconds elapsed/remaining: 511/89, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:57:29|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 35:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:57:30|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:57:30|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 35:
>>> code returned false, seconds elapsed/remaining: 527/73, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:57:45|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 36:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:57:45|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:57:45|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 36:
>>> code returned false, seconds elapsed/remaining: 542/58, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:58:00|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 37:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:58:00|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:58:00|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 37:
>>> code returned false, seconds elapsed/remaining: 557/43, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:58:15|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 38:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:58:16|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:58:16|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 38:
>>> code returned false, seconds elapsed/remaining: 573/27, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:58:31|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 39:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:58:31|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:58:31|9566|6:6|reload|Module.pm:code_loop_timeout(755)|attempt 39:
>>> code returned false, seconds elapsed/remaining: 588/12, sleeping for
>>> 15 seconds
>>> 2011-04-05
>>> 21:58:46|9566|6:6|reload|Module.pm:code_loop_timeout(759)|attempt 40:
>>> waiting for windowsbase1 to respond to SSH
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|OS.pm:is_ssh_responding(386)|windowsbase1 is
>>> NOT responding to SSH, ports 22 or 24 are both closed
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|Module.pm:code_loop_timeout(767)|waiting for
>>> windowsbase1 to respond to SSH, code did not return true after
>>> waiting 600 seconds
>>> |9566|6:6|reload| ---- WARNING ----
>>> |9566|6:6|reload| 2011-04-05
>>> 21:58:47|9566|6:6|reload|OS.pm:wait_for_response(465)|failed to
>>> connect to windowsbase1 via SSH after 600 seconds
>>> |9566|6:6|reload| ( 0) OS.pm, wait_for_response (line: 465)
>>> |9566|6:6|reload| (-1) Version_6.pm, wait_for_response (line: 1767)
>>> |9566|6:6|reload| (-2) Windows.pm, post_load (line: 591)
>>> |9566|6:6|reload| (-3) Version_6.pm, post_load (line: 194)
>>> |9566|6:6|reload| (-4) VMware.pm, load (line: 415)
>>> |9566|6:6|reload| (-5) new.pm, reload_image (line: 618)
>>> |9566|6:6|reload| ---- WARNING ----
>>> |9566|6:6|reload| 2011-04-05
>>> 21:58:47|9566|6:6|reload|Windows.pm:post_load(592)|windowsbase1 never
>>> responded to SSH
>>> |9566|6:6|reload| ( 0) Windows.pm, post_load (line: 592)
>>> |9566|6:6|reload| (-1) Version_6.pm, post_load (line: 194)
>>> |9566|6:6|reload| (-2) VMware.pm, load (line: 415)
>>> |9566|6:6|reload| (-3) new.pm, reload_image (line: 618)
>>> |9566|6:6|reload| (-4) new.pm, process (line: 248)
>>> |9566|6:6|reload| (-5) vcld, make_new_child (line: 568)
>>> |9566|6:6|reload| ---- WARNING ----
>>> |9566|6:6|reload| 2011-04-05
>>> 21:58:47|9566|6:6|reload|Version_6.pm:post_load(198)|failed to
>>> execute parent class post_load() subroutine
>>> |9566|6:6|reload| ( 0) Version_6.pm, post_load (line: 198)
>>> |9566|6:6|reload| (-1) VMware.pm, load (line: 415)
>>> |9566|6:6|reload| (-2) new.pm, reload_image (line: 618)
>>> |9566|6:6|reload| (-3) new.pm, process (line: 248)
>>> |9566|6:6|reload| (-4) vcld, make_new_child (line: 568)
>>> |9566|6:6|reload| (-5) vcld, main (line: 346)
>>> |9566|6:6|reload| ---- WARNING ----
>>> |9566|6:6|reload| 2011-04-05
>>> 21:58:47|9566|6:6|reload|VMware.pm:load(419)|failed to perform OS
>>> post-load tasks on VM windowsbase1 on VM host: esxi.qro.itesm.mx
>>> |9566|6:6|reload| ( 0) VMware.pm, load (line: 419)
>>> |9566|6:6|reload| (-1) new.pm, reload_image (line: 618)
>>> |9566|6:6|reload| (-2) new.pm, process (line: 248)
>>> |9566|6:6|reload| (-3) vcld, make_new_child (line: 568)
>>> |9566|6:6|reload| (-4) vcld, main (line: 346)
>>> |9566|6:6|reload| ---- WARNING ----
>>> |9566|6:6|reload| 2011-04-05
>>> 21:58:47|9566|6:6|reload|new.pm:reload_image(623)|vmwarewin7-WindowsBaseP69-v0
>>> failed to load on windowsbase1, returning
>>> |9566|6:6|reload| ( 0) new.pm, reload_image (line: 623)
>>> |9566|6:6|reload| (-1) new.pm, process (line: 248)
>>> |9566|6:6|reload| (-2) vcld, make_new_child (line: 568)
>>> |9566|6:6|reload| (-3) vcld, main (line: 346)
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|utils.pm:insertloadlog(3875)|inserted
>>> computer=2, loadimagefailed, vmwarewin7-WindowsBaseP69-v0 failed to
>>> load on windowsbase1
>>> |9566|6:6|reload| ---- WARNING ----
>>> |9566|6:6|reload| 2011-04-05
>>> 21:58:47|9566|6:6|reload|new.pm:process(295)|failed to load
>>> windowsbase1 with vmwarewin7-WindowsBaseP69-v0
>>> |9566|6:6|reload| ( 0) new.pm, process (line: 295)
>>> |9566|6:6|reload| (-1) vcld, make_new_child (line: 568)
>>> |9566|6:6|reload| (-2) vcld, main (line: 346)
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:get_computer_state_name(2305)|attempting
>>> to retrieve current state of computer windowsbase1 from the database
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:get_computer_state_name(2336)|retrieved
>>> current state of computer windowsbase1 from the database: reloading
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:_automethod(834)|data
>>> structure updated:
>>> $self->request_data->{reservation}{6}{computer}{state}{name}
>>> |9566|6:6|reload| computer_state_name = reloading
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:get_computer_private_ip_address(1581)|attempting
>>> to retrieve private IP address for computer: windowsbase1
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
>>> contents of /etc/hosts on this management node, contains 7 lines
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:get_computer_private_ip_address(1645)|returning
>>> IP address from /etc/hosts file: 192.168.113.242
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|utils.pm:is_inblockrequest(6163)|zero rows
>>> were returned from database select
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:get_image_affiliation_name(2035)|image
>>> owner id: 1
>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|database
>>> requested (information_schema) does not match handle stored in
>>> $ENV{dbh} (vcl:10.25.19.220)
>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|database
>>> handle stored in $ENV{dbh}
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:retrieve_user_data(1352)|attempting
>>> to retrieve and store data for user: user.id = '1'
>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2709)|database
>>> requested (vcl) does not match handle stored in $ENV{dbh}
>>> (information_schema:10.25.19.220)
>>> 2011-04-05 21:58:47|9566|6:6|reload|utils.pm:getnewdbh(2760)|database
>>> handle stored in $ENV{dbh}
>>> 2011-04-05
>>> 21:58:47|9566|6:6|reload|DataStructure.pm:retrieve_user_data(1415)|data
>>> has been retrieved for user: admin (id: 1)
>>>
>>>
>>> Juan Jose Zamanillo
>>>
>>> jjzamani@itesm.mx <ma...@itesm.mx>
>>>
>>
>