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

Reply via email to