Hi, suddenly I'm unable to create any reservation. Any clues ?
Thanks. ---------- Forwarded message ---------- From: <[email protected]> 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: [email protected] 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
