You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@vcl.apache.org by "Litchfield, Elwin L." <Le...@sar.usf.edu> on 2015/05/02 22:50:49 UTC
Reservationfailure message
root@localhost.localdomain
2:59 PM (21 minutes ago)
to me
reservation failed on VM7Cent6VCL1: process failed because computer is not available
------------------------------------------------------------------------
time: 2015-05-02 14:59:37
caller: State.pm:reservation_failed(240)
( 0) State.pm, reservation_failed (line: 240)
(-1) new.pm, process (line: 246)
(-2) vcld, make_new_child (line: 571)
(-3) vcld, main (line: 350)
------------------------------------------------------------------------
management node: localhost
reservation PID: 14702
parent vcld PID: 2327
request ID: 29
reservation ID: 29
request state/laststate: new/new
request start time: 2015-05-02 15:00:00
request end time: 2015-05-02 15:30:00
for imaging: no
log ID: 25
computer: VM7Cent6VCL1
computer id: 6
computer type: virtualmachine
computer eth0 MAC address: 00:0C:29:D3:40:E3
computer eth1 MAC address: 00:0C:29:D3:40:ED
computer private IP address: 192.168.10.48
computer public IP address: 131.247.29.166
computer in block allocation: no
provisioning module: VCL::Module::Provisioning::VMware::VMware
vm host: VM7
vm host ID: 2
vm host computer ID: 5
vm profile: VMware ESXi - local storage
vm profile VM path: datastore1
vm profile repository path: <undefined>
vm profile datastore path: datastore1
vm profile disk type: dedicated
image: vmwarelinux-vm7c2testvcl212-v0
image display name: vm7c2testvcl2
image ID: 12
image revision ID: 12
image size: 5762 MB
use Sysprep: yes
root access: yes
image owner ID: 1
image owner affiliation: Local
image revision date created: 2015-05-01 09:43:57
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:
|14702|29:29|new| : "uncommitted" => 0,
|14702|29:29|new| : "url" => "/vmfs/volumes/bb84ee19-47a18f64"
|14702|29:29|new| : },
|14702|29:29|new| : "datastore1" => {
|14702|29:29|new| : "accessible" => "true",
|14702|29:29|new| : "capacity" => "491505319936",
|14702|29:29|new| : "datastore" => "vim.Datastore:54b943b2-7ee6229d-f173-0019b9ddadff",
|14702|29:29|new| : "dynamicType" => "<unset>",
|14702|29:29|new| : "freeSpace" => "420603756544",
|14702|29:29|new| : "maintenanceMode" => "<unset>",
|14702|29:29|new| : "multipleHostAccess" => "<unset>",
|14702|29:29|new| : "normal_path" => "/vmfs/volumes/datastore1",
|14702|29:29|new| : "type" => "VMFS",
|14702|29:29|new| : "uncommitted" => "5195810688",
|14702|29:29|new| : "url" => "/vmfs/volumes/54b943b2-7ee6229d-f173-0019b9ddadff"
|14702|29:29|new| : }
|14702|29:29|new| : }
2015-05-02 14:59:36|14702|29:29|new|VMware.pm:get_vmx_base_directory_path(3094)|determined vmx base directory path: /vmfs/volumes/datastore1
2015-05-02 14:59:36|14702|29:29|new|utils.pm:run_ssh_command(4902)|executing SSH command on VM7:
|14702|29:29|new| /usr/bin/ssh -i /etc/vcl/vcl.key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x VM7 'stat /vmfs/volumes/datastore1' 2>&1
2015-05-02 14:59:37|14702|29:29|new|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|14702|29:29|new| File: '/vmfs/volumes/datastore1' -> '54b943b2-7ee6229d-f173-0019b9ddadff'
|14702|29:29|new| Size: 35 Blocks: 0 IO Block: 131072 symbolic link
|14702|29:29|new| Device: 0h/0d Inode: 2147483647 Links: 1
|14702|29:29|new| Access: (0755/lrwxr-xr-x) Uid: ( 0/ root) Gid: ( 0/ root)
|14702|29:29|new| Access: 2015-05-02 18:50:49.000000000
|14702|29:29|new| Modify: 2015-05-02 18:50:49.000000000
|14702|29:29|new| Change: 2015-05-02 18:50:49.000000000
2015-05-02 14:59:37|14702|29:29|new|utils.pm:run_ssh_command(5034)|SSH command executed on VM7, returning (0, "File: '/vmfs/volumes/datastore...")
2015-05-02 14:59:37|14702|29:29|new|Linux.pm:file_exists(1449)|'/vmfs/volumes/datastore1' exists on VM7, files: 0, directories: 0, links: 1
2015-05-02 14:59:37|14702|29:29|new|VMware.pm:is_vm_dedicated(4257)|VM disk mode does not need to be dedicated
2015-05-02 14:59:37|14702|29:29|new|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
2015-05-02 14:59:37|14702|29:29|new|VMware.pm:initialize(351)|not checking if vmdk base directory exists because it is the same as the vmx base directory: /vmfs/volumes/datastore1
2015-05-02 14:59:37|14702|29:29|new|Module.pm:create_provisioning_object(525)|VCL::Module::Provisioning::VMware::VMware provisioner object created for VM7Cent6VCL1, address: 395d528
2015-05-02 14:59:37|14702|29:29|new|State.pm:initialize(154)|returning 1
2015-05-02 14:59:37|14702|29:29|new|vcld:make_new_child(568)|VCL::new object created and initialized
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 29
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_state_name(2436)|attempting to retrieve current state of computer VM7Cent6VCL1 from the database
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_state_name(2467)|retrieved current state of computer VM7Cent6VCL1 from the database: available
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{29}{computer}{state}{name}, data identifier: computer_state_name, data:
|14702|29:29|new| : "available"
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_state_name(2436)|attempting to retrieve current state of computer VM7Cent6VCL1 from the database
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_state_name(2467)|retrieved current state of computer VM7Cent6VCL1 from the database: available
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{29}{computer}{state}{name}, data identifier: computer_state_name, data:
|14702|29:29|new| : "available"
2015-05-02 14:59:37|14702|29:29|new|new.pm:computer_not_being_used(742)|retrieving info for reservations assigned to VM7Cent6VCL1
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_imagemeta_info(4576)|retrieved imagemeta info:
|14702|29:29|new| : {
|14702|29:29|new| : "architecture" => "",
|14702|29:29|new| : "checkuser" => 1,
|14702|29:29|new| : "id" => 7,
|14702|29:29|new| : "postoption" => "",
|14702|29:29|new| : "rootaccess" => 1,
|14702|29:29|new| : "subimages" => 0,
|14702|29:29|new| : "sysprep" => 1,
|14702|29:29|new| : "usergroupid" => ""
|14702|29:29|new| : }
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_connect_method_info(10059)|attempting to retrieve connect method info:
|14702|29:29|new| imagerevision: 8 - vmwarelinux-vm7test78-v0
|14702|29:29|new| OS: 36 - vmwarelinux
|14702|29:29|new| OS type: 2 - linux
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_connect_method_info(10123)|ssh: connectmethodid=1, OStypeid=2, OSid=NULL, imagerevisionid=NULL, disabled=0
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_request_by_computerid(5991)|retrieved info and DataStructure object for 7:7
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_connect_method_info(10059)|attempting to retrieve connect method info:
|14702|29:29|new| imagerevision: 12 - vmwarelinux-vm7c2testvcl212-v0
|14702|29:29|new| OS: 36 - vmwarelinux
|14702|29:29|new| OS type: 2 - linux
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_connect_method_info(10123)|ssh: connectmethodid=1, OStypeid=2, OSid=NULL, imagerevisionid=NULL, disabled=0
2015-05-02 14:59:37|14702|29:29|new|utils.pm:get_request_by_computerid(5991)|retrieved info and DataStructure object for 29:29
2015-05-02 14:59:37|14702|29:29|new|new.pm:computer_not_being_used(779)|checking reservation assigned to VM7Cent6VCL1:
|14702|29:29|new| request:reservation ID: 7:7
|14702|29:29|new| request state: maintenance/image
|14702|29:29|new| request start time: 2015-04-30 11:20:29
|14702|29:29|new| request end time: 2015-04-30 12:20:29
|14702|29:29|new| ---- WARNING ----
|14702|29:29|new| 2015-05-02 14:59:37|14702|29:29|new|new.pm:computer_not_being_used(783)|VM7Cent6VCL1 is NOT available, it is assigned to an existing imaging reservation:
|14702|29:29|new| request:reservation ID: 7:7
|14702|29:29|new| request state: maintenance/image
|14702|29:29|new| request start time: 2015-04-30 11:20:29
|14702|29:29|new| request end time: 2015-04-30 12:20:29
|14702|29:29|new| ( 0) new.pm, computer_not_being_used (line: 783)
|14702|29:29|new| (-1) new.pm, process (line: 127)
|14702|29:29|new| (-2) vcld, make_new_child (line: 571)
|14702|29:29|new| (-3) vcld, main (line: 350)
|14702|29:29|new| ---- WARNING ----
|14702|29:29|new| 2015-05-02 14:59:37|14702|29:29|new|new.pm:process(243)|VM7Cent6VCL1 is NOT available
|14702|29:29|new| ( 0) new.pm, process (line: 243)
|14702|29:29|new| (-1) vcld, make_new_child (line: 571)
|14702|29:29|new| (-2) vcld, main (line: 350)
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_state_name(2436)|attempting to retrieve current state of computer VM7Cent6VCL1 from the database
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_state_name(2467)|retrieved current state of computer VM7Cent6VCL1 from the database: available
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{29}{computer}{state}{name}, data identifier: computer_state_name, data:
|14702|29:29|new| : "available"
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_private_ip_address(1641)|attempting to retrieve private IP address for computer: VM7Cent6VCL1
2015-05-02 14:59:37|14702|29:29|new|OS.pm:get_file_contents(1897)|retrieved 12 lines from file on localhost: '/etc/hosts'
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_computer_private_ip_address(1697)|returning IP address from /etc/hosts file: 192.168.10.48
2015-05-02 14:59:37|14702|29:29|new|utils.pm:is_inblockrequest(5760)|zero rows were returned from database select
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:get_image_affiliation_name(2121)|image owner id: 1
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:retrieve_user_data(1412)|attempting to retrieve and store data for user: user.id = '1'
2015-05-02 14:59:37|14702|29:29|new|DataStructure.pm:retrieve_user_data(1475)|data has been retrieved for user: admin (id: 1)
Click here to Reply or Forward
Lewis Litchfield
Technology Services - Viking Center Lab
University of South Florida Sarasota-Manatee
941-359-4231