Hi everyone, Because of the problems I was having with repeatedly updating an image, I decided to build one image (Windows XP) with all of the necessary applications already installed BEFORE trying to capture it into the VCL.
It didn't take long before I received a "Problem" email. Can someone help me
troubleshoot this? We didn't have any troubles capturing Windows XP images
before.
Here's the error I get (more of the log below):
Can't locate object method "execute" via package
"VCL::Module::OS::Windows::Version_5::XP" at
/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/VMware/vmware_cmd.pm line 101.
Thanks,
Adam
-----------
RECENT LOG ENTRIES FOR THIS PROCESS:
|8993|223:223|image| (-5) Module.pm, new (line: 207)
2012-07-02
14:36:40|8993|223:223|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: vmwg0-120-62
2012-07-02
14:36:40|8993|223:223|image|VMware.pm:get_vmhost_os_object(1232)|attempting to
load VM host OS module: VCL::Module::OS::Windows::Version_5::XP
2012-07-02 14:36:40|8993|223:223|image|VMware.pm:get_vmhost_os_object(1238)|VM
host OS module loaded: VCL::Module::OS::Windows::Version_5::XP
2012-07-02
14:36:40|8993|223:223|image|Module.pm:create_mn_os_object(335)|management node
OS object has already been created, address: 202b9670, returning 1
2012-07-02
14:36:40|8993|223:223|image|Module.pm:new(196)|VCL::Module::OS::Windows::Version_5::XP
object created for image vmwarewinxp-WinXPFullBase75-v0, address: 20b05c00
2012-07-02 14:36:40|8993|223:223|image|VMware.pm:get_vmhost_os_object(1244)|VM
host OS object created: VCL::Module::OS::Windows::Version_5::XP
2012-07-02
14:37:00|8993|223:223|image|OS.pm:is_ssh_responding(401)|vmwg0-120-62 is
responding to SSH, port 22: open, port 24: closed
2012-07-02 14:37:00|8993|223:223|image|VMware.pm:initialize(254)|OS on VM host
vmwg0-120-62 will be controlled using a VCL::Module::OS::Windows::Version_5::XP
OS object
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_vmhost_computer_id:
$self->request_data->{reservation}{223}{computer}{vmhost}{computerid}
|8993|223:223|image| ( 0) DataStructure.pm, _automethod (line: 812)
|8993|223:223|image| (-1) Autoload.pm, __ANON__ (line: 80)
|8993|223:223|image| (-2) VMware.pm, get_vmhost_datastructure (line: 1161)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1282)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 265)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_vmhost_profile_image_id:
$self->request_data->{reservation}{223}{computer}{vmhost}{vmprofile}{imageid}
|8993|223:223|image| ( 0) DataStructure.pm, _automethod (line: 812)
|8993|223:223|image| (-1) Autoload.pm, __ANON__ (line: 80)
|8993|223:223|image| (-2) VMware.pm, get_vmhost_datastructure (line: 1162)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1282)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 265)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: vmwg0-120-62
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1293)|attempting to
load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1299)|loaded VMware
control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2012-07-02 14:37:00|8993|223:223|image|Module.pm:new(172)|set 'vmhost_data' key
for VCL::Module::Provisioning::VMware::vSphere_SDK object from arguments
2012-07-02 14:37:00|8993|223:223|image|Module.pm:new(172)|set 'vmhost_os' key
for VCL::Module::Provisioning::VMware::vSphere_SDK object from arguments
2012-07-02
14:37:00|8993|223:223|image|Module.pm:create_mn_os_object(335)|management node
OS object has already been created, address: 202b9670, returning 1
2012-07-02
14:37:00|8993|223:223|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK
object created for computer vmwg0-120-62, address: 20b32390
2012-07-02 14:37:00|8993|223:223|image|vSphere_SDK.pm:initialize(1826)|vSphere
SDK for Perl does not appear to be installed on this managment node, unable to
load VMware vSphere SDK Perl modules
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1313)|API object
could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_vmhost_computer_id:
$self->request_data->{reservation}{223}{computer}{vmhost}{computerid}
|8993|223:223|image| ( 0) DataStructure.pm, _automethod (line: 812)
|8993|223:223|image| (-1) Autoload.pm, __ANON__ (line: 80)
|8993|223:223|image| (-2) VMware.pm, get_vmhost_datastructure (line: 1161)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1282)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 278)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_vmhost_profile_image_id:
$self->request_data->{reservation}{223}{computer}{vmhost}{vmprofile}{imageid}
|8993|223:223|image| ( 0) DataStructure.pm, _automethod (line: 812)
|8993|223:223|image| (-1) Autoload.pm, __ANON__ (line: 80)
|8993|223:223|image| (-2) VMware.pm, get_vmhost_datastructure (line: 1162)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1282)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 278)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: vmwg0-120-62
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1293)|attempting to
load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1299)|loaded VMware
control module: VCL::Module::Provisioning::VMware::VIM_SSH
2012-07-02 14:37:00|8993|223:223|image|Module.pm:new(172)|set 'vmhost_data' key
for VCL::Module::Provisioning::VMware::VIM_SSH object from arguments
2012-07-02 14:37:00|8993|223:223|image|Module.pm:new(172)|set 'vmhost_os' key
for VCL::Module::Provisioning::VMware::VIM_SSH object from arguments
2012-07-02
14:37:00|8993|223:223|image|Module.pm:create_mn_os_object(335)|management node
OS object has already been created, address: 202b9670, returning 1
2012-07-02
14:37:00|8993|223:223|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VIM_SSH
object created for computer vmwg0-120-62, address: 20b2e690
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|VIM_SSH.pm:initialize(112)|required VM host OS
subroutine is not implemented: execute
|8993|223:223|image| ( 0) VIM_SSH.pm, initialize (line: 112)
|8993|223:223|image| (-1) Module.pm, new (line: 207)
|8993|223:223|image| (-2) VMware.pm, (eval) (line: 1303)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1303)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 278)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1313)|API object
could not be created: VCL::Module::Provisioning::VMware::VIM_SSH
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_vmhost_computer_id:
$self->request_data->{reservation}{223}{computer}{vmhost}{computerid}
|8993|223:223|image| ( 0) DataStructure.pm, _automethod (line: 812)
|8993|223:223|image| (-1) Autoload.pm, __ANON__ (line: 80)
|8993|223:223|image| (-2) VMware.pm, get_vmhost_datastructure (line: 1161)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1282)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 278)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
|8993|223:223|image| ---- WARNING ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:_automethod(812)|corresponding
data has not been initialized for get_vmhost_profile_image_id:
$self->request_data->{reservation}{223}{computer}{vmhost}{vmprofile}{imageid}
|8993|223:223|image| ( 0) DataStructure.pm, _automethod (line: 812)
|8993|223:223|image| (-1) Autoload.pm, __ANON__ (line: 80)
|8993|223:223|image| (-2) VMware.pm, get_vmhost_datastructure (line: 1162)
|8993|223:223|image| (-3) VMware.pm, get_vmhost_api_object (line: 1282)
|8993|223:223|image| (-4) VMware.pm, initialize (line: 278)
|8993|223:223|image| (-5) Module.pm, new (line: 207)
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_datastructure(1190)|created
DataStructure object for VM host: vmwg0-120-62
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1293)|attempting to
load VMware control module: VCL::Module::Provisioning::VMware::vmware_cmd
2012-07-02
14:37:00|8993|223:223|image|VMware.pm:get_vmhost_api_object(1299)|loaded VMware
control module: VCL::Module::Provisioning::VMware::vmware_cmd
2012-07-02 14:37:00|8993|223:223|image|Module.pm:new(172)|set 'vmhost_data' key
for VCL::Module::Provisioning::VMware::vmware_cmd object from arguments
2012-07-02 14:37:00|8993|223:223|image|Module.pm:new(172)|set 'vmhost_os' key
for VCL::Module::Provisioning::VMware::vmware_cmd object from arguments
2012-07-02
14:37:00|8993|223:223|image|Module.pm:create_mn_os_object(335)|management node
OS object has already been created, address: 202b9670, returning 1
2012-07-02
14:37:00|8993|223:223|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vmware_cmd
object created for computer vmwg0-120-62, address: 20c6ffb0
2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:get_computer_private_ip_address(1581)|attempting
to retrieve private IP address for computer: vmwg0-120-62
2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
contents of /etc/hosts on this management node, contains 65 lines
2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:get_computer_private_ip_address(1645)|returning
IP address from /etc/hosts file: 10.5.120.62
2012-07-02 14:37:00|8993|223:223|image|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:get_image_affiliation_name(2035)|image
owner id: 5
2012-07-02 14:37:00|8993|223:223|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:hcr08672.ewp.rpi.edu)
2012-07-02 14:37:00|8993|223:223|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:retrieve_user_data(1352)|attempting
to retrieve and store data for user: user.id = '5'
2012-07-02 14:37:00|8993|223:223|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:hcr08672.ewp.rpi.edu)
2012-07-02 14:37:00|8993|223:223|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2012-07-02
14:37:00|8993|223:223|image|DataStructure.pm:retrieve_user_data(1415)|data has
been retrieved for user: vcladmin (id: 5)
2012-07-02 14:37:01|8993|223:223|image|utils.pm:mail(1255)|SUCCESS -- Sending
mail To: vcl_adminu...@ewp.rpi.edu, PROBLEM --
223:223|image|vcld|vmwg0-120-62|vmwarewinxp-WinXPFullBase75-v0|vcladmin
|8993|223:223|image| ---- CRITICAL ----
|8993|223:223|image| 2012-07-02
14:37:00|8993|223:223|image|vcld:die_handler(636)|Can't locate object method
"execute" via package "VCL::Module::OS::Windows::Version_5::XP" at
/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/VMware/vmware_cmd.pm line 101.
|8993|223:223|image| ( 0) vcld, die_handler (line: 636)
|8993|223:223|image| (-1) vmware_cmd.pm, initialize (line: 101)
|8993|223:223|image| (-2) Module.pm, new (line: 207)
|8993|223:223|image| (-3) VMware.pm, (eval) (line: 1303)
|8993|223:223|image| (-4) VMware.pm, get_vmhost_api_object (line: 1303)
|8993|223:223|image| (-5) VMware.pm, initialize (line: 278)
|8993|223:223|image| (-6) Module.pm, new (line: 207)
|8993|223:223|image| (-7) Module.pm, create_provisioning_object (line: 423)
|8993|223:223|image| (-8) State.pm, initialize (line: 117)
|8993|223:223|image| (-9) Module.pm, new (line: 207)
|8993|223:223|image| (-10) vcld, make_new_child (line: 564)
|8993|223:223|image| (-11) vcld, main (line: 346)
2012-07-02 14:37:01|8993|223:223|image|State.pm:DESTROY(829)|VCL::image
destructor called, address: 200b50a0
2012-07-02
14:37:01|8993|223:223|image|utils.pm:delete_computerloadlog_reservation(6742)|removing
computerloadlog entries matching loadstate = begin
2012-07-02
14:37:01|8993|223:223|image|utils.pm:delete_computerloadlog_reservation(6789)|deleted
rows from computerloadlog for reservation id=223
2012-07-02 14:37:01|8993|223:223|image|State.pm:DESTROY(848)|number of database
handles state process created: 3
2012-07-02 14:37:01|8993|223:223|image|State.pm:DESTROY(876)|VCL::image process
duration: 21 seconds
2012-07-02 14:37:01|6501|vcld:REAPER(718)|VCL process exited for reservation
223, PID: 8993, signal: CHLD
- - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - -
Adam Hechler
Senior Analyst /PC Systems Administrator
Rensselaer Polytechnic Institute
275 Windsor Street
Hartford, CT 06120 USA
Ph: 860-548-2446
Email: hec...@rpi.edu<mailto:hec...@rpi.edu>
Web: http://www.ewp.rpi.edu<http://www.ewp.rpi.edu/>
[Description:
fb]<http://www.facebook.com/pages/Rensselaer-Hartford-Campus/216532895053858>
[Description: tw] <https://twitter.com/#!/RPI_Hartford> [Description: yt]
<http://www.youtube.com/user/RPIHartford> [Description: blog]
<http://rpihartford.blogspot.com/>
<<inline: image001.jpg>>
<<inline: image002.jpg>>
<<inline: image003.jpg>>
<<inline: image004.png>>
