RE: Reservation Error:

2010-03-22 Thread Kumar, Amit H.
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:

2010-03-22 Thread Ryan Johnson
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:

2010-03-22 Thread Kumar, Amit H.
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:

2010-03-22 Thread Ryan Johnson
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:

2010-03-22 Thread Kumar, Amit H.
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