-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
I'm moving this to the vcl-user list. We're trying to separate the vcl-dev
lists and vcl-user lists into development and user support.
Josh
- ---------- Forwarded Message ----------
Subject: Image Failing to boot up in VCL 2.2
Date: Tuesday March 01, 2011, 5:48:25 pm
From: Alexander Patterson <alexander.patter...@csueastbay.edu>
To: vcl-...@incubator.apache.org
011-03-01 14:46:33|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 12 (vmsvc/getallvms)
2011-03-01 14:46:33|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host vclesxi01: vim-cmd vmsvc/getallvms
2011-03-01 14:46:33|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 13 (vmsvc/power.on 304)
2011-03-01 14:46:35|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host vclesxi01: vim-cmd vmsvc/power.on 304
2011-03-01 14:46:35|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 14 (vmsvc/getallvms)
2011-03-01 14:46:35|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host vclesxi01: vim-cmd vmsvc/getallvms
2011-03-01 14:46:35|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 15 (vmsvc/get.tasklist 304)
2011-03-01 14:46:36|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host vclesxi01: vim-cmd vmsvc/get.tasklist 304
2011-03-01 14:46:36|24259|52:44|new|VIM_SSH.pm:_wait_for_task(748)|checking
status of task: haTask-304-vim.VirtualMachine.powerOn-90991068
2011-03-01 14:46:36|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 16 (vimsvc/task_info
haTask-304-vim.VirtualMachine.powerOn-90991068)
2011-03-01 14:46:36|24259|52:44|new|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host vclesxi01: vim-cmd vimsvc/task_info
haTask-304-vim.VirtualMachine.powerOn-90991068
|24259|52:44|new| ---- WARNING ----
|24259|52:44|new| 2011-03-01
14:46:36|24259|52:44|new|VIM_SSH.pm:_wait_for_task(783)|task
haTask-304-vim.VirtualMachine.powerOn-90991068 did not complete
successfully, state: error, error message: The task was canceled by a
user.
|24259|52:44|new| ( 0) VIM_SSH.pm, _wait_for_task (line: 783)
|24259|52:44|new| (-1) VIM_SSH.pm, vm_power_on (line: 969)
|24259|52:44|new| (-2) VMware.pm, load (line: 407)
|24259|52:44|new| (-3) new.pm, reload_image (line: 687)
|24259|52:44|new| (-4) new.pm, process (line: 282)
|24259|52:44|new| (-5) vcld, make_new_child (line: 568)
|24259|52:44|new| ---- WARNING ----
|24259|52:44|new| 2011-03-01
14:46:36|24259|52:44|new|VIM_SSH.pm:vm_power_on(974)|failed to power
on VM: /vmfs/volumes/netappfiler02-sata1/vmguest-8_6-v0/vmguest-8_6-v0.vmx,
the vim power on task did not complete successfully, vim-cmd
vmsvc/power.on 304 output:
|24259|52:44|new| Powering on VM:
|24259|52:44|new| Power on failed
|24259|52:44|new| ( 0) VIM_SSH.pm, vm_power_on (line: 974)
|24259|52:44|new| (-1) VMware.pm, load (line: 407)
|24259|52:44|new| (-2) new.pm, reload_image (line: 687)
|24259|52:44|new| (-3) new.pm, process (line: 282)
|24259|52:44|new| (-4) vcld, make_new_child (line: 568)
|24259|52:44|new| (-5) vcld, main (line: 346)
|24259|52:44|new| ---- WARNING ----
|24259|52:44|new| 2011-03-01
14:46:36|24259|52:44|new|VMware.pm:load(408)|failed to power on VM
vmguest-8 on VM host: vclesxi01
|24259|52:44|new| ( 0) VMware.pm, load (line: 408)
|24259|52:44|new| (-1) new.pm, reload_image (line: 687)
|24259|52:44|new| (-2) new.pm, process (line: 282)
|24259|52:44|new| (-3) vcld, make_new_child (line: 568)
|24259|52:44|new| (-4) vcld, main (line: 346)
|24259|52:44|new| ---- WARNING ----
|24259|52:44|new| 2011-03-01
14:46:36|24259|52:44|new|new.pm:reload_image(692)|vmwarewinxp-build33-v0
failed to load on vmguest-8, returning
|24259|52:44|new| ( 0) new.pm, reload_image (line: 692)
|24259|52:44|new| (-1) new.pm, process (line: 282)
|24259|52:44|new| (-2) vcld, make_new_child (line: 568)
|24259|52:44|new| (-3) vcld, main (line: 346)
2011-03-01 14:46:36|24259|52:44|new|utils.pm:insertloadlog(3875)|inserted
computer=22, loadimagefailed, vmwarewinxp-build33-v0 failed to load on
vmguest-8
|24259|52:44|new| ---- WARNING ----
|24259|52:44|new| 2011-03-01
14:46:36|24259|52:44|new|new.pm:process(329)|failed to load vmguest-8
with vmwarewinxp-build33-v0
|24259|52:44|new| ( 0) new.pm, process (line: 329)
|24259|52:44|new| (-1) vcld, make_new_child (line: 568)
|24259|52:44|new| (-2) vcld, main (line: 346)
2011-03-01 14:46:36|24259|52:44|new|
DataStructure.pm:get_computer_state_name(2299)|attempting
to retrieve current state of computer vmguest-8 from the database
2011-03-01 14:46:36|24259|52:44|new|
DataStructure.pm:get_computer_state_name(2330)|retrieved
current state of computer vmguest-8 from the database: reloading
2011-03-01 14:46:36|24259|52:44|new|DataStructure.pm:_automethod(835)|data
structure updated:
$self->request_data->{reservation}{44}{computer}{state}{name}
|24259|52:44|new| computer_state_name = reloading
2011-03-01 14:46:36|24259|52:44|new|
DataStructure.pm:get_computer_private_ip_address(1582)|attempting
to retrieve private IP address for computer: vmguest-8
2011-03-01 14:46:36|24259|52:44|new|
DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
contents of /etc/hosts on this management node, contains 70 lines
2011-03-01 14:46:36|24259|52:44|new|
DataStructure.pm:get_computer_private_ip_address(1639)|returning
IP address from /etc/hosts file: 10.10.20.215
2011-03-01 14:46:36|24259|52:44|new|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2011-03-01 14:46:36|24259|52:44|new|
DataStructure.pm:get_image_affiliation_name(2029)|image
owner id: 1
2011-03-01 14:46:36|24259|52:44|new|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in
$ENV{dbh} (vcl:127.0.0.1)
2011-03-01 14:46:36|24259|52:44|new|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-03-01 14:46:36|24259|52:44|new|DataStructure.pm:retrieve_user_data(1353)|
attempting
to retrieve and store data for user: user.id = '1'
2011-03-01 14:46:36|24259|52:44|new|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:127.0.0.1)
2011-03-01 14:46:36|24259|52:44|new|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-03-01 14:46:36|24259|52:44|new|DataStructure.pm:retrieve_user_data(1416)|
data
has been retrieved for user: admin (id: 1)
2011-03-01 14:46:36|24259|52:44|new|utils.pm:mail(1255)|SUCCESS --
Sending mail To: alexander.patter...@csueastbay.edu, PROBLEM --
52:44|new|State.pm|vmguest-8>vclesxi01|vmwarewinxp-build33-v0|admin
|24259|52:44|new| ---- CRITICAL ----
|24259|52:44|new| 2011-03-01
14:46:36|24259|52:44|new|State.pm:reservation_failed(223)|reservation
failed on vmguest-8: process failed after trying to load or make
available
|24259|52:44|new| ( 0) State.pm, reservation_failed (line: 223)
|24259|52:44|new| (-1) new.pm, process (line: 332)
|24259|52:44|new| (-2) vcld, make_new_child (line: 568)
|24259|52:44|new| (-3) vcld, main (line: 346)
2011-03-01 14:46:36|24259|52:44|new|utils.pm:insertloadlog(3875)|inserted
computer=22, failed, process failed after trying to load or make
available
2011-03-01 14:46:36|24259|52:44|new|State.pm:reservation_failed(226)|inserted
computerloadlog entry
2011-03-01 14:46:36|24259|52:44|new|State.pm:reservation_failed(234)|updated
log ending value to 'failed', logid=64
2011-03-01 14:46:36|24259|52:44|new|utils.pm:update_computer_state(2033)|
computer
22 state updated to: failed
2011-03-01 14:46:36|24259|52:44|new|State.pm:reservation_failed(245)|computer
vmguest-8 (22) state set to failed
2011-03-01 14:46:36|24259|52:44|new|utils.pm:update_request_state(1991)|
request
52 state updated to: failed, laststate to: new
2011-03-01 14:46:36|24259|52:44|new|State.pm:reservation_failed(258)|set
request state to 'failed'/'new'
2011-03-01 14:46:36|24259|52:44|new|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2011-03-01 14:46:36|24259|52:44|new|State.pm:reservation_failed(276)|vmguest-8
is NOT in blockcomputers table
2011-03-01 14:46:36|24259|52:44|new|State.pm:reservation_failed(279)|exiting 1
2011-03-01 14:46:36|24259|52:44|new|State.pm:DESTROY(839)|VCL::new
destructor called, address: fdcec30
2011-03-01 14:46:36|24259|52:44|new|
utils.pm:delete_computerloadlog_reservation(6742)|removing
computerloadlog entries matching loadstate = begin
2011-03-01 14:46:36|24259|52:44|new|
utils.pm:delete_computerloadlog_reservation(6789)|deleted
rows from computerloadlog for reservation id=44
2011-03-01 14:46:36|24259|52:44|new|State.pm:DESTROY(858)|number of
database handles state process created: 3
2011-03-01 14:46:36|24259|52:44|new|State.pm:DESTROY(886)|VCL::new
process duration: 325 seconds
2011-03-01 14:46:36|24259|52:44|new|VIM_SSH.pm:DESTROY(1685)|vim-cmd
call count: 16
2011-03-01 14:46:36|26901|vcld:REAPER(718)|VCL process exited for
reservation 44, PID: 24259, signal: CHLD
- --
Thanks,
Alex Patterson
User Support Services
Operating System Analyst
California State University, East Bay
- -----------------------------------------
- --
- -------------------------------
Josh Thompson
VCL Developer
North Carolina State University
my GPG/PGP key can be found at pgp.mit.edu
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2.0.16 (GNU/Linux)
iEYEARECAAYFAk1uSs4ACgkQV/LQcNdtPQNfuQCggCD1bOzw0JSzfYH/0w1OylXD
YdEAmwQFstcknIMbiMIQ90YSCeQ+kKl4
=FQ5n
-----END PGP SIGNATURE-----
