You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@vcl.apache.org by "Augustus, Kris" <AU...@ECU.EDU> on 2009/06/26 22:35:03 UTC
Reservation problems
We have tried the suggestions posted by Brian and still are having issues. The error messages below are from the recent changes suggested. I am also attaching screenshots of the tables with the changes implemented. Our networking and virtual switch names are different but the tables should be easy to understand. Thanks for all the help so far.
2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(678)|checking if TECS-ESX-4600-2 is pingable
2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(681)|TECS-ESX-4600-2 is pingable (1)
2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(695)|Trying to ssh...
2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(733)|status set to RELOAD
2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(741)|returning node status hash reference ($node_status->{status}=RELOAD)
2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(528)|node_status returned a hash reference
2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(533)|node_status hash reference contains key {status}=RELOAD
2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(575)|request state is reload, node will be reloaded regardless of status
2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(601)|node status is reload, TECS-ESX-4600-2 will be reloaded
2009-05-27 15:44:02|20074|61:61|reload|utils.pm:insertloadlog(5324)|inserted computer=1, loadimageblade, TECS-ESX-4600-2 must be reloaded with esx3-linuxtest-v0
2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(615)|calling VCL::Module::Provisioning::esx->does_image_exist()
|20074|61:61|reload| ---- WARNING ----
|20074|61:61|reload| 2009-05-27 15:44:02|20074|61:61|reload|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_profile_datastore_path: $self->request_data->{reservation}{61}{computer}{vmhost}{vmprofile}{datastorepath}
|20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
|20074|61:61|reload| (-1) DataStructure.pm, _automethod (line: 610)
|20074|61:61|reload| (-2) Autoload.pm, __ANON__ (line: 80)
|20074|61:61|reload| (-3) esx.pm, does_image_exist (line: 759)
|20074|61:61|reload| (-4) new.pm, reload_image (line: 617)
|20074|61:61|reload| (-5) new.pm, process (line: 266)
|20074|61:61|reload| (-6) vcld, make_new_child (line: 600)
|20074|61:61|reload| ---- WARNING ----
|20074|61:61|reload| 2009-05-27 15:44:02|20074|61:61|reload|vcld:warning_handler(642)|Use of uninitialized value in split at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 759.
|20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
|20074|61:61|reload| (-1) vcld, warning_handler (line: 642)
|20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 759)
|20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
|20074|61:61|reload| (-4) new.pm, process (line: 266)
|20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
|20074|61:61|reload| (-6) vcld, main (line: 347)
|20074|61:61|reload| ---- WARNING ----
|20074|61:61|reload| 2009-05-27 15:44:02|20074|61:61|reload|vcld:warning_handler(642)|Use of uninitialized value $datastore_share_path in concatenation (.) or string at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 766.
|20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
|20074|61:61|reload| (-1) vcld, warning_handler (line: 642)
|20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 766)
|20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
|20074|61:61|reload| (-4) new.pm, process (line: 266)
|20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
|20074|61:61|reload| (-6) vcld, main (line: 347)
|20074|61:61|reload| ---- WARNING ----
|20074|61:61|reload| 2009-05-27 15:44:02|20074|61:61|reload|utils.pm:run_ssh_command(6630)|computer node was not specified
|20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
|20074|61:61|reload| (-1) utils.pm, run_ssh_command (line: 6630)
|20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 769)
|20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
|20074|61:61|reload| (-4) new.pm, process (line: 266)
|20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
|20074|61:61|reload| (-6) vcld, main (line: 347)
Can't use an undefined value as an ARRAY reference at
/opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770 (#2)
(F) A value used as either a hard reference or a symbolic reference must
be a defined value. This helps to delurk some insidious errors.
Uncaught exception from user code:
Can't use an undefined value as an ARRAY reference at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770.
at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770
VCL::Module::Provisioning::esx::does_image_exist('VCL::Module::Provisioning::esx=HASH(0xb8d5b5c)', 'esx3-linuxtest-v0') called at /opt/vcl/bin/../lib/VCL/new.pm line 617
VCL::new::reload_image('VCL::new=HASH(0xb8f4794)') called at /opt/vcl/bin/../lib/VCL/new.pm line 266
VCL::new::process('VCL::new=HASH(0xb8f4794)') called at /opt/vcl/bin/vcld line 600
VCL::vcld::make_new_child('HASH(0xb937e44)') called at /opt/vcl/bin/vcld line 347
VCL::vcld::main() called at /opt/vcl/bin/vcld line 93
2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(442)|destructor called, ref($self)=VCL::new
2009-05-27 15:44:02|20074|61:61|reload|utils.pm:delete_computerloadlog_reservation(8041)|removing computerloadlog entries matching loadstate = begin
2009-05-27 15:44:02|20074|61:61|reload|utils.pm:delete_computerloadlog_reservation(8088)|deleted rows from computerloadlog for reservation id=61
2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(445)|removed computerloadlog rows with loadstate=begin for reservation
2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(453)|number of database handles state process created: 1
2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(462)|process has a database handle stored in $ENV{dbh}, attempting disconnect
2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(464)|$ENV{dbh}: database disconnect successful
2009-05-27 15:44:02|25733|vcld:REAPER(750)|VCL process exited for reservation 61
2009-05-27 15:44:02|25733|vcld:main(166)|lastcheckin time updated for management node 1: 2009-05-27 15:44:02
2009-05-27 15:44:14|25733|vcld:main(166)|lastcheckin time updated for management node 1: 2009-05-27 15:44:14
2009-05-27 15:44:26|25733|vcld:main(166)|lastcheckin time updated for management node 1: 2009-05-27 15:44:26
2009-05-27 15:44:38|25733|vcld:main(166)|lastcheckin time updated for management node 1: 2009-05-27 15:44:38
2009-05-27 15:44:51|25733|vcld:main(166)|lastcheckin time updated for management node 1: 2009-05-27 15:44:51
2009-05-27 15:44:51|25733|62:62|new|utils.pm:check_time(1167)|reservation start time was in the past 17 minutes (-15)
2009-05-27 15:44:51|25733|62:62|new|utils.pm:reservation_being_processed(9818)|computerloadlog 'begin' entry does NOT exist for reservation 62
2009-05-27 15:44:51|25733|62:62|new|utils.pm:reservation_being_processed(9870)|reservation is NOT currently being processed
2009-05-27 15:44:51|25733|62:62|new|vcld:main(282)|reservation 62 is NOT already being processed
2009-05-27 15:44:51|25733|62:62|new|utils.pm:get_request_info(5991)|non-NCSU user affiliation found: Local
2009-05-27 15:44:51|25733|62:62|new|utils.pm:get_management_node_info(7277)|management node info retrieved from database for 150
2009-05-27 15:44:51|25733|62:62|new|vcld:main(287)|retrieved request information from database
2009-05-27 15:44:51|25733|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:51|25733|62:62|new|utils.pm:update_request_state(2283)|request 62 state updated to: pending, laststate to: new
2009-05-27 15:44:51|25733|62:62|new|vcld:main(327)|request state updated to pending, laststate new
2009-05-27 15:44:51|25733|62:62|new|utils.pm:insertloadlog(5324)|inserted computer=1, begin, beginning to process, state is new
2009-05-27 15:44:51|25733|62:62|new|vcld:main(340)|inserted 'begin' entry into computerloadlog for reservation 62
2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(511)|creating new process
2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(515)|loaded VCL::new module
2009-05-27 15:44:51|20138|62:62|new|vcld:make_new_child(588)|vcld environment variable set to 0 for this process
2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called, class=VCL::new
2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::new object created
2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(572)|current number of forked kids: 1
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(86)|obtained a database handle for this state process, stored as $ENV{dbh}
2009-05-27 15:44:51|20138|62:62|new|State.pm:check_image_os(361)|no corrections need to be made, not an imaging request, returning 1
2009-05-27 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8352)|reservation count: 1
2009-05-27 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8371)|PARENTIMAGE: 1
2009-05-27 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8372)|SUBIMAGE: 0
2009-05-27 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8390)|renamed process to 'vcld VCL::new 62:62 new'
2009-05-27 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(122)|attempting to load provisioning module: VCL::Module::Provisioning::esx
2009-05-27 15:44:51|20138|62:62|new|esx.pm:initialize(117)|vmware ESX module initialized
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(129)|VCL::Module::Provisioning::esx module successfully loaded
2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called, class=VCL::Module::Provisioning::esx
2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::Module::Provisioning::esx object created
2009-05-27 15:44:51|20138|62:62|new|esx.pm:initialize(117)|vmware ESX module initialized
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(133)|VCL::Module::Provisioning::esx provisioner object successfully created
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(147)|attempting to load OS module: VCL::Module::OS::Linux
2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called, class=VCL::Module::OS::Linux
2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::Module::OS::Linux object created
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(155)|VCL::Module::OS::Linux OS object successfully created
2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(170)|returning 1
2009-05-27 15:44:51|20138|62:62|new|vcld:make_new_child(597)|VCL::new object created and initialized
2009-05-27 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:51|20138|62:62|new|utils.pm:get_management_node_id(9383)|environment variable: 1
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(135)|reservation is parent = 1
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(136)|preload only = 0
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(137)|originating request state = new
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(138)|originating request laststate = new
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(139)|originating computer state = available
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(140)|originating computer type = blade
2009-05-27 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:51|20138|62:62|new|utils.pm:get_management_node_id(9383)|environment variable: 1
2009-05-27 15:44:51|20138|62:62|new|new.pm:computer_not_being_used(779)|TECS-ESX-4600-2 state is available
2009-05-27 15:44:51|20138|62:62|new|new.pm:computer_not_being_used(789)|TECS-ESX-4600-2 is available, its state is available
2009-05-27 15:44:51|20138|62:62|new|new.pm:process(170)|TECS-ESX-4600-2 is not being used
2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|returning true: parent reservation ID for this request: 62
2009-05-27 15:44:52|20138|62:62|new|utils.pm:get_management_node_id(9383)|environment variable: 1
2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(513)|calling VCL::Module::Provisioning::esx->node_status()
2009-05-27 15:44:52|20138|62:62|new|utils.pm:insertloadlog(5324)|inserted computer=1, statuscheck, checking status of node
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_profile_vmpath: $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{vmpath}
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
|20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
|20138|62:62|new| (-3) esx.pm, node_status (line: 636)
|20138|62:62|new| (-4) new.pm, reload_image (line: 517)
|20138|62:62|new| (-5) new.pm, process (line: 266)
|20138|62:62|new| (-6) vcld, make_new_child (line: 600)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_profile_datastore_path: $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{datastorepath}
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
|20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
|20138|62:62|new| (-3) esx.pm, node_status (line: 637)
|20138|62:62|new| (-4) new.pm, reload_image (line: 517)
|20138|62:62|new| (-5) new.pm, process (line: 266)
|20138|62:62|new| (-6) vcld, make_new_child (line: 600)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_type: $self->request_data->{reservation}{62}{computer}{vmhost}{type}
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
|20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
|20138|62:62|new| (-3) esx.pm, node_status (line: 639)
|20138|62:62|new| (-4) new.pm, reload_image (line: 517)
|20138|62:62|new| (-5) new.pm, process (line: 266)
|20138|62:62|new| (-6) vcld, make_new_child (line: 600)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_hostname: $self->request_data->{reservation}{62}{computer}{vmhost}{hostname}
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
|20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
|20138|62:62|new| (-3) esx.pm, node_status (line: 640)
|20138|62:62|new| (-4) new.pm, reload_image (line: 517)
|20138|62:62|new| (-5) new.pm, process (line: 266)
|20138|62:62|new| (-6) vcld, make_new_child (line: 600)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_image_name: $self->request_data->{reservation}{62}{computer}{vmhost}{imagename}
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
|20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
|20138|62:62|new| (-3) esx.pm, node_status (line: 641)
|20138|62:62|new| (-4) new.pm, reload_image (line: 517)
|20138|62:62|new| (-5) new.pm, process (line: 266)
|20138|62:62|new| (-6) vcld, make_new_child (line: 600)
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(646)|Entering node_status, checking status of TECS-ESX-4600-2
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(647)|request_for_imaging: 0
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(648)|requeseted image name: esx3-linuxtest-v0
Use of uninitialized value $vmhost_type in string eq at
/opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 663 (#1)
(W uninitialized) An undefined value was used as if it were already
defined. It was interpreted as a "" or a 0, but maybe it was a mistake.
To suppress this warning assign a defined value to your variables.
To help you figure out what was undefined, perl will try to tell you the
name of the variable (if any) that was undefined. In some cases it cannot
do this, so it also tells you what operation you used the undefined value
in. Note, however, that perl optimizes your program and the operation
displayed in the warning may not necessarily appear literally in your
program. For example, "that $foo" is usually optimized into "that "
. $foo, and the warning will refer to the concatenation (.) operator,
even though there is no . in your program.
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value $vmhost_type in string eq at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 663.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, node_status (line: 663)
|20138|62:62|new| (-3) new.pm, reload_image (line: 517)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value $vmhost_imagename in pattern match (m//) at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 670.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, node_status (line: 670)
|20138|62:62|new| (-3) new.pm, reload_image (line: 517)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value $vmhost_imagename in concatenation (.) or string at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, node_status (line: 673)
|20138|62:62|new| (-3) new.pm, reload_image (line: 517)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value $vmhost_type in concatenation (.) or string at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, node_status (line: 673)
|20138|62:62|new| (-3) new.pm, reload_image (line: 517)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value $vmhost_hostname in concatenation (.) or string at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, node_status (line: 673)
|20138|62:62|new| (-3) new.pm, reload_image (line: 517)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
/usr/lib/sendmail: option requires an argument -- f
2009-05-27 15:44:52|20138|62:62|new|utils.pm:mail(1301)|SUCCESS -- Sending mail To: , PROBLEM -- esx.pm
|20138|62:62|new| ---- CRITICAL ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(673)|could not set ssh identity variable for image type= host=
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) esx.pm, node_status (line: 673)
|20138|62:62|new| (-2) new.pm, reload_image (line: 517)
|20138|62:62|new| (-3) new.pm, process (line: 266)
|20138|62:62|new| (-4) vcld, make_new_child (line: 600)
|20138|62:62|new| (-5) vcld, main (line: 347)
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(678)|checking if TECS-ESX-4600-2 is pingable
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(681)|TECS-ESX-4600-2 is pingable (1)
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(695)|Trying to ssh...
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(733)|status set to RELOAD
2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(741)|returning node status hash reference ($node_status->{status}=RELOAD)
2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(528)|node_status returned a hash reference
2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(533)|node_status hash reference contains key {status}=RELOAD
2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(601)|node status is RELOAD, TECS-ESX-4600-2 will be reloaded
2009-05-27 15:44:52|20138|62:62|new|utils.pm:insertloadlog(5324)|inserted computer=1, loadimageblade, TECS-ESX-4600-2 must be reloaded with esx3-linuxtest-v0
2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(615)|calling VCL::Module::Provisioning::esx->does_image_exist()
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding data has not been initialized for get_vmhost_profile_datastore_path: $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{datastorepath}
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
|20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
|20138|62:62|new| (-3) esx.pm, does_image_exist (line: 759)
|20138|62:62|new| (-4) new.pm, reload_image (line: 617)
|20138|62:62|new| (-5) new.pm, process (line: 266)
|20138|62:62|new| (-6) vcld, make_new_child (line: 600)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value in split at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 759.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, does_image_exist (line: 759)
|20138|62:62|new| (-3) new.pm, reload_image (line: 617)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized value $datastore_share_path in concatenation (.) or string at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 766.
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) vcld, warning_handler (line: 642)
|20138|62:62|new| (-2) esx.pm, does_image_exist (line: 766)
|20138|62:62|new| (-3) new.pm, reload_image (line: 617)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
|20138|62:62|new| ---- WARNING ----
|20138|62:62|new| 2009-05-27 15:44:52|20138|62:62|new|utils.pm:run_ssh_command(6630)|computer node was not specified
|20138|62:62|new| ( 0) utils.pm, notify (line: 691)
|20138|62:62|new| (-1) utils.pm, run_ssh_command (line: 6630)
|20138|62:62|new| (-2) esx.pm, does_image_exist (line: 769)
|20138|62:62|new| (-3) new.pm, reload_image (line: 617)
|20138|62:62|new| (-4) new.pm, process (line: 266)
|20138|62:62|new| (-5) vcld, make_new_child (line: 600)
|20138|62:62|new| (-6) vcld, main (line: 347)
Can't use an undefined value as an ARRAY reference at
/opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770 (#2)
(F) A value used as either a hard reference or a symbolic reference must
be a defined value. This helps to delurk some insidious errors.
Uncaught exception from user code:
Can't use an undefined value as an ARRAY reference at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770.
at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770
VCL::Module::Provisioning::esx::does_image_exist('VCL::Module::Provisioning::esx=HASH(0xb8d5b6c)', 'esx3-linuxtest-v0') called at /opt/vcl/bin/../lib/VCL/new.pm line 617
VCL::new::reload_image('VCL::new=HASH(0xb8d61cc)') called at /opt/vcl/bin/../lib/VCL/new.pm line 266
VCL::new::process('VCL::new=HASH(0xb8d61cc)') called at /opt/vcl/bin/vcld line 600
VCL::vcld::make_new_child('HASH(0xb8996a4)') called at /opt/vcl/bin/vcld line 347
VCL::vcld::main() called at /opt/vcl/bin/vcld line 93
2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(442)|destructor called, ref($self)=VCL::new
2009-05-27 15:44:52|20138|62:62|new|utils.pm:delete_computerloadlog_reservation(8041)|removing computerloadlog entries matching loadstate = begin
2009-05-27 15:44:52|20138|62:62|new|utils.pm:delete_computerloadlog_reservation(8088)|deleted rows from computerloadlog for reservation id=62
2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(445)|removed computerloadlog rows with loadstate=begin for reservation
2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(453)|number of database handles state process created: 1
2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(462)|process has a database handle stored in $ENV{dbh}, attempting disconnect
2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(464)|$ENV{dbh}: database disconnect successful
2009-05-27 15:44:52|25733|vcld:REAPER(750)|VCL process exited for reservation 62
2009-05-27 15:44:52|25733|vcld:main(166)|lastcheckin time updated for management node 1: 2009-05-27 15:44:52
Kris Augustus
CCNA, RHCE, A+
Lab Supervisor
College of TECS
East Carolina University
252-737-1978
augustusk@ecu.edu<ma...@ecu.edu>
Intelligence plus character- that is the goal of true education.
Dr. Martin Luther King Jr.
Re: Reservation problems
Posted by Aaron Peeler <aa...@ncsu.edu>.
Hello Kris,
It looks like you might have an older vcl version according to the output.
Could you download the latest version of the management node code from
<https://svn.apache.org/repos/asf/incubator/vcl/trunk>
You could try to just update the managementnode code, but depending on how
old the php code is you might also want to upgrade that also.
On this error log it's stating that the call on
get_vmhost_profile_datastore_path is not returning anything from the
Datastructure module, so upgraded the management node code might resolve
that. The field "datastorepath" in your vprofile.jpg image for the
vmprofile table look right.
Aaron
--On June 26, 2009 4:35:03 PM -0400 "Augustus, Kris" <AU...@ECU.EDU>
wrote:
>
>
> We have tried the suggestions posted by Brian and still are having
> issues. The error messages below are from the recent changes suggested. I
> am also attaching screenshots of the tables with the changes implemented.
> Our networking and virtual switch names are different but the tables
> should be easy to understand. Thanks for all the help so far.
>
>
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(678)|checking
> if TECS-ESX-4600-2 is pingable
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|esx.pm:node_status(681)|TECS-ESX-4600-2 is
> pingable (1)
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(695)|Trying to
> ssh...
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(733)|status set
> to RELOAD
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(741)|returning
> node status hash reference ($node_status->{status}=RELOAD)
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|new.pm:reload_image(528)|node_status returned
> a hash reference
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|new.pm:reload_image(533)|node_status hash
> reference contains key {status}=RELOAD
>
> 2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(575)|request
> state is reload, node will be reloaded regardless of status
>
> 2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(601)|node
> status is reload, TECS-ESX-4600-2 will be reloaded
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|utils.pm:insertloadlog(5324)|inserted
> computer=1, loadimageblade, TECS-ESX-4600-2 must be reloaded with
> esx3-linuxtest-v0
>
> 2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(615)|calling
> VCL::Module::Provisioning::esx->does_image_exist()
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|DataStructure.pm:_automethod(610)|correspond
>| ing data has not been initialized for get_vmhost_profile_datastore_path:
>| $self->request_data->{reservation}{61}{computer}{vmhost}{vmprofile}{data
>| storepath}
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20074|61:61|reload| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20074|61:61|reload| (-3) esx.pm, does_image_exist (line: 759)
>
>| 20074|61:61|reload| (-4) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-5) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|vcld:warning_handler(642)|Use of
>| uninitialized value in split at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 759.
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) vcld, warning_handler (line: 642)
>
>| 20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 759)
>
>| 20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-4) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
>
>| 20074|61:61|reload| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|vcld:warning_handler(642)|Use of
>| uninitialized value $datastore_share_path in concatenation (.) or string
>| at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 766.
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) vcld, warning_handler (line: 642)
>
>| 20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 766)
>
>| 20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-4) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
>
>| 20074|61:61|reload| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|utils.pm:run_ssh_command(6630)|computer node
>| was not specified
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) utils.pm, run_ssh_command (line: 6630)
>
>| 20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 769)
>
>| 20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-4) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
>
>| 20074|61:61|reload| (-6) vcld, main (line: 347)
>
>
>
> Can't use an undefined value as an ARRAY reference at
>
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line
> 770 (#2)
>
> (F) A value used as either a hard reference or a symbolic reference
> must
>
> be a defined value. This helps to delurk some insidious errors.
>
>
>
> Uncaught exception from user code:
>
> Can't use an undefined value as an ARRAY reference at
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770.
>
> at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770
>
>
> VCL::Module::Provisioning::esx::does_image_exist('VCL::Module::Provisioni
> ng::esx=HASH(0xb8d5b5c)', 'esx3-linuxtest-v0') called at
> /opt/vcl/bin/../lib/VCL/new.pm line 617
>
> VCL::new::reload_image('VCL::new=HASH(0xb8f4794)') called
> at /opt/vcl/bin/../lib/VCL/new.pm line 266
>
> VCL::new::process('VCL::new=HASH(0xb8f4794)') called at
> /opt/vcl/bin/vcld line 600
>
> VCL::vcld::make_new_child('HASH(0xb937e44)') called at
> /opt/vcl/bin/vcld line 347
>
> VCL::vcld::main() called at /opt/vcl/bin/vcld line 93
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(442)|destructor
> called, ref($self)=VCL::new
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|utils.pm:delete_computerloadlog_reservation(8
> 041)|removing computerloadlog entries matching loadstate = begin
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|utils.pm:delete_computerloadlog_reservation(8
> 088)|deleted rows from computerloadlog for reservation id=61
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(445)|removed
> computerloadlog rows with loadstate=begin for reservation
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(453)|number of
> database handles state process created: 1
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(462)|process has
> a database handle stored in $ENV{dbh}, attempting disconnect
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(464)|$ENV{dbh}:
> database disconnect successful
>
> 2009-05-27 15:44:02|25733|vcld:REAPER(750)|VCL process exited for
> reservation 61
>
> 2009-05-27 15:44:02|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:02
>
> 2009-05-27 15:44:14|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:14
>
> 2009-05-27 15:44:26|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:26
>
> 2009-05-27 15:44:38|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:38
>
> 2009-05-27 15:44:51|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:51
>
> 2009-05-27 15:44:51|25733|62:62|new|utils.pm:check_time(1167)|reservation
> start time was in the past 17 minutes (-15)
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:reservation_being_processed(9818)|compu
> terloadlog 'begin' entry does NOT exist for reservation 62
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:reservation_being_processed(9870)|reser
> vation is NOT currently being processed
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(282)|reservation 62 is NOT
> already being processed
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:get_request_info(5991)|non-NCSU user
> affiliation found: Local
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:get_management_node_info(7277)|manageme
> nt node info retrieved from database for 150
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(287)|retrieved request
> information from database
>
> 2009-05-27
> 15:44:51|25733|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:update_request_state(2283)|request 62
> state updated to: pending, laststate to: new
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(327)|request state updated
> to pending, laststate new
>
> 2009-05-27 15:44:51|25733|62:62|new|utils.pm:insertloadlog(5324)|inserted
> computer=1, begin, beginning to process, state is new
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(340)|inserted 'begin' entry
> into computerloadlog for reservation 62
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(511)|creating new
> process
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(515)|loaded
> VCL::new module
>
> 2009-05-27 15:44:51|20138|62:62|new|vcld:make_new_child(588)|vcld
> environment variable set to 0 for this process
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called,
> class=VCL::new
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::new object
> created
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(572)|current
> number of forked kids: 1
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(86)|obtained a
> database handle for this state process, stored as $ENV{dbh}
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:check_image_os(361)|no
> corrections need to be made, not an imaging request, returning 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8352)|reservation
> count: 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8371)|PARENTIMAGE:
> 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8372)|SUBIMAGE: 0
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8390)|renamed
> process to 'vcld VCL::new 62:62 new'
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(122)|attempting
> to load provisioning module: VCL::Module::Provisioning::esx
>
> 2009-05-27 15:44:51|20138|62:62|new|esx.pm:initialize(117)|vmware ESX
> module initialized
>
> 2009-05-27
> 15:44:51|20138|62:62|new|State.pm:initialize(129)|VCL::Module::Provisioni
> ng::esx module successfully loaded
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called,
> class=VCL::Module::Provisioning::esx
>
> 2009-05-27
> 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::Module::Provisioning::es
> x object created
>
> 2009-05-27 15:44:51|20138|62:62|new|esx.pm:initialize(117)|vmware ESX
> module initialized
>
> 2009-05-27
> 15:44:51|20138|62:62|new|State.pm:initialize(133)|VCL::Module::Provisioni
> ng::esx provisioner object successfully created
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(147)|attempting
> to load OS module: VCL::Module::OS::Linux
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called,
> class=VCL::Module::OS::Linux
>
> 2009-05-27
> 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::Module::OS::Linux object
> created
>
> 2009-05-27
> 15:44:51|20138|62:62|new|State.pm:initialize(155)|VCL::Module::OS::Linux
> OS object successfully created
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(170)|returning 1
>
> 2009-05-27 15:44:51|20138|62:62|new|vcld:make_new_child(597)|VCL::new
> object created and initialized
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:get_management_node_id(9383)|environmen
> t variable: 1
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(135)|reservation is
> parent = 1
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(136)|preload only = 0
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(137)|originating
> request state = new
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(138)|originating
> request laststate = new
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(139)|originating
> computer state = available
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(140)|originating
> computer type = blade
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:get_management_node_id(9383)|environmen
> t variable: 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|new.pm:computer_not_being_used(779)|TECS-ESX-460
> 0-2 state is available
>
> 2009-05-27
> 15:44:51|20138|62:62|new|new.pm:computer_not_being_used(789)|TECS-ESX-460
> 0-2 is available, its state is available
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(170)|TECS-ESX-4600-2
> is not being used
>
> 2009-05-27
> 15:44:52|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:52|20138|62:62|new|utils.pm:get_management_node_id(9383)|environmen
> t variable: 1
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(513)|calling
> VCL::Module::Provisioning::esx->node_status()
>
> 2009-05-27 15:44:52|20138|62:62|new|utils.pm:insertloadlog(5324)|inserted
> computer=1, statuscheck, checking status of node
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_profile_vmpath:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{vmpa
>| th}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 636)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_profile_datastore_path:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{data
>| storepath}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 637)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_type:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{type}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 639)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_hostname:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{hostname}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 640)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_image_name:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{imagename}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 641)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(646)|Entering
> node_status, checking status of TECS-ESX-4600-2
>
> 2009-05-27
> 15:44:52|20138|62:62|new|esx.pm:node_status(647)|request_for_imaging: 0
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(648)|requeseted
> image name: esx3-linuxtest-v0
>
> Use of uninitialized value $vmhost_type in string eq at
>
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line
> 663 (#1)
>
> (W uninitialized) An undefined value was used as if it were already
>
> defined. It was interpreted as a "" or a 0, but maybe it was a
> mistake.
>
> To suppress this warning assign a defined value to your variables.
>
>
>
> To help you figure out what was undefined, perl will try to tell you
> the
>
> name of the variable (if any) that was undefined. In some cases it
> cannot
>
> do this, so it also tells you what operation you used the undefined
> value
>
> in. Note, however, that perl optimizes your program and the
> operation
>
> displayed in the warning may not necessarily appear literally in your
>
> program. For example, "that $foo" is usually optimized into "that "
>
> . $foo, and the warning will refer to the concatenation (.) operator,
>
> even though there is no . in your program.
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_type in string eq at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 663.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 663)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_imagename in pattern match (m//) at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 670.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 670)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_imagename in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_type in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_hostname in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
> /usr/lib/sendmail: option requires an argument -- f
>
> 2009-05-27 15:44:52|20138|62:62|new|utils.pm:mail(1301)|SUCCESS --
> Sending mail To: , PROBLEM -- esx.pm
>
>
>
>| 20138|62:62|new| ---- CRITICAL ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|esx.pm:node_status(673)|could not set ssh
>| identity variable for image type= host=
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-2) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-3) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-4) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-5) vcld, main (line: 347)
>
>
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(678)|checking if
> TECS-ESX-4600-2 is pingable
>
> 2009-05-27
> 15:44:52|20138|62:62|new|esx.pm:node_status(681)|TECS-ESX-4600-2 is
> pingable (1)
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(695)|Trying to
> ssh...
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(733)|status set to
> RELOAD
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(741)|returning
> node status hash reference ($node_status->{status}=RELOAD)
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(528)|node_status
> returned a hash reference
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(533)|node_status
> hash reference contains key {status}=RELOAD
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(601)|node status
> is RELOAD, TECS-ESX-4600-2 will be reloaded
>
> 2009-05-27 15:44:52|20138|62:62|new|utils.pm:insertloadlog(5324)|inserted
> computer=1, loadimageblade, TECS-ESX-4600-2 must be reloaded with
> esx3-linuxtest-v0
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(615)|calling
> VCL::Module::Provisioning::esx->does_image_exist()
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_profile_datastore_path:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{data
>| storepath}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, does_image_exist (line: 759)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value in split at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm
>| line 759.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, does_image_exist (line: 759)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $datastore_share_path in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 766.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, does_image_exist (line: 766)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|utils.pm:run_ssh_command(6630)|computer node
>| was not specified
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) utils.pm, run_ssh_command (line: 6630)
>
>| 20138|62:62|new| (-2) esx.pm, does_image_exist (line: 769)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
> Can't use an undefined value as an ARRAY reference at
>
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line
> 770 (#2)
>
> (F) A value used as either a hard reference or a symbolic reference
> must
>
> be a defined value. This helps to delurk some insidious errors.
>
>
>
> Uncaught exception from user code:
>
> Can't use an undefined value as an ARRAY reference at
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770.
>
> at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770
>
>
> VCL::Module::Provisioning::esx::does_image_exist('VCL::Module::Provisioni
> ng::esx=HASH(0xb8d5b6c)', 'esx3-linuxtest-v0') called at
> /opt/vcl/bin/../lib/VCL/new.pm line 617
>
> VCL::new::reload_image('VCL::new=HASH(0xb8d61cc)') called
> at /opt/vcl/bin/../lib/VCL/new.pm line 266
>
> VCL::new::process('VCL::new=HASH(0xb8d61cc)') called at
> /opt/vcl/bin/vcld line 600
>
> VCL::vcld::make_new_child('HASH(0xb8996a4)') called at
> /opt/vcl/bin/vcld line 347
>
> VCL::vcld::main() called at /opt/vcl/bin/vcld line 93
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(442)|destructor
> called, ref($self)=VCL::new
>
> 2009-05-27
> 15:44:52|20138|62:62|new|utils.pm:delete_computerloadlog_reservation(8041
> )|removing computerloadlog entries matching loadstate = begin
>
> 2009-05-27
> 15:44:52|20138|62:62|new|utils.pm:delete_computerloadlog_reservation(8088
> )|deleted rows from computerloadlog for reservation id=62
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(445)|removed
> computerloadlog rows with loadstate=begin for reservation
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(453)|number of
> database handles state process created: 1
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(462)|process has a
> database handle stored in $ENV{dbh}, attempting disconnect
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(464)|$ENV{dbh}:
> database disconnect successful
>
> 2009-05-27 15:44:52|25733|vcld:REAPER(750)|VCL process exited for
> reservation 62
>
> 2009-05-27 15:44:52|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:52
>
>
>
>
>
> Kris Augustus
>
> CCNA, RHCE, A+
>
> Lab Supervisor
>
> College of TECS
>
> East Carolina University
>
> 252-737-1978
>
> augustusk@ecu.edu
>
>
>
> Intelligence plus character- that is the goal of true education.
>
> Dr. Martin Luther King Jr.
>
>
Aaron Peeler
OIT Advanced Computing
College of Engineering-NCSU
919.513.4571
http://vcl.ncsu.edu
Re: Reservation problems
Posted by Aaron Peeler <aa...@ncsu.edu>.
Hello Kris,
It looks like you might have an older vcl version according to the output.
Could you download the latest version of the management node code from
<https://svn.apache.org/repos/asf/incubator/vcl/trunk>
You could try to just update the managementnode code, but depending on how
old the php code is you might also want to upgrade that also.
On this error log it's stating that the call on
get_vmhost_profile_datastore_path is not returning anything from the
Datastructure module, so upgraded the management node code might resolve
that. The field "datastorepath" in your vprofile.jpg image for the
vmprofile table look right.
Aaron
--On June 26, 2009 4:35:03 PM -0400 "Augustus, Kris" <AU...@ECU.EDU>
wrote:
>
>
> We have tried the suggestions posted by Brian and still are having
> issues. The error messages below are from the recent changes suggested. I
> am also attaching screenshots of the tables with the changes implemented.
> Our networking and virtual switch names are different but the tables
> should be easy to understand. Thanks for all the help so far.
>
>
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(678)|checking
> if TECS-ESX-4600-2 is pingable
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|esx.pm:node_status(681)|TECS-ESX-4600-2 is
> pingable (1)
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(695)|Trying to
> ssh...
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(733)|status set
> to RELOAD
>
> 2009-05-27 15:44:02|20074|61:61|reload|esx.pm:node_status(741)|returning
> node status hash reference ($node_status->{status}=RELOAD)
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|new.pm:reload_image(528)|node_status returned
> a hash reference
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|new.pm:reload_image(533)|node_status hash
> reference contains key {status}=RELOAD
>
> 2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(575)|request
> state is reload, node will be reloaded regardless of status
>
> 2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(601)|node
> status is reload, TECS-ESX-4600-2 will be reloaded
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|utils.pm:insertloadlog(5324)|inserted
> computer=1, loadimageblade, TECS-ESX-4600-2 must be reloaded with
> esx3-linuxtest-v0
>
> 2009-05-27 15:44:02|20074|61:61|reload|new.pm:reload_image(615)|calling
> VCL::Module::Provisioning::esx->does_image_exist()
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|DataStructure.pm:_automethod(610)|correspond
>| ing data has not been initialized for get_vmhost_profile_datastore_path:
>| $self->request_data->{reservation}{61}{computer}{vmhost}{vmprofile}{data
>| storepath}
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20074|61:61|reload| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20074|61:61|reload| (-3) esx.pm, does_image_exist (line: 759)
>
>| 20074|61:61|reload| (-4) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-5) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|vcld:warning_handler(642)|Use of
>| uninitialized value in split at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 759.
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) vcld, warning_handler (line: 642)
>
>| 20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 759)
>
>| 20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-4) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
>
>| 20074|61:61|reload| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|vcld:warning_handler(642)|Use of
>| uninitialized value $datastore_share_path in concatenation (.) or string
>| at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 766.
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) vcld, warning_handler (line: 642)
>
>| 20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 766)
>
>| 20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-4) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
>
>| 20074|61:61|reload| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20074|61:61|reload| ---- WARNING ----
>
>| 20074|61:61|reload| 2009-05-27
>| 15:44:02|20074|61:61|reload|utils.pm:run_ssh_command(6630)|computer node
>| was not specified
>
>| 20074|61:61|reload| ( 0) utils.pm, notify (line: 691)
>
>| 20074|61:61|reload| (-1) utils.pm, run_ssh_command (line: 6630)
>
>| 20074|61:61|reload| (-2) esx.pm, does_image_exist (line: 769)
>
>| 20074|61:61|reload| (-3) new.pm, reload_image (line: 617)
>
>| 20074|61:61|reload| (-4) new.pm, process (line: 266)
>
>| 20074|61:61|reload| (-5) vcld, make_new_child (line: 600)
>
>| 20074|61:61|reload| (-6) vcld, main (line: 347)
>
>
>
> Can't use an undefined value as an ARRAY reference at
>
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line
> 770 (#2)
>
> (F) A value used as either a hard reference or a symbolic reference
> must
>
> be a defined value. This helps to delurk some insidious errors.
>
>
>
> Uncaught exception from user code:
>
> Can't use an undefined value as an ARRAY reference at
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770.
>
> at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770
>
>
> VCL::Module::Provisioning::esx::does_image_exist('VCL::Module::Provisioni
> ng::esx=HASH(0xb8d5b5c)', 'esx3-linuxtest-v0') called at
> /opt/vcl/bin/../lib/VCL/new.pm line 617
>
> VCL::new::reload_image('VCL::new=HASH(0xb8f4794)') called
> at /opt/vcl/bin/../lib/VCL/new.pm line 266
>
> VCL::new::process('VCL::new=HASH(0xb8f4794)') called at
> /opt/vcl/bin/vcld line 600
>
> VCL::vcld::make_new_child('HASH(0xb937e44)') called at
> /opt/vcl/bin/vcld line 347
>
> VCL::vcld::main() called at /opt/vcl/bin/vcld line 93
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(442)|destructor
> called, ref($self)=VCL::new
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|utils.pm:delete_computerloadlog_reservation(8
> 041)|removing computerloadlog entries matching loadstate = begin
>
> 2009-05-27
> 15:44:02|20074|61:61|reload|utils.pm:delete_computerloadlog_reservation(8
> 088)|deleted rows from computerloadlog for reservation id=61
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(445)|removed
> computerloadlog rows with loadstate=begin for reservation
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(453)|number of
> database handles state process created: 1
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(462)|process has
> a database handle stored in $ENV{dbh}, attempting disconnect
>
> 2009-05-27 15:44:02|20074|61:61|reload|State.pm:DESTROY(464)|$ENV{dbh}:
> database disconnect successful
>
> 2009-05-27 15:44:02|25733|vcld:REAPER(750)|VCL process exited for
> reservation 61
>
> 2009-05-27 15:44:02|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:02
>
> 2009-05-27 15:44:14|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:14
>
> 2009-05-27 15:44:26|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:26
>
> 2009-05-27 15:44:38|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:38
>
> 2009-05-27 15:44:51|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:51
>
> 2009-05-27 15:44:51|25733|62:62|new|utils.pm:check_time(1167)|reservation
> start time was in the past 17 minutes (-15)
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:reservation_being_processed(9818)|compu
> terloadlog 'begin' entry does NOT exist for reservation 62
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:reservation_being_processed(9870)|reser
> vation is NOT currently being processed
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(282)|reservation 62 is NOT
> already being processed
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:get_request_info(5991)|non-NCSU user
> affiliation found: Local
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:get_management_node_info(7277)|manageme
> nt node info retrieved from database for 150
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(287)|retrieved request
> information from database
>
> 2009-05-27
> 15:44:51|25733|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|25733|62:62|new|utils.pm:update_request_state(2283)|request 62
> state updated to: pending, laststate to: new
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(327)|request state updated
> to pending, laststate new
>
> 2009-05-27 15:44:51|25733|62:62|new|utils.pm:insertloadlog(5324)|inserted
> computer=1, begin, beginning to process, state is new
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:main(340)|inserted 'begin' entry
> into computerloadlog for reservation 62
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(511)|creating new
> process
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(515)|loaded
> VCL::new module
>
> 2009-05-27 15:44:51|20138|62:62|new|vcld:make_new_child(588)|vcld
> environment variable set to 0 for this process
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called,
> class=VCL::new
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::new object
> created
>
> 2009-05-27 15:44:51|25733|62:62|new|vcld:make_new_child(572)|current
> number of forked kids: 1
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(86)|obtained a
> database handle for this state process, stored as $ENV{dbh}
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:check_image_os(361)|no
> corrections need to be made, not an imaging request, returning 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8352)|reservation
> count: 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8371)|PARENTIMAGE:
> 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8372)|SUBIMAGE: 0
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:rename_vcld_process(8390)|renamed
> process to 'vcld VCL::new 62:62 new'
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(122)|attempting
> to load provisioning module: VCL::Module::Provisioning::esx
>
> 2009-05-27 15:44:51|20138|62:62|new|esx.pm:initialize(117)|vmware ESX
> module initialized
>
> 2009-05-27
> 15:44:51|20138|62:62|new|State.pm:initialize(129)|VCL::Module::Provisioni
> ng::esx module successfully loaded
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called,
> class=VCL::Module::Provisioning::esx
>
> 2009-05-27
> 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::Module::Provisioning::es
> x object created
>
> 2009-05-27 15:44:51|20138|62:62|new|esx.pm:initialize(117)|vmware ESX
> module initialized
>
> 2009-05-27
> 15:44:51|20138|62:62|new|State.pm:initialize(133)|VCL::Module::Provisioni
> ng::esx provisioner object successfully created
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(147)|attempting
> to load OS module: VCL::Module::OS::Linux
>
> 2009-05-27 15:44:51|20138|62:62|new|Module.pm:new(80)|constructor called,
> class=VCL::Module::OS::Linux
>
> 2009-05-27
> 15:44:51|20138|62:62|new|Module.pm:new(102)|VCL::Module::OS::Linux object
> created
>
> 2009-05-27
> 15:44:51|20138|62:62|new|State.pm:initialize(155)|VCL::Module::OS::Linux
> OS object successfully created
>
> 2009-05-27 15:44:51|20138|62:62|new|State.pm:initialize(170)|returning 1
>
> 2009-05-27 15:44:51|20138|62:62|new|vcld:make_new_child(597)|VCL::new
> object created and initialized
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:get_management_node_id(9383)|environmen
> t variable: 1
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(135)|reservation is
> parent = 1
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(136)|preload only = 0
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(137)|originating
> request state = new
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(138)|originating
> request laststate = new
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(139)|originating
> computer state = available
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(140)|originating
> computer type = blade
>
> 2009-05-27
> 15:44:51|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:51|20138|62:62|new|utils.pm:get_management_node_id(9383)|environmen
> t variable: 1
>
> 2009-05-27
> 15:44:51|20138|62:62|new|new.pm:computer_not_being_used(779)|TECS-ESX-460
> 0-2 state is available
>
> 2009-05-27
> 15:44:51|20138|62:62|new|new.pm:computer_not_being_used(789)|TECS-ESX-460
> 0-2 is available, its state is available
>
> 2009-05-27 15:44:51|20138|62:62|new|new.pm:process(170)|TECS-ESX-4600-2
> is not being used
>
> 2009-05-27
> 15:44:52|20138|62:62|new|DataStructure.pm:is_parent_reservation(785)|retu
> rning true: parent reservation ID for this request: 62
>
> 2009-05-27
> 15:44:52|20138|62:62|new|utils.pm:get_management_node_id(9383)|environmen
> t variable: 1
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(513)|calling
> VCL::Module::Provisioning::esx->node_status()
>
> 2009-05-27 15:44:52|20138|62:62|new|utils.pm:insertloadlog(5324)|inserted
> computer=1, statuscheck, checking status of node
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_profile_vmpath:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{vmpa
>| th}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 636)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_profile_datastore_path:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{data
>| storepath}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 637)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_type:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{type}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 639)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_hostname:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{hostname}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 640)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_image_name:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{imagename}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, node_status (line: 641)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(646)|Entering
> node_status, checking status of TECS-ESX-4600-2
>
> 2009-05-27
> 15:44:52|20138|62:62|new|esx.pm:node_status(647)|request_for_imaging: 0
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(648)|requeseted
> image name: esx3-linuxtest-v0
>
> Use of uninitialized value $vmhost_type in string eq at
>
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line
> 663 (#1)
>
> (W uninitialized) An undefined value was used as if it were already
>
> defined. It was interpreted as a "" or a 0, but maybe it was a
> mistake.
>
> To suppress this warning assign a defined value to your variables.
>
>
>
> To help you figure out what was undefined, perl will try to tell you
> the
>
> name of the variable (if any) that was undefined. In some cases it
> cannot
>
> do this, so it also tells you what operation you used the undefined
> value
>
> in. Note, however, that perl optimizes your program and the
> operation
>
> displayed in the warning may not necessarily appear literally in your
>
> program. For example, "that $foo" is usually optimized into "that "
>
> . $foo, and the warning will refer to the concatenation (.) operator,
>
> even though there is no . in your program.
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_type in string eq at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 663.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 663)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_imagename in pattern match (m//) at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 670.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 670)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_imagename in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_type in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $vmhost_hostname in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 674.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
> /usr/lib/sendmail: option requires an argument -- f
>
> 2009-05-27 15:44:52|20138|62:62|new|utils.pm:mail(1301)|SUCCESS --
> Sending mail To: , PROBLEM -- esx.pm
>
>
>
>| 20138|62:62|new| ---- CRITICAL ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|esx.pm:node_status(673)|could not set ssh
>| identity variable for image type= host=
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) esx.pm, node_status (line: 673)
>
>| 20138|62:62|new| (-2) new.pm, reload_image (line: 517)
>
>| 20138|62:62|new| (-3) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-4) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-5) vcld, main (line: 347)
>
>
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(678)|checking if
> TECS-ESX-4600-2 is pingable
>
> 2009-05-27
> 15:44:52|20138|62:62|new|esx.pm:node_status(681)|TECS-ESX-4600-2 is
> pingable (1)
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(695)|Trying to
> ssh...
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(733)|status set to
> RELOAD
>
> 2009-05-27 15:44:52|20138|62:62|new|esx.pm:node_status(741)|returning
> node status hash reference ($node_status->{status}=RELOAD)
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(528)|node_status
> returned a hash reference
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(533)|node_status
> hash reference contains key {status}=RELOAD
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(601)|node status
> is RELOAD, TECS-ESX-4600-2 will be reloaded
>
> 2009-05-27 15:44:52|20138|62:62|new|utils.pm:insertloadlog(5324)|inserted
> computer=1, loadimageblade, TECS-ESX-4600-2 must be reloaded with
> esx3-linuxtest-v0
>
> 2009-05-27 15:44:52|20138|62:62|new|new.pm:reload_image(615)|calling
> VCL::Module::Provisioning::esx->does_image_exist()
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|DataStructure.pm:_automethod(610)|corresponding
>| data has not been initialized for get_vmhost_profile_datastore_path:
>| $self->request_data->{reservation}{62}{computer}{vmhost}{vmprofile}{data
>| storepath}
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) DataStructure.pm, _automethod (line: 610)
>
>| 20138|62:62|new| (-2) Autoload.pm, __ANON__ (line: 80)
>
>| 20138|62:62|new| (-3) esx.pm, does_image_exist (line: 759)
>
>| 20138|62:62|new| (-4) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-5) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-6) vcld, make_new_child (line: 600)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value in split at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm
>| line 759.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, does_image_exist (line: 759)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|vcld:warning_handler(642)|Use of uninitialized
>| value $datastore_share_path in concatenation (.) or string at
>| /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 766.
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) vcld, warning_handler (line: 642)
>
>| 20138|62:62|new| (-2) esx.pm, does_image_exist (line: 766)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
>
>
>| 20138|62:62|new| ---- WARNING ----
>
>| 20138|62:62|new| 2009-05-27
>| 15:44:52|20138|62:62|new|utils.pm:run_ssh_command(6630)|computer node
>| was not specified
>
>| 20138|62:62|new| ( 0) utils.pm, notify (line: 691)
>
>| 20138|62:62|new| (-1) utils.pm, run_ssh_command (line: 6630)
>
>| 20138|62:62|new| (-2) esx.pm, does_image_exist (line: 769)
>
>| 20138|62:62|new| (-3) new.pm, reload_image (line: 617)
>
>| 20138|62:62|new| (-4) new.pm, process (line: 266)
>
>| 20138|62:62|new| (-5) vcld, make_new_child (line: 600)
>
>| 20138|62:62|new| (-6) vcld, main (line: 347)
>
>
>
> Can't use an undefined value as an ARRAY reference at
>
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line
> 770 (#2)
>
> (F) A value used as either a hard reference or a symbolic reference
> must
>
> be a defined value. This helps to delurk some insidious errors.
>
>
>
> Uncaught exception from user code:
>
> Can't use an undefined value as an ARRAY reference at
> /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770.
>
> at /opt/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 770
>
>
> VCL::Module::Provisioning::esx::does_image_exist('VCL::Module::Provisioni
> ng::esx=HASH(0xb8d5b6c)', 'esx3-linuxtest-v0') called at
> /opt/vcl/bin/../lib/VCL/new.pm line 617
>
> VCL::new::reload_image('VCL::new=HASH(0xb8d61cc)') called
> at /opt/vcl/bin/../lib/VCL/new.pm line 266
>
> VCL::new::process('VCL::new=HASH(0xb8d61cc)') called at
> /opt/vcl/bin/vcld line 600
>
> VCL::vcld::make_new_child('HASH(0xb8996a4)') called at
> /opt/vcl/bin/vcld line 347
>
> VCL::vcld::main() called at /opt/vcl/bin/vcld line 93
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(442)|destructor
> called, ref($self)=VCL::new
>
> 2009-05-27
> 15:44:52|20138|62:62|new|utils.pm:delete_computerloadlog_reservation(8041
> )|removing computerloadlog entries matching loadstate = begin
>
> 2009-05-27
> 15:44:52|20138|62:62|new|utils.pm:delete_computerloadlog_reservation(8088
> )|deleted rows from computerloadlog for reservation id=62
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(445)|removed
> computerloadlog rows with loadstate=begin for reservation
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(453)|number of
> database handles state process created: 1
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(462)|process has a
> database handle stored in $ENV{dbh}, attempting disconnect
>
> 2009-05-27 15:44:52|20138|62:62|new|State.pm:DESTROY(464)|$ENV{dbh}:
> database disconnect successful
>
> 2009-05-27 15:44:52|25733|vcld:REAPER(750)|VCL process exited for
> reservation 62
>
> 2009-05-27 15:44:52|25733|vcld:main(166)|lastcheckin time updated for
> management node 1: 2009-05-27 15:44:52
>
>
>
>
>
> Kris Augustus
>
> CCNA, RHCE, A+
>
> Lab Supervisor
>
> College of TECS
>
> East Carolina University
>
> 252-737-1978
>
> augustusk@ecu.edu
>
>
>
> Intelligence plus character- that is the goal of true education.
>
> Dr. Martin Luther King Jr.
>
>
Aaron Peeler
OIT Advanced Computing
College of Engineering-NCSU
919.513.4571
http://vcl.ncsu.edu