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
