Hi All again, After a long break I am back to where, I was unable to get the Reservation to work:
So, I was able to get Image capture work smoothly with the only short come of not completing the automatically created reservation process after image capture. Which failed at the point after registering the VM on the VMhost(ESXi). It does send the correct information and commands for registering the VM but it registeres the VM as "Unknown"; Following upon Ryan's comment on un mounting and remounting the NFS store. I started to make reservations and yet haven't been successful. As usual something silly/unknown that I can't see. Two unusual things: 1) esx.pm:node_status(814)|could not set ssh identity variable for image type= host= 2) "SSH exits with the exit status of the remote command or with 255 if an error occurred, output:" had same issue during image capture, and again Ryan pointed out a workaround to point to my NFS store directly. "Pushing the problem, doesn't not necessarily solve it" :) Please advise....................................... 3 2010-04-01 11:04:58|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:04:58 4 2010-04-01 11:05:03|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:03 5 2010-04-01 11:05:08|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:08 6 2010-04-01 11:05:13|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:13 7 2010-04-01 11:05:18|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:18 8 2010-04-01 11:05:23|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:23 9 2010-04-01 11:05:28|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:28 10 2010-04-01 11:05:33|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:33 11 2010-04-01 11:05:38|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:38 12 2010-04-01 11:05:43|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:43 13 2010-04-01 11:05:48|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:48 14 2010-04-01 11:05:53|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:53 15 2010-04-01 11:05:53|5754|8:8|new|utils.pm:reservation_being_processed(9634)|computerloadlog 'begin' entry does NOT exist for reservation 8 16 2010-04-01 11:05:53|5754|8:8|new|utils.pm:reservation_being_processed(9686)|reservation is NOT currently being processed 17 2010-04-01 11:05:53|5754|8:8|new|vcld:main(276)|reservation 8 is NOT already being processed 18 19 |5754|8:8|new| ---- WARNING ---- 20 |5754|8:8|new| 2010-04-01 11:05:53|5754|8:8|new|utils.pm:get_request_info(5218)|preferredimageid is not set for computer id=2 21 |5754|8:8|new| ( 0) utils.pm, notify (line: 737) 22 |5754|8:8|new| (-1) utils.pm, get_request_info (line: 5218) 23 |5754|8:8|new| (-2) vcld, main (line: 280) 24 25 2010-04-01 11:05:53|5754|8:8|new|utils.pm:get_request_info(5354)|standalone affiliation found: Local 26 2010-04-01 11:05:53|5754|8:8|new|utils.pm:get_management_node_info(6815)|management node info retrieved from database for colbert 27 2010-04-01 11:05:53|5754|8:8|new|vcld:main(281)|retrieved request information from database 28 2010-04-01 11:05:53|5754|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 29 2010-04-01 11:05:53|5754|8:8|new|utils.pm:update_request_state(2186)|request 8 state updated to: pending, laststate to: new 30 2010-04-01 11:05:53|5754|8:8|new|utils.pm:insertloadlog(4710)|inserted computer=2, begin, beginning to process, state is new 31 2010-04-01 11:05:53|5754|8:8|new|vcld:make_new_child(509)|loaded VCL::new module 32 2010-04-01 11:05:53|12084|8:8|new|vcld:make_new_child(582)|vcld environment variable set to 0 for this process 33 2010-04-01 11:05:53|12084|8:8|new|Module.pm:new(132)|constructor called, class=VCL::new 34 2010-04-01 11:05:53|5754|8:8|new|vcld:make_new_child(566)|current number of forked kids: 1 35 2010-04-01 11:05:53|12084|8:8|new|Module.pm:new(154)|VCL::new object created 36 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh} 37 2010-04-01 11:05:53|12084|8:8|new|State.pm:check_image_os(820)|no corrections need to be made, not an imaging request, returning 1 38 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 39 2010-04-01 11:05:53|12084|8:8|new|utils.pm:rename_vcld_process(7863)|reservation count: 1 40 2010-04-01 11:05:53|12084|8:8|new|utils.pm:rename_vcld_process(7882)|PARENTIMAGE: 1 41 2010-04-01 11:05:53|12084|8:8|new|utils.pm:rename_vcld_process(7883)|SUBIMAGE: 0 42 2010-04-01 11:05:53|12084|8:8|new|utils.pm:rename_vcld_process(7901)|renamed process to 'vcld VCL::new 8:8 new' 43 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 44 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 45 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(121)|attempting to load provisioning module: VCL::Module::Provisioning::esx 46 2010-04-01 11:05:53|12084|8:8|new|esx.pm:initialize(138)|esx vmware toolkit root path found: /usr/lib/vmware-vcli/apps 47 2010-04-01 11:05:53|12084|8:8|new|esx.pm:initialize(140)|vmware ESX module initialized 48 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(127)|VCL::Module::Provisioning::esx module loaded 49 2010-04-01 11:05:53|12084|8:8|new|Module.pm:new(132)|constructor called, class=VCL::Module::Provisioning::esx 50 2010-04-01 11:05:53|12084|8:8|new|Module.pm:new(154)|VCL::Module::Provisioning::esx object created 51 2010-04-01 11:05:53|12084|8:8|new|esx.pm:initialize(138)|esx vmware toolkit root path found: /usr/lib/vmware-vcli/apps 52 2010-04-01 11:05:53|12084|8:8|new|esx.pm:initialize(140)|vmware ESX module initialized 53 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(131)|VCL::Module::Provisioning::esx provisioner object created 54 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(145)|attempting to load OS module: VCL::Module::OS::Linux 55 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(151)|VCL::Module::OS::Linux module loaded 56 2010-04-01 11:05:53|12084|8:8|new|Module.pm:new(132)|constructor called, class=VCL::Module::OS::Linux 57 2010-04-01 11:05:53|12084|8:8|new|Module.pm:new(154)|VCL::Module::OS::Linux object created 58 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(154)|VCL::Module::OS::Linux OS object created 59 2010-04-01 11:05:53|12084|8:8|new|State.pm:initialize(169)|returning 1 60 2010-04-01 11:05:53|12084|8:8|new|vcld:make_new_child(591)|VCL::new object created and initialized 61 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 62 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:get_computer_state_name(1946)|attempting to retrieve current state of computer esx35-hypervisor-1 from the database 63 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:get_computer_state_name(1977)|retrieved current state of computer esx35-hypervisor-1 from the database: available 64 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(697)|data structure updated: $self->request_data->{reservation}{8}{computer}{state}{name} 65 |12084|8:8|new| computer_state_name = available 66 67 |12084|8:8|new| ---- WARNING ---- 68 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_computer_preferredimage_id: $self->request_data->{reservation}{8}{computer}{preferredimage}{id} 69 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 70 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 71 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 72 |12084|8:8|new| (-3) new.pm, process (line: 106) 73 |12084|8:8|new| (-4) vcld, make_new_child (line: 594) 74 |12084|8:8|new| (-5) vcld, main (line: 341) 75 76 77 |12084|8:8|new| ---- WARNING ---- 78 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_computer_preferredimage_name: $self->request_data->{reservation}{8}{computer}{preferredimage}{name} 79 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 80 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 81 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 82 |12084|8:8|new| (-3) new.pm, process (line: 107) 83 |12084|8:8|new| (-4) vcld, make_new_child (line: 594) 84 |12084|8:8|new| (-5) vcld, main (line: 341) 85 86 2010-04-01 11:05:53|12084|8:8|new|utils.pm:get_management_node_id(9364)|environment variable: 1 87 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(134)|reservation is parent = 1 88 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(135)|preload only = 0 89 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(136)|originating request state = new 90 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(137)|originating request laststate = new 91 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(138)|originating computer state = available 92 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(139)|originating computer type = blade 93 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 94 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:get_computer_state_name(1946)|attempting to retrieve current state of computer esx35-hypervisor-1 from the database 95 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:get_computer_state_name(1977)|retrieved current state of computer esx35-hypervisor-1 from the database: available 96 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(697)|data structure updated: $self->request_data->{reservation}{8}{computer}{state}{name} 97 |12084|8:8|new| computer_state_name = available 98 99 |12084|8:8|new| ---- WARNING ---- 100 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_computer_preferredimage_id: $self->request_data->{reservation}{8}{computer}{preferredimage}{id} 101 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 102 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 103 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 104 |12084|8:8|new| (-3) new.pm, computer_not_being_used (line: 750) 105 |12084|8:8|new| (-4) new.pm, process (line: 170) 106 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 107 |12084|8:8|new| (-6) vcld, main (line: 341) 108 109 110 |12084|8:8|new| ---- WARNING ---- 111 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_computer_preferredimage_name: $self->request_data->{reservation}{8}{computer}{preferredimage}{name} 112 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 113 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 114 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 115 |12084|8:8|new| (-3) new.pm, computer_not_being_used (line: 751) 116 |12084|8:8|new| (-4) new.pm, process (line: 170) 117 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 118 |12084|8:8|new| (-6) vcld, main (line: 341) 119 120 2010-04-01 11:05:53|12084|8:8|new|utils.pm:get_management_node_id(9364)|environment variable: 1 121 2010-04-01 11:05:53|12084|8:8|new|new.pm:computer_not_being_used(788)|esx35-hypervisor-1 state is available 122 2010-04-01 11:05:53|12084|8:8|new|new.pm:computer_not_being_used(798)|esx35-hypervisor-1 is available, its state is available 123 2010-04-01 11:05:53|12084|8:8|new|new.pm:process(170)|esx35-hypervisor-1 is not being used 124 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 8 125 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:get_computer_state_name(1946)|attempting to retrieve current state of computer esx35-hypervisor-1 from the database 126 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:get_computer_state_name(1977)|retrieved current state of computer esx35-hypervisor-1 from the database: available 127 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(697)|data structure updated: $self->request_data->{reservation}{8}{computer}{state}{name} 128 |12084|8:8|new| computer_state_name = available 129 130 |12084|8:8|new| ---- WARNING ---- 131 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_computer_preferredimage_id: $self->request_data->{reservation}{8}{computer}{preferredimage}{id} 132 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 133 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 134 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 135 |12084|8:8|new| (-3) new.pm, reload_image (line: 480) 136 |12084|8:8|new| (-4) new.pm, process (line: 266) 137 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 138 |12084|8:8|new| (-6) vcld, main (line: 341) 139 140 141 |12084|8:8|new| ---- WARNING ---- 142 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_computer_preferredimage_name: $self->request_data->{reservation}{8}{computer}{preferredimage}{name} 143 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 144 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 145 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 146 |12084|8:8|new| (-3) new.pm, reload_image (line: 481) 147 |12084|8:8|new| (-4) new.pm, process (line: 266) 148 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 149 |12084|8:8|new| (-6) vcld, main (line: 341) 150 151 2010-04-01 11:05:53|12084|8:8|new|utils.pm:get_management_node_id(9364)|environment variable: 1 152 2010-04-01 11:05:53|12084|8:8|new|new.pm:reload_image(514)|calling VCL::Module::Provisioning::esx->node_status() 153 2010-04-01 11:05:53|12084|8:8|new|utils.pm:insertloadlog(4710)|inserted computer=2, statuscheck, checking status of node 154 155 |12084|8:8|new| ---- WARNING ---- 156 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_vmhost_profile_vmpath: $self->request_data->{reservation}{8}{computer}{vmhost}{vmprofile}{vmpath} 157 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 158 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 159 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 160 |12084|8:8|new| (-3) esx.pm, node_status (line: 776) 161 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 162 |12084|8:8|new| (-5) new.pm, process (line: 266) 163 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 164 165 166 |12084|8:8|new| ---- WARNING ---- 167 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_vmhost_profile_datastore_path: $self->request_data->{reservation}{8}{computer}{vmhost}{vmprofile}{datastorepath} 168 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 169 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 170 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 171 |12084|8:8|new| (-3) esx.pm, node_status (line: 777) 172 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 173 |12084|8:8|new| (-5) new.pm, process (line: 266) 174 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 175 176 177 |12084|8:8|new| ---- WARNING ---- 178 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_vmhost_type: $self->request_data->{reservation}{8}{computer}{vmhost}{type} 179 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 180 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 181 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 182 |12084|8:8|new| (-3) esx.pm, node_status (line: 779) 183 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 184 |12084|8:8|new| (-5) new.pm, process (line: 266) 185 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 186 187 188 |12084|8:8|new| ---- WARNING ---- 189 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_vmhost_hostname: $self->request_data->{reservation}{8}{computer}{vmhost}{hostname} 190 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 191 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 192 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 193 |12084|8:8|new| (-3) esx.pm, node_status (line: 780) 194 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 195 |12084|8:8|new| (-5) new.pm, process (line: 266) 196 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 197 198 199 |12084|8:8|new| ---- WARNING ---- 200 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|DataStructure.pm:_automethod(675)|corresponding data has not been initialized for get_vmhost_image_name: $self->request_data->{reservation}{8}{computer}{vmhost}{imagename} 201 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 202 |12084|8:8|new| (-1) DataStructure.pm, _automethod (line: 675) 203 |12084|8:8|new| (-2) Autoload.pm, __ANON__ (line: 80) 204 |12084|8:8|new| (-3) esx.pm, node_status (line: 781) 205 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 206 |12084|8:8|new| (-5) new.pm, process (line: 266) 207 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 208 216 2010-04-01 11:05:53|12084|8:8|new|esx.pm:node_status(787)|Entering node_status, checking status of esx35-hypervisor-1 217 2010-04-01 11:05:53|12084|8:8|new|esx.pm:node_status(788)|request_for_imaging: 0 218 2010-04-01 11:05:53|12084|8:8|new|esx.pm:node_status(789)|requeseted image name: esx3-basewinxp-v0 219 Use of uninitialized value in string eq at 220 /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 804 (#1) 221 (W uninitialized) An undefined value was used as if it were already 222 defined. It was interpreted as a "" or a 0, but maybe it was a mistake. 223 To suppress this warning assign a defined value to your variables. 224 225 To help you figure out what was undefined, perl tells you what operation 226 you used the undefined value in. Note, however, that perl optimizes your 227 program and the operation displayed in the warning may not necessarily 228 appear literally in your program. For example, "that $foo" is 229 usually optimized into "that " . $foo, and the warning will refer to 230 the concatenation (.) operator, even though there is no . in your 231 program. 232 233 234 |12084|8:8|new| ---- WARNING ---- 235 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|vcld:warning_handler(636)|Use of uninitialized value in string eq at /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 804. 236 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 237 |12084|8:8|new| (-1) vcld, warning_handler (line: 636) 238 |12084|8:8|new| (-2) esx.pm, node_status (line: 804) 239 |12084|8:8|new| (-3) new.pm, reload_image (line: 518) 240 |12084|8:8|new| (-4) new.pm, process (line: 266) 241 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 242 |12084|8:8|new| (-6) vcld, main (line: 341) 243 244 245 |12084|8:8|new| ---- WARNING ---- 246 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|vcld:warning_handler(636)|Use of uninitialized value in pattern match (m//) at /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 811. 247 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 248 |12084|8:8|new| (-1) vcld, warning_handler (line: 636) 249 |12084|8:8|new| (-2) esx.pm, node_status (line: 811) 250 |12084|8:8|new| (-3) new.pm, reload_image (line: 518) 251 |12084|8:8|new| (-4) new.pm, process (line: 266) 252 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 253 |12084|8:8|new| (-6) vcld, main (line: 341) 254 255 256 |12084|8:8|new| ---- WARNING ---- 257 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|vcld:warning_handler(636)|Use of uninitialized value in concatenation (.) or string at /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 815. 258 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 259 |12084|8:8|new| (-1) vcld, warning_handler (line: 636) 260 |12084|8:8|new| (-2) esx.pm, node_status (line: 814) 261 |12084|8:8|new| (-3) new.pm, reload_image (line: 518) 262 |12084|8:8|new| (-4) new.pm, process (line: 266) 263 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 264 |12084|8:8|new| (-6) vcld, main (line: 341) 265 266 267 |12084|8:8|new| ---- WARNING ---- 268 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|vcld:warning_handler(636)|Use of uninitialized value in concatenation (.) or string at /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 815. 269 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 270 |12084|8:8|new| (-1) vcld, warning_handler (line: 636) 271 |12084|8:8|new| (-2) esx.pm, node_status (line: 814) 272 |12084|8:8|new| (-3) new.pm, reload_image (line: 518) 273 |12084|8:8|new| (-4) new.pm, process (line: 266) 274 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 275 |12084|8:8|new| (-6) vcld, main (line: 341) 276 277 278 |12084|8:8|new| ---- WARNING ---- 279 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|vcld:warning_handler(636)|Use of uninitialized value in concatenation (.) or string at /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/esx.pm line 815. 280 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 281 |12084|8:8|new| (-1) vcld, warning_handler (line: 636) 282 |12084|8:8|new| (-2) esx.pm, node_status (line: 814) 283 |12084|8:8|new| (-3) new.pm, reload_image (line: 518) 284 |12084|8:8|new| (-4) new.pm, process (line: 266) 285 |12084|8:8|new| (-5) vcld, make_new_child (line: 594) 286 |12084|8:8|new| (-6) vcld, main (line: 341) 287 288 No recipient addresses found in header 289 2010-04-01 11:05:54|12084|8:8|new|utils.pm:mail(1348)|SUCCESS -- Sending mail To: , PROBLEM -- esx.pm 290 291 |12084|8:8|new| ---- CRITICAL ---- 292 |12084|8:8|new| 2010-04-01 11:05:53|12084|8:8|new|esx.pm:node_status(814)|could not set ssh identity variable for image type= host= 293 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 294 |12084|8:8|new| (-1) esx.pm, node_status (line: 814) 295 |12084|8:8|new| (-2) new.pm, reload_image (line: 518) 296 |12084|8:8|new| (-3) new.pm, process (line: 266) 297 |12084|8:8|new| (-4) vcld, make_new_child (line: 594) 298 |12084|8:8|new| (-5) vcld, main (line: 341) 299 300 2010-04-01 11:05:54|12084|8:8|new|esx.pm:node_status(819)|checking if esx35-hypervisor-1 is pingable 301 2010-04-01 11:05:54|12084|8:8|new|esx.pm:node_status(822)|esx35-hypervisor-1 is pingable (1) 302 2010-04-01 11:05:54|12084|8:8|new|esx.pm:node_status(836)|Trying to ssh... 303 2010-04-01 11:05:54|12084|8:8|new|utils.pm:run_ssh_command(6180)|executing SSH command on esx35-hypervisor-1: 304 |12084|8:8|new| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x esx35-hypervisor-1 'uname -s' 2>&1 305 306 |12084|8:8|new| ---- WARNING ---- 307 |12084|8:8|new| 2010-04-01 11:05:54|12084|8:8|new|utils.pm:run_ssh_command(6252)|attempt 1/3: failed to execute SSH command on esx35-hypervisor-1: uname -s, exit status: 255, SSH exits with the exit status of the remote command or with 255 if an error occurred, output: 308 |12084|8:8|new| ssh output (uname -s): VMkernel 309 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 310 |12084|8:8|new| (-1) utils.pm, run_ssh_command (line: 6252) 311 |12084|8:8|new| (-2) utils.pm, _sshd_status (line: 2755) 312 |12084|8:8|new| (-3) esx.pm, node_status (line: 839) 313 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 314 |12084|8:8|new| (-5) new.pm, process (line: 266) 315 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 316 317 2010-04-01 11:05:54|12084|8:8|new|utils.pm:run_ssh_command(6172)|sleeping for 2 seconds before making next SSH attempt 318 2010-04-01 11:05:56|12084|8:8|new|utils.pm:run_ssh_command(6184)|attempt 2/3: executing SSH command on esx35-hypervisor-1: 319 |12084|8:8|new| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x esx35-hypervisor-1 'uname -s' 2>&1 320 321 |12084|8:8|new| ---- WARNING ---- 322 |12084|8:8|new| 2010-04-01 11:05:56|12084|8:8|new|utils.pm:run_ssh_command(6252)|attempt 2/3: failed to execute SSH command on esx35-hypervisor-1: uname -s, exit status: 255, SSH exits with the exit status of the remote command or with 255 if an error occurred, output: 323 |12084|8:8|new| ssh output (uname -s): VMkernel 324 |12084|8:8|new| ( 0) utils.pm, notify (line: 737) 325 |12084|8:8|new| (-1) utils.pm, run_ssh_command (line: 6252) 326 |12084|8:8|new| (-2) utils.pm, _sshd_status (line: 2755) 327 |12084|8:8|new| (-3) esx.pm, node_status (line: 839) 328 |12084|8:8|new| (-4) new.pm, reload_image (line: 518) 329 |12084|8:8|new| (-5) new.pm, process (line: 266) 330 |12084|8:8|new| (-6) vcld, make_new_child (line: 594) 331 332 2010-04-01 11:05:56|12084|8:8|new|utils.pm:run_ssh_command(6172)|sleeping for 15 seconds before making next SSH attempt 333 2010-04-01 11:05:58|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:05:58 334 2010-04-01 11:06:03|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:06:03 335 2010-04-01 11:06:08|5754|vcld:main(165)|lastcheckin time updated for management node 1: 2010-04-01 11:06:08 336 Thank you, Amit