Looks like the attachment did not go through.
Here is the file inline!

Thanks.

reservation failed on vclh1: process failed after trying to load or make
available


Time: 2011-02-08 11:39:17
PID: 5653

Caller: State.pm:reservation_failed(287)

( 0) utils.pm, notify (line: 630)
(-1) State.pm, reservation_failed (line: 287)
(-2) new.pm, process (line: 332)
(-3) vcld, make_new_child (line: 595)
(-4) vcld, main (line: 342)

RECENT LOG ENTRIES FOR THIS PROCESS:

2011-02-08 11:25:32|5653|6:6|new|VMware.pm:does_image_exist(2940)|image does
not exist in the non-persistent directory on the VM host, checking the image
repository
2011-02-08
11:25:32|5653|6:6|new|VMware.pm:get_repository_vmdk_base_directory_path(2654)|repository
path is not set for the VM profile, using management node install path:
/install/vmware_images
2011-02-08 11:25:32|5653|6:6|new|utils.pm:run_command(9082)|executed
command: find "/install/vmware_images/vmwarewinxp-basev06-v0" -iname
"vmwarewinxp-basev06-v0.vmdk", pid: 5671, exit status: 0, output:
|5653|6:6|new|
/install/vmware_images/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk
2011-02-08 11:25:32|5653|6:6|new|VMware.pm:does_image_exist(2959)|image
exists in the image repository:
/install/vmware_images/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk
2011-02-08 11:25:32|5653|6:6|new|new.pm:reload_image(641)|vmwarewinxp-basev06-v0
exists on this management node
2011-02-08 11:25:32|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, doesimageexists, confirmed image exists
2011-02-08 11:25:32|5653|6:6|new|utils.pm:update_computer_state(2091)|computer
3 state updated to: reloading
2011-02-08 11:25:32|5653|6:6|new|new.pm:reload_image(674)|computer vclh1
state set to reloading
2011-02-08 11:25:32|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, info, computer state updated to reloading
2011-02-08 11:25:32|5653|6:6|new|new.pm:reload_image(685)|calling
VCL::Module::Provisioning::VMware::VMware->load() subroutine
2011-02-08 11:25:32|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, info, calling VCL::Module::Provisioning::VMware::VMware->load()
subroutine
2011-02-08 11:25:32|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, doesimageexists, image exists vmwarewinxp-basev06-v0
2011-02-08 11:25:32|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, startload, vclh1 vmwarewinxp-basev06-v0
2011-02-08
11:25:32|5653|6:6|new|VMware.pm:get_vmx_file_paths(3332)|attempting to find
existing vmx files on the VM host
2011-02-08 11:25:32|5653|6:6|new|Linux.pm:find_files(1933)|attempting to
find files on vhost2, base directory path: '/var/lib/vmware/Virtual
Machines/', pattern: *.vmx, command: find "/var/lib/vmware/Virtual
Machines/" -type f -iname "*.vmx"
2011-02-08 11:25:32|5653|6:6|new|Linux.pm:find_files(1951)|matching file
count: 2
2011-02-08 11:25:34|5653|6:6|new|VMware.pm:get_vmx_file_paths(3343)|found 3
unique vmx files on VM host:
|5653|6:6|new| /nfs/vms/Win7/Win7.vmx
|5653|6:6|new| /var/lib/vmware/Virtual Machines/vclh1/vclh1.vmx
|5653|6:6|new| /var/lib/vmware/Virtual Machines/vclvmh1/vclvmh1.vmx
2011-02-08
11:25:35|5653|6:6|new|VMware.pm:remove_existing_vms(1106)|ignoring existing
vmx file '/nfs/vms/Win7/Win7.vmx' because it does not begin with the base
directory path: '/var/lib/vmware/Virtual Machines'
2011-02-08
11:25:35|5653|6:6|new|VMware.pm:remove_existing_vms(1129)|ignoring existing
vmx directory: vclh1
2011-02-08
11:25:35|5653|6:6|new|VMware.pm:remove_existing_vms(1129)|ignoring existing
vmx directory: vclvmh1
2011-02-08
11:25:35|5653|6:6|new|VMware.pm:get_repository_vmdk_base_directory_path(2654)|repository
path is not set for the VM profile, using management node install path:
/install/vmware_images
2011-02-08 11:25:35|5653|6:6|new|Linux.pm:file_exists(1341)|file or
directory does not exist on vhost2: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk'
2011-02-08 11:25:35|5653|6:6|new|VMware.pm:prepare_vmdk(1468)|vmdk file does
NOT exist on VM host: /var/lib/vmware/Virtual\
Machines/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk
2011-02-08 11:25:35|5653|6:6|new|VMware.pm:prepare_vmdk(1554)|found 1 vmdk
files in image repository directory:
'/install/vmware_images/vmwarewinxp-basev06-v0':
|5653|6:6|new|
/install/vmware_images/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk
2011-02-08 11:25:36|5653|6:6|new|Linux.pm:create_directory(1475)|directory
either created or already exists on vhost2: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-basev06-v0'
2011-02-08 11:25:36|5653|6:6|new|utils.pm:run_scp_command(5671)|attempt 1/3:
executing SCP command: /usr/bin/scp -B -i /etc/vcl/vcl.key -P 22 -p -r
/install/vmware_images/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk
vhost2:"/var/lib/vmware/Virtual\
Machines/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk" 2>&1
2011-02-08 11:39:13|5653|6:6|new|utils.pm:run_scp_command(5722)|scp
successful: attempt 1/3, exit status: 0, output:
2011-02-08 11:39:13|5653|6:6|new|Linux.pm:copy_file_to(1740)|copied file
from management node to vhost2:
'/install/vmware_images/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk'
--> vhost2:'/var/lib/vmware/Virtual\
Machines/vmwarewinxp-basev06-v0/vmwarewinxp-basev06-v0.vmdk'
2011-02-08 11:39:13|5653|6:6|new|VMware.pm:prepare_vmdk(1566)|copied vmdk
files from management node image repository to the VM host, took 818 seconds
2011-02-08
11:39:16|5653|6:6|new|VMware.pm:get_vmhost_product_name(4089)|VMware product
being used on VM host vhost2: 'VMware Server 2.0.2 build-203138'
2011-02-08
11:39:17|5653|6:6|new|VIM_SSH.pm:_get_datastore_names(477)|retrieved
datastore names: standard, NFS-ISO, NFS
|5653|6:6|new| ---- WARNING ----
|5653|6:6|new| 2011-02-08
11:39:17|5653|6:6|new|VIM_SSH.pm:_get_datastore_path(586)|unable to
determine datastore name in path: /var/lib/vmware/Virtual\
Machines/vmwarewinxp-basev06-v0, datastore names:
|5653|6:6|new| standard
|5653|6:6|new| NFS-ISO
|5653|6:6|new| NFS
|5653|6:6|new| ( 0) utils.pm, notify (line: 630)
|5653|6:6|new| (-1) VIM_SSH.pm, _get_datastore_path (line: 586)
|5653|6:6|new| (-2) VIM_SSH.pm, get_virtual_disk_type (line: 1290)
|5653|6:6|new| (-3) VMware.pm, check_vmdk_disk_type (line: 1615)
|5653|6:6|new| (-4) VMware.pm, prepare_vmdk (line: 1568)
|5653|6:6|new| (-5) VMware.pm, load (line: 334)
|5653|6:6|new| (-6) new.pm, reload_image (line: 687)
|5653|6:6|new| ---- WARNING ----
|5653|6:6|new| 2011-02-08
11:39:17|5653|6:6|new|VIM_SSH.pm:get_virtual_disk_type(1292)|unable to
determine vmdk directory datastore path from vmdk directory path:
/var/lib/vmware/Virtual\ Machines/vmwarewinxp-basev06-v0
|5653|6:6|new| ( 0) utils.pm, notify (line: 630)
|5653|6:6|new| (-1) VIM_SSH.pm, get_virtual_disk_type (line: 1292)
|5653|6:6|new| (-2) VMware.pm, check_vmdk_disk_type (line: 1615)
|5653|6:6|new| (-3) VMware.pm, prepare_vmdk (line: 1568)
|5653|6:6|new| (-4) VMware.pm, load (line: 334)
|5653|6:6|new| (-5) new.pm, reload_image (line: 687)
|5653|6:6|new| (-6) new.pm, process (line: 282)
2011-02-08
11:39:17|5653|6:6|new|VMware.pm:check_vmdk_disk_type(1617)|skipping vmdk
disk type check because virtual disk type could not be retrieved from the
API object
2011-02-08 11:39:17|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, transfervm, copied vmwarewinxp-basev06-v0 to vclh1
2011-02-08
11:39:17|5653|6:6|new|VMware.pm:get_vm_os_configuration(3211)|retrieved
default VM configuration for OS: xp-x86
|5653|6:6|new| : {
|5653|6:6|new| :   "ethernet-virtualDev" => "vlance",
|5653|6:6|new| :   "guestOS" => "winXPPro",
|5653|6:6|new| :   "scsi-virtualDev" => "busLogic"
|5653|6:6|new| : }
|5653|6:6|new| ---- WARNING ----
|5653|6:6|new| 2011-02-08
11:39:17|5653|6:6|new|DataStructure.pm:_automethod(764)|corresponding data
has not been initialized for get_computer_eth0_mac_address:
$self->request_data->{reservation}{6}{computer}{eth0macaddress}
|5653|6:6|new| ( 0) utils.pm, notify (line: 630)
|5653|6:6|new| (-1) DataStructure.pm, _automethod (line: 764)
|5653|6:6|new| (-2) Autoload.pm, __ANON__ (line: 80)
|5653|6:6|new| (-3) VMware.pm, prepare_vmx (line: 1171)
|5653|6:6|new| (-4) VMware.pm, load (line: 341)
|5653|6:6|new| (-5) new.pm, reload_image (line: 687)
|5653|6:6|new| (-6) new.pm, process (line: 282)
|5653|6:6|new| ---- WARNING ----
|5653|6:6|new| 2011-02-08 11:39:17|5653|6:6|new|VMware.pm:load(342)|failed
to prepare vmx file for vclh1 on VM host: vhost2
|5653|6:6|new| ( 0) utils.pm, notify (line: 630)
|5653|6:6|new| (-1) VMware.pm, load (line: 342)
|5653|6:6|new| (-2) new.pm, reload_image (line: 687)
|5653|6:6|new| (-3) new.pm, process (line: 282)
|5653|6:6|new| (-4) vcld, make_new_child (line: 595)
|5653|6:6|new| (-5) vcld, main (line: 342)
|5653|6:6|new| ---- WARNING ----
|5653|6:6|new| 2011-02-08
11:39:17|5653|6:6|new|new.pm:reload_image(692)|vmwarewinxp-basev06-v0
failed to load on vclh1, returning
|5653|6:6|new| ( 0) utils.pm, notify (line: 630)
|5653|6:6|new| (-1) new.pm, reload_image (line: 692)
|5653|6:6|new| (-2) new.pm, process (line: 282)
|5653|6:6|new| (-3) vcld, make_new_child (line: 595)
|5653|6:6|new| (-4) vcld, main (line: 342)
2011-02-08 11:39:17|5653|6:6|new|utils.pm:insertloadlog(3933)|inserted
computer=3, loadimagefailed, vmwarewinxp-basev06-v0 failed to load on vclh1
|5653|6:6|new| ---- WARNING ----
|5653|6:6|new| 2011-02-08 11:39:17|5653|6:6|new|new.pm:process(329)|failed
to load vclh1 with vmwarewinxp-basev06-v0
|5653|6:6|new| ( 0) utils.pm, notify (line: 630)
|5653|6:6|new| (-1) new.pm, process (line: 329)
|5653|6:6|new| (-2) vcld, make_new_child (line: 595)
|5653|6:6|new| (-3) vcld, main (line: 342)
2011-02-08
11:39:17|5653|6:6|new|DataStructure.pm:get_computer_state_name(2227)|attempting
to retrieve current state of computer vclh1 from the database
2011-02-08
11:39:17|5653|6:6|new|DataStructure.pm:get_computer_state_name(2258)|retrieved
current state of computer vclh1 from the database: reloading
2011-02-08 11:39:17|5653|6:6|new|DataStructure.pm:_automethod(786)|data
structure updated:
$self->request_data->{reservation}{6}{computer}{state}{name}
|5653|6:6|new| computer_state_name = reloading

Reply via email to