Hi All
I'm stuck on creating a base image (Linux) for newly deployed Apache VCL
system and looking for help...
This is the closes I got so far to a working system.
I'm using ESXi 4.1 (60 days evaluation), ssh is enabled, ESXi server name
is esxiServer01, added to the host file on VCL management server. Linux
server being imaged is vm-00 running on esxiServer01. Added to the host
file as well on VCL server. I can ping both and ssh -i key host works.
Below is the full log for the process. These lines puzzle me:
2011-11-01
08:40:59|16965|20:20|image|OS.pm:is_ssh_responding(405)|esxiServer01 is NOT
responding to SSH, SSH command failed, port 22: open, port 24: closed
2011-11-01 08:40:59|16965|20:20|image|VMware.pm:initialize(257)|unable to
control OS of VM host esxiServer01 using VCL::Module::OS::Linux::UnixLab OS
object because VM host is not responding to SSH
since I can ping the VM host and 'ssh -i /etc/vcl/vcl.key esxiServer01'
works fine.
Also all http(s) links work fine when I access them from management server.
Is there a way to create and register images manually? Can I use Veeam
FastSCP or similar software to copy VMs to VCL server and register them? If
there is way to do so, anyone has a link?
I would appreciate any help. Thank you.
Here is the full log file for the imaging process:
VCL::image object could not be created and initialized
------------------------------------------------------------------------
time: 2011-11-01 08:40:59
caller: vcld:make_new_child(571)
( 0) vcld, make_new_child (line: 571)
(-1) vcld, main (line: 346)
------------------------------------------------------------------------
management node: vcl-01
reservation PID: 16965
parent vcld PID: 5086
request ID: 20
reservation ID: 20
request state/laststate: image/image
request start time: 2011-11-01 08:40:49
request end time: 2011-11-01 09:40:49
for imaging: no
log ID: none
computer: vm-00
computer id: 23
computer type: virtualmachine
computer eth0 MAC address: <undefined>
computer eth1 MAC address: <undefined>
computer private IP address: 192.168.200.15
computer public IP address: 192.168.200.15
computer in block allocation: no
provisioning module: VCL::Module::Provisioning::VMware::VMware
vm host: esxiServer01
vm host ID: 3
vm host computer ID: 11
vm profile: VMware ESX - local storage
vm profile VM path: /vmfs/volumes/4e72606b-01b8dc93-5cdb-0023aeae26d2/
vm profile repository path: 0
vm profile datastore path:
/vmfs/volumes/4e72606b-01b8dc93-5cdb-0023aeae26d2/
vm profile disk type: localdisk
image: vmwarelinux-linuximage0128-v0
image display name: linux-image-01
image ID: 28
image revision ID: 28
image size: 1450 MB
use Sysprep: yes
root access: yes
image owner ID: 1
image owner affiliation: Local
image revision date created: 2011-11-01 08:40:49
image revision production: yes
OS module: VCL::Module::OS::Linux
user: admin
user name: vcl admin
user ID: 1
user affiliation: Local
------------------------------------------------------------------------
RECENT LOG ENTRIES FOR THIS PROCESS:
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_automethod(834)|data structure
updated: $self->request_data->{reservation}{0}{computer}{IPaddress}
|16965|20:20|image| computer_ip_address = 127.0.0.1
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_mn_os_object(366)|VCL::Module::OS::Linux::ManagementNode
OS object created, address: 1b2415c0
2011-11-01 08:40:51|16965|20:20|image|Module.pm:new(192)|VCL::image object
created for state image, address: 1b03b4c0
2011-11-01 08:40:51|16965|20:20|image|State.pm:initialize(86)|obtained a
database handle for this state process, stored as $ENV{dbh}
2011-11-01 08:40:51|16965|20:20|image|State.pm:check_image_os(762)|no
corrections need to be made to image OS: vmwarelinux
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:is_parent_reservation(991)|returning
true: parent reservation ID for this request: 20
2011-11-01
08:40:51|16965|20:20|image|utils.pm:rename_vcld_process(7098)|reservation
count: 1
2011-11-01
08:40:51|16965|20:20|image|utils.pm:rename_vcld_process(7117)|PARENTIMAGE:
1
2011-11-01
08:40:51|16965|20:20|image|utils.pm:rename_vcld_process(7118)|SUBIMAGE:
0
2011-11-01 08:40:51|16965|20:20|image|utils.pm:rename_vcld_process(7136)|renamed
process to 'vcld 20:20 image vm-00>esxiServer01
vmwarelinux-linuximage0128-v0 admin'
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:is_parent_reservation(991)|returning
true: parent reservation ID for this request: 20
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:is_parent_reservation(991)|returning
true: parent reservation ID for this request: 20
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_os_object(298)|VCL::Module::OS::Linux
module loaded
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_mn_os_object(335)|management
node OS object has already been created, address: 1b2415c0, returning 1
2011-11-01
08:40:51|16965|20:20|image|Module.pm:new(196)|VCL::Module::OS::Linux object
created for image vmwarelinux-linuximage0128-v0, address: 1b266810
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_os_object(304)|VCL::Module::OS::Linux
OS object created for vmwarelinux-linuximage0128-v0, address: 1b266810
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_provisioning_object(420)|VCL::Module::Provisioning::VMware::VMware
module loaded
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_mn_os_object(335)|management
node OS object has already been created, address: 1b2415c0, returning 1
2011-11-01
08:40:51|16965|20:20|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VMware
object created for computer vm-00, address: 1b2667c0
2011-11-01
08:40:51|16965|20:20|image|VMware.pm:initialize(230)|initializing
VCL::Module::Provisioning::VMware::VMware object
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(613)|computer ID
argument was specified, retrieving data for computer ID: 11
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(643)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(660)|retrieved data
for imagerevision ID: 4
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(676)|retrieved data
for image ID: 4
2011-11-01
08:40:51|16965|20:20|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: esxiServer01
2011-11-01 08:40:51|16965|20:20|image|VMware.pm:initialize(249)|attempting
to create OS object for the image currently loaded on the VM host:
esxiServer01
|16965|20:20|image| image name: noimage
|16965|20:20|image| OS module: VCL::Module::OS::Linux::UnixLab
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(613)|computer ID
argument was specified, retrieving data for computer ID: 11
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(643)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(660)|retrieved data
for imagerevision ID: 4
2011-11-01
08:40:51|16965|20:20|image|DataStructure.pm:_initialize(676)|retrieved data
for image ID: 4
2011-11-01
08:40:51|16965|20:20|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: esxiServer01
2011-11-01
08:40:51|16965|20:20|image|VMware.pm:get_vmhost_os_object(1232)|attempting
to load VM host OS module: VCL::Module::OS::Linux::UnixLab
2011-11-01
08:40:51|16965|20:20|image|VMware.pm:get_vmhost_os_object(1238)|VM host OS
module loaded: VCL::Module::OS::Linux::UnixLab
2011-11-01
08:40:51|16965|20:20|image|Module.pm:create_mn_os_object(335)|management
node OS object has already been created, address: 1b2415c0, returning 1
2011-11-01
08:40:51|16965|20:20|image|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab
object created for image noimage, address: 1b5dc1d0
2011-11-01
08:40:51|16965|20:20|image|VMware.pm:get_vmhost_os_object(1244)|VM host OS
object created: VCL::Module::OS::Linux::UnixLab
2011-11-01
08:40:59|16965|20:20|image|OS.pm:is_ssh_responding(405)|esxiServer01 is NOT
responding to SSH, SSH command failed, port 22: open, port 24: closed
2011-11-01 08:40:59|16965|20:20|image|VMware.pm:initialize(257)|unable to
control OS of VM host esxiServer01 using VCL::Module::OS::Linux::UnixLab OS
object because VM host is not responding to SSH
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:_initialize(613)|computer ID
argument was specified, retrieving data for computer ID: 11
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:_initialize(643)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:_initialize(660)|retrieved data
for imagerevision ID: 4
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:_initialize(676)|retrieved data
for image ID: 4
2011-11-01
08:40:59|16965|20:20|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: esxiServer01
2011-11-01
08:40:59|16965|20:20|image|VMware.pm:get_vmhost_api_object(1293)|attempting
to load VMware control module:
VCL::Module::Provisioning::VMware::vSphere_SDK
2011-11-01
08:40:59|16965|20:20|image|VMware.pm:get_vmhost_api_object(1299)|loaded
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2011-11-01 08:40:59|16965|20:20|image|Module.pm:new(172)|set 'vmhost_data'
key for VCL::Module::Provisioning::VMware::vSphere_SDK object from arguments
2011-11-01 08:40:59|16965|20:20|image|Module.pm:new(172)|set 'vmhost_os'
key for VCL::Module::Provisioning::VMware::vSphere_SDK object from arguments
2011-11-01
08:40:59|16965|20:20|image|Module.pm:create_mn_os_object(335)|management
node OS object has already been created, address: 1b2415c0, returning 1
2011-11-01
08:40:59|16965|20:20|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK
object created for computer vm-00, address: 1b5dc460
2011-11-01
08:40:59|16965|20:20|image|vSphere_SDK.pm:initialize(1829)|loaded VMware
vSphere SDK modules
2011-11-01
08:40:59|16965|20:20|image|vSphere_SDK.pm:initialize(1871)|attempting to
connect to VM host:https://esxiServer01/sdk
2011-11-01 08:40:59|16965|20:20|image|vSphere_SDK.pm:initialize(1883)|unable
to connect to VM host using URL:https://esxiServer01/sdk, error:
|16965|20:20|image| Server version unavailable at '
https://esxiServer01:443/sdk/vimService.wsdl<https://esxiServer01/sdk/vimService.wsdl>'
at /usr/lib/perl5/5.8.8/VMware/VICommon.pm line 545.
2011-11-01
08:40:59|16965|20:20|image|vSphere_SDK.pm:initialize(1871)|attempting to
connect to VM host:https://esxiServer01:8333/sdk
2011-11-01 08:40:59|16965|20:20|image|vSphere_SDK.pm:initialize(1883)|unable
to connect to VM host using URL:https://esxiServer01:8333/sdk, error:
|16965|20:20|image| Error connecting to server at '
https://esxiServer01:8333/sdk': Connection refused
2011-11-01
08:40:59|16965|20:20|image|vSphere_SDK.pm:initialize(1896)|unable connect
to VM host: esxiServer01
2011-11-01
08:40:59|16965|20:20|image|VMware.pm:get_vmhost_api_object(1313)|API object
could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
|16965|20:20|image| ---- WARNING ----
|16965|20:20|image| 2011-11-01
08:40:59|16965|20:20|image|VMware.pm:initialize(273)|no methods are
available to control VM host esxiServer01, the vSphere SDK cannot be used
to control the VM host and the host OS cannot be controlled via SSH
|16965|20:20|image| ( 0) VMware.pm, initialize (line: 273)
|16965|20:20|image| (-1) Module.pm, new (line: 207)
|16965|20:20|image| (-2) Module.pm, create_provisioning_object (line: 423)
|16965|20:20|image| (-3) State.pm, initialize (line: 117)
|16965|20:20|image| (-4) Module.pm, new (line: 207)
|16965|20:20|image| (-5) vcld, make_new_child (line: 564)
|16965|20:20|image| ---- WARNING ----
|16965|20:20|image| 2011-11-01
08:40:59|16965|20:20|image|Module.pm:create_provisioning_object(431)|provisioning
object could not be created, returning 0
|16965|20:20|image| ( 0) Module.pm, create_provisioning_object (line: 431)
|16965|20:20|image| (-1) State.pm, initialize (line: 117)
|16965|20:20|image| (-2) Module.pm, new (line: 207)
|16965|20:20|image| (-3) vcld, make_new_child (line: 564)
|16965|20:20|image| (-4) vcld, main (line: 346)
|16965|20:20|image| ---- WARNING ----
|16965|20:20|image| 2011-11-01
08:40:59|16965|20:20|image|State.pm:initialize(118)|failed to create
provisioning object
|16965|20:20|image| ( 0) State.pm, initialize (line: 118)
|16965|20:20|image| (-1) Module.pm, new (line: 207)
|16965|20:20|image| (-2) vcld, make_new_child (line: 564)
|16965|20:20|image| (-3) vcld, main (line: 346)
2011-11-01 08:40:59|16965|20:20|image|State.pm:DESTROY(829)|VCL::image
destructor called, address: 1b03b4c0
2011-11-01
08:40:59|16965|20:20|image|utils.pm:delete_computerloadlog_reservation(6742)|removing
computerloadlog entries matching loadstate = begin
2011-11-01
08:40:59|16965|20:20|image|utils.pm:delete_computerloadlog_reservation(6789)|deleted
rows from computerloadlog for reservation id=20
2011-11-01 08:40:59|16965|20:20|image|State.pm:DESTROY(848)|number of
database handles state process created: 1
2011-11-01 08:40:59|16965|20:20|image|State.pm:DESTROY(876)|VCL::image
process duration: 8 seconds
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:get_computer_private_ip_address(1581)|attempting
to retrieve private IP address for computer: vm-00
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
contents of /etc/hosts on this management node, contains 7 lines
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:get_computer_private_ip_address(1645)|returning
IP address from /etc/hosts file: 192.168.200.15
2011-11-01 08:40:59|16965|20:20|image|utils.pm:getnewdbh(2703)|unable to
use database handle stored in $ENV{dbh}
2011-11-01 08:40:59|16965|20:20|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-11-01 08:40:59|16965|20:20|image|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:get_image_affiliation_name(2035)|image
owner id: 1
2011-11-01 08:40:59|16965|20:20|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:localhost)
2011-11-01 08:40:59|16965|20:20|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:retrieve_user_data(1352)|attempting
to retrieve and store data for user:user.id = '1'
2011-11-01 08:40:59|16965|20:20|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:localhost)
2011-11-01 08:40:59|16965|20:20|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-11-01
08:40:59|16965|20:20|image|DataStructure.pm:retrieve_user_data(1415)|data
has been retrieved for user: admin (id: 1)
