-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
On Friday, July 05, 2013 8:17:35 AM Toan Trac wrote:
> Josh,
>
>
>
> Thanks for your reply as I continue to dig through my issues.
>
>
>
> When I changed to VMware, I get the failed log showing "vSphere SDK for
> Perl does not appear to be installed on this managment node, unable to load
> VMware vSphere SDK Perl modules, error:". I even tried updating the
> vSphere SDK module but I don't think its the issue. See below for a full
> log.
>
>
>
> I have a few questions.
>
>
> 1) I ran into an older documentation of version 2.21 that says about
> ESX/ESXi to use SSH identity key authentication. Do I have to configure it?
> I am working on version 2.3.2 now?
Unless you are using a licensed version of ESXi, you will need to enable SSH
identity key authentication. When using SSH identity key authentication, you
may still see messages in vcld.log about not being able to use the vSphere
SDK. I think it tries the SDK first and then tries SSH. When using the free
license for ESXi, the SDK is restricted to read only actions. So, you need to
use SSH. You don't need to have the SDK installed.
> 2) On the VMware host, do you know
> what version (provisioning) I should set it at if I am using ESXi 4.0?
> -xCAT 1.3 -xCAT 2.1 -xCAT 2.x
For the VMware host, you should select "None" as the provisioning engine -
i.e. VCL is not provisioning (loading) the physical VMware host; you have
manually installed it and are just adding it to VCL for VCL to be able to
provision VMs on it.
> 3) Since I am building this setup
> for learning process and will be handed a live system to manage soon, my
> hardware setup is limited. So I want to make sure my setup is not the
> cause of the image failed. The db, web and management node are on the same
> server with just one (192.168.1.70) NIC.
Having the VCL stack on a system with one NIC should be okay, though I'm not
completely sure about that working for the management node.
> Also my ESXi 4.0 host has just
> one NIC with an IP address 192.168.1.2 (so I stated public/private with
> same IP). For the windows 2003 machine that I tried to capture the image,
> it has 2 NICS (private -192.168.1.80, public - 192.168.1.81).
VCL expects every provisioned node to have 2 NICs that are on separate
networks. Having 2 NICs on a VM that are on the same subnet will be a
problem. I think having the VMware host with just one NIC is okay. It
definitely only needs to have an IP bound on one network; however, it may
still need to have the 2nd NIC for the VMs. I think you can create 2
vswitches in ESXi on the same NIC and use different subnets on each one. You
could use 192.168.1.0/24 for one subnet and 10.10.10.0/24 [1] for the other
subnet. You'd need to use the 192.168.1.0 subnet for the VCL "private"
network so that it would be on the same subnet as the management node.
Give that a shot and see if it works better.
Josh
[1] You don't have to use 10.10.10.0, but you might have to use something
other than 192.168.x.x. I'm not familiar with how vcld determines which
network is the private one and which is the public one. Having another
192.168.x.x network might confuse it.
>
>
>
> LOGS:
>
>
>
>
>
> 2013-07-05 02:06:02|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:02
>
> 2013-07-05 02:06:07|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:07
>
> 2013-07-05 02:06:12|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:12
>
> 2013-07-05 02:06:16|6017|vcld:setup_management_node(818)|created
> DataStructure object to be used for vcld setup
>
> 2013-07-05 02:06:16|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_xcat_21' module
>
> 2013-07-05 02:06:16|6017|xCAT.pm:initialize(110)|XCATROOT environment
> variable is not set, using /opt/xcat
>
> |6017| ---- WARNING ----
> |
> |6017| 2013-07-05 02:06:16|6017|xCAT.pm:initialize(119)|unable to
>
> initialize xCAT module, /opt/xcat directory does not exist
>
> |6017| ( 0) xCAT.pm, initialize (line: 119)
> |
> |6017| (-1) xCAT.pm, (eval) (line: 3205)
> |
> |6017| (-2) (eval 87), (eval) (line: 3)
> |
> |6017| (-3) base.pm, import (line: 91)
> |
> |6017| (-4) xCAT21.pm, BEGIN (line: 47)
> |
> |6017| (-5) xCAT.pm, (eval) (line: 0)
>
> 2013-07-05 02:06:16|6017|xCAT21.pm:initialize(110)|XCATROOT environment
> variable is not set, using /opt/xcat
>
> |6017| ---- WARNING ----
> |
> |6017| 2013-07-05 02:06:16|6017|xCAT21.pm:initialize(119)|unable to
>
> initialize xCAT module, /opt/xcat directory does not exist
>
> |6017| ( 0) xCAT21.pm, initialize (line: 119)
> |
> |6017| (-1) xCAT21.pm, (eval) (line: 2167)
> |
> |6017| (-2) (eval 86), BEGIN (line: 2)
> |
> |6017| (-3) xCAT21.pm, (eval) (line: 0)
> |
> |6017| (-4) xCAT21.pm, (eval) (line: 0)
> |
> |6017| (-5) vcld, setup_management_node (line: 833)
>
> 2013-07-05 02:06:16|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_vmware' module
>
> 2013-07-05 02:06:16|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_winvista' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_osx' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_win7' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'state_image' module
>
> 2013-07-05 02:06:17|6017|DataStructure.pm:_initialize(643)|image ID
> argument was specified: noimage, DataStructure object will contain image
> information for the production imagerevision of this image
>
> 2013-07-05 02:06:17|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:17
>
> 2013-07-05 02:06:17|6017|utils.pm:get_default_imagemeta_info(4625)|retrieved
> default imagemeta info:
> |6017| : {
> |
> |6017| : "architecture" => "",
> |
> |6017| : "checkuser" => 1,
> |
> |6017| : "id" => "",
> |
> |6017| : "postoption" => "",
> |
> |6017| : "rootaccess" => 1,
> |
> |6017| : "subimages" => 0,
> |
> |6017| : "sysprep" => 1,
> |
> |6017| : "usergroupid" => ""
> |
> |6017| : }
>
> 2013-07-05 02:06:17|6017|DataStructure.pm:_initialize(674)|retrieved data
> for imagerevision ID: 1
>
> 2013-07-05 02:06:17|6017|DataStructure.pm:_initialize(690)|retrieved data
> for image ID: 1
>
> 2013-07-05
> 02:06:17|6017|Module.pm:create_mn_os_object(376)|VCL::Module::OS::Linux::Man
> agementNode module loaded
>
> 2013-07-05
> 02:06:17|6017|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode
> object created for image noimage, address: 42ef870
>
> 2013-07-05
> 02:06:17|6017|Module.pm:create_mn_os_object(381)|VCL::Module::OS::Linux::Man
> agementNode OS object created, address: 42ef870
>
> 2013-07-05 02:06:17|6017|Module.pm:new(192)|VCL::image object created for
> state available, address: 2deef28
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_xcat_13' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'base_module' module
>
> 2013-07-05 02:06:17|6017|Module.pm:new(203)|VCL::Module object created,
> address: 42efae0
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_win2008' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_win2003' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_libvirt' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_esxi' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_unix' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_lab' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'predictive_level_1' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_winxp' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_linux_ubuntu' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_unix_lab' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_xCAT_2x' module
>
> 2013-07-05 02:06:17|6017|xCAT2.pm:initialize(110)|XCATROOT environment
> variable is not set, using /opt/xcat
>
> |6017| ---- WARNING ----
> |
> |6017| 2013-07-05 02:06:17|6017|xCAT2.pm:initialize(119)|unable to
>
> initialize xCAT module, /opt/xcat directory does not exist
>
> |6017| ( 0) xCAT2.pm, initialize (line: 119)
> |
> |6017| (-1) xCAT2.pm, (eval) (line: 2179)
> |
> |6017| (-2) (eval 123), BEGIN (line: 2)
> |
> |6017| (-3) xCAT2.pm, (eval) (line: 0)
> |
> |6017| (-4) xCAT2.pm, (eval) (line: 0)
> |
> |6017| (-5) vcld, setup_management_node (line: 833)
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'predictive_level_0' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_windows' module
>
> 2013-07-05 02:06:17|6017|Module.pm:new(196)|VCL::Module::OS::Windows object
> created for image <not set>, address: 42efab0
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'provisioning_vbox' module
>
> 2013-07-05 02:06:17|6017|vcld:setup_management_node(830)|checking if
> setup() subroutine has been implemented by 'os_linux' module
>
> 2013-07-05 02:06:17|6017|utils.pm:setup_get_array_choice(9769)|choices
>
> argument:
> |6017| VCL Base Module
> |
> |6017| VCL Image State Module
> |
> |6017| Windows OS Module
>
> 2013-07-05 02:06:18|6017|utils.pm:setup_get_array_choice(9769)|choices
>
> argument:
> |6017| Capture Base Image
>
> 2013-07-05
> 02:06:19|6017|utils.pm:get_management_node_id(8364)|management_node_id
> environment variable not set
>
> 2013-07-05
> 02:06:19|6017|utils.pm:get_management_node_id(8370)|get_managementnode_info
> (): 1
>
> 2013-07-05 02:06:22|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:22
>
> 2013-07-05 02:06:27|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:27
>
> 2013-07-05 02:06:27|6017|utils.pm:get_computer_ids(7962)|found computers
> matching identifier: 192.168.1.80, IDs: 9
>
> 2013-07-05 02:06:27|6017|utils.pm:setup_get_array_choice(9769)|choices
>
> argument:
> |6017| Generic Linux (VMware)
> |
> |6017| OSX Snow Leopard (VMware)
> |
> |6017| Ubuntu (VMware)
> |
> |6017| Windows 2003 Server (VMware)
> |
> |6017| Windows 7 (VMware)
> |
> |6017| Windows Server 2008 (VMware)
> |
> |6017| Windows Vista (VMware)
> |
> |6017| Windows XP (VMware)
>
> 2013-07-05 02:06:29|6017|utils.pm:setup_get_array_choice(9769)|choices
>
> argument:
> |6017| x86
> |
> |6017| x86_64
>
> 2013-07-05 02:06:30|6017|utils.pm:setup_get_array_choice(9769)|choices
>
> argument:
> |6017| Yes
> |
> |6017| No
>
> 2013-07-05 02:06:32|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:32
>
> 2013-07-05 02:06:37|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:37
>
> 2013-07-05 02:06:41|6017|utils.pm:insert_request(8043)|inserted new reload
> request into request table, request id=21
>
> 2013-07-05 02:06:41|6017|utils.pm:insert_request(8074)|inserted new reload
> request into reservation table, reservation id=21
>
> 2013-07-05 02:06:42|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:42
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:reservation_being_processed(8634)|comput
> erloadlog 'begin' entry does NOT exist for reservation 21
>
> 2013-07-05 02:06:42|2152|21:21|image|utils.pm:run_command(8706)|executed
> command: pgrep -fl 'vcld [0-9]+:21 ', pid: 6066, exit status: 1, output:
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:is_management_node_process_running(8939)
> |process is NOT running, identifier: 'vcld [0-9]+:21 '
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:reservation_being_processed(8655)|reserv
> ation is NOT currently being processed
>
> 2013-07-05 02:06:42|2152|21:21|image|vcld:main(282)|reservation 21 is NOT
> already being processed
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:get_default_imagemeta_info(4625)|retriev
> ed
> default imagemeta info:
> |2152|21:21|image| : {
> |
> |2152|21:21|image| : "architecture" => "",
> |
> |2152|21:21|image| : "checkuser" => 1,
> |
> |2152|21:21|image| : "id" => "",
> |
> |2152|21:21|image| : "postoption" => "",
> |
> |2152|21:21|image| : "rootaccess" => 1,
> |
> |2152|21:21|image| : "subimages" => 0,
> |
> |2152|21:21|image| : "sysprep" => 1,
> |
> |2152|21:21|image| : "usergroupid" => ""
> |
> |2152|21:21|image| : }
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:get_imagemeta_info(4576)|retrieved
> imagemeta info:
> |2152|21:21|image| : {
> |
> |2152|21:21|image| : "architecture" => "",
> |
> |2152|21:21|image| : "checkuser" => 1,
> |
> |2152|21:21|image| : "id" => 26,
> |
> |2152|21:21|image| : "postoption" => "",
> |
> |2152|21:21|image| : "rootaccess" => 1,
> |
> |2152|21:21|image| : "subimages" => 0,
> |
> |2152|21:21|image| : "sysprep" => 0,
> |
> |2152|21:21|image| : "usergroupid" => ""
> |
> |2152|21:21|image| : }
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:get_management_node_info(5456)|retrievin
> g current management node info for 'localhost' from database, cached data is
> stale: 3938 seconds old
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:get_management_node_info(5603)|retrieved
> management node info: 'localhost' (localhost)
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:get_connect_method_info(10059)|attemptin
> g
> to retrieve connect method info:
> |2152|21:21|image| imagerevision: 22 - vmwarewin2003-w200327-v0
> |
> |2152|21:21|image| OS: 37 - vmwarewin2003
> |
> |2152|21:21|image| OS type: 1 - windows
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:get_connect_method_info(10123)|RDP:
> connectmethodid=2, OStypeid=1, OSid=NULL, imagerevisionid=NULL, disabled=0
>
> 2013-07-05 02:06:42|2152|21:21|image|vcld:main(287)|retrieved request
> information from database
>
> 2013-07-05
> 02:06:42|2152|21:21|image|DataStructure.pm:is_parent_reservation(1004)|retur
> ning true: parent reservation ID for this request: 21
>
> 2013-07-05
> 02:06:42|2152|21:21|image|utils.pm:update_request_state(1545)|request 21
> state updated to: pending, laststate to: image
>
> 2013-07-05 02:06:42|2152|21:21|image|utils.pm:insertloadlog(3665)|inserted
> computer=9, begin, beginning to process, state is image
>
> 2013-07-05 02:06:42|2152|21:21|image|vcld:make_new_child(518)|loaded
> VCL::image module
>
> 2013-07-05 02:06:42|2152|21:21|image|vcld:make_new_child(542)|current
> number of forked kids: 1
>
> 2013-07-05 02:06:42|6093|21:21|image|vcld:make_new_child(558)|vcld
> environment variable set to 0 for this process
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(643)|image ID
> argument was specified: noimage, DataStructure object will contain image
> information for the production imagerevision of this image
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(674)|retrieved data
> for imagerevision ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(690)|retrieved data
> for image ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_mn_os_object(376)|VCL::Module::OS
> ::Linux::ManagementNode module loaded
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:new(196)|VCL::Module::OS::Linux::Managem
> entNode object created for image noimage, address: 2bd55c8
>
> 2013-07-05 02:06:42|6093|21:21|image|DataStructure.pm:_automethod(847)|data
> structure updated, hash path:
> $self->request_data->{reservation}{0}{computer}{hostname}, data identifier:
>
> computer_hostname, data:
> |6093|21:21|image| : "localhost"
>
> 2013-07-05 02:06:42|6093|21:21|image|DataStructure.pm:_automethod(847)|data
> structure updated, hash path:
> $self->request_data->{reservation}{0}{computer}{NODENAME}, data identifier:
>
> computer_node_name, data:
> |6093|21:21|image| : "localhost"
>
> 2013-07-05 02:06:42|6093|21:21|image|DataStructure.pm:_automethod(847)|data
> structure updated, hash path:
> $self->request_data->{reservation}{0}{computer}{SHORTNAME}, data
>
> identifier: computer_short_name, data:
> |6093|21:21|image| : "localhost"
>
> 2013-07-05 02:06:42|6093|21:21|image|DataStructure.pm:_automethod(847)|data
> structure updated, hash path:
> $self->request_data->{reservation}{0}{computer}{IPaddress}, data
>
> identifier: computer_ip_address, data:
> |6093|21:21|image| : "192.168.1.70"
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_mn_os_object(381)|VCL::Module::OS
> ::Linux::ManagementNode OS object created, address: 2bd55c8
>
> 2013-07-05 02:06:42|6093|21:21|image|Module.pm:new(192)|VCL::image object
> created for state image, address: 24bffa0
>
> 2013-07-05 02:06:42|6093|21:21|image|State.pm:initialize(88)|obtained a
> database handle for this state process, stored as $ENV{dbh}
>
> 2013-07-05 02:06:42|6093|21:21|image|State.pm:check_image_os(790)|no
> corrections need to be made to image OS: vmwarewin2003
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:is_parent_reservation(1004)|retur
> ning true: parent reservation ID for this request: 21
>
> 2013-07-05
> 02:06:42|6093|21:21|image|utils.pm:rename_vcld_process(6752)|reservation
> count: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|utils.pm:rename_vcld_process(6771)|PARENTIMAGE: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|utils.pm:rename_vcld_process(6772)|SUBIMAGE: 0
>
> 2013-07-05
> 02:06:42|6093|21:21|image|utils.pm:rename_vcld_process(6790)|renamed
> process to 'vcld 21:21 image 192>192.168.1.2 vmwarewin2003-w200327-v0
> admin'
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:is_parent_reservation(1004)|retur
> ning true: parent reservation ID for this request: 21
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:is_parent_reservation(1004)|retur
> ning true: parent reservation ID for this request: 21
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_os_object(308)|VCL::Module::OS::W
> indows::Version_5::2003 module loaded
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:new(196)|VCL::Module::OS::Windows::Versi
> on_5::2003 object created for image vmwarewin2003-w200327-v0, address:
> 2bd8c58
>
> 2013-07-05 02:06:42|6093|21:21|image|Windows.pm:initialize(214)|beginning
> Windows module initialization
>
> 2013-07-05 02:06:42|6093|21:21|image|Windows.pm:initialize(224)|Windows
> module initialization complete
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_os_object(314)|VCL::Module::OS::W
> indows::Version_5::2003 OS object created, address: 2bd8c58
>
> 2013-07-05 02:06:42|6093|21:21|image|State.pm:initialize(125)|computer is a
> VM, attempting to create VM host OS object
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(619)|computer ID
> argument was specified, retrieving data for computer ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(643)|image ID
> argument was specified: 1, DataStructure object will contain image
> information for the production imagerevision of this image
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(674)|retrieved data
> for imagerevision ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(690)|retrieved data
> for image ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_vmhost_os_object(454)|attempting
> to load VM host OS module: VCL::Module::OS::Linux::UnixLab (image: 1)
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_vmhost_os_object(460)|VM host OS
> module loaded: VCL::Module::OS::Linux::UnixLab
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab
> object created for image noimage, address: 2bd8fd0
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_vmhost_os_object(465)|VCL::Module
> ::OS::Linux::UnixLab OS object created, address: 2bd8fd0
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:create_provisioning_object(519)|VCL::Mod
> ule::Provisioning::VMware::VMware module loaded
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:new(200)|VCL::Module::Provisioning::VMwa
> re::VMware object created for computer 192, address: 2bf1fe0
>
> 2013-07-05 02:06:42|6093|21:21|image|VMware.pm:initialize(267)|initializing
> VCL::Module::Provisioning::VMware::VMware object
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(619)|computer ID
> argument was specified, retrieving data for computer ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(643)|image ID
> argument was specified: 1, DataStructure object will contain image
> information for the production imagerevision of this image
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(674)|retrieved data
> for imagerevision ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(690)|retrieved data
> for image ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|VMware.pm:get_vmhost_datastructure(1397)|created
> DataStructure object for VM host: 192
>
> 2013-07-05 02:06:42|6093|21:21|image|VMware.pm:initialize(287)|VM profile
> assigned to 192: VMware vCenter
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(619)|computer ID
> argument was specified, retrieving data for computer ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(643)|image ID
> argument was specified: 1, DataStructure object will contain image
> information for the production imagerevision of this image
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(674)|retrieved data
> for imagerevision ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|DataStructure.pm:_initialize(690)|retrieved data
> for image ID: 1
>
> 2013-07-05
> 02:06:42|6093|21:21|image|VMware.pm:get_vmhost_datastructure(1397)|created
> DataStructure object for VM host: 192
>
> 2013-07-05
> 02:06:42|6093|21:21|image|VMware.pm:get_vmhost_api_object(1500)|attempting
> to load VMware control module:
> VCL::Module::Provisioning::VMware::vSphere_SDK
>
> 2013-07-05
> 02:06:42|6093|21:21|image|VMware.pm:get_vmhost_api_object(1506)|loaded
> VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
>
> 2013-07-05
> 02:06:42|6093|21:21|image|Module.pm:new(200)|VCL::Module::Provisioning::VMwa
> re::vSphere_SDK object created for computer 192, address: 3742208
>
> 2013-07-05 02:06:42|6093|21:21|image|vSphere_SDK.pm:initialize(100)|vSphere
> SDK for Perl does not appear to be installed on this managment node, unable
>
> to load VMware vSphere SDK Perl modules, error:
> |6093|21:21|image| Can't locate VMware/VIRuntime.pm in @INC (@INC contains:
> /usr/local/vcl/bin/../../../.. /usr/local/vcl/bin/../..
> /usr/local/vcl/bin/../../.. /usr/local/vcl/bin/../../../../..
> /usr/local/vcl/bin/.. /usr/local/vcl/bin/../lib /usr/local/lib64/perl5
> /usr/local/share/perl5 /usr/lib64/perl5/vendor_perl
> /usr/share/perl5/vendor_perl /usr/lib64/perl5 /usr/share/perl5 .) at (eval
> 219) line 1.
>
> |6093|21:21|image| BEGIN failed--compilation aborted at (eval 219) line 1.
>
> 2013-07-05
> 02:06:42|6093|21:21|image|VMware.pm:get_vmhost_api_object(1520)|API object
> could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:run_command(8694)|$? is set
> to -1, setting exit status to 0, Perl bug likely encountered
>
> 2013-07-05 02:06:42|6093|21:21|image|OS.pm:is_ssh_responding(653)|192 is
> NOT responding to SSH, ports 22 or 24 are both closed
>
> 2013-07-05 02:06:42|6093|21:21|image|VMware.pm:initialize(307)|unable to
> control OS of VM host 192 using VCL::Module::OS::Linux::UnixLab OS object
> because VM host is not responding to SSH
>
> |6093|21:21|image| ---- WARNING ----
> |
> |6093|21:21|image| 2013-07-05
>
> 02:06:42|6093|21:21|image|Module.pm:create_provisioning_object(530)|provisio
> ning object could not be created, returning 0
>
> |6093|21:21|image| ( 0) Module.pm, create_provisioning_object (line: 530)
> |
> |6093|21:21|image| (-1) State.pm, initialize (line: 136)
> |
> |6093|21:21|image| (-2) Module.pm, new (line: 207)
> |
> |6093|21:21|image| (-3) vcld, make_new_child (line: 567)
> |
> |6093|21:21|image| (-4) vcld, main (line: 350)
> |
> |
> |
> |
> |
> |6093|21:21|image| ---- WARNING ----
> |
> |6093|21:21|image| 2013-07-05
>
> 02:06:42|6093|21:21|image|State.pm:initialize(145)|failed to create
> provisioning object
>
> |6093|21:21|image| ( 0) State.pm, initialize (line: 145)
> |
> |6093|21:21|image| (-1) Module.pm, new (line: 207)
> |
> |6093|21:21|image| (-2) vcld, make_new_child (line: 567)
> |
> |6093|21:21|image| (-3) vcld, main (line: 350)
>
> 2013-07-05
> 02:06:42|6093|21:21|image|utils.pm:delete_computerloadlog_reservation(6396)
> |removing computerloadlog entries matching loadstate = begin
>
> 2013-07-05
> 02:06:42|6093|21:21|image|utils.pm:delete_computerloadlog_reservation(6443)
> |deleted rows from computerloadlog for reservation id=21
>
> 2013-07-05 02:06:42|6093|21:21|image|State.pm:DESTROY(929)|VCL::image
> process duration: 0 seconds
>
> |6093|21:21|image| ---- CRITICAL ----
> |
> |6093|21:21|image| 2013-07-05
>
> 02:06:42|6093|21:21|image|vcld:make_new_child(574)|VCL::image object could
> not be created and initialized
>
> |6093|21:21|image| ( 0) vcld, make_new_child (line: 574)
> |
> |6093|21:21|image| (-1) vcld, main (line: 350)
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:switch_state(6906)|called
> from VCL::vcld::make_new_child(575)
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:switch_state(6942)|parent:
> parent reservation ID for this request: 21
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:switch_state(6953)|previous
> request state is image, not setting request state to failed, setting
> request state to maintenance
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:getnewdbh(2474)|unable to use
> database handle stored in $ENV{dbh}
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:getnewdbh(2531)|database
> handle stored in $ENV{dbh}
>
> 2013-07-05 02:06:42|6093|21:21|image|utils.pm:insertloadlog(3665)|inserted
> computer=9, info, VCL::vcld: switching request state to maintenance
>
> 2013-07-05
> 02:06:43|6093|21:21|image|utils.pm:update_request_state(1545)|request 21
> state updated to: maintenance, laststate to: image
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:switch_state(6977)|request
> state changed: image->maintenance, laststate: image->image
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:insertloadlog(3665)|inserted
> computer=9, info, VCL::vcld: request state changed to maintenance,
> laststate to image
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:insertloadlog(3665)|inserted
> computer=9, info, VCL::vcld: switching computer state to maintenance
>
> 2013-07-05
> 02:06:43|6093|21:21|image|utils.pm:update_computer_state(1587)|computer 9
> state updated to: maintenance
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:switch_state(6995)|computer
> 192 state changed: available->maintenance
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:switch_state(7005)|log table
> id=0, ending set to failed
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:insertloadlog(3665)|inserted
> computer=9, info, VCL::vcld: process exiting
>
> 2013-07-05 02:06:43|6093|21:21|image|utils.pm:switch_state(7021)|process
> exiting
>
> 2013-07-05 02:06:43|2152|vcld:REAPER(721)|VCL process exited for
> reservation 21, PID: 6093, signal: CHLD
>
> 2013-07-05 02:06:43|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:43
>
> 2013-07-05 02:06:48|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:48
>
> 2013-07-05 02:06:53|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:53
>
> 2013-07-05 02:06:58|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:06:58
>
> 2013-07-05 02:07:03|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:03
>
> 2013-07-05 02:07:08|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:08
>
> 2013-07-05 02:07:13|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:13
>
> 2013-07-05 02:07:18|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:18
>
> 2013-07-05 02:07:23|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:23
>
> 2013-07-05 02:07:28|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:28
>
> 2013-07-05 02:07:33|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:33
>
> 2013-07-05 02:07:38|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:38
>
> 2013-07-05 02:07:43|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:43
>
> 2013-07-05 02:07:48|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:48
>
> 2013-07-05 02:07:53|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:53
>
> 2013-07-05 02:07:58|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:07:58
>
> 2013-07-05 02:08:03|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:03
>
> 2013-07-05 02:08:08|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:08
>
> 2013-07-05 02:08:13|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:13
>
> 2013-07-05 02:08:18|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:18
>
> 2013-07-05 02:08:23|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:23
>
> 2013-07-05 02:08:28|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:28
>
> 2013-07-05 02:08:33|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:33
>
> 2013-07-05 02:08:38|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:38
>
> 2013-07-05 02:08:43|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:43
>
> 2013-07-05 02:08:48|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:48
>
> 2013-07-05 02:08:53|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:53
>
> 2013-07-05 02:08:58|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:08:58
>
> 2013-07-05 02:09:03|2152|vcld:main(167)|lastcheckin time updated for
> management node 1: 2013-07-05 02:09:03
>
> On Fri, Jul 5, 2013 at 4:45 AM, Josh Thompson <[email protected]>wrote:
> > On Thursday, July 04, 2013 10:38:52 PM Toan Trac wrote:
> > > Hello,
> > >
> > > I have been troubleshooting for some time now. Esxi is 4.0 (enabled
> > > ssh,
> > > and tested). I notice the log shows the fail on ssh. Below is the
> > > complete log.
> >
> > <snip>
> >
> >
> > 22:29:19|3748|7:7|image|Module.pm:create_provisioning_object(519)|VCL::Mod
> > ul>
> > > e::Provisioning::libvirt module loaded
> > > 2013-07-04
> >
> > 22:29:19|3748|7:7|image|Module.pm:new(200)|VCL::Module::Provisioning::libv
> > ir>
> > > t object created for computer win2003, address: 3bff460
> > > 2013-07-04 22:29:19|3748|7:7|image|libvirt.pm:initialize(99)|libvirt
> >
> > driver
> >
> > > module directory path:
> > > /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt
> > > 2013-07-04 22:29:19|3748|7:7|image|OS.pm:find_files(2917)|attempting to
> > > find files on localhost, base directory path:
> > > '/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/', pattern:
> > > *.pm, command: /usr/bin/find
> > > "/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/" -type f
> >
> > -iname
> >
> > > "*.pm"
> > > 2013-07-04 22:29:19|3748|7:7|image|OS.pm:find_files(2945)|files found:
> > > 1,
> > > base directory:
> > > '/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/', pattern:
> > > '*.pm'
> > >
> > > |3748|7:7|image| command: '/usr/bin/find
> > >
> > > "/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/" -type f
> >
> > -iname
> >
> > > "*.pm"'
> > > 2013-07-04
> >
> > 22:29:19|3748|7:7|image|Module.pm:new(200)|VCL::Module::Provisioning::libv
> > ir>
> > > t::KVM object created for computer win2003, address: 43d3ed8
> >
> > > 2013-07-04 22:29:19|3748|7:7|image|utils.pm:
> > run_ssh_command(4902)|executing
> >
> > > SSH command on 192:
> > > |3748|7:7|image| /usr/bin/ssh -o StrictHostKeyChecking=no -o
> > >
> > > UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3
> >
> > -l
> >
> > > root -p 22 -x 192 'which virsh' 2>&1
> > >
> > > |3748|7:7|image| ---- WARNING ----
> > > |3748|7:7|image| 2013-07-04
> > >
> > > 22:29:19|3748|7:7|image|utils.pm:run_ssh_command(5010)|attempt
> >
> > > 1/3: failed to execute SSH command on 192: 'which virsh', exit status:
> > 255,
> >
> > > SSH exits with the exit status of the remote command or with 255 if an
> > >
> > > error occurred, output:
> > > |3748|7:7|image| ssh output (which virs...): ssh: connect to host 192
> >
> > port
> >
> > > 22: Invalid argument
> >
> > <snip>
> >
> > Toan,
> >
> > This part of the log shows that you have the provisioning engine for the
> > computer set to libvirt. You need to switch it to VMware if you are using
> > ESXi.
> >
> > As a side note, in the U.S., yesterday was a big holiday. So, many people
> > are
> > out some or all of this week. More people will be available to help next
> > week.
> >
> > Josh
> > --
> > -------------------------------
> > Josh Thompson
> > Systems Programmer
> > Advanced Computing | VCL Developer
> > North Carolina State University
> >
> > [email protected]
> > 919-515-5323
> >
> > my GPG/PGP key can be found at pgp.mit.edu
> >
> > All electronic mail messages in connection with State business which
> > are sent to or received by this account are subject to the NC Public
> > Records Law and may be disclosed to third parties.
- --
- -------------------------------
Josh Thompson
VCL Developer
North Carolina State University
my GPG/PGP key can be found at pgp.mit.edu
All electronic mail messages in connection with State business which
are sent to or received by this account are subject to the NC Public
Records Law and may be disclosed to third parties.
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2.0.19 (GNU/Linux)
iEYEARECAAYFAlHW9KwACgkQV/LQcNdtPQPRrgCeOb9egWJtio3GwEeA/tlzde7N
fl0AnAjkX/3f4g0oyzMQQRliglcLeaeA
=yXxR
-----END PGP SIGNATURE-----
