Hello,
I am installing VCL (Standalone system). When trying to create the Base
image, it throws up a few errors and also pops up an OpenSSH box and asks me
to put the password for 'vcluser'. Could anyone help with this?
2011-04-23 01:56:00|14840|utils.pm:insert_request(8443)|inserted new reload
request into request table, request id=13
2011-04-23 01:56:00|14840|utils.pm:insert_request(8474)|inserted new reload
request into reservation table, reservation id=13
2011-04-23
01:56:01|5088|13:13|image|utils.pm:reservation_being_processed(9010)|computerloadlog
'begin' entry does NOT exist for reservation 13
2011-04-23 01:56:01|5088|13:13|image|utils.pm:run_command(9082)|executed
command: pgrep -fl 'vcld [0-9]+:13 ', pid: 14929, exit status: 1, output:
2011-04-23
01:56:01|5088|13:13|image|utils.pm:is_management_node_process_running(9259)|process
is NOT running, identifier: 'vcld [0-9]+:13 '
2011-04-23
01:56:01|5088|13:13|image|utils.pm:reservation_being_processed(9031)|reservation
is NOT currently being processed
2011-04-23 01:56:01|5088|13:13|image|vcld:main(277)|reservation 13 is NOT
already being processed
2011-04-23 01:56:01|5088|13:13|image|utils.pm:get_request_info(4589)|standalone
affiliation found: Local
2011-04-23 01:56:01|5088|13:13|image|vcld:main(282)|retrieved request
information from database
2011-04-23
01:56:01|5088|13:13|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 13
2011-04-23 01:56:01|5088|13:13|image|utils.pm:update_request_state(2049)|request
13 state updated to: pending, laststate to: image
2011-04-23 01:56:01|5088|13:13|image|utils.pm:insertloadlog(3933)|inserted
computer=6, begin, beginning to process, state is image
2011-04-23 01:56:01|5088|13:13|image|vcld:make_new_child(510)|loaded
VCL::image module
2011-04-23 01:56:01|5088|13:13|image|vcld:make_new_child(567)|current number
of forked kids: 1
2011-04-23 01:56:01|14944|13:13|image|vcld:make_new_child(583)|vcld
environment variable set to 0 for this process
2011-04-23 01:56:01|14944|13:13|image|Module.pm:new(134)|constructor called,
class=VCL::image
2011-04-23 01:56:01|14944|13:13|image|Module.pm:new(156)|VCL::image object
created
2011-04-23 01:56:01|14944|13:13|image|State.pm:initialize(85)|obtained a
database handle for this state process, stored as $ENV{dbh}
2011-04-23 01:56:01|14944|13:13|image|State.pm:check_image_os(836)|no
corrections need to be made to image OS: vmwarelinux
2011-04-23
01:56:01|14944|13:13|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 13
2011-04-23
01:56:01|14944|13:13|image|utils.pm:rename_vcld_process(7183)|reservation
count: 1
2011-04-23
01:56:01|14944|13:13|image|utils.pm:rename_vcld_process(7202)|PARENTIMAGE:
1
2011-04-23
01:56:01|14944|13:13|image|utils.pm:rename_vcld_process(7203)|SUBIMAGE:
0
2011-04-23 01:56:01|14944|13:13|image|utils.pm:rename_vcld_process(7221)|renamed
process to 'vcld 13:13 image vmguest-1 vmwarelinux-1524-v0 admin'
2011-04-23
01:56:01|14944|13:13|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 13
2011-04-23
01:56:01|14944|13:13|image|DataStructure.pm:is_parent_reservation(943)|returning
true: parent reservation ID for this request: 13
2011-04-23 01:56:01|14944|13:13|image|State.pm:initialize(118)|attempting to
load provisioning module: VCL::Module::Provisioning::VMware::VMware
2011-04-23
01:56:02|14944|13:13|image|State.pm:initialize(124)|VCL::Module::Provisioning::VMware::VMware
module loaded
2011-04-23 01:56:02|14944|13:13|image|Module.pm:new(134)|constructor called,
class=VCL::Module::Provisioning::VMware::VMware
2011-04-23
01:56:02|14944|13:13|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VMware
object created
2011-04-23
01:56:02|14944|13:13|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 4
2011-04-23
01:56:02|14944|13:13|image|VMware.pm:get_vmhost_datastructure(923)|created
DataStructure object for VM host: localvmhost
2011-04-23
01:56:02|14944|13:13|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 4
2011-04-23
01:56:02|14944|13:13|image|VMware.pm:get_vmhost_datastructure(923)|created
DataStructure object for VM host: localvmhost
2011-04-23
01:56:02|14944|13:13|image|VMware.pm:get_vmhost_api_object(1027)|attempting
to load VMware control module:
VCL::Module::Provisioning::VMware::vSphere_SDK
2011-04-23
01:56:04|14944|13:13|image|VMware.pm:get_vmhost_api_object(1033)|loaded
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2011-04-23 01:56:04|14944|13:13|image|Module.pm:new(134)|constructor called,
class=VCL::Module::Provisioning::VMware::vSphere_SDK
2011-04-23
01:56:04|14944|13:13|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::vSphere_SDK
object created
2011-04-23
01:56:04|14944|13:13|image|vSphere_SDK.pm:initialize(1816)|attempting to
connect to VM host: https://localvmhost/sdk
2011-04-23 01:56:04|14944|13:13|image|vSphere_SDK.pm:initialize(1822)|unable
to connect to VM host: https://localvmhost/sdk, username: 'vcluser', error:
|14944|13:13|image| Can't locate HTTP/Config.pm in @INC (@INC contains:
/usr/local/vcl/bin/../../../.. /usr/local/vcl/bin/../..
/usr/local/vcl/bin/.. /usr/local/vcl/bin/../lib
/usr/lib64/perl5/site_perl/5.8.8/x86_64-linux-thread-multi
/usr/lib/perl5/site_perl/5.8.8 /usr/lib/perl5/site_perl/5.8.5
/usr/lib/perl5/site_perl
/usr/lib64/perl5/vendor_perl/5.8.8/x86_64-linux-thread-multi
/usr/lib/perl5/vendor_perl/5.8.8 /usr/lib/perl5/vendor_perl
/usr/lib64/perl5/5.8.8/x86_64-linux-thread-multi /usr/lib/perl5/5.8.8 .) at
/usr/lib/perl5/site_perl/5.8.8/LWP/UserAgent.pm line 746.
2011-04-23
01:56:04|14944|13:13|image|vSphere_SDK.pm:initialize(1816)|attempting to
connect to VM host: https://localvmhost:8333/sdk
2011-04-23 01:56:04|14944|13:13|image|vSphere_SDK.pm:initialize(1822)|unable
to connect to VM host: https://localvmhost:8333/sdk, username: 'vcluser',
error:
|14944|13:13|image| Can't locate HTTP/Config.pm in @INC (@INC contains:
/usr/local/vcl/bin/../../../.. /usr/local/vcl/bin/../..
/usr/local/vcl/bin/.. /usr/local/vcl/bin/../lib
/usr/lib64/perl5/site_perl/5.8.8/x86_64-linux-thread-multi
/usr/lib/perl5/site_perl/5.8.8 /usr/lib/perl5/site_perl/5.8.5
/usr/lib/perl5/site_perl
/usr/lib64/perl5/vendor_perl/5.8.8/x86_64-linux-thread-multi
/usr/lib/perl5/vendor_perl/5.8.8 /usr/lib/perl5/vendor_perl
/usr/lib64/perl5/5.8.8/x86_64-linux-thread-multi /usr/lib/perl5/5.8.8 .) at
/usr/lib/perl5/site_perl/5.8.8/LWP/UserAgent.pm line 746.
|14944|13:13|image| ---- WARNING ----
|14944|13:13|image| 2011-04-23
01:56:04|14944|13:13|image|vSphere_SDK.pm:initialize(1831)|failed to connect
to VM host: localvmhost
|14944|13:13|image| ( 0) utils.pm, notify (line: 630)
|14944|13:13|image| (-1) vSphere_SDK.pm, initialize (line: 1831)
|14944|13:13|image| (-2) Module.pm, new (line: 159)
|14944|13:13|image| (-3) VMware.pm, (eval) (line: 1037)
|14944|13:13|image| (-4) VMware.pm, get_vmhost_api_object (line: 1037)
|14944|13:13|image| (-5) VMware.pm, initialize (line: 224)
|14944|13:13|image| (-6) Module.pm, new (line: 159)
|14944|13:13|image| ---- WARNING ----
|14944|13:13|image| 2011-04-23
01:56:04|14944|13:13|image|VMware.pm:get_vmhost_api_object(1040)|API object
could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK, no
eval error
|14944|13:13|image| ( 0) utils.pm, notify (line: 630)
|14944|13:13|image| (-1) VMware.pm, get_vmhost_api_object (line: 1040)
|14944|13:13|image| (-2) VMware.pm, initialize (line: 224)
|14944|13:13|image| (-3) Module.pm, new (line: 159)
|14944|13:13|image| (-4) State.pm, initialize (line: 127)
|14944|13:13|image| (-5) Module.pm, new (line: 159)
|14944|13:13|image| (-6) vcld, make_new_child (line: 591)
2011-04-23 01:56:04|14944|13:13|image|VMware.pm:initialize(240)|attempting
to create OS object for the image currently loaded on the VM host:
localvmhost
|14944|13:13|image| image name: noimage
|14944|13:13|image| OS module: VCL::Module::OS::Linux::UnixLab
2011-04-23
01:56:04|14944|13:13|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 4
2011-04-23
01:56:04|14944|13:13|image|VMware.pm:get_vmhost_datastructure(923)|created
DataStructure object for VM host: localvmhost
2011-04-23
01:56:04|14944|13:13|image|VMware.pm:get_vmhost_os_object(965)|attempting to
load VM host OS module: VCL::Module::OS::Linux::UnixLab
2011-04-23 01:56:05|14944|13:13|image|VMware.pm:get_vmhost_os_object(971)|VM
host OS module loaded: VCL::Module::OS::Linux::UnixLab
2011-04-23 01:56:05|14944|13:13|image|Module.pm:new(134)|constructor called,
class=VCL::Module::OS::Linux::UnixLab
2011-04-23
01:56:05|14944|13:13|image|Module.pm:new(156)|VCL::Module::OS::Linux::UnixLab
object created
2011-04-23 01:56:05|14944|13:13|image|VMware.pm:get_vmhost_os_object(977)|VM
host OS object created: VCL::Module::OS::Linux::UnixLab
2011-04-23 01:56:05|14944|13:13|image|VMware.pm:initialize(241)|created OS
object to control the OS of VM host: localvmhost
2011-04-23 01:56:05|14944|13:13|image|utils.pm:nmap_port(2699)|port 22 is
open on localvmhost
2011-04-23 01:56:05|14944|13:13|image|utils.pm:nmap_port(2707)|port 24 is
closed on localvmhost
2011-04-23
01:56:13|14944|13:13|image|OS.pm:is_ssh_responding(430)|localvmhost is
responding to SSH, port 22: open, port 24: closed
2011-04-23 01:56:13|14944|13:13|image|VMware.pm:initialize(250)|OS of VM
host localvmhost will be controlled via SSH using OS object:
VCL::Module::OS::Linux::UnixLab
2011-04-23
01:56:13|14944|13:13|image|DataStructure.pm:_initialize(594)|computer ID
argument was specified, retrieving data for computer ID: 4
2011-04-23
01:56:13|14944|13:13|image|VMware.pm:get_vmhost_datastructure(923)|created
DataStructure object for VM host: localvmhost
2011-04-23
01:56:13|14944|13:13|image|VMware.pm:get_vmhost_api_object(1027)|attempting
to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2011-04-23
01:56:13|14944|13:13|image|VMware.pm:get_vmhost_api_object(1033)|loaded
VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2011-04-23 01:56:13|14944|13:13|image|Module.pm:new(134)|constructor called,
class=VCL::Module::Provisioning::VMware::VIM_SSH
2011-04-23
01:56:13|14944|13:13|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VIM_SSH
object created
2011-04-23 01:56:28|14944|13:13|image|VIM_SSH.pm:initialize(141)|VIM
executable available on VM host: vmware-vim-cmd
2011-04-23
01:56:28|14944|13:13|image|VIM_SSH.pm:initialize(143)|VCL::Module::Provisioning::VMware::VIM_SSH
object initialized
2011-04-23
01:56:28|14944|13:13|image|VMware.pm:get_vmhost_api_object(1046)|created API
object: VCL::Module::Provisioning::VMware::VIM_SSH
2011-04-23 01:56:28|14944|13:13|image|VMware.pm:initialize(264)|VIM SSH
command object will be used to control the VM: vmguest-1
2011-04-23
01:56:41|14944|13:13|image|VMware.pm:get_vmhost_product_name(4089)|VMware
product being used on VM host localvmhost: 'VMware Server 2.0.2
build-203138'
2011-04-23 01:56:48|14944|13:13|image|VMware.pm:initialize(294)|VMware
provisioning object initialized:
|14944|13:13|image| VM host OS object type: VCL::Module::OS::Linux::UnixLab
|14944|13:13|image| API object type:
VCL::Module::Provisioning::VMware::VIM_SSH
2011-04-23
01:56:48|14944|13:13|image|State.pm:initialize(128)|VCL::Module::Provisioning::VMware::VMware
provisioner object created
2011-04-23 01:56:48|14944|13:13|image|State.pm:initialize(142)|attempting to
load OS module: VCL::Module::OS::Linux
2011-04-23
01:56:48|14944|13:13|image|State.pm:initialize(148)|VCL::Module::OS::Linux
module loaded
2011-04-23 01:56:48|14944|13:13|image|Module.pm:new(134)|constructor called,
class=VCL::Module::OS::Linux
2011-04-23
01:56:48|14944|13:13|image|Module.pm:new(156)|VCL::Module::OS::Linux object
created
2011-04-23
01:56:48|14944|13:13|image|State.pm:initialize(151)|VCL::Module::OS::Linux
OS object created
2011-04-23 01:56:48|14944|13:13|image|State.pm:initialize(166)|returning 1
2011-04-23 01:56:48|14944|13:13|image|vcld:make_new_child(592)|VCL::image
object created and initialized
2011-04-23 01:56:50|14944|13:13|image|utils.pm:mail(1234)|SUCCESS -- Sending
mail To: vishn...@gmail.com, VCL IMAGE Creation Started: vmwarelinux-1524-v0
2011-04-23 01:56:57|14944|13:13|image|Linux.pm:file_exists(1341)|file or
directory does not exist on localvmhost: '/var/lib/vmware/Virtual
Machines/vmwarelinux-1524-v0/vmwarelinux-1524-v0.vmdk'
2011-04-23 01:56:57|14944|13:13|image|VMware.pm:does_image_exist(2940)|image
does not exist in the non-persistent directory on the VM host, checking the
image repository
2011-04-23
01:56:57|14944|13:13|image|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-04-23 01:56:57|14944|13:13|image|utils.pm:run_command(9082)|executed
command: find "/install/vmware_images/vmwarelinux-1524-v0" -iname
"vmwarelinux-1524-v0.vmdk", pid: 15154, exit status: 1, output:
|14944|13:13|image| find: /install/vmware_images: No such file or directory
2011-04-23 01:56:57|14944|13:13|image|VMware.pm:does_image_exist(2967)|image
does not exist in image repository:
/install/vmware_images/vmwarelinux-1524-v0/vmwarelinux-1524-v0.vmdk
2011-04-23 01:56:57|14944|13:13|image|image.pm:process(148)|image
vmwarelinux-1524-v0 does not exist in the repository
2011-04-23 01:56:57|14944|13:13|image|DataStructure.pm:_automethod(786)|data
structure updated: $self->request_data->{reservation}{13}{image}{lastupdate}
|14944|13:13|image| image_lastupdate = 2011-04-23 01:56:57
2011-04-23 01:56:57|14944|13:13|image|DataStructure.pm:_automethod(786)|data
structure updated:
$self->request_data->{reservation}{13}{imagerevision}{datecreated}
|14944|13:13|image| imagerevision_date_created = 2011-04-23 01:56:57
2011-04-23 01:56:57|14944|13:13|image|image.pm:process(164)|calling
provisioning module's capture() subroutine
2011-04-23 01:56:57|14944|13:13|image|utils.pm:nmap_port(2707)|port 22 is
closed on vmguest-1
2011-04-23 01:56:58|14944|13:13|image|utils.pm:nmap_port(2707)|port 24 is
closed on vmguest-1
2011-04-23 01:56:58|14944|13:13|image|OS.pm:is_ssh_responding(416)|vmguest-1
is NOT responding to SSH, ports 22 or 24 are both closed
|14944|13:13|image| ---- WARNING ----
|14944|13:13|image| 2011-04-23
01:56:58|14944|13:13|image|VMware.pm:capture(403)|unable to capture image,
VM vmguest-1 is not responding to SSH
|14944|13:13|image| ( 0) utils.pm, notify (line: 630)
|14944|13:13|image| (-1) VMware.pm, capture (line: 403)
|14944|13:13|image| (-2) image.pm, process (line: 165)
|14944|13:13|image| (-3) vcld, make_new_child (line: 595)
|14944|13:13|image| (-4) vcld, main (line: 342)
|14944|13:13|image| ---- WARNING ----
|14944|13:13|image| 2011-04-23
01:56:58|14944|13:13|image|image.pm:process(169)|vmwarelinux-1524-v0
image failed to be captured by provisioning module
|14944|13:13|image| ( 0) utils.pm, notify (line: 630)
|14944|13:13|image| (-1) image.pm, process (line: 169)
|14944|13:13|image| (-2) vcld, make_new_child (line: 595)
|14944|13:13|image| (-3) vcld, main (line: 342)
2011-04-23 01:56:59|14944|13:13|image|utils.pm:mail(1234)|SUCCESS -- Sending
mail To: vishn...@gmail.com, PROBLEM -- image.pm
|14944|13:13|image| ---- CRITICAL ----
|14944|13:13|image| 2011-04-23
01:56:58|14944|13:13|image|image.pm:reservation_failed(393)|vmwarelinux-1524-v0
image creation failed
|14944|13:13|image| ( 0) utils.pm, notify (line: 630)
|14944|13:13|image| (-1) image.pm, reservation_failed (line: 393)
|14944|13:13|image| (-2) image.pm, process (line: 170)
|14944|13:13|image| (-3) vcld, make_new_child (line: 595)
|14944|13:13|image| (-4) vcld, main (line: 342)
2011-04-23 01:57:00|14944|13:13|image|utils.pm:mail(1234)|SUCCESS -- Sending
mail To: root@localhost, VCL -- NOTICE DELAY Image Creation 15
2011-04-23 01:57:02|14944|13:13|image|utils.pm:mail(1234)|SUCCESS -- Sending
mail To: vishn...@gmail.com, VCL -- NOTICE FAILED Image Creation 15
2011-04-23
01:57:02|14944|13:13|image|utils.pm:update_request_state(2049)|request
13 state updated to: maintenance, laststate to: image
2011-04-23 01:57:02|14944|13:13|image|image.pm:reservation_failed(445)|request
state set to maintenance, laststate to image
2011-04-23
01:57:02|14944|13:13|image|utils.pm:update_computer_state(2091)|computer
6 state updated to: maintenance
2011-04-23 01:57:02|14944|13:13|image|image.pm:reservation_failed(453)|vmguest-1
state set to maintenance
2011-04-23 01:57:02|14944|13:13|image|image.pm:
reservation_failed(460)|exiting
2011-04-23 01:57:02|14944|13:13|image|State.pm:DESTROY(902)|destructor
called, ref($self)=VCL::image
2011-04-23
01:57:02|14944|13:13|image|utils.pm:delete_computerloadlog_reservation(6829)|removing
computerloadlog entries matching loadstate = begin
2011-04-23
01:57:02|14944|13:13|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted
rows from computerloadlog for reservation id=13
2011-04-23 01:57:02|14944|13:13|image|State.pm:DESTROY(909)|removed
computerloadlog rows with loadstate=begin for reservation
2011-04-23 01:57:02|14944|13:13|image|State.pm:DESTROY(921)|number of
database handles state process created: 1
2011-04-23 01:57:02|14944|13:13|image|State.pm:DESTROY(930)|process has a
database handle stored in $ENV{dbh}, attempting disconnect
2011-04-23 01:57:02|14944|13:13|image|State.pm:DESTROY(932)|$ENV{dbh}:
database disconnect successful
2011-04-23 01:57:02|14944|13:13|image|State.pm:DESTROY(946)|VCL::image
process 14944 exiting
2011-04-23 01:57:03|5088|vcld:REAPER(745)|VCL process exited for reservation
13, PID: 14944, signal: CHLD
2011-04-23 01:57:13|5088|vcld:main(166)|lastcheckin time updated for
management node 1: 2011-04-23 01:57:13
2011-04-23 02:07:13|5088|vcl
