The first thing I see is that the password is not set in the vm host
profile.
|8938|1:5|image| 2010-10-12
11:55:53|8938|1:5|image|vSphere_SDK.pm:initialize(1789)|VM host password
is not configured in the database for the VM profile
You'll need to update the vm profile for this host and then retry. Below
is a reply from Andy in a previous thread for when you need to manually
restart the image capture.
--------------------
When the capture process failed, the VCL request (request.id = 1) was
left in the database and its state
was changed to 'maintenance' (request.stateid = 10). To restart the image
capture process, all you need to do is change request.stateid back to the
'image' state (request.stateid = 16) and your running vcld daemon should
restart
the process. You don't need to run 'vcld -setup' again.
There is currently no web interface or utility to change the request
state so it
will have to be done manually to the database. You'll probably want to
install
phpmyadmin if you haven't done so already. The statement to update the
request
state:
update request set stateid = 16 where id = 1;
The VCL daemon will begin processing the imaging request again within a few
seconds. Run 'tail -f /var/log/vcld.log' before executing the SQL
statement to
watch the progress.
-------------------
Hope this helps.
Aaron
On 10/15/10 3:45 PM, Hicham Gibet Tani wrote:
---------- Forwarded message ----------
From: *Hicham Gibet Tani* <hgtsweethone...@gmail.com
<mailto:hgtsweethone...@gmail.com>>
Date: 2010/10/12
Subject: Abdelmalek Essaadi university, Computer engineering
department, MOROCCO
To: vcl-u...@incubator.apache.org <mailto:vcl-u...@incubator.apache.org>
Good afternoon everybody,
We started testing VCL 2.2 and we did all the configuration steps for
a standalone VMware VCL environment but we are experiencing some
problems with the API object.
2010-10-12
11:55:53|7432|1:5|image|utils.pm:reservation_being_processed(9010)|computerloadlog
'begin' entry does NOT exist for reservation 5
2010-10-12 11:55:53|7432|1:5|image|utils.pm:run_command(9082)|executed
command: pgrep -fl 'vcld [0-9]+:5 ', pid: 8924, exit status: 1, output:
2010-10-12
11:55:53|7432|1:5|image|utils.pm:is_management_node_process_running(9259)|process
is NOT running, identifier: 'vcld [0-9]+:5 '
2010-10-12
11:55:53|7432|1:5|image|utils.pm:reservation_being_processed(9031)|reservation
is NOT currently being processed
2010-10-12 11:55:53|7432|1:5|image|vcld:main(277)|reservation 5 is NOT
already being processed
2010-10-12
11:55:53|7432|1:5|image|utils.pm:get_request_info(4589)|standalone
affiliation found: Local
2010-10-12 11:55:53|7432|1:5|image|vcld:main(282)|retrieved request
information from database
2010-10-12
11:55:53|7432|1:5|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 5
2010-10-12
11:55:53|7432|1:5|image|utils.pm:update_request_state(2049)|request 1
state updated to: pending, laststate to: image
2010-10-12
11:55:53|7432|1:5|image|utils.pm:insertloadlog(3933)|inserted
computer=4, begin, beginning to process, state is image
2010-10-12 11:55:53|7432|1:5|image|vcld:make_new_child(510)|loaded
VCL::image module
2010-10-12 11:55:53|8938|1:5|image|vcld:make_new_child(583)|vcld
environment variable set to 0 for this process
2010-10-12 11:55:53|7432|1:5|image|vcld:make_new_child(567)|current
number of forked kids: 1
2010-10-12 11:55:53|8938|1:5|image|Module.pm:new(134)|constructor
called, class=VCL::image
2010-10-12 11:55:53|8938|1:5|image|Module.pm:new(156)|VCL::image
object created
2010-10-12 11:55:53|8938|1:5|image|State.pm:initialize(85)|obtained a
database handle for this state process, stored as $ENV{dbh}
2010-10-12 11:55:53|8938|1:5|image|State.pm:check_image_os(836)|no
corrections need to be made to image OS: vmwarelinux
2010-10-12
11:55:53|8938|1:5|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 5
2010-10-12
11:55:53|8938|1:5|image|utils.pm:rename_vcld_process(7183)|reservation
count: 1
2010-10-12
11:55:53|8938|1:5|image|utils.pm:rename_vcld_process(7202)|PARENTIMAGE: 1
2010-10-12
11:55:53|8938|1:5|image|utils.pm:rename_vcld_process(7203)|SUBIMAGE: 0
2010-10-12
11:55:53|8938|1:5|image|utils.pm:rename_vcld_process(7221)|renamed
process to 'vcld 1:5 image vmguest-1 vmwarelinux-base8-v1 admin'
2010-10-12
11:55:53|8938|1:5|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 5
2010-10-12
11:55:53|8938|1:5|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 5
2010-10-12 11:55:53|8938|1:5|image|State.pm:initialize(118)|attempting
to load provisioning module: VCL::Module::Provisioning::VMware::VMware
2010-10-12
11:55:53|8938|1:5|image|State.pm:initialize(124)|VCL::Module::Provisioning::VMware::VMware
module loaded
2010-10-12 11:55:53|8938|1:5|image|Module.pm:new(134)|constructor
called, class=VCL::Module::Provisioning::VMware::VMware
2010-10-12
11:55:53|8938|1:5|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VMware
object created
2010-10-12
11:55:53|8938|1:5|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 3
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure
object for VM host: localvmhost
2010-10-12
11:55:53|8938|1:5|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 3
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure
object for VM host: localvmhost
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_api_object(1027)|attempting
to load VMware control module:
VCL::Module::Provisioning::VMware::vSphere_SDK
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_api_object(1033)|loaded
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2010-10-12 11:55:53|8938|1:5|image|Module.pm:new(134)|constructor
called, class=VCL::Module::Provisioning::VMware::vSphere_SDK
2010-10-12
11:55:53|8938|1:5|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::vSphere_SDK
object created
|8938|1:5|image| ---- WARNING ----
|8938|1:5|image| 2010-10-12
11:55:53|8938|1:5|image|vSphere_SDK.pm:initialize(1789)|VM host
password is not configured in the database for the VM profile
|8938|1:5|image| ( 0) utils.pm <http://utils.pm>, notify (line: 630)
|8938|1:5|image| (-1) vSphere_SDK.pm, initialize (line: 1789)
|8938|1:5|image| (-2) Module.pm, new (line: 159)
|8938|1:5|image| (-3) VMware.pm, (eval) (line: 1037)
|8938|1:5|image| (-4) VMware.pm, get_vmhost_api_object (line: 1037)
|8938|1:5|image| (-5) VMware.pm, initialize (line: 224)
|8938|1:5|image| (-6) Module.pm, new (line: 159)
|8938|1:5|image| ---- WARNING ----
|8938|1:5|image| 2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_api_object(1040)|API
object could not be created:
VCL::Module::Provisioning::VMware::vSphere_SDK, no eval error
|8938|1:5|image| ( 0) utils.pm <http://utils.pm>, notify (line: 630)
|8938|1:5|image| (-1) VMware.pm, get_vmhost_api_object (line: 1040)
|8938|1:5|image| (-2) VMware.pm, initialize (line: 224)
|8938|1:5|image| (-3) Module.pm, new (line: 159)
|8938|1:5|image| (-4) State.pm, initialize (line: 127)
|8938|1:5|image| (-5) Module.pm, new (line: 159)
|8938|1:5|image| (-6) vcld, make_new_child (line: 591)
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:initialize(240)|attempting to create
OS object for the image currently loaded on the VM host: localvmhost
|8938|1:5|image| image name: noimage
|8938|1:5|image| OS module: VCL::Module::OS::Linux::UnixLab
2010-10-12
11:55:53|8938|1:5|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 3
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure
object for VM host: localvmhost
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_os_object(965)|attempting
to load VM host OS module: VCL::Module::OS::Linux::UnixLab
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_os_object(971)|VM host OS
module loaded: VCL::Module::OS::Linux::UnixLab
2010-10-12 11:55:53|8938|1:5|image|Module.pm:new(134)|constructor
called, class=VCL::Module::OS::Linux::UnixLab
2010-10-12
11:55:53|8938|1:5|image|Module.pm:new(156)|VCL::Module::OS::Linux::UnixLab
object created
2010-10-12
11:55:53|8938|1:5|image|VMware.pm:get_vmhost_os_object(977)|VM host OS
object created: VCL::Module::OS::Linux::UnixLab
2010-10-12 11:55:53|8938|1:5|image|VMware.pm:initialize(241)|created
OS object to control the OS of VM host: localvmhost
2010-10-12 11:55:53|8938|1:5|image|utils.pm:nmap_port(2699)|port 22 is
open on localvmhost
2010-10-12 11:55:53|8938|1:5|image|utils.pm:nmap_port(2707)|port 24 is
closed on localvmhost
2010-10-12
11:55:54|8938|1:5|image|OS.pm:is_ssh_responding(430)|localvmhost is
responding to SSH, port 22: open, port 24: closed
2010-10-12 11:55:54|8938|1:5|image|VMware.pm:initialize(250)|OS of VM
host localvmhost will be controlled via SSH using OS object:
VCL::Module::OS::Linux::UnixLab
2010-10-12
11:55:54|8938|1:5|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 3
2010-10-12
11:55:54|8938|1:5|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure
object for VM host: localvmhost
2010-10-12
11:55:54|8938|1:5|image|VMware.pm:get_vmhost_api_object(1027)|attempting
to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2010-10-12
11:55:54|8938|1:5|image|VMware.pm:get_vmhost_api_object(1033)|loaded
VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2010-10-12 11:55:54|8938|1:5|image|Module.pm:new(134)|constructor
called, class=VCL::Module::Provisioning::VMware::VIM_SSH
2010-10-12
11:55:54|8938|1:5|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VIM_SSH
object created
2010-10-12 11:55:54|8938|1:5|image|VIM_SSH.pm:initialize(126)|failed
to determine which VIM executable is available on the VM host, output:
|8938|1:5|image| bash: vim-cmd: command not found
|8938|1:5|image| bash: vmware-vim-cmd: command not found
|8938|1:5|image| ---- WARNING ----
|8938|1:5|image| 2010-10-12
11:55:54|8938|1:5|image|VMware.pm:get_vmhost_api_object(1040)|API
object could not be created:
VCL::Module::Provisioning::VMware::VIM_SSH, no eval error
|8938|1:5|image| ( 0) utils.pm <http://utils.pm>, notify (line: 630)
|8938|1:5|image| (-1) VMware.pm, get_vmhost_api_object (line: 1040)
|8938|1:5|image| (-2) VMware.pm, initialize (line: 264)
|8938|1:5|image| (-3) Module.pm, new (line: 159)
|8938|1:5|image| (-4) State.pm, initialize (line: 127)
|8938|1:5|image| (-5) Module.pm, new (line: 159)
|8938|1:5|image| (-6) vcld, make_new_child (line: 591)
|8938|1:5|image| ---- WARNING ----
|8938|1:5|image| 2010-10-12
11:55:54|8938|1:5|image|VMware.pm:initialize(271)|failed to create an
API object to control the VM: vmguest-1
|8938|1:5|image| ( 0) utils.pm <http://utils.pm>, notify (line: 630)
|8938|1:5|image| (-1) VMware.pm, initialize (line: 271)
|8938|1:5|image| (-2) Module.pm, new (line: 159)
|8938|1:5|image| (-3) State.pm, initialize (line: 127)
|8938|1:5|image| (-4) Module.pm, new (line: 159)
|8938|1:5|image| (-5) vcld, make_new_child (line: 591)
|8938|1:5|image| (-6) vcld, main (line: 342)
|8938|1:5|image| ---- WARNING ----
|8938|1:5|image| 2010-10-12
11:55:54|8938|1:5|image|State.pm:initialize(132)|provisioning object
could not be created, returning 0
|8938|1:5|image| ( 0) utils.pm <http://utils.pm>, notify (line: 630)
|8938|1:5|image| (-1) State.pm, initialize (line: 132)
|8938|1:5|image| (-2) Module.pm, new (line: 159)
|8938|1:5|image| (-3) vcld, make_new_child (line: 591)
|8938|1:5|image| (-4) vcld, main (line: 342)
2010-10-12 11:55:54|8938|1:5|image|State.pm:DESTROY(902)|destructor
called, ref($self)=VCL::image
2010-10-12
11:55:54|8938|1:5|image|utils.pm:delete_computerloadlog_reservation(6829)|removing
computerloadlog entries matching loadstate = begin
2010-10-12
11:55:54|8938|1:5|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted
rows from computerloadlog for reservation id=5
2010-10-12 11:55:54|8938|1:5|image|State.pm:DESTROY(909)|removed
computerloadlog rows with loadstate=begin for reservation
2010-10-12 11:55:54|8938|1:5|image|State.pm:DESTROY(921)|number of
database handles state process created: 1
2010-10-12 11:55:54|8938|1:5|image|State.pm:DESTROY(930)|process has a
database handle stored in $ENV{dbh}, attempting disconnect
2010-10-12 11:55:54|8938|1:5|image|State.pm:DESTROY(932)|$ENV{dbh}:
database disconnect successful
2010-10-12 11:55:54|8938|1:5|image|State.pm:DESTROY(946)|VCL::image
process 8938 exiting
2010-10-12 11:55:55|8938|1:5|image|utils.pm:mail(1234)|SUCCESS --
Sending mail To: hgtsweethone...@gmail.com
<mailto:hgtsweethone...@gmail.com>, PROBLEM -- vcld
|8938|1:5|image| ---- CRITICAL ----
|8938|1:5|image| 2010-10-12
11:55:54|8938|1:5|image|vcld:make_new_child(598)|VCL::image object
could not be created and initialized
|8938|1:5|image| ( 0) utils.pm <http://utils.pm>, notify (line: 630)
|8938|1:5|image| (-1) vcld, make_new_child (line: 598)
|8938|1:5|image| (-2) vcld, main (line: 342)
2010-10-12 11:55:55|8938|1:5|image|utils.pm:switch_state(7338)|called
from VCL::vcld::make_new_child(599)
2010-10-12 11:55:55|8938|1:5|image|utils.pm:switch_state(7374)|parent:
parent reservation ID for this request: 5
2010-10-12 11:55:55|8938|1:5|image|utils.pm:getnewdbh(2761)|unable to
use database handle stored in $ENV{dbh}
2010-10-12 11:55:55|8938|1:5|image|utils.pm:getnewdbh(2818)|database
handle stored in $ENV{dbh}
2010-10-12
11:55:55|8938|1:5|image|utils.pm:insertloadlog(3933)|inserted
computer=4, info, VCL::vcld: switching request state to failed
2010-10-12
11:55:55|8938|1:5|image|utils.pm:update_request_state(2049)|request 1
state updated to: failed, laststate to: image
2010-10-12 11:55:55|8938|1:5|image|utils.pm:switch_state(7398)|req=1:
request state changed: image->failed, laststate: image->image
2010-10-12
11:55:55|8938|1:5|image|utils.pm:insertloadlog(3933)|inserted
computer=4, info, VCL::vcld: request state changed to failed,
laststate to image
2010-10-12 11:55:55|8938|1:5|image|utils.pm:switch_state(7408)|req=1:
computer state not specified, vmguest-1 state not changed
2010-10-12 11:55:55|8938|1:5|image|utils.pm:switch_state(7426)|req=1:
log table id=0, ending set to failed
2010-10-12
11:55:55|8938|1:5|image|utils.pm:insertloadlog(3933)|inserted
computer=4, info, VCL::vcld: process exiting
2010-10-12 11:55:55|8938|1:5|image|utils.pm:switch_state(7442)|req=1:
process exiting
2010-10-12 11:55:55|7432|vcld:REAPER(745)|VCL process exited for
reservation 5, PID: 8938, signal: CHLD
2010-10-12 11:55:55|7432|vcld:main(166)|lastcheckin time updated for
management node 1: 2010-10-12 11:55:55
2010-10-12 11:55:55|7432|1:5|failed|vcld:main(251)|request deleted
Please we need your advice.
Cordially,
Gibet Tani Hicham
Eloutouate Lamiae.
--
Aaron Peeler
Program Manager
Virtual Computing Lab
NC State University
aaron_pee...@ncsu.edu
919-513-4571