RE: Reservation Error:
Hi Andy, I followed through every step in esx.README and still not successful in capturing an image: I can SSH to the hypervisor and the VMhost through the management node with the/etc/vcl/vcl.key etc etc ..everything else looks okay; below I also have the query's that I used to create this image and reservation. Please advise. 2010-03-22 13:55:11|14001|vcld:main(165)|lastcheckin time updated for management node 1: 2010-03-22 13:55:11 2010-03-22 13:55:11|14001|2:3|image|utils.pm:reservation_being_processed(9634)|computerloadlog 'begin' entry does NOT exist for reservation 3 2010-03-22 13:55:11|14001|2:3|image|utils.pm:reservation_being_processed(9686)|reservation is NOT currently being processed 2010-03-22 13:55:11|14001|2:3|image|vcld:main(276)|reservation 3 is NOT already being processed |14001|2:3|image| WARNING |14001|2:3|image| 2010-03-22 13:55:11|14001|2:3|image|utils.pm:get_request_info(5218)|preferredimageid is not set for computer id=3 |14001|2:3|image| ( 0) utils.pm, notify (line: 737) |14001|2:3|image| (-1) utils.pm, get_request_info (line: 5218) |14001|2:3|image| (-2) vcld, main (line: 280) 2010-03-22 13:55:11|14001|2:3|image|utils.pm:get_request_info(5354)|standalone affiliation found: Local 2010-03-22 13:55:11|14001|2:3|image|utils.pm:get_management_node_info(6815)|management node info retrieved from database for colbert 2010-03-22 13:55:11|14001|2:3|image|vcld:main(281)|retrieved request information from database 2010-03-22 13:55:11|14001|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 3 2010-03-22 13:55:11|14001|2:3|image|utils.pm:update_request_state(2186)|request 2 state updated to: pending, laststate to: image 2010-03-22 13:55:11|14001|2:3|image|utils.pm:insertloadlog(4710)|inserted computer=3, begin, beginning to process, state is image 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(509)|loaded VCL::image module 2010-03-22 13:55:11|14061|2:3|image|vcld:make_new_child(582)|vcld environment variable set to 0 for this process 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(132)|constructor called, class=VCL::image 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(154)|VCL::image object created 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(566)|current number of forked kids: 1 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh} 2010-03-22 13:55:11|14061|2:3|image|State.pm:check_image_os(839)|no corrections need to be made to image OS: esx35 2010-03-22 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 3 2010-03-22 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7863)|reservation count: 1 2010-03-22 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7882)|PARENTIMAGE: 1 2010-03-22 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7883)|SUBIMAGE: 0 2010-03-22 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7901)|renamed process to 'vcld VCL::image 2:3 image imaging' 2010-03-22 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 3 2010-03-22 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 3 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(121)|attempting to load provisioning module: VCL::Module::Provisioning::esx 2010-03-22 13:55:11|14061|2:3|image|esx.pm:initialize(138)|esx vmware toolkit root path found: /usr/lib/vmware-vcli/apps 2010-03-22 13:55:11|14061|2:3|image|esx.pm:initialize(140)|vmware ESX module initialized 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(127)|VCL::Module::Provisioning::esx module loaded 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(132)|constructor called, class=VCL::Module::Provisioning::esx 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(154)|VCL::Module::Provisioning::esx object created 2010-03-22 13:55:11|14061|2:3|image|esx.pm:initialize(138)|esx vmware toolkit root path found: /usr/lib/vmware-vcli/apps 2010-03-22 13:55:11|14061|2:3|image|esx.pm:initialize(140)|vmware ESX module initialized 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(131)|VCL::Module::Provisioning::esx provisioner object created 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(145)|attempting to load OS module: VCL::Module::OS::Linux 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(151)|VCL::Module::OS::Linux module loaded 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(132)|constructor called, class=VCL::Module::OS::Linux 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(154)|VCL::Module::OS::Linux object created 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(154)|VCL::Module::OS::Linux OS object created 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(169)|r
Re: Reservation Error:
I ran into this as well using the free version of Esxi, I'm not sure what version you are using. It has to do with the exit status returned from the Esx server. Try running the ssh command that VCL runs from the command line, but add the -v option to print out more information. The work-around I came up with was just to specify the server that was actually exporting the NFS. 14061|2:3|image| WARNING |14061|2:3|image| 2010-03-22 13:55:12|14061|2:3|image|utils.pm:run_ssh_command(6252)|attempt 1/3: failed to execute SSH command on 128.82.128.252: ls -1 /vmfs/volumes/15a5e041-86bf33de//golden 2>&1, exit status: 255, SSH exits with the exit status of the remote command or with 255 if an error occurred, output: |14061|2:3|image| ssh output (ls -1 /vmf...): esx3-basewinxp-v0 #>>> notice that the command returns the correct output #>>> Here it sees the image But I don't understand why it fails to create it. |14061|2:3|image| ( 0) utils.pm, notify (line: 737) |14061|2:3|image| (-1) utils.pm, run_ssh_command (line: 6252) |14061|2:3|image| (-2) esx.pm, does_image_exist (line: 910) |14061|2:3|image| (-3) image.pm, process (line: 135) |14061|2:3|image| (-4) vcld, make_new_child (line: 594) |14061|2:3|image| (-5) vcld, main (line: 341) On Mon, Mar 22, 2010 at 2:08 PM, Kumar, Amit H. wrote: > Hi Andy, > > I followed through every step in esx.README and still not successful in > capturing an image: > > I can SSH to the hypervisor and the VMhost through the management node with > the/etc/vcl/vcl.key etc etc ..everything else looks okay; below I also have > the query's that I used to create this image and reservation. > > Please advise. > > 2010-03-22 13:55:11|14001|vcld:main(165)|lastcheckin time updated for > management node 1: 2010-03-22 13:55:11 > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:reservation_being_processed(9634)|computerloadlog > 'begin' entry does NOT exist for reservation 3 > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:reservation_being_processed(9686)|reservation > is NOT currently being processed > 2010-03-22 13:55:11|14001|2:3|image|vcld:main(276)|reservation 3 is NOT > already being processed > > |14001|2:3|image| WARNING > |14001|2:3|image| 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:get_request_info(5218)|preferredimageid > is not set for computer id=3 > |14001|2:3|image| ( 0) utils.pm, notify (line: 737) > |14001|2:3|image| (-1) utils.pm, get_request_info (line: 5218) > |14001|2:3|image| (-2) vcld, main (line: 280) > > 2010-03-22 13:55:11|14001|2:3|image|utils.pm:get_request_info(5354)|standalone > affiliation found: Local > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:get_management_node_info(6815)|management > node info retrieved from database for colbert > 2010-03-22 13:55:11|14001|2:3|image|vcld:main(281)|retrieved request > information from database > 2010-03-22 > 13:55:11|14001|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > true: parent reservation ID for this request: 3 > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:update_request_state(2186)|request > 2 state updated to: pending, laststate to: image > 2010-03-22 13:55:11|14001|2:3|image|utils.pm:insertloadlog(4710)|inserted > computer=3, begin, beginning to process, state is image > 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(509)|loaded > VCL::image module > 2010-03-22 13:55:11|14061|2:3|image|vcld:make_new_child(582)|vcld > environment variable set to 0 for this process > 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(132)|constructor called, > class=VCL::image > 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(154)|VCL::image object > created > 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(566)|current number > of forked kids: 1 > 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(85)|obtained a > database handle for this state process, stored as $ENV{dbh} > 2010-03-22 13:55:11|14061|2:3|image|State.pm:check_image_os(839)|no > corrections need to be made to image OS: esx35 > 2010-03-22 > 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > true: parent reservation ID for this request: 3 > 2010-03-22 > 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7863)|reservation > count: 1 > 2010-03-22 > 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7882)|PARENTIMAGE: > 1 > 2010-03-22 > 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7883)|SUBIMAGE: > 0 > 2010-03-22 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7901)|renamed > process to 'vcld VCL::image 2:3 image imaging' > 2010-03-22 > 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > true: parent reservation ID for this request: 3 > 2010-03-22 > 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > true: parent reservation ID for this request: 3 > 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(121)|attempting to > load provisioning module: VCL::Module::Provisioning::
RE: Reservation Error:
Hi Ryan, Well I am using the free version as well. I don't seem to understand your workaround. Do you mean that: Instead of specifying the IP:path_to_images for Data store path, I would rather benefit from specifying just the IP: ? Thank you for your feedback, Amit > -Original Message- > From: Ryan Johnson [mailto:rjoh...@gwmail.gwu.edu] > Sent: Monday, March 22, 2010 3:55 PM > To: vcl-dev@incubator.apache.org > Subject: Re: Reservation Error: > > I ran into this as well using the free version of Esxi, I'm not sure what > version you are using. It has to do with the exit status returned from the > Esx server. Try running the ssh command that VCL runs from the command > line, but add the -v option to print out more information. The work-around > I came up with was just to specify the server that was actually exporting > the NFS. > > 14061|2:3|image| WARNING > |14061|2:3|image| 2010-03-22 > 13:55:12|14061|2:3|image|utils.pm:run_ssh_command(6252)|attempt > 1/3: failed to execute SSH command on 128.82.128.252: ls -1 > /vmfs/volumes/15a5e041-86bf33de//golden 2>&1, exit status: 255, SSH exits > with the exit status of the remote command or with 255 if an error occurred, > output: > |14061|2:3|image| ssh output (ls -1 /vmf...): esx3-basewinxp-v0 #>>> > notice that the command returns the correct output > > #>>> Here it sees the image But I don't understand why it fails to create > it. > > |14061|2:3|image| ( 0) utils.pm, notify (line: 737) > |14061|2:3|image| (-1) utils.pm, run_ssh_command (line: 6252) > |14061|2:3|image| (-2) esx.pm, does_image_exist (line: 910) > |14061|2:3|image| (-3) image.pm, process (line: 135) > |14061|2:3|image| (-4) vcld, make_new_child (line: 594) > |14061|2:3|image| (-5) vcld, main (line: 341) > > > On Mon, Mar 22, 2010 at 2:08 PM, Kumar, Amit H. wrote: > > > Hi Andy, > > > > I followed through every step in esx.README and still not successful in > > capturing an image: > > > > I can SSH to the hypervisor and the VMhost through the management node with > > the/etc/vcl/vcl.key etc etc ..everything else looks okay; below I also have > > the query's that I used to create this image and reservation. > > > > Please advise. > > > > 2010-03-22 13:55:11|14001|vcld:main(165)|lastcheckin time updated for > > management node 1: 2010-03-22 13:55:11 > > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:reservation_being_processed(9634)|computerloadlog > > 'begin' entry does NOT exist for reservation 3 > > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:reservation_being_processed(9686)|reservation > > is NOT currently being processed > > 2010-03-22 13:55:11|14001|2:3|image|vcld:main(276)|reservation 3 is NOT > > already being processed > > > > |14001|2:3|image| WARNING > > |14001|2:3|image| 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:get_request_info(5218)|preferredimageid > > is not set for computer id=3 > > |14001|2:3|image| ( 0) utils.pm, notify (line: 737) > > |14001|2:3|image| (-1) utils.pm, get_request_info (line: 5218) > > |14001|2:3|image| (-2) vcld, main (line: 280) > > > > 2010-03-22 > > 13:55:11|14001|2:3|image|utils.pm:get_request_info(5354)|standalone > > affiliation found: Local > > 2010-03-22 > 13:55:11|14001|2:3|image|utils.pm:get_management_node_info(6815)|management > > node info retrieved from database for colbert > > 2010-03-22 13:55:11|14001|2:3|image|vcld:main(281)|retrieved request > > information from database > > 2010-03-22 > > 13:55:11|14001|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > > true: parent reservation ID for this request: 3 > > 2010-03-22 > > 13:55:11|14001|2:3|image|utils.pm:update_request_state(2186)|request > > 2 state updated to: pending, laststate to: image > > 2010-03-22 13:55:11|14001|2:3|image|utils.pm:insertloadlog(4710)|inserted > > computer=3, begin, beginning to process, state is image > > 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(509)|loaded > > VCL::image module > > 2010-03-22 13:55:11|14061|2:3|image|vcld:make_new_child(582)|vcld > > environment variable set to 0 for this process > > 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(132)|constructor called, > > class=VCL::image > > 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(154)|VCL::image object > > created > > 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(566)|current number > > of forked kids: 1 > > 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(85)|obtained a > > database handle for this state process, stored as $ENV{dbh} > > 2010-03-22 13:55:11|14061|2:3|image|State.pm:check_image_os(839)|no > > corrections need to be made to image OS: esx35 > > 2010-03-22 > > 13:55:11|14061|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > > true: parent reservation ID for this request: 3 > > 2010-03-22 > > 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7863)|reservation > > count: 1 > > 2010-03-22 > 13:55:11|14061|2:3|image|utils.pm:rename_vcld_process(7882)|PARENTIMAG
Re: Reservation Error:
No what I did was avoid running ssh commands on the Esxi entirely by specifying the IP of a remote server which is hosting a network file system as the datastorepath column in the vmprofile table. On Mon, Mar 22, 2010 at 3:59 PM, Kumar, Amit H. wrote: > Hi Ryan, > > Well I am using the free version as well. I don't seem to understand your > workaround. > Do you mean that: Instead of specifying the IP:path_to_images for Data > store path, I would rather benefit from specifying just the IP: ? > > Thank you for your feedback, > Amit > > > > -Original Message- > > From: Ryan Johnson [mailto:rjoh...@gwmail.gwu.edu] > > Sent: Monday, March 22, 2010 3:55 PM > > To: vcl-dev@incubator.apache.org > > Subject: Re: Reservation Error: > > > > I ran into this as well using the free version of Esxi, I'm not sure what > > version you are using. It has to do with the exit status returned from > the > > Esx server. Try running the ssh command that VCL runs from the command > > line, but add the -v option to print out more information. The > work-around > > I came up with was just to specify the server that was actually exporting > > the NFS. > > > > 14061|2:3|image| WARNING > > |14061|2:3|image| 2010-03-22 > > 13:55:12|14061|2:3|image|utils.pm:run_ssh_command(6252)|attempt > > 1/3: failed to execute SSH command on 128.82.128.252: ls -1 > > /vmfs/volumes/15a5e041-86bf33de//golden 2>&1, exit status: 255, SSH exits > > with the exit status of the remote command or with 255 if an error > occurred, > > output: > > |14061|2:3|image| ssh output (ls -1 /vmf...): esx3-basewinxp-v0 > #>>> > > notice that the command returns the correct output > > > > #>>> Here it sees the image But I don't understand why it fails to create > > it. > > > > |14061|2:3|image| ( 0) utils.pm, notify (line: 737) > > |14061|2:3|image| (-1) utils.pm, run_ssh_command (line: 6252) > > |14061|2:3|image| (-2) esx.pm, does_image_exist (line: 910) > > |14061|2:3|image| (-3) image.pm, process (line: 135) > > |14061|2:3|image| (-4) vcld, make_new_child (line: 594) > > |14061|2:3|image| (-5) vcld, main (line: 341) > > > > > > On Mon, Mar 22, 2010 at 2:08 PM, Kumar, Amit H. wrote: > > > > > Hi Andy, > > > > > > I followed through every step in esx.README and still not successful in > > > capturing an image: > > > > > > I can SSH to the hypervisor and the VMhost through the management node > with > > > the/etc/vcl/vcl.key etc etc ..everything else looks okay; below I also > have > > > the query's that I used to create this image and reservation. > > > > > > Please advise. > > > > > > 2010-03-22 13:55:11|14001|vcld:main(165)|lastcheckin time updated for > > > management node 1: 2010-03-22 13:55:11 > > > 2010-03-22 > > 13:55:11|14001|2:3|image|utils.pm: > reservation_being_processed(9634)|computerloadlog > > > 'begin' entry does NOT exist for reservation 3 > > > 2010-03-22 > > 13:55:11|14001|2:3|image|utils.pm: > reservation_being_processed(9686)|reservation > > > is NOT currently being processed > > > 2010-03-22 13:55:11|14001|2:3|image|vcld:main(276)|reservation 3 is NOT > > > already being processed > > > > > > |14001|2:3|image| WARNING > > > |14001|2:3|image| 2010-03-22 > > 13:55:11|14001|2:3|image|utils.pm: > get_request_info(5218)|preferredimageid > > > is not set for computer id=3 > > > |14001|2:3|image| ( 0) utils.pm, notify (line: 737) > > > |14001|2:3|image| (-1) utils.pm, get_request_info (line: 5218) > > > |14001|2:3|image| (-2) vcld, main (line: 280) > > > > > > 2010-03-22 13:55:11|14001|2:3|image|utils.pm: > get_request_info(5354)|standalone > > > affiliation found: Local > > > 2010-03-22 > > 13:55:11|14001|2:3|image|utils.pm: > get_management_node_info(6815)|management > > > node info retrieved from database for colbert > > > 2010-03-22 13:55:11|14001|2:3|image|vcld:main(281)|retrieved request > > > information from database > > > 2010-03-22 > > > > 13:55:11|14001|2:3|image|DataStructure.pm:is_parent_reservation(854)|returning > > > true: parent reservation ID for this request: 3 > > > 2010-03-22 13:55:11|14001|2:3|image|utils.pm: > update_request_state(2186)|request > > > 2 state updated to: pending, laststate to: image > > > 2010-03-22 13:55:11|14001|2:3|image|utils.pm: > insertloadlog(4710)|inserted > > > computer=3, begin, beginning to process, state is image > > > 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(509)|loaded > > > VCL::image module > > > 2010-03-22 13:55:11|14061|2:3|image|vcld:make_new_child(582)|vcld > > > environment variable set to 0 for this process > > > 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(132)|constructor > called, > > > class=VCL::image > > > 2010-03-22 13:55:11|14061|2:3|image|Module.pm:new(154)|VCL::image > object > > > created > > > 2010-03-22 13:55:11|14001|2:3|image|vcld:make_new_child(566)|current > number > > > of forked kids: 1 > > > 2010-03-22 13:55:11|14061|2:3|image|State.pm:initialize(85)|obtained a > > > database handle for this state process, stored
RE: Reservation Error:
Hi Ryan, you are great! I made quite a bit of progress with your suggestion. I hope you can help me with this next step error: Seems like image creation was successful but upon realod/poweron VMware perl toolkit looks for server '128' instead of the full ip. Something seems to be missing: Hope you can shed some more light on this!!! 2010-03-22 16:50:28|14912|vcld:main(165)|lastcheckin time updated for management node 1: 2010-03-22 16:50:28 2010-03-22 16:50:33|14912|vcld:main(165)|lastcheckin time updated for management node 1: 2010-03-22 16:50:33 === OUTPUT for vcld run on 2010-03-22 16:51:04 === 2010-03-22 16:51:04|15551|vcld:main(116)|vcld environment variable set to 1 for this process 2010-03-22 16:51:04|15551|utils.pm:rename_vcld_process(7901)|renamed process to 'vcld vcld' 2010-03-22 16:51:04|15551|utils.pm:get_management_node_info(6815)|management node info retrieved from database for colbert 2010-03-22 16:51:04|15551|vcld:main(127)|retrieved management node information from database 2010-03-22 16:51:04|15551|vcld:main(140)|management_node_id environment variable set: 1 2010-03-22 16:51:04|15551|vcld:main(148)|management node checkin interval is 5 seconds 2010-03-22 16:51:04|15551|vcld:main(149)|vcld started on colbert.vcls.priv.odu.edu 2010-03-22 16:51:09|15551|vcld:main(165)|lastcheckin time updated for management node 1: 2010-03-22 16:51:09 2010-03-22 16:51:09|15551|2:2|image|utils.pm:reservation_being_processed(9634)|computerloadlog 'begin' entry does NOT exist for reservation 2 2010-03-22 16:51:09|15551|2:2|image|utils.pm:reservation_being_processed(9686)|reservation is NOT currently being processed 2010-03-22 16:51:09|15551|2:2|image|vcld:main(276)|reservation 2 is NOT already being processed |15551|2:2|image| WARNING |15551|2:2|image| 2010-03-22 16:51:09|15551|2:2|image|utils.pm:get_request_info(5218)|preferredimageid is not set for computer id=3 |15551|2:2|image| ( 0) utils.pm, notify (line: 737) |15551|2:2|image| (-1) utils.pm, get_request_info (line: 5218) |15551|2:2|image| (-2) vcld, main (line: 280) 2010-03-22 16:51:09|15551|2:2|image|utils.pm:get_request_info(5354)|standalone affiliation found: Local 2010-03-22 16:51:09|15551|2:2|image|utils.pm:get_management_node_info(6815)|management node info retrieved from database for colbert 2010-03-22 16:51:09|15551|2:2|image|vcld:main(281)|retrieved request information from database 2010-03-22 16:51:09|15551|2:2|image|utils.pm:get_management_node_info(6815)|management node info retrieved from database for colbert 2010-03-22 16:51:09|15551|2:2|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 2 2010-03-22 16:51:09|15551|2:2|image|utils.pm:update_request_state(2186)|request 2 state updated to: pending, laststate to: image 2010-03-22 16:51:09|15551|2:2|image|utils.pm:insertloadlog(4710)|inserted computer=3, begin, beginning to process, state is image 2010-03-22 16:51:09|15551|2:2|image|vcld:make_new_child(509)|loaded VCL::image module 2010-03-22 16:51:09|15553|2:2|image|vcld:make_new_child(582)|vcld environment variable set to 0 for this process 2010-03-22 16:51:09|15553|2:2|image|Module.pm:new(132)|constructor called, class=VCL::image 2010-03-22 16:51:09|15553|2:2|image|Module.pm:new(154)|VCL::image object created 2010-03-22 16:51:09|15551|2:2|image|vcld:make_new_child(566)|current number of forked kids: 1 2010-03-22 16:51:09|15553|2:2|image|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh} 2010-03-22 16:51:09|15553|2:2|image|State.pm:check_image_os(839)|no corrections need to be made to image OS: esx35 2010-03-22 16:51:09|15553|2:2|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 2 2010-03-22 16:51:09|15553|2:2|image|utils.pm:rename_vcld_process(7863)|reservation count: 1 2010-03-22 16:51:09|15553|2:2|image|utils.pm:rename_vcld_process(7882)|PARENTIMAGE: 1 2010-03-22 16:51:09|15553|2:2|image|utils.pm:rename_vcld_process(7883)|SUBIMAGE: 0 2010-03-22 16:51:09|15553|2:2|image|utils.pm:rename_vcld_process(7901)|renamed process to 'vcld VCL::image 2:2 image imaging' 2010-03-22 16:51:09|15553|2:2|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 2 2010-03-22 16:51:09|15553|2:2|image|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 2 2010-03-22 16:51:09|15553|2:2|image|State.pm:initialize(121)|attempting to load provisioning module: VCL::Module::Provisioning::esx 2010-03-22 16:51:09|15553|2:2|image|esx.pm:initialize(138)|esx vmware toolkit root path found: /usr/lib/vmware-vcli/apps 2010-03-22 16:51:09|15553|2:2|image|esx.pm:initialize(140)|vmware ESX module initialized 2010-03-22 16:51:09|15553|2:2|image|State.pm:initia