Hello all --
I'm running into errors running "vcld -setup" to pull a base image
from my ESXi 4.1 box. I've managed to troubleshoot all of the errors
I've encountered up to this point... The process runs for a long while
and gets all the way to pulling the VMDK files from the hypervisor.
It appears to fail when attempting to schedule a reservation.
I've included the last sections of the vcld.log file below -- please
let me know if more information is needed.
The VM is subsequently purged from ESXi and I have to manually rebuild
the VM guest profile with the remaining copy of the VMDK disk file.
Any assistance available from the list is deeply appreciated!
Thanks & regards,
Mike
|7472|9:9|reload| ---- WARNING ----
|7472|9:9|reload| 2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_computer_eth0_mac_address:
$self->request_data->{reservation}{9}{computer}{eth0macaddress}
|7472|9:9|reload| ( 0) DataStructure.pm, _automethod (line: 812)
|7472|9:9|reload| (-1) Autoload.pm, __ANON__ (line: 80)
|7472|9:9|reload| (-2) VMware.pm, prepare_vmx (line: 1457)
|7472|9:9|reload| (-3) VMware.pm, load (line: 394)
|7472|9:9|reload| (-4) new.pm, reload_image (line: 618)
|7472|9:9|reload| (-5) new.pm, process (line: 248)
|7472|9:9|reload| ---- WARNING ----
|7472|9:9|reload| 2011-12-19
16:49:38|7472|9:9|reload|VMware.pm:load(395)|failed to prepare vmx
file for xpbase on VM host: vmware
|7472|9:9|reload| ( 0) VMware.pm, load (line: 395)
|7472|9:9|reload| (-1) new.pm, reload_image (line: 618)
|7472|9:9|reload| (-2) new.pm, process (line: 248)
|7472|9:9|reload| (-3) vcld, make_new_child (line: 568)
|7472|9:9|reload| (-4) vcld, main (line: 346)
|7472|9:9|reload| ---- WARNING ----
|7472|9:9|reload| 2011-12-19
16:49:38|7472|9:9|reload|new.pm:reload_image(623)|vmwarewinxp-xptest512-v0
failed to load on xpbase, returning
|7472|9:9|reload| ( 0) new.pm, reload_image (line: 623)
|7472|9:9|reload| (-1) new.pm, process (line: 248)
|7472|9:9|reload| (-2) vcld, make_new_child (line: 568)
|7472|9:9|reload| (-3) vcld, main (line: 346)
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:insertloadlog(3875)|inserted
computer=7, loadimagefailed, vmwarewinxp-xptest512-v0 failed to load
on xpbase
|7472|9:9|reload| ---- WARNING ----
|7472|9:9|reload| 2011-12-19
16:49:38|7472|9:9|reload|new.pm:process(295)|failed to load xpbase
with vmwarewinxp-xptest512-v0
|7472|9:9|reload| ( 0) new.pm, process (line: 295)
|7472|9:9|reload| (-1) vcld, make_new_child (line: 568)
|7472|9:9|reload| (-2) vcld, main (line: 346)
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:get_computer_state_name(2305)|attempting
to retrieve current state of computer xpbase from the database
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:get_computer_state_name(2336)|retrieved
current state of computer xpbase from the database: reloading
2011-12-19 16:49:38|7472|9:9|reload|DataStructure.pm:_automethod(834)|data
structure updated:
$self->request_data->{reservation}{9}{computer}{state}{name}
|7472|9:9|reload| computer_state_name = reloading
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:get_computer_private_ip_address(1581)|attempting
to retrieve private IP address for computer: xpbase
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
contents of /etc/hosts on this management node, contains 6 lines
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:get_computer_private_ip_address(1645)|returning
IP address from /etc/hosts file: 10.100.0.245
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:get_image_affiliation_name(2035)|image
owner id: 1
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in
$ENV{dbh} (vcl:127.0.0.1)
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:retrieve_user_data(1352)|attempting
to retrieve and store data for user: user.id = '1'
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:127.0.0.1)
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-12-19
16:49:38|7472|9:9|reload|DataStructure.pm:retrieve_user_data(1415)|data
has been retrieved for user: admin (id: 1)
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:mail(1268)|SUCCESS --
Sending mail To: 0, PROBLEM --
9:9|reload|State.pm|xpbase>vmware|vmwarewinxp-xptest512-v0|vclreload
|7472|9:9|reload| ---- CRITICAL ----
|7472|9:9|reload| 2011-12-19
16:49:38|7472|9:9|reload|State.pm:reservation_failed(213)|reservation
failed on xpbase: process failed after trying to load or make
available
|7472|9:9|reload| ( 0) State.pm, reservation_failed (line: 213)
|7472|9:9|reload| (-1) new.pm, process (line: 298)
|7472|9:9|reload| (-2) vcld, make_new_child (line: 568)
|7472|9:9|reload| (-3) vcld, main (line: 346)
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:insertloadlog(3875)|inserted
computer=7, failed, process failed after trying to load or make
available
2011-12-19 16:49:38|7472|9:9|reload|State.pm:reservation_failed(216)|inserted
computerloadlog entry
2011-12-19
16:49:38|7472|9:9|reload|utils.pm:update_computer_state(2033)|computer
7 state updated to: failed
2011-12-19 16:49:38|7472|9:9|reload|State.pm:reservation_failed(235)|computer
xpbase (7) state set to failed
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:update_request_state(1991)|request
9 state updated to: failed, laststate to: image
2011-12-19 16:49:38|7472|9:9|reload|State.pm:reservation_failed(248)|set
request state to 'failed'/'image'
2011-12-19 16:49:38|7472|9:9|reload|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2011-12-19 16:49:38|7472|9:9|reload|State.pm:reservation_failed(266)|xpbase
is NOT in blockcomputers table
2011-12-19 16:49:38|7472|9:9|reload|State.pm:reservation_failed(269)|exiting 1
2011-12-19 16:49:38|7472|9:9|reload|State.pm:DESTROY(829)|VCL::new
destructor called, address: 9a0bb6c
2011-12-19
16:49:38|7472|9:9|reload|utils.pm:delete_computerloadlog_reservation(6742)|removing
computerloadlog entries matching loadstate = begin
2011-12-19
16:49:38|7472|9:9|reload|utils.pm:delete_computerloadlog_reservation(6789)|deleted
rows from computerloadlog for reservation id=9
2011-12-19 16:49:38|7472|9:9|reload|State.pm:DESTROY(848)|number of
database handles state process created: 3
2011-12-19 16:49:38|7472|9:9|reload|State.pm:DESTROY(876)|VCL::new
process duration: 6 seconds
2011-12-19 16:49:38|7472|9:9|reload|VIM_SSH.pm:DESTROY(1685)|vim-cmd
call count: 2
2011-12-19 16:49:38|2636|vcld:REAPER(718)|VCL process exited for
reservation 9, PID: 7472, signal: CHLD
