Hello,

I'm having an issue with a new build Image

Here is the error Log


State   Est/Act
Time    Total
Time
confirming image exists(22)     0:04/0:14       0:14
starting load process(40)       0:06/0:02       0:16
creating configuration file(28) 0:02/5:32       5:48
starting virtual machine(48)    0:03/0:04       5:52
machine booting(46)     1:08/9:45       15:37

[b]failed: could not collect dynamic IP address for node[/b]

2011-02-10 09:10:54|18390|36903:36639|new|vmware.pm:load(883)|vmguest-70
ROUND 1 checks loop 52 of 40
2011-02-10 
09:10:54|18390|36903:36639|new|utils.pm:run_ssh_command(6180)|executing
SSH command on vclesx08:
|18390|36903:36639|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p
22 -x vclesx08 'vmware-cmd
/virtualmachines/36639vmguest-70/36639vmguest-70.vmx getstate' 2>&1
2011-02-10 
09:10:54|18390|36903:36639|new|utils.pm:run_ssh_command(6262)|run_ssh_command
output:
|18390|36903:36639|new| Use of uninitialized value in string eq at
/usr/lib64/perl5/site_perl/5.8.8/x86_64-linux-thread-multi/VMware/VmPerl.pm
line 114.
|18390|36903:36639|new| getstate() = on
2011-02-10 09:10:54|18390|36903:36639|new|utils.pm:run_ssh_command(6276)|SSH
command executed on vclesx08, returning (0, "Use of uninitialized
value in ...")
2011-02-10 09:10:54|18390|36903:36639|new|vmware.pm:load(887)|rechecking
state of vm vmguest-70
/virtualmachines/36639vmguest-70/36639vmguest-70.vmx
2011-02-10 09:10:54|18390|36903:36639|new|vmware.pm:load(892)|vm
vmguest-70 reports on
2011-02-10 09:10:54|18390|36903:36639|new|vmware.pm:load(903)|sshd is
NOT active on vmguest-70 yet
2011-02-10 09:10:56|12372|vcld:main(165)|lastcheckin time updated for
management node 1: 2011-02-10 09:10:56
2011-02-10 
09:10:56|18814|36902:36638|inuse|utils.pm:check_connection(1765)|checking
for connection by yw5765 on vmguest-59, attempt 23
2011-02-10 
09:10:56|18814|36902:36638|inuse|utils.pm:run_ssh_command(6180)|executing
SSH command on vmguest-59:
|18814|36902:36638|inuse| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p
22 -x vmguest-59 'netstat -an' 2>&1
2011-02-10 09:11:01|12372|vcld:main(165)|lastcheckin time updated for
management node 1: 2011-02-10 09:11:01
2011-02-10 09:11:05|18390|36903:36639|new|vmware.pm:load(883)|vmguest-70
ROUND 1 checks loop 53 of 40
2011-02-10 
09:11:05|18390|36903:36639|new|utils.pm:run_ssh_command(6180)|executing
SSH command on vclesx08:
|18390|36903:36639|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p
22 -x vclesx08 'vmware-cmd
/virtualmachines/36639vmguest-70/36639vmguest-70.vmx getstate' 2>&1
2011-02-10 
09:11:05|18390|36903:36639|new|utils.pm:run_ssh_command(6262)|run_ssh_command
output:
|18390|36903:36639|new| Use of uninitialized value in string eq at
/usr/lib64/perl5/site_perl/5.8.8/x86_64-linux-thread-multi/VMware/VmPerl.pm
line 114.
|18390|36903:36639|new| getstate() = on
2011-02-10 09:11:05|18390|36903:36639|new|utils.pm:run_ssh_command(6276)|SSH
command executed on vclesx08, returning (0, "Use of uninitialized
value in ...")
2011-02-10 09:11:05|18390|36903:36639|new|vmware.pm:load(887)|rechecking
state of vm vmguest-70
/virtualmachines/36639vmguest-70/36639vmguest-70.vmx
2011-02-10 09:11:05|18390|36903:36639|new|vmware.pm:load(892)|vm
vmguest-70 reports on
Use of uninitialized value in pattern match (m//) at
        /usr/local/vcl/bin/../lib/VCL/utils.pm line 2745 (#1)

|18390|36903:36639|new| ---- WARNING ----
|18390|36903:36639|new| 2011-02-10
09:11:05|18390|36903:36639|new|vcld:warning_handler(636)|Use of
uninitialized value in pattern match (m//) at
/usr/local/vcl/bin/../lib/VCL/utils.pm line 2745.
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) vcld, warning_handler (line: 636)
|18390|36903:36639|new| (-2) utils.pm, _sshd_status (line: 2745)
|18390|36903:36639|new| (-3) vmware.pm, load (line: 894)
|18390|36903:36639|new| (-4) new.pm, reload_image (line: 665)
|18390|36903:36639|new| (-5) new.pm, process (line: 266)
|18390|36903:36639|new| (-6) vcld, make_new_child (line: 594)


|18390|36903:36639|new| ---- WARNING ----
|18390|36903:36639|new| 2011-02-10
09:11:05|18390|36903:36639|new|vcld:warning_handler(636)|Use of
uninitialized value in pattern match (m//) at
/usr/local/vcl/bin/../lib/VCL/utils.pm line 2745.
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) vcld, warning_handler (line: 636)
|18390|36903:36639|new| (-2) utils.pm, _sshd_status (line: 2745)
|18390|36903:36639|new| (-3) vmware.pm, load (line: 894)
|18390|36903:36639|new| (-4) new.pm, reload_image (line: 665)
|18390|36903:36639|new| (-5) new.pm, process (line: 266)
|18390|36903:36639|new| (-6) vcld, make_new_child (line: 594)

2011-02-10 
09:11:05|18390|36903:36639|new|utils.pm:run_ssh_command(6180)|executing
SSH command on vmguest-70:
|18390|36903:36639|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p
22 -x vmguest-70 'uname -s' 2>&1
2011-02-10 09:11:06|12372|vcld:main(165)|lastcheckin time updated for
management node 1: 2011-02-10 09:11:06
2011-02-10 
09:11:06|18390|36903:36639|new|utils.pm:run_ssh_command(6262)|run_ssh_command
output:
|18390|36903:36639|new| CYGWIN_NT-5.1
2011-02-10 09:11:06|18390|36903:36639|new|utils.pm:run_ssh_command(6276)|SSH
command executed on vmguest-70, returning (0, "CYGWIN_NT-5.1")
2011-02-10 09:11:06|18390|36903:36639|new|vmware.pm:load(896)|vmguest-70
now has active sshd running, maybe we missed the READY flag setting
STAGE5 flag
2011-02-10 09:11:06|18390|36903:36639|new|utils.pm:insertloadlog(4710)|inserted
computer=74, vmround2, waiting for ssh to become active
2011-02-10 09:11:06|18390|36903:36639|new|vmware.pm:load(1029)|READY
flag set for /virtualmachines/36639vmguest-70/36639vmguest-70.vmx,
proceeding
2011-02-10 
09:11:06|18390|36903:36639|new|utils.pm:run_ssh_command(6180)|executing
SSH command on vmguest-70:
|18390|36903:36639|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p
22 -x vmguest-70 'uname -s' 2>&1
2011-02-10 
09:11:08|18390|36903:36639|new|utils.pm:run_ssh_command(6262)|run_ssh_command
output:
|18390|36903:36639|new| CYGWIN_NT-5.1
2011-02-10 09:11:08|18390|36903:36639|new|utils.pm:run_ssh_command(6276)|SSH
command executed on vmguest-70, returning (0, "CYGWIN_NT-5.1")
2011-02-10 09:11:08|18390|36903:36639|new|vmware.pm:load(1038)|vmguest-70
now has active sshd running, ok to proceed to sync ssh keys
2011-02-10 09:11:08|18390|36903:36639|new|vmware.pm:load(1072)|removing
vmguest-70 ssh public key from /root/.ssh/known_hosts
2011-02-10 09:11:08|18390|36903:36639|new|vmware.pm:load(1072)|removing
vmguest-70 ssh public key from /root/.ssh/known_hosts
2011-02-10 09:11:08|18390|36903:36639|new|utils.pm:insertloadlog(4710)|inserted
computer=74, info, starting post configurations on node
2011-02-10 09:11:08|18390|36903:36639|new|utils.pm:insertloadlog(4710)|inserted
computer=74, dynamicDHCPaddress, collecting dynamic IP address for
node
2011-02-10 
09:11:08|18390|36903:36639|new|utils.pm:getdynamicaddress(1605)|PrivateIP
address for vmguest-70 collected 10.10.10.150
2011-02-10 
09:11:08|18390|36903:36639|new|utils.pm:run_ssh_command(6180)|executing
SSH command on vmguest-70:
|18390|36903:36639|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p
22 -x vmguest-70 'ipconfig' 2>&1
2011-02-10 
09:11:09|18390|36903:36639|new|utils.pm:run_ssh_command(6262)|run_ssh_command
output:
|18390|36903:36639|new| none
2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:run_ssh_command(6276)|SSH
command executed on vmguest-70, returning (0, "none")

|18390|36903:36639|new| ---- WARNING ----
|18390|36903:36639|new| 2011-02-10
09:11:09|18390|36903:36639|new|vcld:warning_handler(636)|Use of
uninitialized value in concatenation (.) or string at
/usr/local/vcl/bin/../lib/VCL/utils.pm line 1652.
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) vcld, warning_handler (line: 636)
|18390|36903:36639|new| (-2) utils.pm, getdynamicaddress (line: 1652)
|18390|36903:36639|new| (-3) vmware.pm, load (line: 1104)
|18390|36903:36639|new| (-4) new.pm, reload_image (line: 665)
|18390|36903:36639|new| (-5) new.pm, process (line: 266)
|18390|36903:36639|new| (-6) vcld, make_new_child (line: 594)

2011-02-10 
09:11:09|18390|36903:36639|new|utils.pm:getdynamicaddress(1652)|dynamic
IP address for vmguest-70 collected:
No recipient addresses found in header
2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:mail(1348)|SUCCESS
-- Sending mail To: , PROBLEM -- vmware.pm

|18390|36903:36639|new| ---- CRITICAL ----
|18390|36903:36639|new| 2011-02-10
09:11:09|18390|36903:36639|new|vmware.pm:load(1116)|could not fetch
dynamic address from vmguest-70 vmwarewinxp-build97-v1
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) vmware.pm, load (line: 1116)
|18390|36903:36639|new| (-2) new.pm, reload_image (line: 665)
|18390|36903:36639|new| (-3) new.pm, process (line: 266)
|18390|36903:36639|new| (-4) vcld, make_new_child (line: 594)
|18390|36903:36639|new| (-5) vcld, main (line: 341)

2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:insertloadlog(4710)|inserted
computer=74, failed, could not collect dynamic IP address for node

|18390|36903:36639|new| ---- WARNING ----
|18390|36903:36639|new| 2011-02-10
09:11:09|18390|36903:36639|new|new.pm:reload_image(670)|vmwarewinxp-build97-v1
failed to load on vmguest-70, returning
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) new.pm, reload_image (line: 670)
|18390|36903:36639|new| (-2) new.pm, process (line: 266)
|18390|36903:36639|new| (-3) vcld, make_new_child (line: 594)
|18390|36903:36639|new| (-4) vcld, main (line: 341)

2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:insertloadlog(4710)|inserted
computer=74, loadimagefailed, vmwarewinxp-build97-v1 failed to load on
vmguest-70

|18390|36903:36639|new| ---- WARNING ----
|18390|36903:36639|new| 2011-02-10
09:11:09|18390|36903:36639|new|new.pm:process(313)|failed to load
vmguest-70 with vmwarewinxp-build97-v1
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) new.pm, process (line: 313)
|18390|36903:36639|new| (-2) vcld, make_new_child (line: 594)
|18390|36903:36639|new| (-3) vcld, main (line: 341)

2011-02-10 
09:11:09|18390|36903:36639|new|DataStructure.pm:get_computer_state_name(1946)|attempting
to retrieve current state of computer vmguest-70 from the database
2011-02-10 
09:11:09|18390|36903:36639|new|DataStructure.pm:get_computer_state_name(1977)|retrieved
current state of computer vmguest-70 from the database: reloading
2011-02-10 09:11:09|18390|36903:36639|new|DataStructure.pm:_automethod(697)|data
structure updated:
$self->request_data->{reservation}{36639}{computer}{state}{name}
|18390|36903:36639|new| computer_state_name = reloading
No recipient addresses found in header
2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:mail(1348)|SUCCESS
-- Sending mail To: , PROBLEM -- State.pm

|18390|36903:36639|new| ---- CRITICAL ----
|18390|36903:36639|new| 2011-02-10
09:11:09|18390|36903:36639|new|State.pm:reservation_failed(290)|reservation
failed on vmguest-70: process failed after trying to load or make
available
|18390|36903:36639|new| ( 0) utils.pm, notify (line: 737)
|18390|36903:36639|new| (-1) State.pm, reservation_failed (line: 290)
|18390|36903:36639|new| (-2) new.pm, process (line: 316)
|18390|36903:36639|new| (-3) vcld, make_new_child (line: 594)
|18390|36903:36639|new| (-4) vcld, main (line: 341)

2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:insertloadlog(4710)|inserted
computer=74, failed, process failed after trying to load or make
available
2011-02-10 
09:11:09|18390|36903:36639|new|State.pm:reservation_failed(293)|inserted
computerloadlog entry
2011-02-10 
09:11:09|18390|36903:36639|new|State.pm:reservation_failed(301)|updated
log ending value to 'failed', logid=11784
2011-02-10 
09:11:09|18390|36903:36639|new|utils.pm:update_computer_state(2228)|computer
74 state updated to: failed
2011-02-10 
09:11:09|18390|36903:36639|new|State.pm:reservation_failed(312)|computer
vmguest-70 (74) state set to failed
2011-02-10 
09:11:09|18390|36903:36639|new|utils.pm:update_request_state(2186)|request
36903 state updated to: failed, laststate to: new
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:reservation_failed(325)|set
request state to 'failed'/'new'
2011-02-10 09:11:09|18390|36903:36639|new|utils.pm:is_inblockrequest(6972)|zero
rows were returned from database select
2011-02-10 
09:11:09|18390|36903:36639|new|State.pm:reservation_failed(343)|vmguest-70
is NOT in blockcomputers table
2011-02-10 
09:11:09|18390|36903:36639|new|State.pm:reservation_failed(346)|exiting
1
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:DESTROY(905)|destructor
called, ref($self)=VCL::new
2011-02-10 
09:11:09|18390|36903:36639|new|utils.pm:delete_computerloadlog_reservation(7551)|removing
computerloadlog entries matching loadstate = begin
2011-02-10 
09:11:09|18390|36903:36639|new|utils.pm:delete_computerloadlog_reservation(7598)|deleted
rows from computerloadlog for reservation id=36639
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:DESTROY(912)|removed
computerloadlog rows with loadstate=begin for reservation
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:DESTROY(924)|number
of database handles state process created: 1
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:DESTROY(933)|process
has a database handle stored in $ENV{dbh}, attempting disconnect
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:DESTROY(935)|$ENV{dbh}:
database disconnect successful
2011-02-10 09:11:09|18390|36903:36639|new|State.pm:DESTROY(949)|VCL::new
process 18390 exiting
2011-02-10 09:11:09|12372|vcld:REAPER(744)|VCL process exited for
reservation 36639


-- 
Thanks,
Alex  Patterson
User Support Services
Operating System Analyst
California State University, East Bay

Reply via email to