You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@vcl.apache.org by António Aragão <aa...@di.uminho.pt> on 2014/11/25 19:53:50 UTC

Fwd: PROBLEM -- vclnode1|235:235|new|vcld|vm7>servidor12.di.uminho.pt|vmwarewin7-WIN7_20140314_VMWARE_0000167-v0|a12596

Hi,

suddenly I'm unable to create any reservation. Any clues ?

Thanks.

---------- Forwarded message ----------
From: <ro...@vclnode1.apachevcl.di.uminho.pt>
Date: 2014-11-25 18:47 GMT+00:00
Subject: PROBLEM -- vclnode1|235:235|new|vcld|vm7>servidor12.di.uminho.pt
|vmwarewin7-WIN7_20140314_VMWARE_0000167-v0|a12596
To: aaragao@di.uminho.pt


VCL::new object could not be created and initialized
------------------------------------------------------------------------
time: 2014-11-25 18:47:47
caller: vcld:make_new_child(574)
( 0) vcld, make_new_child (line: 574)
(-1) vcld, main (line: 350)
------------------------------------------------------------------------
management node: vclnode1.apachevcl.di.uminho.pt
reservation PID: 1883
parent vcld PID: 1862

request ID: 235
reservation ID: 235
request state/laststate: new/new
request start time: 2014-11-25 18:30:00
request end time: 2014-11-25 22:45:00
for imaging: no
log ID: 188

computer: vm7.apachevcl.di.uminho.pt
computer id: 11
computer type: virtualmachine
computer eth0 MAC address: 00:50:56:00:00:0c
computer eth1 MAC address: 00:50:56:00:00:0d
computer private IP address: 10.100.0.107
computer public IP address: 192.168.93.107
computer in block allocation: no
provisioning module: VCL::Module::Provisioning::VMware::VMware

vm host: servidor12.di.uminho.pt
vm host ID: 1
vm host computer ID: 1
vm profile: vmhostprofile1
vm profile VM path: /vmfs/volumes/datastore1/
vm profile repository path: /vmfs/volumes/vcl_images/
vm profile datastore path: /vmfs/volumes/datastore1/
vm profile disk type: shared

image: vmwarewin7-WIN7_20140314_VMWARE_0000167-v0
image display name: WIN7_20140314_VMWARE_00001
image ID: 67
image revision ID: 64
image size: 35231 MB
use Sysprep: no
root access: yes
image owner ID: 1
image owner affiliation: Local
image revision date created: 2014-03-14 12:23:42
image revision production: yes
OS module: VCL::Module::OS::Windows::Version_6::7

user: a12596
user name:
user ID: 10
user affiliation: DIALUNOSLDAP
------------------------------------------------------------------------
RECENT LOG ENTRIES FOR THIS PROCESS:
2014-11-25
18:43:31|1883|235:235|new|DataStructure.pm:_initialize(674)|retrieved data
for imagerevision ID: 1
2014-11-25
18:43:31|1883|235:235|new|DataStructure.pm:_initialize(690)|retrieved data
for image ID: 1
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_mn_os_object(376)|VCL::Module::OS::Linux::ManagementNode
module loaded
2014-11-25
18:43:32|1883|235:235|new|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode
object created for image noimage, address: 9d22398
2014-11-25 18:43:32|1883|235:235|new|DataStructure.pm:_automethod(847)|data
structure updated, hash path:
$self->request_data->{reservation}{0}{computer}{hostname}, data identifier:
computer_hostname, data:
|1883|235:235|new| : "vclnode1.apachevcl.di.uminho.pt"
2014-11-25 18:43:32|1883|235:235|new|DataStructure.pm:_automethod(847)|data
structure updated, hash path:
$self->request_data->{reservation}{0}{computer}{NODENAME}, data identifier:
computer_node_name, data:
|1883|235:235|new| : "vclnode1"
2014-11-25 18:43:32|1883|235:235|new|DataStructure.pm:_automethod(847)|data
structure updated, hash path:
$self->request_data->{reservation}{0}{computer}{SHORTNAME}, data
identifier: computer_short_name, data:
|1883|235:235|new| : "vclnode1"
2014-11-25 18:43:32|1883|235:235|new|DataStructure.pm:_automethod(847)|data
structure updated, hash path:
$self->request_data->{reservation}{0}{computer}{IPaddress}, data
identifier: computer_ip_address, data:
|1883|235:235|new| : "192.168.93.2"
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_mn_os_object(381)|VCL::Module::OS::Linux::ManagementNode
OS object created, address: 9d22398
2014-11-25 18:43:32|1883|235:235|new|Module.pm:new(192)|VCL::new object
created for state new, address: 99dc108
2014-11-25 18:43:32|1883|235:235|new|State.pm:initialize(88)|obtained a
database handle for this state process, stored as $ENV{dbh}
2014-11-25 18:43:32|1883|235:235|new|State.pm:check_image_os(771)|no
corrections need to be made, not an imaging request, returning 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:is_parent_reservation(1004)|returning
true: parent reservation ID for this request: 235
2014-11-25 18:43:32|1883|235:235|new|utils.pm:rename_vcld_process(6752)|reservation
count: 1
2014-11-25 18:43:32|1883|235:235|new|utils.pm:rename_vcld_process(6771)|PARENTIMAGE:
1
2014-11-25 18:43:32|1883|235:235|new|utils.pm:rename_vcld_process(6772)|SUBIMAGE:
0
2014-11-25 18:43:32|1883|235:235|new|utils.pm:rename_vcld_process(6790)|renamed
process to 'vcld 235:235 new vm7>servidor12.di.uminho.pt
vmwarewin7-WIN7_20140314_VMWARE_0000167-v0 a12596'
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:is_parent_reservation(1004)|returning
true: parent reservation ID for this request: 235
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:is_parent_reservation(1004)|returning
true: parent reservation ID for this request: 235
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_os_object(308)|VCL::Module::OS::Windows::Version_6::7
module loaded
2014-11-25
18:43:32|1883|235:235|new|Module.pm:new(196)|VCL::Module::OS::Windows::Version_6::7
object created for image vmwarewin7-WIN7_20140314_VMWARE_0000167-v0,
address: 9d22ec8
2014-11-25 18:43:32|1883|235:235|new|Windows.pm:initialize(214)|beginning
Windows module initialization
2014-11-25 18:43:32|1883|235:235|new|Windows.pm:initialize(224)|Windows
module initialization complete
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_os_object(314)|VCL::Module::OS::Windows::Version_6::7
OS object created, address: 9d22ec8
2014-11-25 18:43:32|1883|235:235|new|State.pm:initialize(125)|computer is a
VM, attempting to create VM host OS object
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(619)|computer ID
argument was specified, retrieving data for computer ID: 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(643)|image ID
argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(674)|retrieved data
for imagerevision ID: 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(690)|retrieved data
for image ID: 1
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_vmhost_os_object(454)|attempting
to load VM host OS module: VCL::Module::OS::Linux::UnixLab (image: 1)
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_vmhost_os_object(460)|VM host OS
module loaded: VCL::Module::OS::Linux::UnixLab
2014-11-25
18:43:32|1883|235:235|new|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab
object created for image noimage, address: a0e60a8
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_vmhost_os_object(465)|VCL::Module::OS::Linux::UnixLab
OS object created, address: a0e60a8
2014-11-25
18:43:32|1883|235:235|new|Module.pm:create_provisioning_object(519)|VCL::Module::Provisioning::VMware::VMware
module loaded
2014-11-25
18:43:32|1883|235:235|new|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VMware
object created for computer vm7, address: a0e6388
2014-11-25 18:43:32|1883|235:235|new|VMware.pm:initialize(267)|initializing
VCL::Module::Provisioning::VMware::VMware object
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(619)|computer ID
argument was specified, retrieving data for computer ID: 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(643)|image ID
argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(674)|retrieved data
for imagerevision ID: 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(690)|retrieved data
for image ID: 1
2014-11-25
18:43:32|1883|235:235|new|VMware.pm:get_vmhost_datastructure(1397)|created
DataStructure object for VM host: servidor12
2014-11-25 18:43:32|1883|235:235|new|VMware.pm:initialize(287)|VM profile
assigned to servidor12: vmhostprofile1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(619)|computer ID
argument was specified, retrieving data for computer ID: 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(643)|image ID
argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(674)|retrieved data
for imagerevision ID: 1
2014-11-25
18:43:32|1883|235:235|new|DataStructure.pm:_initialize(690)|retrieved data
for image ID: 1
2014-11-25
18:43:32|1883|235:235|new|VMware.pm:get_vmhost_datastructure(1397)|created
DataStructure object for VM host: servidor12
2014-11-25
18:43:32|1883|235:235|new|VMware.pm:get_vmhost_api_object(1500)|attempting
to load VMware control module:
VCL::Module::Provisioning::VMware::vSphere_SDK
2014-11-25
18:43:32|1883|235:235|new|VMware.pm:get_vmhost_api_object(1506)|loaded
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2014-11-25
18:43:32|1883|235:235|new|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK
object created for computer vm7, address: a427b60
2014-11-25 18:43:32|1883|235:235|new|vSphere_SDK.pm:initialize(106)|loaded
VMware vSphere SDK modules
2014-11-25
18:43:32|1883|235:235|new|vSphere_SDK.pm:initialize(150)|attempting to
connect to VM host: https://servidor12.di.uminho.pt/sdk (root)
2014-11-25 18:44:35|1883|235:235|new|vSphere_SDK.pm:initialize(161)|unable
to connect to VM host using URL: https://servidor12.di.uminho.pt/sdk, error:
|1883|235:235|new| Server version unavailable at '
https://servidor12.di.uminho.pt:443/sdk/vimService.wsdl' at
/usr/share/perl5/VMware/VICommon.pm line 545.
2014-11-25
18:44:35|1883|235:235|new|vSphere_SDK.pm:initialize(150)|attempting to
connect to VM host: https://servidor12.di.uminho.pt:8333/sdk (root)
2014-11-25 18:45:38|1883|235:235|new|vSphere_SDK.pm:initialize(161)|unable
to connect to VM host using URL: https://servidor12.di.uminho.pt:8333/sdk,
error:
|1883|235:235|new| Server version unavailable at '
https://servidor12.di.uminho.pt:8333/sdk/vimService.wsdl' at
/usr/share/perl5/VMware/VICommon.pm line 545.
2014-11-25
18:45:39|1883|235:235|new|vSphere_SDK.pm:initialize(150)|attempting to
connect to VM host: https://servidor12/sdk (root)
2014-11-25 18:46:42|1883|235:235|new|vSphere_SDK.pm:initialize(161)|unable
to connect to VM host using URL: https://servidor12/sdk, error:
|1883|235:235|new| Server version unavailable at '
https://servidor12:443/sdk/vimService.wsdl' at
/usr/share/perl5/VMware/VICommon.pm line 545.
2014-11-25
18:46:42|1883|235:235|new|vSphere_SDK.pm:initialize(150)|attempting to
connect to VM host: https://servidor12:8333/sdk (root)
2014-11-25 18:47:45|1883|235:235|new|vSphere_SDK.pm:initialize(161)|unable
to connect to VM host using URL: https://servidor12:8333/sdk, error:
|1883|235:235|new| Server version unavailable at '
https://servidor12:8333/sdk/vimService.wsdl' at
/usr/share/perl5/VMware/VICommon.pm line 545.
2014-11-25 18:47:45|1883|235:235|new|vSphere_SDK.pm:initialize(178)|failed
to connect to VM host servidor12.di.uminho.pt, Util::connect returned
'undefined'
2014-11-25
18:47:45|1883|235:235|new|VMware.pm:get_vmhost_api_object(1520)|API object
could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
2014-11-25 18:47:46|1883|235:235|new|utils.pm:run_command(8694)|$? is set
to -1, setting exit status to 0, Perl bug likely encountered
2014-11-25 18:47:47|1883|235:235|new|utils.pm:run_command(8694)|$? is set
to -1, setting exit status to 0, Perl bug likely encountered
2014-11-25
18:47:47|1883|235:235|new|OS.pm:is_ssh_responding(653)|servidor12 is NOT
responding to SSH, ports 22 or 24 are both closed
2014-11-25 18:47:47|1883|235:235|new|VMware.pm:initialize(307)|unable to
control OS of VM host servidor12 using VCL::Module::OS::Linux::UnixLab OS
object because VM host is not responding to SSH
|1883|235:235|new| ---- WARNING ----
|1883|235:235|new| 2014-11-25
18:47:47|1883|235:235|new|Module.pm:create_provisioning_object(530)|provisioning
object could not be created, returning 0
|1883|235:235|new| ( 0) Module.pm, create_provisioning_object (line: 530)
|1883|235:235|new| (-1) State.pm, initialize (line: 136)
|1883|235:235|new| (-2) Module.pm, new (line: 207)
|1883|235:235|new| (-3) vcld, make_new_child (line: 567)
|1883|235:235|new| (-4) vcld, main (line: 350)
|1883|235:235|new| ---- WARNING ----
|1883|235:235|new| 2014-11-25
18:47:47|1883|235:235|new|State.pm:initialize(145)|failed to create
provisioning object
|1883|235:235|new| ( 0) State.pm, initialize (line: 145)
|1883|235:235|new| (-1) Module.pm, new (line: 207)
|1883|235:235|new| (-2) vcld, make_new_child (line: 567)
|1883|235:235|new| (-3) vcld, main (line: 350)
2014-11-25 18:47:47|1883|235:235|new|utils.pm:delete_computerloadlog_reservation(6396)|removing
computerloadlog entries matching loadstate = begin
2014-11-25 18:47:47|1883|235:235|new|utils.pm:delete_computerloadlog_reservation(6443)|deleted
rows from computerloadlog for reservation id=235
2014-11-25 18:47:47|1883|235:235|new|State.pm:DESTROY(929)|VCL::new process
duration: 255 seconds
2014-11-25 18:47:47|1883|235:235|new|utils.pm:get_management_node_info(5456)|retrieving
current management node info for 'vclnode1.apachevcl.di.uminho.pt' from
database, cached data is stale: 256 seconds old
2014-11-25 18:47:47|1883|235:235|new|utils.pm:getnewdbh(2474)|unable to use
database handle stored in $ENV{dbh}
2014-11-25 18:47:47|1883|235:235|new|utils.pm:getnewdbh(2531)|database
handle stored in $ENV{dbh}
2014-11-25 18:47:47|1883|235:235|new|utils.pm:get_management_node_info(5603)|retrieved
management node info: 'vclnode1.apachevcl.di.uminho.pt' (vclnode1)
2014-11-25
18:47:47|1883|235:235|new|DataStructure.pm:get_computer_private_ip_address(1641)|attempting
to retrieve private IP address for computer: vm7
2014-11-25
18:47:47|1883|235:235|new|OS.pm:get_file_contents(1897)|retrieved 61 lines
from file on vclnode1: '/etc/hosts'
2014-11-25
18:47:47|1883|235:235|new|DataStructure.pm:get_computer_private_ip_address(1697)|returning
IP address from /etc/hosts file: 10.100.0.107
2014-11-25 18:47:47|1883|235:235|new|utils.pm:is_inblockrequest(5760)|zero
rows were returned from database select
2014-11-25
18:47:47|1883|235:235|new|DataStructure.pm:get_image_affiliation_name(2121)|image
owner id: 1
2014-11-25
18:47:47|1883|235:235|new|DataStructure.pm:retrieve_user_data(1412)|attempting
to retrieve and store data for user: user.id = '1'
2014-11-25
18:47:47|1883|235:235|new|DataStructure.pm:retrieve_user_data(1475)|data
has been retrieved for user: admin (id: 1)



-- 
http://www.di.uminho.pt/~apa/email/cartao_virtual_email_aaragao.png