I keep getting this error when trying to create a reservation for our
vista image. If I try to remake the reservation after this error and
manually change the node state back to available the image reservation
completes with no errors but I get this 90% of the time when creating the
first reservation
2010-05-18 09:09:25|29495|195:229|new|utils.pm:run_ssh_command(6276)|SSH
command executed on node1, returning (0, "The command completed
successf...")
2010-05-18
09:09:25|29495|195:229|new|Windows.pm:set_password(1642)|password changed
to 'nGgEwz' for user 'Administrator' on node1
2010-05-18
09:09:25|29495|195:229|new|Windows.pm:set_password(1664)|changing
passwords for scheduled tasks
2010-05-18
09:09:25|29495|195:229|new|utils.pm:run_ssh_command(6180)|executing SSH
command on node1:
|29495|195:229|new| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x
node1 'schtasks.exe /Query /V /FO LIST' 2>&1
2010-05-18
09:09:27|29495|195:229|new|Windows.pm:set_password(1666)|queried scheduled
tasks on node1
2010-05-18
09:09:27|29495|195:229|new|Windows.pm:set_password(1686)|password needs to
be updated for scheduled task:
\RealCreateProcessScheduledTask2746250S-1-5-21-862658267-2660668950-1197910184-500
|29495|195:229|new| Run As User: root-PC\Administrator
2010-05-18
09:09:27|29495|195:229|new|Windows.pm:set_password(1686)|password needs to
be updated for scheduled task:
\WPD\SqmUpload_S-1-5-21-862658267-2660668950-1197910184-500
|29495|195:229|new| Run As User: root-PC\Administrator
2010-05-18
09:09:27|29495|195:229|new|utils.pm:run_ssh_command(6180)|executing SSH
command on node1:
|29495|195:229|new| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x
node1 'schtasks.exe /Change /RP "nGgEwz" /TN
"\RealCreateProcessScheduledTask2746250S-1-5-21-862658267-2660668950-1197910184-500"'
2>&1
2010-05-18
09:09:28|29495|195:229|new|utils.pm:run_ssh_command(6262)|run_ssh_command
output:
|29495|195:229|new| ERROR: The parameter is incorrect.
2010-05-18 09:09:28|29495|195:229|new|utils.pm:run_ssh_command(6272)|SSH
command executed on node1, command:
|29495|195:229|new| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x
node1 'schtasks.exe /Change /RP "nGgEwz" /TN
"\RealCreateProcessScheduledTask2746250S-1-5-21-862658267-2660668950-1197910184-500"'
2>&1
|29495|195:229|new| returning (1, "ERROR: The parameter is incorr...")
|29495|195:229|new| ---- WARNING ----
|29495|195:229|new| 2010-05-18
09:09:28|29495|195:229|new|Windows.pm:set_password(1698)|failed to change
password for scheduled task:
\RealCreateProcessScheduledTask2746250S-1-5-21-862658267-2660668950-1197910184-500,
exit status: 1, output:
|29495|195:229|new| ERROR: The parameter is incorrect.
|29495|195:229|new| ( 0) utils.pm, notify (line: 737)
|29495|195:229|new| (-1) Windows.pm, set_password (line: 1698)
|29495|195:229|new| (-2) Windows.pm, post_load (line: 570)
|29495|195:229|new| (-3) Version_6.pm, post_load (line: 206)
|29495|195:229|new| (-4) new.pm, reload_image (line: 692)
|29495|195:229|new| (-5) new.pm, process (line: 266)
|29495|195:229|new| (-6) vcld, make_new_child (line: 594)
|29495|195:229|new| ---- WARNING ----
|29495|195:229|new| 2010-05-18
09:09:28|29495|195:229|new|Windows.pm:post_load(571)|failed to set random
Administrator password
|29495|195:229|new| ( 0) utils.pm, notify (line: 737)
|29495|195:229|new| (-1) Windows.pm, post_load (line: 571)
|29495|195:229|new| (-2) Version_6.pm, post_load (line: 206)
|29495|195:229|new| (-3) new.pm, reload_image (line: 692)
|29495|195:229|new| (-4) new.pm, process (line: 266)
|29495|195:229|new| (-5) vcld, make_new_child (line: 594)
|29495|195:229|new| (-6) vcld, main (line: 341)
|29495|195:229|new| ---- WARNING ----
|29495|195:229|new| 2010-05-18
09:09:28|29495|195:229|new|Version_6.pm:post_load(210)|failed to execute
parent class post_load() subroutine
|29495|195:229|new| ( 0) utils.pm, notify (line: 737)
|29495|195:229|new| (-1) Version_6.pm, post_load (line: 210)
|29495|195:229|new| (-2) new.pm, reload_image (line: 692)
|29495|195:229|new| (-3) new.pm, process (line: 266)
|29495|195:229|new| (-4) vcld, make_new_child (line: 594)
|29495|195:229|new| (-5) vcld, main (line: 341)
2010-05-18 09:09:29|17716|vcld:main(165)|lastcheckin time updated for
management node 1: 2010-05-18 09:09:29
2010-05-18 09:09:31|29495|195:229|new|utils.pm:mail(1348)|SUCCESS --
Sending mail To: , PROBLEM -- new.pm
|29495|195:229|new| ---- CRITICAL ----
|29495|195:229|new| 2010-05-18
09:09:28|29495|195:229|new|new.pm:reload_image(697)|failed to perform OS
post-load tasks for winvista-Vistafacstaff54-v0 on node1, returning
|29495|195:229|new| ( 0) utils.pm, notify (line: 737)
|29495|195:229|new| (-1) new.pm, reload_image (line: 697)
|29495|195:229|new| (-2) new.pm, process (line: 266)
|29495|195:229|new| (-3) vcld, make_new_child (line: 594)
|29495|195:229|new| (-4) vcld, main (line: 341)
2010-05-18
09:09:31|29495|195:229|new|utils.pm:insertloadlog(4710)|inserted
computer=1, loadimagefailed, failed to perform OS post-load tasks for
winvista-Vistafacstaff54-v0 on node1
|29495|195:229|new| ---- WARNING ----
|29495|195:229|new| 2010-05-18
09:09:31|29495|195:229|new|new.pm:process(313)|failed to load node1 with
winvista-Vistafacstaff54-v0
|29495|195:229|new| ( 0) utils.pm, notify (line: 737)
|29495|195:229|new| (-1) new.pm, process (line: 313)
|29495|195:229|new| (-2) vcld, make_new_child (line: 594)
|29495|195:229|new| (-3) vcld, main (line: 341)
2010-05-18
09:09:31|29495|195:229|new|DataStructure.pm:get_computer_state_name(1946)|attempting
to retrieve current state of computer node1 from the database
2010-05-18
09:09:31|29495|195:229|new|DataStructure.pm:get_computer_state_name(1977)|retrieved
current state of computer node1 from the database: reloading
2010-05-18
09:09:31|29495|195:229|new|DataStructure.pm:_automethod(697)|data
structure updated:
$self->request_data->{reservation}{229}{computer}{state}{name}
|29495|195:229|new| computer_state_name = reloading
2010-05-18 09:09:32|29495|195:229|new|utils.pm:mail(1348)|SUCCESS --
Sending mail To: , PROBLEM -- State.pm
|29495|195:229|new| ---- CRITICAL ----
|29495|195:229|new| 2010-05-18
09:09:31|29495|195:229|new|State.pm:reservation_failed(290)|reservation
failed on node1: process failed after trying to load or make available
|29495|195:229|new| ( 0) utils.pm, notify (line: 737)
|29495|195:229|new| (-1) State.pm, reservation_failed (line: 290)
|29495|195:229|new| (-2) new.pm, process (line: 316)
|29495|195:229|new| (-3) vcld, make_new_child (line: 594)
|29495|195:229|new| (-4) vcld, main (line: 341)
2010-05-18
09:09:32|29495|195:229|new|utils.pm:insertloadlog(4710)|inserted
computer=1, failed, process failed after trying to load or make available
2010-05-18
09:09:32|29495|195:229|new|State.pm:reservation_failed(293)|inserted
computerloadlog entry
2010-05-18
09:09:32|29495|195:229|new|State.pm:reservation_failed(301)|updated log
ending value to 'failed', logid=70
2010-05-18
09:09:32|29495|195:229|new|utils.pm:update_computer_state(2228)|computer 1
state updated to: failed
2010-05-18
09:09:32|29495|195:229|new|State.pm:reservation_failed(312)|computer node1
(1) state set to failed
2010-05-18
09:09:32|29495|195:229|new|utils.pm:update_request_state(2186)|request 195
state updated to: failed, laststate to: new
2010-05-18 09:09:32|29495|195:229|new|State.pm:reservation_failed(325)|set
request state to 'failed'/'new'
2010-05-18
09:09:32|29495|195:229|new|utils.pm:is_inblockrequest(6972)|zero rows were
returned from database select
2010-05-18
09:09:32|29495|195:229|new|State.pm:reservation_failed(343)|node1 is NOT
in blockcomputers table
2010-05-18
09:09:32|29495|195:229|new|State.pm:reservation_failed(346)|exiting 1
2010-05-18 09:09:32|29495|195:229|new|State.pm:DESTROY(905)|destructor
called, ref($self)=VCL::new
2010-05-18
09:09:32|29495|195:229|new|utils.pm:delete_computerloadlog_reservation(7551)|removing
computerloadlog entries matching loadstate = begin
2010-05-18
09:09:32|29495|195:229|new|utils.pm:delete_computerloadlog_reservation(7598)|deleted
rows from computerloadlog for reservation id=229
2010-05-18 09:09:32|29495|195:229|new|State.pm:DESTROY(912)|removed
computerloadlog rows with loadstate=begin for reservation
2010-05-18 09:09:32|29495|195:229|new|State.pm:DESTROY(924)|number of
database handles state process created: 1
2010-05-18 09:09:32|29495|195:229|new|State.pm:DESTROY(933)|process has a
database handle stored in $ENV{dbh}, attempting disconnect
2010-05-18 09:09:32|29495|195:229|new|State.pm:DESTROY(935)|$ENV{dbh}:
database disconnect successful
2010-05-18 09:09:32|29495|195:229|new|State.pm:DESTROY(949)|VCL::new
process 29495 exiting
2010-05-18 09:09:32|17716|vcld:REAPER(744)|VCL process exited for
reservation 229
Roger Herling
Sr. Desktop Administrator
Marist College
PH: 845-575-3347
