vcl-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Dmitri Chebotarov <dcheb...@gmu.edu>
Subject Re: windows post load tasks
Date Wed, 29 Aug 2012 17:08:31 GMT
Hi William 

Could you please check that network names in VM host profile matches network names on the hosts?
Also, when you edit VM on the host, do you see that both network adapters are connected (check box on the right)?

Thank you. 

--
Dmitri Chebotarov
Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
223 Aquia Building, Ffx, MSN: 1B5
Phone: (703) 993-6175
Fax: (703) 993-3404


On Wednesday, August 29, 2012 at 10:22 , William Robinson wrote:

> the devices are set for dhcp. 'ipconfig /all' shows correct mac address 
> configuration. snippet from the dhcpd.conf for vm in question (works perfectly, 
> as far as i can tell, before capture):
> 
> host vclvm0001-man0 {
> hardware ethernet 00:50:56:00:00:01;
> fixed-address 10.128.64.100;
> }
> host vclvm0001 {
> hardware ethernet 00:50:56:00:00:02;
> fixed-address 10.128.64.200;
> }
> 
> Generate eth0/eth1 MAC are set to 'No'
> 
> 'ipconfig /renew' does not work because the vm thinks that there is no 
> connection established to the adapters. dhcp logs see no communication from vm 
> after capture.
> 
> thanks!
> 
> will
> 
> On 08/29/2012 09:55 AM, Aaron Peeler wrote:
> > Hello Will,
> > 
> > Can you confirm you can get a dhcp assigned address at all by logging
> > into the node and running ipconfig /renew?
> > 
> > Are the adapters set for dhcp?
> > 
> > What are the settings for your vmware profile under VCL? Are Generate
> > eth0 MAC and Generate eth1 MAC set to "no" or "yes"?
> > 
> > What is your dhcp server setup like? Is it using fixed IP addresses to
> > predefined MACs?
> > 
> > Other than that, maybe firewall related?
> > 
> > Aaron
> > 
> > 
> > On Wed, Aug 29, 2012 at 8:53 AM, William Robinson
> > <wr@exchange.clemson.edu (mailto:wr@exchange.clemson.edu)> wrote:
> > > hi all,
> > > 
> > > i could use some input as to why our vms do not receive ip addresses after
> > > creating an image. behavior i'm seeing is not exactly the same as that
> > > mentioned in other threads (using the updated scripts). log entries are
> > > below. thanks.
> > > 
> > > 
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:reservation_being_processed(8672)|computerloadlog
> > > 'begin' entry does NOT exist for reservation 5
> > > 2012-08-23 14:22:29|20492|5:5|reload|utils.pm:run_command(8744)|executed
> > > command: pgrep -fl 'vcld [0-9]+:5 ', pid: 13282, exit status: 1, output:
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:is_management_node_process_running(8977)|process
> > > is NOT running, identifier: 'vcld [0-9]+:5 '
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:reservation_being_processed(8693)|reservation
> > > is NOT currently being processed
> > > 2012-08-23 14:22:29|20492|5:5|reload|vcld:main(282)|reservation 5 is NOT
> > > already being processed
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:get_management_node_info(5494)|retrieving
> > > current management node info for 'localhost' from database, cached data is
> > > stale: 2179 seconds old
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:get_management_node_info(5641)|retrieved
> > > management node info: 'localhost' (localhost)
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:get_connect_method_info(10097)|attempting
> > > to retrieve connect method info:
> > > |20492|5:5|reload| imagerevision: 3 - vmwarewin7-win7_x86_base23-v0
> > > |20492|5:5|reload| OS: 35 - vmwarewin7
> > > |20492|5:5|reload| OS type: 1 - windows
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:get_connect_method_info(10161)|RDP:
> > > connectmethodid=2, OStypeid=1, OSid=NULL, imagerevisionid=NULL, disabled=0
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:get_connect_method_info(10190)|retrieved
> > > connect method info:
> > > |20492|5:5|reload| : {
> > > |20492|5:5|reload| : 2 => {
> > > |20492|5:5|reload| : "connectmethodmap" => {
> > > |20492|5:5|reload| : "OSid" => undef,
> > > |20492|5:5|reload| : "OStypeid" => 1,
> > > |20492|5:5|reload| : "autoprovisioned" => undef,
> > > |20492|5:5|reload| : "connectmethodid" => 2,
> > > |20492|5:5|reload| : "disabled" => 0,
> > > |20492|5:5|reload| : "imagerevisionid" => undef
> > > |20492|5:5|reload| : },
> > > |20492|5:5|reload| : "description" => "Remote Desktop",
> > > |20492|5:5|reload| : "id" => 2,
> > > |20492|5:5|reload| : "name" => "RDP",
> > > |20492|5:5|reload| : "port" => 3389,
> > > |20492|5:5|reload| : "protocol" => "",
> > > |20492|5:5|reload| : "servicename" => "TermService",
> > > |20492|5:5|reload| : "startupscript" => undef
> > > |20492|5:5|reload| : }
> > > |20492|5:5|reload| : }
> > > 2012-08-23 14:22:29|20492|5:5|reload|utils.pm:get_user_info(7578)|UID value
> > > is not configured for user vclreload, setting UID to VCL user ID: vclreload,
> > > standalone: 1
> > > 2012-08-23 14:22:29|20492|5:5|reload|utils.pm:get_user_info(7578)|UID value
> > > is not configured for user vclreload, setting UID to VCL user ID: vclreload,
> > > standalone: 1
> > > 2012-08-23 14:22:29|20492|5:5|reload|vcld:main(287)|retrieved request
> > > information from database
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|DataStructure.pm:is_parent_reservation(993)|returning
> > > true: parent reservation ID for this request: 5
> > > 2012-08-23
> > > 14:22:29|20492|5:5|reload|utils.pm:update_request_state(1545)|request 5
> > > state updated to: pending, laststate to: reload
> > > 2012-08-23 14:22:29|20492|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, begin, beginning to process, state is reload
> > > 2012-08-23 14:22:29|20492|5:5|reload|vcld:make_new_child(510)|request will
> > > be processed by new.pm
> > > 2012-08-23 14:22:29|20492|5:5|reload|vcld:make_new_child(518)|loaded
> > > VCL::new module
> > > 2012-08-23 14:22:29|20492|5:5|reload|vcld:make_new_child(542)|current number
> > > of forked kids: 1
> > > 2012-08-23 14:22:29|13299|5:5|reload|vcld:make_new_child(558)|vcld
> > > environment variable set to 0 for this process
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(637)|image
> > > ID argument was specified: noimage, DataStructure object will contain image
> > > information for the production imagerevision of this image
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(668)|retrieved data
> > > for imagerevision ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(684)|retrieved data
> > > for image ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_mn_os_object(376)|VCL::Module::OS::Linux::ManagementNode
> > > module loaded
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode
> > > object created for image noimage, address: 4626478
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{0}{computer}{hostname}, data identifier:
> > > computer_hostname, data:
> > > |13299|5:5|reload| : "localhost"
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{0}{computer}{NODENAME}, data identifier:
> > > computer_node_name, data:
> > > |13299|5:5|reload| : "localhost"
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{0}{computer}{SHORTNAME}, data identifier:
> > > computer_short_name, data:
> > > |13299|5:5|reload| : "localhost"
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{0}{computer}{IPaddress}, data identifier:
> > > computer_ip_address, data:
> > > |13299|5:5|reload| : "127.0.0.1"
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_mn_os_object(381)|VCL::Module::OS::Linux::ManagementNode
> > > OS object created, address: 4626478
> > > 2012-08-23 14:22:29|13299|5:5|reload|Module.pm:new(192)|VCL::new object
> > > created for state reload, address: 3ebc910
> > > 2012-08-23 14:22:29|13299|5:5|reload|State.pm:initialize(88)|obtained a
> > > database handle for this state process, stored as $ENV{dbh}
> > > 2012-08-23 14:22:29|13299|5:5|reload|State.pm:check_image_os(771)|no
> > > corrections need to be made, not an imaging request, returning 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:is_parent_reservation(993)|returning
> > > true: parent reservation ID for this request: 5
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|utils.pm:rename_vcld_process(6790)|reservation
> > > count: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|utils.pm:rename_vcld_process(6809)|PARENTIMAGE: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|utils.pm:rename_vcld_process(6810)|SUBIMAGE: 0
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|utils.pm:rename_vcld_process(6828)|renamed process
> > > to 'vcld 5:5 reload vclvm0001-man0>vclhv01 vmwarewin7-win7_x86_base23-v0
> > > vclreload'
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:is_parent_reservation(993)|returning
> > > true: parent reservation ID for this request: 5
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:is_parent_reservation(993)|returning
> > > true: parent reservation ID for this request: 5
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_os_object(308)|VCL::Module::OS::Windows::Version_6::7
> > > module loaded
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:new(196)|VCL::Module::OS::Windows::Version_6::7
> > > object created for image vmwarewin7-win7_x86_base23-v0, address: 4629ac8
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_os_object(314)|VCL::Module::OS::Windows::Version_6::7
> > > OS object created, address: 4629ac8
> > > 2012-08-23 14:22:29|13299|5:5|reload|State.pm:initialize(125)|computer is a
> > > VM, attempting to create VM host OS object
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(613)|computer ID
> > > argument was specified, retrieving data for computer ID: 1
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(637)|image
> > > ID argument was specified: 1, DataStructure object will contain image
> > > information for the production imagerevision of this image
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(668)|retrieved data
> > > for imagerevision ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(684)|retrieved data
> > > for image ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_vmhost_os_object(454)|attempting
> > > to load VM host OS module: VCL::Module::OS::Linux::UnixLab (image: 1)
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_vmhost_os_object(460)|VM host OS
> > > module loaded: VCL::Module::OS::Linux::UnixLab
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab
> > > object created for image noimage, address: 46440e8
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_vmhost_os_object(465)|VCL::Module::OS::Linux::UnixLab
> > > OS object created, address: 46440e8
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:create_provisioning_object(519)|VCL::Module::Provisioning::VMware::VMware
> > > module loaded
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VMware
> > > object created for computer vclvm0001-man0, address: 4644490
> > > 2012-08-23 14:22:29|13299|5:5|reload|VMware.pm:initialize(267)|initializing
> > > VCL::Module::Provisioning::VMware::VMware object
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(613)|computer ID
> > > argument was specified, retrieving data for computer ID: 1
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(637)|image
> > > ID argument was specified: 1, DataStructure object will contain image
> > > information for the production imagerevision of this image
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(668)|retrieved data
> > > for imagerevision ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(684)|retrieved data
> > > for image ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|VMware.pm:get_vmhost_datastructure(1392)|created
> > > DataStructure object for VM host: vclhv01
> > > 2012-08-23 14:22:29|13299|5:5|reload|VMware.pm:initialize(287)|VM profile
> > > assigned to vclhv01: VMware ESXi - double disk
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(613)|computer ID
> > > argument was specified, retrieving data for computer ID: 1
> > > 2012-08-23 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(637)|image
> > > ID argument was specified: 1, DataStructure object will contain image
> > > information for the production imagerevision of this image
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(668)|retrieved data
> > > for imagerevision ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|DataStructure.pm:_initialize(684)|retrieved data
> > > for image ID: 1
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|VMware.pm:get_vmhost_datastructure(1392)|created
> > > DataStructure object for VM host: vclhv01
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|VMware.pm:get_vmhost_api_object(1495)|attempting
> > > to load VMware control module:
> > > VCL::Module::Provisioning::VMware::vSphere_SDK
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|VMware.pm:get_vmhost_api_object(1501)|loaded
> > > VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
> > > 2012-08-23
> > > 14:22:29|13299|5:5|reload|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK
> > > object created for computer vclvm0001-man0, address: 523ff10
> > > 2012-08-23 14:22:30|13299|5:5|reload|vSphere_SDK.pm:initialize(101)|loaded
> > > VMware vSphere SDK modules
> > > 2012-08-23
> > > 14:22:30|13299|5:5|reload|vSphere_SDK.pm:initialize(145)|attempting to
> > > connect to VM host: https://vclhv01/sdk (root)
> > > 2012-08-23
> > > 14:22:31|13299|5:5|reload|vSphere_SDK.pm:initialize(163)|connected to VM
> > > host: https://vclhv01/sdk, username: 'root'
> > > 2012-08-23
> > > 14:22:31|13299|5:5|reload|vSphere_SDK.pm:initialize(177)|connected to
> > > vclhv01, VIM object type: Vim
> > > 2012-08-23
> > > 14:22:31|13299|5:5|reload|VMware.pm:get_vmhost_api_object(1522)|created API
> > > object: VCL::Module::Provisioning::VMware::vSphere_SDK
> > > 2012-08-23
> > > 14:22:31|13299|5:5|reload|vSphere_SDK.pm:is_restricted(1502)|access to the
> > > VM host via the vSphere SDK is restricted due to the license:
> > > RestrictedVersionFault
> > > 2012-08-23 14:22:32|13299|5:5|reload|OS.pm:is_ssh_responding(640)|vclhv01 is
> > > responding to SSH, port 22: open, port 24: closed
> > > 2012-08-23 14:22:32|13299|5:5|reload|VMware.pm:initialize(303)|OS on VM host
> > > vclhv01 will be controlled using VCL::Module::OS::Linux::UnixLab OS object
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|DataStructure.pm:_initialize(613)|computer ID
> > > argument was specified, retrieving data for computer ID: 1
> > > 2012-08-23 14:22:32|13299|5:5|reload|DataStructure.pm:_initialize(637)|image
> > > ID argument was specified: 1, DataStructure object will contain image
> > > information for the production imagerevision of this image
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|DataStructure.pm:_initialize(668)|retrieved data
> > > for imagerevision ID: 1
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|DataStructure.pm:_initialize(684)|retrieved data
> > > for image ID: 1
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|VMware.pm:get_vmhost_datastructure(1392)|created
> > > DataStructure object for VM host: vclhv01
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|VMware.pm:get_vmhost_api_object(1495)|attempting
> > > to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|VMware.pm:get_vmhost_api_object(1501)|loaded
> > > VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VIM_SSH
> > > object created for computer vclvm0001-man0, address: 62a5460
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > ; vmware-vim-cmd' 2>&1
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Commands available under /:
> > > |13299|5:5|reload| hbrsvc/ internalsvc/ solo/ vmsvc/
> > > |13299|5:5|reload| hostsvc/ proxysvc/ vimsvc/ help
> > > |13299|5:5|reload| sh: vmware-vim-cmd: not found
> > > 2012-08-23 14:22:32|13299|5:5|reload|utils.pm:run_ssh_command(5064)|SSH
> > > command executed on vclhv01, command:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > ; vmware-vim-cmd' 2>&1
> > > |13299|5:5|reload| returning (127, "Commands available under /: hb...")
> > > 2012-08-23 14:22:32|13299|5:5|reload|VIM_SSH.pm:initialize(138)|VIM
> > > executable available on VM host: vim-cmd
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|VIM_SSH.pm:initialize(140)|VCL::Module::Provisioning::VMware::VIM_SSH
> > > object initialized
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|VMware.pm:get_vmhost_api_object(1522)|created API
> > > object: VCL::Module::Provisioning::VMware::VIM_SSH
> > > 2012-08-23 14:22:32|13299|5:5|reload|VMware.pm:initialize(312)|VM host
> > > vclhv01 will be controlled using vim-cmd via SSH
> > > 2012-08-23 14:22:32|13299|5:5|reload|VMware.pm:initialize(327)|VMware OS and
> > > API objects created for VM host vclhv01:
> > > |13299|5:5|reload| VM host OS object type: VCL::Module::OS::Linux::UnixLab
> > > |13299|5:5|reload| VMware API object type:
> > > VCL::Module::Provisioning::VMware::VIM_SSH
> > > 2012-08-23
> > > 14:22:32|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vmware
> > > -v' 2>&1
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| VMware ESXi 5.0.0 build-623860
> > > 2012-08-23 14:22:33|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "VMware ESXi 5.0.0 build-623860")
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|VMware.pm:get_vmhost_product_name(6621)|VMware
> > > product being used on VM host vclhv01: 'VMware ESXi 5.0.0 build-623860'
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > hostsvc/datastore/listsummary' 2>&1
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| (vim.Datastore.Summary) [
> > > |13299|5:5|reload| (vim.Datastore.Summary) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| datastore =
> > > 'vim.Datastore:4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2',
> > > |13299|5:5|reload| name = "datastore2",
> > > |13299|5:5|reload| url =
> > > "/vmfs/volumes/4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2",
> > > |13299|5:5|reload| capacity = 999922073600,
> > > |13299|5:5|reload| freeSpace = 918493855744,
> > > |13299|5:5|reload| uncommitted = 0,
> > > |13299|5:5|reload| accessible = true,
> > > |13299|5:5|reload| multipleHostAccess = <unset>,
> > > |13299|5:5|reload| type = "VMFS",
> > > |13299|5:5|reload| maintenanceMode = <unset>,
> > > |13299|5:5|reload| },
> > > |13299|5:5|reload| (vim.Datastore.Summary) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| datastore =
> > > 'vim.Datastore:4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2',
> > > |13299|5:5|reload| name = "datastore1",
> > > |13299|5:5|reload| url =
> > > "/vmfs/volumes/4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2",
> > > |13299|5:5|reload| capacity = 74625056768,
> > > |13299|5:5|reload| freeSpace = 70827114496,
> > > |13299|5:5|reload| uncommitted = 0,
> > > |13299|5:5|reload| accessible = true,
> > > |13299|5:5|reload| multipleHostAccess = <unset>,
> > > |13299|5:5|reload| type = "VMFS",
> > > |13299|5:5|reload| maintenanceMode = <unset>,
> > > |13299|5:5|reload| },
> > > |13299|5:5|reload| (vim.Datastore.Summary) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| datastore =
> > > 'vim.Datastore:10.128.64.10:/images/src_images',
> > > |13299|5:5|reload| name = "src_images",
> > > |13299|5:5|reload| url = "/vmfs/volumes/be3112c9-7ee70531",
> > > |13299|5:5|reload| capacity = 984505425920,
> > > |13299|5:5|reload| freeSpace = 808206823424,
> > > |13299|5:5|reload| uncommitted = 0,
> > > |13299|5:5|reload| accessible = true,
> > > |13299|5:5|reload| multipleHostAccess = <unset>,
> > > |13299|5:5|reload| type = "NFS",
> > > |13299|5:5|reload| maintenanceMode = <unset>,
> > > |13299|5:5|reload| }
> > > |13299|5:5|reload| ]
> > > 2012-08-23 14:22:33|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "(vim.Datastore.Summary) [
> > > (vim...")
> > > 2012-08-23 14:22:33|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd hostsvc/datastore/listsummary
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|VMware.pm:get_datastore_info(6747)|retrieved
> > > datastore info from VM host:
> > > |13299|5:5|reload| : {
> > > |13299|5:5|reload| : "datastore1" => {
> > > |13299|5:5|reload| : "accessible" => "true",
> > > |13299|5:5|reload| : "capacity" => "74625056768",
> > > |13299|5:5|reload| : "datastore" =>
> > > "vim.Datastore:4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2",
> > > |13299|5:5|reload| : "dynamicType" => "<unset>",
> > > |13299|5:5|reload| : "freeSpace" => "70827114496",
> > > |13299|5:5|reload| : "maintenanceMode" => "<unset>",
> > > |13299|5:5|reload| : "multipleHostAccess" => "<unset>",
> > > |13299|5:5|reload| : "normal_path" => "/vmfs/volumes/datastore1",
> > > |13299|5:5|reload| : "type" => "VMFS",
> > > |13299|5:5|reload| : "uncommitted" => 0,
> > > |13299|5:5|reload| : "url" =>
> > > "/vmfs/volumes/4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2"
> > > |13299|5:5|reload| : },
> > > |13299|5:5|reload| : "datastore2" => {
> > > |13299|5:5|reload| : "accessible" => "true",
> > > |13299|5:5|reload| : "capacity" => "999922073600",
> > > |13299|5:5|reload| : "datastore" =>
> > > "vim.Datastore:4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2",
> > > |13299|5:5|reload| : "dynamicType" => "<unset>",
> > > |13299|5:5|reload| : "freeSpace" => "918493855744",
> > > |13299|5:5|reload| : "maintenanceMode" => "<unset>",
> > > |13299|5:5|reload| : "multipleHostAccess" => "<unset>",
> > > |13299|5:5|reload| : "normal_path" => "/vmfs/volumes/datastore2",
> > > |13299|5:5|reload| : "type" => "VMFS",
> > > |13299|5:5|reload| : "uncommitted" => 0,
> > > |13299|5:5|reload| : "url" =>
> > > "/vmfs/volumes/4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2"
> > > |13299|5:5|reload| : },
> > > |13299|5:5|reload| : "src_images" => {
> > > |13299|5:5|reload| : "accessible" => "true",
> > > |13299|5:5|reload| : "capacity" => "984505425920",
> > > |13299|5:5|reload| : "datastore" =>
> > > "vim.Datastore:10.128.64.10:/images/src_images",
> > > |13299|5:5|reload| : "dynamicType" => "<unset>",
> > > |13299|5:5|reload| : "freeSpace" => "808206823424",
> > > |13299|5:5|reload| : "maintenanceMode" => "<unset>",
> > > |13299|5:5|reload| : "multipleHostAccess" => "<unset>",
> > > |13299|5:5|reload| : "normal_path" => "/vmfs/volumes/src_images",
> > > |13299|5:5|reload| : "type" => "NFS",
> > > |13299|5:5|reload| : "uncommitted" => 0,
> > > |13299|5:5|reload| : "url" => "/vmfs/volumes/be3112c9-7ee70531"
> > > |13299|5:5|reload| : }
> > > |13299|5:5|reload| : }
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|VMware.pm:get_vmx_base_directory_path(3089)|determined
> > > vmx base directory path: /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:33|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2' 2>&1
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| File: "/vmfs/volumes/datastore2 ->
> > > 4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2"
> > > |13299|5:5|reload| Size: 35 Blocks: 0 IO Block: 131072
> > > symbolic link
> > > |13299|5:5|reload| Device: 0h/0d Inode: 2147483647 Links: 1
> > > |13299|5:5|reload| Access: (0755/lrwxr-xr-x) Uid: ( 0/ root) Gid: (
> > > 0/ root)
> > > |13299|5:5|reload| Access: 2012-08-23 18:22:34.000000000
> > > |13299|5:5|reload| Modify: 2012-08-23 18:22:34.000000000
> > > |13299|5:5|reload| Change: 2012-08-23 18:22:34.000000000
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "File:
> > > "/vmfs/volumes/datastore...")
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|Linux.pm:file_exists(1356)|'/vmfs/volumes/datastore2'
> > > exists on vclhv01, files: 0, directories: 0, links: 1
> > > 2012-08-23 14:22:34|13299|5:5|reload|VMware.pm:is_vm_dedicated(4246)|VM disk
> > > mode does not need to be dedicated
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23 14:22:34|13299|5:5|reload|VMware.pm:initialize(351)|not checking
> > > if vmdk base directory exists because it is the same as the vmx base
> > > directory: /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|Module.pm:create_provisioning_object(525)|VCL::Module::Provisioning::VMware::VMware
> > > provisioner object created for vclvm0001-man0, address: 4644490
> > > 2012-08-23 14:22:34|13299|5:5|reload|State.pm:initialize(154)|returning 1
> > > 2012-08-23 14:22:34|13299|5:5|reload|vcld:make_new_child(568)|VCL::new
> > > object created and initialized
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:is_parent_reservation(993)|returning
> > > true: parent reservation ID for this request: 5
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2433)|attempting
> > > to retrieve current state of computer vclvm0001-man0 from the database
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2464)|retrieved
> > > current state of computer vclvm0001-man0 from the database: reload
> > > 2012-08-23 14:22:34|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{5}{computer}{state}{name}, data
> > > identifier: computer_state_name, data:
> > > |13299|5:5|reload| : "reload"
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:is_inblockrequest(5798)|zero
> > > rows were returned from database select
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2433)|attempting
> > > to retrieve current state of computer vclvm0001-man0 from the database
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2464)|retrieved
> > > current state of computer vclvm0001-man0 from the database: reload
> > > 2012-08-23 14:22:34|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{5}{computer}{state}{name}, data
> > > identifier: computer_state_name, data:
> > > |13299|5:5|reload| : "reload"
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|new.pm:computer_not_being_used(726)|retrieving
> > > info for reservations assigned to vclvm0001-man0
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:get_connect_method_info(10097)|attempting
> > > to retrieve connect method info:
> > > |13299|5:5|reload| imagerevision: 3 - vmwarewin7-win7_x86_base23-v0
> > > |13299|5:5|reload| OS: 35 - vmwarewin7
> > > |13299|5:5|reload| OS type: 1 - windows
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:get_connect_method_info(10161)|RDP:
> > > connectmethodid=2, OStypeid=1, OSid=NULL, imagerevisionid=NULL, disabled=0
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:get_connect_method_info(10190)|retrieved
> > > connect method info:
> > > |13299|5:5|reload| : {
> > > |13299|5:5|reload| : 2 => {
> > > |13299|5:5|reload| : "connectmethodmap" => {
> > > |13299|5:5|reload| : "OSid" => undef,
> > > |13299|5:5|reload| : "OStypeid" => 1,
> > > |13299|5:5|reload| : "autoprovisioned" => undef,
> > > |13299|5:5|reload| : "connectmethodid" => 2,
> > > |13299|5:5|reload| : "disabled" => 0,
> > > |13299|5:5|reload| : "imagerevisionid" => undef
> > > |13299|5:5|reload| : },
> > > |13299|5:5|reload| : "description" => "Remote Desktop",
> > > |13299|5:5|reload| : "id" => 2,
> > > |13299|5:5|reload| : "name" => "RDP",
> > > |13299|5:5|reload| : "port" => 3389,
> > > |13299|5:5|reload| : "protocol" => "",
> > > |13299|5:5|reload| : "servicename" => "TermService",
> > > |13299|5:5|reload| : "startupscript" => undef
> > > |13299|5:5|reload| : }
> > > |13299|5:5|reload| : }
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:get_user_info(7578)|UID value
> > > is not configured for user vclreload, setting UID to VCL user ID: vclreload,
> > > standalone: 1
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:get_user_info(7578)|UID value
> > > is not configured for user vclreload, setting UID to VCL user ID: vclreload,
> > > standalone: 1
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:get_request_by_computerid(6029)|retrieved
> > > info and DataStructure object for 5:5
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|new.pm:computer_not_being_used(739)|vclvm0001-man0
> > > is not assigned to any other reservations
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:process(127)|vclvm0001-man0 is
> > > not being used
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2433)|attempting
> > > to retrieve current state of computer vclvm0001-man0 from the database
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2464)|retrieved
> > > current state of computer vclvm0001-man0 from the database: reload
> > > 2012-08-23 14:22:34|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{5}{computer}{state}{name}, data
> > > identifier: computer_state_name, data:
> > > |13299|5:5|reload| : "reload"
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:reload_image(504)|calling
> > > VCL::Module::Provisioning::VMware::VMware->node_status()
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, statuscheck, checking status of node
> > > 2012-08-23 14:22:34|13299|5:5|reload|VMware.pm:node_status(1151)|attempting
> > > to check the status of computer vclvm0001-man0, image:
> > > vmwarewin7-win7_x86_base23-v0
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23 14:22:34|13299|5:5|reload|VMware.pm:node_status(1188)|VM
> > > vclvm0001-man0 is not responding to SSH, returning 'RELOAD'
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:reload_image(519)|node_status
> > > returned a hash reference
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:reload_image(524)|node_status
> > > hash reference contains key {status}=RELOAD
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:reload_image(569)|request state
> > > is reload, node will be reloaded regardless of status
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:reload_image(602)|node status is
> > > reload, vclvm0001-man0 will be reloaded
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, loadimageblade, vclvm0001-man0 must be reloaded with
> > > vmwarewin7-win7_x86_base23-v0
> > > 2012-08-23 14:22:34|13299|5:5|reload|new.pm:reload_image(616)|calling
> > > VCL::Module::Provisioning::VMware::VMware->does_image_exist()
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vmwarewin7\-win7_x86_base23\-v0/vmwarewin7\-win7_x86_base23\-v0\.vmdk'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| File:
> > > "/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk"
> > > |13299|5:5|reload| Size: 565 Blocks: 128 IO Block: 131072
> > > regular file
> > > |13299|5:5|reload| Device: 31c76cacbda6e9dbh/3586955117395896795d
> > > Inode: 83887364 Links: 1
> > > |13299|5:5|reload| Access: (0600/-rw-------) Uid: ( 0/ root) Gid: (
> > > 0/ root)
> > > |13299|5:5|reload| Access: 2012-08-23 18:08:23.000000000
> > > |13299|5:5|reload| Modify: 2012-08-23 18:08:23.000000000
> > > |13299|5:5|reload| Change: 2012-08-23 17:57:53.000000000
> > > 2012-08-23 14:22:34|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "File:
> > > "/vmfs/volumes/datastore...")
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|Linux.pm:file_exists(1356)|'/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk'
> > > exists on vclhv01, files: 1, directories: 0, links: 0
> > > 2012-08-23 14:22:34|13299|5:5|reload|VMware.pm:does_image_exist(4580)|image
> > > exists in the shared directory on the VM host:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk
> > > 2012-08-23
> > > 14:22:34|13299|5:5|reload|new.pm:reload_image(619)|vmwarewin7-win7_x86_base23-v0
> > > exists on this management node
> > > 2012-08-23 14:22:35|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, doesimageexists, confirmed image exists
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|utils.pm:update_computer_state(1587)|computer 5
> > > state updated to: reloading
> > > 2012-08-23 14:22:35|13299|5:5|reload|new.pm:reload_image(652)|computer
> > > vclvm0001-man0 state set to reloading
> > > 2012-08-23 14:22:35|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, info, computer state updated to reloading
> > > 2012-08-23 14:22:35|13299|5:5|reload|new.pm:reload_image(663)|calling
> > > VCL::Module::Provisioning::VMware::VMware->load() subroutine
> > > 2012-08-23 14:22:35|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, info, calling VCL::Module::Provisioning::VMware::VMware->load()
> > > subroutine
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|VMware.pm:get_vmx_base_directory_path(3089)|determined
> > > vmx base directory path: /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|VMware.pm:get_vmx_file_path(3029)|determined vmx
> > > file path:
> > > /vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx
> > > 2012-08-23 14:22:35|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, doesimageexists, image exists vmwarewin7-win7_x86_base23-v0
> > > 2012-08-23 14:22:35|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, startload, vclvm0001-man0 vmwarewin7-win7_x86_base23-v0
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|VMware.pm:get_vmx_file_paths(5226)|attempting to
> > > find existing vmx files on the VM host
> > > 2012-08-23 14:22:35|13299|5:5|reload|OS.pm:find_files(2821)|attempting to
> > > find files on vclhv01, base directory path: '/vmfs/volumes/datastore2/',
> > > pattern: *.vmx, command: /usr/bin/find "/vmfs/volumes/datastore2/" -type f
> > > -iname "*.vmx"
> > > 2012-08-23 14:22:35|13299|5:5|reload|OS.pm:find_files(2849)|files found: 1,
> > > base directory: '/vmfs/volumes/datastore2/', pattern: '*.vmx'
> > > |13299|5:5|reload| command: '/usr/bin/find "/vmfs/volumes/datastore2/" -type
> > > f -iname "*.vmx"'
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|VMware.pm:get_vmx_file_paths(5232)|found 1 vmx
> > > files under /vmfs/volumes/datastore2
> > > |13299|5:5|reload|
> > > /vmfs/volumes/datastore2/vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx
> > > 2012-08-23
> > > 14:22:35|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:36|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:36|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > hostsvc/datastore/listsummary' 2>&1
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| (vim.Datastore.Summary) [
> > > |13299|5:5|reload| (vim.Datastore.Summary) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| datastore =
> > > 'vim.Datastore:4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2',
> > > |13299|5:5|reload| name = "datastore2",
> > > |13299|5:5|reload| url =
> > > "/vmfs/volumes/4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2",
> > > |13299|5:5|reload| capacity = 999922073600,
> > > |13299|5:5|reload| freeSpace = 918493855744,
> > > |13299|5:5|reload| uncommitted = 0,
> > > |13299|5:5|reload| accessible = true,
> > > |13299|5:5|reload| multipleHostAccess = <unset>,
> > > |13299|5:5|reload| type = "VMFS",
> > > |13299|5:5|reload| maintenanceMode = <unset>,
> > > |13299|5:5|reload| },
> > > |13299|5:5|reload| (vim.Datastore.Summary) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| datastore =
> > > 'vim.Datastore:4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2',
> > > |13299|5:5|reload| name = "datastore1",
> > > |13299|5:5|reload| url =
> > > "/vmfs/volumes/4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2",
> > > |13299|5:5|reload| capacity = 74625056768,
> > > |13299|5:5|reload| freeSpace = 70827114496,
> > > |13299|5:5|reload| uncommitted = 0,
> > > |13299|5:5|reload| accessible = true,
> > > |13299|5:5|reload| multipleHostAccess = <unset>,
> > > |13299|5:5|reload| type = "VMFS",
> > > |13299|5:5|reload| maintenanceMode = <unset>,
> > > |13299|5:5|reload| },
> > > |13299|5:5|reload| (vim.Datastore.Summary) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| datastore =
> > > 'vim.Datastore:10.128.64.10:/images/src_images',
> > > |13299|5:5|reload| name = "src_images",
> > > |13299|5:5|reload| url = "/vmfs/volumes/be3112c9-7ee70531",
> > > |13299|5:5|reload| capacity = 984505425920,
> > > |13299|5:5|reload| freeSpace = 808206823424,
> > > |13299|5:5|reload| uncommitted = 0,
> > > |13299|5:5|reload| accessible = true,
> > > |13299|5:5|reload| multipleHostAccess = <unset>,
> > > |13299|5:5|reload| type = "NFS",
> > > |13299|5:5|reload| maintenanceMode = <unset>,
> > > |13299|5:5|reload| }
> > > |13299|5:5|reload| ]
> > > 2012-08-23 14:22:36|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "(vim.Datastore.Summary) [
> > > (vim...")
> > > 2012-08-23 14:22:36|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd hostsvc/datastore/listsummary
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VMware.pm:get_datastore_info(6747)|retrieved
> > > datastore info from VM host:
> > > |13299|5:5|reload| : {
> > > |13299|5:5|reload| : "datastore1" => {
> > > |13299|5:5|reload| : "accessible" => "true",
> > > |13299|5:5|reload| : "capacity" => "74625056768",
> > > |13299|5:5|reload| : "datastore" =>
> > > "vim.Datastore:4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2",
> > > |13299|5:5|reload| : "dynamicType" => "<unset>",
> > > |13299|5:5|reload| : "freeSpace" => "70827114496",
> > > |13299|5:5|reload| : "maintenanceMode" => "<unset>",
> > > |13299|5:5|reload| : "multipleHostAccess" => "<unset>",
> > > |13299|5:5|reload| : "normal_path" => "/vmfs/volumes/datastore1",
> > > |13299|5:5|reload| : "type" => "VMFS",
> > > |13299|5:5|reload| : "uncommitted" => 0,
> > > |13299|5:5|reload| : "url" =>
> > > "/vmfs/volumes/4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2"
> > > |13299|5:5|reload| : },
> > > |13299|5:5|reload| : "datastore2" => {
> > > |13299|5:5|reload| : "accessible" => "true",
> > > |13299|5:5|reload| : "capacity" => "999922073600",
> > > |13299|5:5|reload| : "datastore" =>
> > > "vim.Datastore:4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2",
> > > |13299|5:5|reload| : "dynamicType" => "<unset>",
> > > |13299|5:5|reload| : "freeSpace" => "918493855744",
> > > |13299|5:5|reload| : "maintenanceMode" => "<unset>",
> > > |13299|5:5|reload| : "multipleHostAccess" => "<unset>",
> > > |13299|5:5|reload| : "normal_path" => "/vmfs/volumes/datastore2",
> > > |13299|5:5|reload| : "type" => "VMFS",
> > > |13299|5:5|reload| : "uncommitted" => 0,
> > > |13299|5:5|reload| : "url" =>
> > > "/vmfs/volumes/4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2"
> > > |13299|5:5|reload| : },
> > > |13299|5:5|reload| : "src_images" => {
> > > |13299|5:5|reload| : "accessible" => "true",
> > > |13299|5:5|reload| : "capacity" => "984505425920",
> > > |13299|5:5|reload| : "datastore" =>
> > > "vim.Datastore:10.128.64.10:/images/src_images",
> > > |13299|5:5|reload| : "dynamicType" => "<unset>",
> > > |13299|5:5|reload| : "freeSpace" => "808206823424",
> > > |13299|5:5|reload| : "maintenanceMode" => "<unset>",
> > > |13299|5:5|reload| : "multipleHostAccess" => "<unset>",
> > > |13299|5:5|reload| : "normal_path" => "/vmfs/volumes/src_images",
> > > |13299|5:5|reload| : "type" => "NFS",
> > > |13299|5:5|reload| : "uncommitted" => 0,
> > > |13299|5:5|reload| : "url" => "/vmfs/volumes/be3112c9-7ee70531"
> > > |13299|5:5|reload| : }
> > > |13299|5:5|reload| : }
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VIM_SSH.pm:get_registered_vms(835)|found 1
> > > registered VMs
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VMware.pm:get_vmx_file_paths(5236)|found 1
> > > registered vmx files
> > > |13299|5:5|reload|
> > > /vmfs/volumes/datastore2/vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VMware.pm:get_vmx_file_paths(5241)|found 1 vmx
> > > files on VM host
> > > |13299|5:5|reload|
> > > /vmfs/volumes/datastore2/vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VMware.pm:remove_existing_vms(1557)|checking
> > > existing vmx file:
> > > '/vmfs/volumes/datastore2/vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx'
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VMware.pm:_get_vmx_file_path_computer_name(7391)|determined
> > > computer name 'vclvm0009-man0' from directory name: 'vclvm0009-man0_2-v0'
> > > 2012-08-23
> > > 14:22:36|13299|5:5|reload|VMware.pm:remove_existing_vms(1574)|ignoring
> > > existing vmx file: vclvm0009-man0_2-v0.vmx, the directory computer name
> > > 'vclvm0009-man0' does not match the reservation computer name
> > > 'vclvm0001-man0'
> > > 2012-08-23 14:22:36|13299|5:5|reload|OS.pm:find_files(2821)|attempting to
> > > find files on vclhv01, base directory path: '/vmfs/volumes/datastore2/',
> > > pattern: *vclvm0001-man0*.vmx, command: /usr/bin/find
> > > "/vmfs/volumes/datastore2/" -type f -iname "*vclvm0001-man0*.vmx"
> > > 2012-08-23 14:22:36|13299|5:5|reload|OS.pm:find_files(2849)|files found: 0,
> > > base directory: '/vmfs/volumes/datastore2/', pattern: '*vclvm0001-man0*.vmx'
> > > |13299|5:5|reload| command: '/usr/bin/find "/vmfs/volumes/datastore2/" -type
> > > f -iname "*vclvm0001-man0*.vmx"'
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:update_computer_imagename(5671)|successfully
> > > retreived image info for noimage
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:update_currentimage(5723)|updating
> > > computer 5: image=1, imagerevision=1
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:update_currentimage(5753)|updated
> > > currentimageid and imagerevision id for computer id 5
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:update_computer_imagename(5683)|successfully
> > > updated computerid= 5 image_id= 1 imagerevision_id= 1
> > > 2012-08-23 14:22:37|13299|5:5|reload|VMware.pm:remove_existing_vms(1630)|set
> > > computer vclvm0001-man0 current image to 'noimage'
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:check_vmhost_disk_space(2371)|checking
> > > if enough space is available on VM host vclhv01
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:is_vmx_vmdk_volume_shared(2273)|vmx and
> > > vmdk base directory paths are identical: '/vmfs/volumes/datastore2', they
> > > are on the same volume
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat -f
> > > "/vmfs/volumes/datastore2"' 2>&1
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| File: "/vmfs/volumes/datastore2"
> > > |13299|5:5|reload| ID: 31c76cacbda6e9db Namelen: 127 Type: vmfs3
> > > |13299|5:5|reload| Block size: 8388608
> > > |13299|5:5|reload| Blocks: Total: 119200 Free: 112524 Available: 112524
> > > |13299|5:5|reload| Inodes: Total: 2147483647 Free: 2147483647
> > > 2012-08-23 14:22:37|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "File:
> > > "/vmfs/volumes/datastore...")
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|Linux.pm:get_available_space(1609)|space available
> > > on volume on vclhv01 containing '/vmfs/volumes/datastore2': 943,919,726,592
> > > bytes - 921,796,608.0 KB - 900,192.0 MB - 879.09 GB
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:get_vm_os_configuration(4884)|returning
> > > matching 'win7-x86_64' OS configuration: vmwarewin7, image architecture:
> > > x86_64
> > > |13299|5:5|reload| : {
> > > |13299|5:5|reload| : "cpu_socket_limit" => 2,
> > > |13299|5:5|reload| : "ethernet-virtualDev" => "e1000",
> > > |13299|5:5|reload| : "guestOS" => "windows7-64",
> > > |13299|5:5|reload| : "memsize" => 2048,
> > > |13299|5:5|reload| : "scsi-virtualDev" => "lsiLogic"
> > > |13299|5:5|reload| : }
> > > 2012-08-23 14:22:37|13299|5:5|reload|VMware.pm:get_vm_ram(5044)|image
> > > minimum RAM value (0 MB) is too low for the windows7-64 guest OS, adjusting
> > > to 2048 MB
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:get_vm_additional_vmx_bytes_required(5710)|2147483648
> > > additional bytes required for VM vmem file
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23 14:22:37|13299|5:5|reload|VMware.pm:get_image_size_bytes(4488)|VM
> > > profile vmdisk is set to 'dedicated', attempting to retrieve image size from
> > > image repository
> > > 2012-08-23
> > > 14:22:37|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /images/vcl_repo' 2>&1
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| stat: cannot stat '/images/vcl_repo': No such file or
> > > directory
> > > 2012-08-23 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5064)|SSH
> > > command executed on vclhv01, command:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /images/vcl_repo' 2>&1
> > > |13299|5:5|reload| returning (1, "stat: cannot stat '/images/vcl...")
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:is_repository_mounted_on_vmhost(6705)|image
> > > repository is NOT mounted on VM host vclhv01: /images/vcl_repo
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_image_size_bytes(4496)|checking size
> > > of image in image repository in management node: localhost:/images/vcl_repo
> > > 2012-08-23 14:22:38|13299|5:5|reload|Linux.pm:get_file_size(1847)|size of
> > > /images/vcl_repo/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0*.vmdk
> > > on localhost:
> > > |13299|5:5|reload| file count: 18
> > > |13299|5:5|reload| reserved: 13,379,306,986 bytes - 13,065,729.5 KB -
> > > 12,759.5 MB - 12.46 GB
> > > |13299|5:5|reload| used: 13,379,338,240 bytes - 13,065,760.0 KB - 12,759.5
> > > MB - 12.46 GB
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_image_size_bytes(4500)|size of image
> > > retrieved from image repository on management node: 13,379,338,240 bytes -
> > > 13,065,760.0 KB - 12,759.5 MB - 12.46 GB
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat -L
> > > -c "%F:%n:%s:%b:%B"
> > > /vmfs/volumes/datastore2/vmwarewin7\-win7_x86_base23\-v0/vmwarewin7\-win7_x86_base23\-v0*\.vmdk'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| regular
> > > file:/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0-flat.vmdk:34359738368:26591232:512
> > > |13299|5:5|reload| regular
> > > file:/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk:565:128:512
> > > 2012-08-23 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "regular
> > > file:/vmfs/volumes/dat...")
> > > 2012-08-23 14:22:38|13299|5:5|reload|Linux.pm:get_file_size(1847)|size of
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0*.vmdk
> > > on vclhv01:
> > > |13299|5:5|reload| file count: 2
> > > |13299|5:5|reload| reserved: 34,359,738,933 bytes - 33,554,432.6 KB -
> > > 32,768.0 MB - 32.00 GB
> > > |13299|5:5|reload| used: 13,614,776,320 bytes - 13,295,680.0 KB - 12,984.1
> > > MB - 12.68 GB
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_image_size_bytes(4510)|retrieved the
> > > size of the image from the datastore on the VM host: 13,614,776,320
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_image_size_bytes(4520)|image size
> > > retrieved from both the image repository and VM host datastore:
> > > |13299|5:5|reload| image repository: 13,379,338,240
> > > |13299|5:5|reload| VM host datastore: 13,614,776,320
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_image_size_bytes(4540)|size of
> > > vmwarewin7-win7_x86_base23-v0 image:
> > > |13299|5:5|reload| 13,614,776,320 bytes
> > > |13299|5:5|reload| 12,984 MB
> > > |13299|5:5|reload| 12.68 GB
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_vm_additional_vmx_bytes_required(5723)|3403694080
> > > additional bytes required for delta/REDO files because VM disk mode is
> > > shared
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_vm_additional_vmx_bytes_required(5726)|estimate
> > > of additional space required for the vmx directory:
> > > |13299|5:5|reload| vmem/vswp file: 2,147,483,648 bytes - 2,097,152.0 KB -
> > > 2,048.0 MB - 2.00 GB
> > > |13299|5:5|reload| redo files: 3,403,694,080 bytes - 3,323,920.0 KB -
> > > 3,246.0 MB - 3.17 GB
> > > |13299|5:5|reload| total: 5,551,177,728 bytes - 5,421,072.0 KB - 5,294.0 MB
> > > - 5.17 GB
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vclvm0001\-man0_3\-v0/vclvm0001\-man0_3\-v0\.vmx'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| stat: cannot stat
> > > '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx': No
> > > such file or directory
> > > 2012-08-23 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5064)|SSH
> > > command executed on vclhv01, command:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vclvm0001\-man0_3\-v0/vclvm0001\-man0_3\-v0\.vmx'
> > > 2>&1
> > > |13299|5:5|reload| returning (1, "stat: cannot stat '/vmfs/volum...")
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vmwarewin7\-win7_x86_base23\-v0/vmwarewin7\-win7_x86_base23\-v0\.vmdk'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| File:
> > > "/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk"
> > > |13299|5:5|reload| Size: 565 Blocks: 128 IO Block: 131072
> > > regular file
> > > |13299|5:5|reload| Device: 31c76cacbda6e9dbh/3586955117395896795d
> > > Inode: 83887364 Links: 1
> > > |13299|5:5|reload| Access: (0600/-rw-------) Uid: ( 0/ root) Gid: (
> > > 0/ root)
> > > |13299|5:5|reload| Access: 2012-08-23 18:08:23.000000000
> > > |13299|5:5|reload| Modify: 2012-08-23 18:08:23.000000000
> > > |13299|5:5|reload| Change: 2012-08-23 17:57:53.000000000
> > > 2012-08-23 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "File:
> > > "/vmfs/volumes/datastore...")
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|Linux.pm:file_exists(1356)|'/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk'
> > > exists on vclhv01, files: 1, directories: 0, links: 0
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23 14:22:38|13299|5:5|reload|VMware.pm:get_image_size_bytes(4488)|VM
> > > profile vmdisk is set to 'dedicated', attempting to retrieve image size from
> > > image repository
> > > 2012-08-23
> > > 14:22:38|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /images/vcl_repo' 2>&1
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| stat: cannot stat '/images/vcl_repo': No such file or
> > > directory
> > > 2012-08-23 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(5064)|SSH
> > > command executed on vclhv01, command:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /images/vcl_repo' 2>&1
> > > |13299|5:5|reload| returning (1, "stat: cannot stat '/images/vcl...")
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:is_repository_mounted_on_vmhost(6705)|image
> > > repository is NOT mounted on VM host vclhv01: /images/vcl_repo
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_image_size_bytes(4496)|checking size
> > > of image in image repository in management node: localhost:/images/vcl_repo
> > > 2012-08-23 14:22:39|13299|5:5|reload|Linux.pm:get_file_size(1847)|size of
> > > /images/vcl_repo/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0*.vmdk
> > > on localhost:
> > > |13299|5:5|reload| file count: 18
> > > |13299|5:5|reload| reserved: 13,379,306,986 bytes - 13,065,729.5 KB -
> > > 12,759.5 MB - 12.46 GB
> > > |13299|5:5|reload| used: 13,379,338,240 bytes - 13,065,760.0 KB - 12,759.5
> > > MB - 12.46 GB
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_image_size_bytes(4500)|size of image
> > > retrieved from image repository on management node: 13,379,338,240 bytes -
> > > 13,065,760.0 KB - 12,759.5 MB - 12.46 GB
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat -L
> > > -c "%F:%n:%s:%b:%B"
> > > /vmfs/volumes/datastore2/vmwarewin7\-win7_x86_base23\-v0/vmwarewin7\-win7_x86_base23\-v0*\.vmdk'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| regular
> > > file:/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0-flat.vmdk:34359738368:26591232:512
> > > |13299|5:5|reload| regular
> > > file:/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk:565:128:512
> > > 2012-08-23 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "regular
> > > file:/vmfs/volumes/dat...")
> > > 2012-08-23 14:22:39|13299|5:5|reload|Linux.pm:get_file_size(1847)|size of
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0*.vmdk
> > > on vclhv01:
> > > |13299|5:5|reload| file count: 2
> > > |13299|5:5|reload| reserved: 34,359,738,933 bytes - 33,554,432.6 KB -
> > > 32,768.0 MB - 32.00 GB
> > > |13299|5:5|reload| used: 13,614,776,320 bytes - 13,295,680.0 KB - 12,984.1
> > > MB - 12.68 GB
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_image_size_bytes(4510)|retrieved the
> > > size of the image from the datastore on the VM host: 13,614,776,320
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_image_size_bytes(4520)|image size
> > > retrieved from both the image repository and VM host datastore:
> > > |13299|5:5|reload| image repository: 13,379,338,240
> > > |13299|5:5|reload| VM host datastore: 13,614,776,320
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_image_size_bytes(4540)|size of
> > > vmwarewin7-win7_x86_base23-v0 image:
> > > |13299|5:5|reload| 13,614,776,320 bytes
> > > |13299|5:5|reload| 12,984 MB
> > > |13299|5:5|reload| 12.68 GB
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vm_additional_vmdk_bytes_required(5667)|no
> > > additional space required for vmdk files because they already exist on VM
> > > host
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vm_additional_vmdk_bytes_required(5672)|VM
> > > requires appoximately 0 additional bytes (0 MB, 0 GB) of disk space on the
> > > VM host for the vmdk directory
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:check_vmhost_disk_space(2404)|enough
> > > space is available on shared vmx/vmdk volume on VM host vclhv01:
> > > '/vmfs/volumes/datastore2'
> > > |13299|5:5|reload| vmx additional space required: 5,551,177,728 bytes -
> > > 5,421,072.0 KB - 5,294.0 MB - 5.17 GB
> > > |13299|5:5|reload| vmdk additional space required: 0 bytes - 0.0 KB
> > > - 0.0 MB - 0.00 GB
> > > |13299|5:5|reload| total additional space required: 5,551,177,728 bytes -
> > > 5,421,072.0 KB - 5,294.0 MB - 5.17 GB
> > > |13299|5:5|reload| shared vmx/vmdk volume available space: 943,919,726,592
> > > bytes - 921,796,608.0 KB - 900,192.0 MB - 879.09 GB
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vclvm0001\-man0_3\-v0/vclvm0001\-man0_3\-v0\.vmx'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| stat: cannot stat
> > > '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx': No
> > > such file or directory
> > > 2012-08-23 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(5064)|SSH
> > > command executed on vclhv01, command:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vclvm0001\-man0_3\-v0/vclvm0001\-man0_3\-v0\.vmx'
> > > 2>&1
> > > |13299|5:5|reload| returning (1, "stat: cannot stat '/vmfs/volum...")
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|Module.pm:new(203)|VCL::Module::Semaphore object
> > > created, address: 6402830
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|Module.pm:code_loop_timeout(879)|attempting to
> > > open lockfile, maximum of 1200 seconds
> > > 2012-08-23 14:22:39|13299|5:5|reload|Semaphore.pm:open_lockfile(175)|opened
> > > and obtained an exclusive lock on file:
> > > /tmp/vclhv01-vmfs-volumes-datastore2-vmwarewin7-win7_x86_base23-v0.semaphore
> > > 2012-08-23 14:22:39|13299|5:5|reload|Semaphore.pm:open_lockfile(182)|wrote
> > > to file:
> > > /tmp/vclhv01-vmfs-volumes-datastore2-vmwarewin7-win7_x86_base23-v0.semaphore,
> > > contents:
> > > |13299|5:5|reload| '13299 vcld 5:5 reload vclvm0001-man0>vclhv01
> > > vmwarewin7-win7_x86_base23-v0 vclreload'
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|Module.pm:code_loop_timeout(899)|attempting to
> > > open lockfile, code returned true
> > > 2012-08-23 14:22:39|13299|5:5|reload|Module.pm:get_semaphore(977)|created
> > > 'vclhv01-/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0' Semaphore
> > > object, memory address: 6402830
> > > 2012-08-23
> > > 14:22:39|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vmwarewin7\-win7_x86_base23\-v0/vmwarewin7\-win7_x86_base23\-v0\.vmdk'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| File:
> > > "/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk"
> > > |13299|5:5|reload| Size: 565 Blocks: 128 IO Block: 131072
> > > regular file
> > > |13299|5:5|reload| Device: 31c76cacbda6e9dbh/3586955117395896795d
> > > Inode: 83887364 Links: 1
> > > |13299|5:5|reload| Access: (0600/-rw-------) Uid: ( 0/ root) Gid: (
> > > 0/ root)
> > > |13299|5:5|reload| Access: 2012-08-23 18:08:23.000000000
> > > |13299|5:5|reload| Modify: 2012-08-23 18:08:23.000000000
> > > |13299|5:5|reload| Change: 2012-08-23 17:57:53.000000000
> > > 2012-08-23 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "File:
> > > "/vmfs/volumes/datastore...")
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|Linux.pm:file_exists(1356)|'/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk'
> > > exists on vclhv01, files: 1, directories: 0, links: 0
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:prepare_vmdk(2108)|VM is not
> > > dedicated and shared vmdk file already exists on VM host vclhv01:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|Semaphore.pm:release_lockfile(280)|deleted file:
> > > /tmp/vclhv01-vmfs-volumes-datastore2-vmwarewin7-win7_x86_base23-v0.semaphore
> > > 2012-08-23 14:22:40|13299|5:5|reload|Semaphore.pm:DESTROY(377)|destroyed
> > > Semaphore object, memory address: 6402830
> > > 2012-08-23 14:22:40|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, transfervm, copied vmwarewin7-win7_x86_base23-v0 to
> > > vclvm0001-man0
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vclvm0001\-man0_3\-v0/vclvm0001\-man0_3\-v0\.vmx'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| stat: cannot stat
> > > '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx': No
> > > such file or directory
> > > 2012-08-23 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(5064)|SSH
> > > command executed on vclhv01, command:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vclvm0001\-man0_3\-v0/vclvm0001\-man0_3\-v0\.vmx'
> > > 2>&1
> > > |13299|5:5|reload| returning (1, "stat: cannot stat '/vmfs/volum...")
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vm_ram(5044)|image
> > > minimum RAM value (0 MB) is too low for the windows7-64 guest OS, adjusting
> > > to 2048 MB
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'stat
> > > /vmfs/volumes/datastore2/vmwarewin7\-win7_x86_base23\-v0/vmwarewin7\-win7_x86_base23\-v0\.vmx\.reference'
> > > 2>&1
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| File:
> > > "/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference"
> > > |13299|5:5|reload| Size: 3230 Blocks: 128 IO Block: 131072
> > > regular file
> > > |13299|5:5|reload| Device: 31c76cacbda6e9dbh/3586955117395896795d
> > > Inode: 88081668 Links: 1
> > > |13299|5:5|reload| Access: (0755/-rwxr-xr-x) Uid: ( 0/ root) Gid: (
> > > 0/ root)
> > > |13299|5:5|reload| Access: 2012-08-23 18:22:19.000000000
> > > |13299|5:5|reload| Modify: 2012-08-23 18:08:25.000000000
> > > |13299|5:5|reload| Change: 2012-08-23 18:08:25.000000000
> > > 2012-08-23 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "File:
> > > "/vmfs/volumes/datastore...")
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|Linux.pm:file_exists(1356)|'/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference'
> > > exists on vclhv01, files: 1, directories: 0, links: 0
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_reference_vmx_info(5456)|found
> > > reference vmx file in shared vmdk directory on VM host:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vmx_info(5302)|attempting
> > > to retrieve info from vmx file:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference
> > > 2012-08-23 14:22:40|13299|5:5|reload|OS.pm:get_file_contents(1816)|retrieved
> > > 95 lines from file on vclhv01:
> > > '/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference'
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vmx_info(5343)|vmx file
> > > does not contain a computer_id value, attempting to determine matching
> > > computer
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:_get_vmx_file_path_computer_name(7395)|computer
> > > name could not be determined from directory name:
> > > 'vmwarewin7-win7_x86_base23-v0'
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vmx_info(5357)|unable to
> > > determine computer name from vmx file path:
> > > '/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference'
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vmx_info(5371)|ignoring
> > > ide1:0, filename property does not end with .vmdk:
> > > /vmfs/volumes/be3112c9-7ee70531/en_windows_7_enterprise_with_sp1_x64_dvd_620201.iso
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vmx_info(5379)|scsi0:0
> > > mode property not set, setting default value: persistent
> > > 2012-08-23 14:22:40|13299|5:5|reload|VMware.pm:get_vmx_info(5388)|vmdk path
> > > appears to be relative: vclvm0001-man0.vmdk, prepending the vmx directory:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vclvm0001-man0.vmdk
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_reference_vmx_info(5488)|retrieved
> > > reference vmx info from file:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmx.reference
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vm_ethernet_adapter_type(4986)|retrieved
> > > VM ethernet adapter type from reference vmx file: e1000
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vmdk_base_directory_path_shared(3561)|using
> > > VM profile datastore path as the vmdk base directory path:
> > > /vmfs/volumes/datastore2
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vm_disk_adapter_type(4729)|retrieved
> > > VM disk adapter type from reference vmx file: lsisas1068
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vm_virtual_hardware_version(4788)|retrieved
> > > VM virtual hardware version from reference vmx file: 8
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|VMware.pm:get_vm_virtual_hardware_version(4791)|unable
> > > to retrieve VM virtual hardware version from reference vmx file,
> > > 'virtualHW.version' key does not exist
> > > 2012-08-23
> > > 14:22:40|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vmware
> > > -v' 2>&1
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| VMware ESXi 5.0.0 build-623860
> > > 2012-08-23 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "VMware ESXi 5.0.0 build-623860")
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|VMware.pm:get_vmhost_product_name(6621)|VMware
> > > product being used on VM host vclhv01: 'VMware ESXi 5.0.0 build-623860'
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|VMware.pm:get_vm_virtual_hardware_version(4838)|returning
> > > hardware version: 8
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vmware
> > > -v' 2>&1
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| VMware ESXi 5.0.0 build-623860
> > > 2012-08-23 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "VMware ESXi 5.0.0 build-623860")
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|VMware.pm:get_vmhost_product_name(6621)|VMware
> > > product being used on VM host vclhv01: 'VMware ESXi 5.0.0 build-623860'
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|VMware.pm:get_vm_cpu_configuration(5152)|VM CPU
> > > configuration:
> > > |13299|5:5|reload| sockets: 1
> > > |13299|5:5|reload| cores per socket: 1
> > > |13299|5:5|reload| total cores: 1
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'ls -d
> > > --color=never "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0" 2>&1 || mkdir
> > > -p "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0" 2>&1 && ls -d
> > > --color=never "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0"' 2>&1
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| ls: /vmfs/volumes/datastore2/vclvm0001-man0_3-v0: No such
> > > file or directory
> > > |13299|5:5|reload| /vmfs/volumes/datastore2/vclvm0001-man0_3-v0
> > > 2012-08-23 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "ls:
> > > /vmfs/volumes/datastore2/v...")
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|Linux.pm:create_directory(1476)|directory created
> > > on vclhv01: '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0'
> > > 2012-08-23 14:22:41|13299|5:5|reload|VMware.pm:prepare_vmx(1719)|vm info:
> > > |13299|5:5|reload| display name:
> > > vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)
> > > |13299|5:5|reload| vmx file path:
> > > /vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx
> > > |13299|5:5|reload| vmdk file path:
> > > /vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|VMware.pm:get_vm_ethernet_adapter_type(4986)|retrieved
> > > VM ethernet adapter type from reference vmx file: e1000
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|VMware.pm:get_vm_ethernet_adapter_type(4986)|retrieved
> > > VM ethernet adapter type from reference vmx file: e1000
> > > 2012-08-23 14:22:41|13299|5:5|reload|VMware.pm:prepare_vmx(1918)|image
> > > project is: vcl, additional network adapters will not be configured
> > > 2012-08-23 14:22:41|13299|5:5|reload|VMware.pm:prepare_vmx(1921)|vmx
> > > parameters:
> > > |13299|5:5|reload| : {
> > > |13299|5:5|reload| : "#computer_id" => 5,
> > > |13299|5:5|reload| : "#image_id" => 3,
> > > |13299|5:5|reload| : "#imagerevision_id" => 3,
> > > |13299|5:5|reload| : ".encoding" => "UTF-8",
> > > |13299|5:5|reload| : "config.version" => 8,
> > > |13299|5:5|reload| : "cpuid.coresPerSocket" => 1,
> > > |13299|5:5|reload| : "displayName" =>
> > > "vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)",
> > > |13299|5:5|reload| : "ethernet0.address" => "00:50:56:00:00:01",
> > > |13299|5:5|reload| : "ethernet0.addressType" => "static",
> > > |13299|5:5|reload| : "ethernet0.connectionType" => "custom",
> > > |13299|5:5|reload| : "ethernet0.networkName" => "Private",
> > > |13299|5:5|reload| : "ethernet0.present" => "TRUE",
> > > |13299|5:5|reload| : "ethernet0.virtualDev" => "e1000",
> > > |13299|5:5|reload| : "ethernet1.address" => "00:50:56:00:00:02",
> > > |13299|5:5|reload| : "ethernet1.addressType" => "static",
> > > |13299|5:5|reload| : "ethernet1.connectionType" => "custom",
> > > |13299|5:5|reload| : "ethernet1.networkName" => "Public",
> > > |13299|5:5|reload| : "ethernet1.present" => "TRUE",
> > > |13299|5:5|reload| : "ethernet1.virtualDev" => "e1000",
> > > |13299|5:5|reload| : "floppy0.present" => "FALSE",
> > > |13299|5:5|reload| : "guestOS" => "windows7-64",
> > > |13299|5:5|reload| : "gui.exitOnCLIHLT" => "TRUE",
> > > |13299|5:5|reload| : "memsize" => 2048,
> > > |13299|5:5|reload| : "msg.autoAnswer" => "TRUE",
> > > |13299|5:5|reload| : "numvcpus" => 1,
> > > |13299|5:5|reload| : "pciBridge0.present" => "TRUE",
> > > |13299|5:5|reload| : "pciBridge4.functions" => 8,
> > > |13299|5:5|reload| : "pciBridge4.present" => "TRUE",
> > > |13299|5:5|reload| : "pciBridge4.virtualDev" => "pcieRootPort",
> > > |13299|5:5|reload| : "pciBridge5.functions" => 8,
> > > |13299|5:5|reload| : "pciBridge5.present" => "TRUE",
> > > |13299|5:5|reload| : "pciBridge5.virtualDev" => "pcieRootPort",
> > > |13299|5:5|reload| : "pciBridge6.functions" => 8,
> > > |13299|5:5|reload| : "pciBridge6.present" => "TRUE",
> > > |13299|5:5|reload| : "pciBridge6.virtualDev" => "pcieRootPort",
> > > |13299|5:5|reload| : "pciBridge7.functions" => 8,
> > > |13299|5:5|reload| : "pciBridge7.present" => "TRUE",
> > > |13299|5:5|reload| : "pciBridge7.virtualDev" => "pcieRootPort",
> > > |13299|5:5|reload| : "powerType.powerOff" => "soft",
> > > |13299|5:5|reload| : "powerType.powerOn" => "hard",
> > > |13299|5:5|reload| : "powerType.reset" => "soft",
> > > |13299|5:5|reload| : "powerType.suspend" => "hard",
> > > |13299|5:5|reload| : "sched.swap.dir" =>
> > > "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/",
> > > |13299|5:5|reload| : "scsi0.present" => "TRUE",
> > > |13299|5:5|reload| : "scsi0.virtualDev" => "lsisas1068",
> > > |13299|5:5|reload| : "scsi0:0.fileName" =>
> > > "/vmfs/volumes/datastore2/vmwarewin7-win7_x86_base23-v0/vmwarewin7-win7_x86_base23-v0.vmdk",
> > > |13299|5:5|reload| : "scsi0:0.mode" => "persistent",
> > > |13299|5:5|reload| : "scsi0:0.present" => "TRUE",
> > > |13299|5:5|reload| : "scsi0:0.sharedBus" => "none",
> > > |13299|5:5|reload| : "scsi0:0.writeThrough" => "TRUE",
> > > |13299|5:5|reload| : "snapshot.redoNotWithParent" => "TRUE",
> > > |13299|5:5|reload| : "svga.autodetect" => "TRUE",
> > > |13299|5:5|reload| : "toolScripts.afterPowerOn" => "FALSE",
> > > |13299|5:5|reload| : "toolScripts.afterResume" => "FALSE",
> > > |13299|5:5|reload| : "toolScripts.beforePowerOff" => "FALSE",
> > > |13299|5:5|reload| : "toolScripts.beforeSuspend" => "FALSE",
> > > |13299|5:5|reload| : "tools.remindInstall" => "FALSE",
> > > |13299|5:5|reload| : "tools.syncTime" => "FALSE",
> > > |13299|5:5|reload| : "usb.present" => "TRUE",
> > > |13299|5:5|reload| : "uuid.action" => "keep",
> > > |13299|5:5|reload| : "virtualHW.version" => 8,
> > > |13299|5:5|reload| : "vmci0.present" => "TRUE",
> > > |13299|5:5|reload| : "workingDir" =>
> > > "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0"
> > > |13299|5:5|reload| : }
> > > 2012-08-23 14:22:41|13299|5:5|reload|VMware.pm:prepare_vmx(1932)|created
> > > temporary vmx file: /tmp/vclvm0001-man0_3-v0.vmx
> > > 2012-08-23
> > > 14:22:41|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'ls -d
> > > --color=never "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0" 2>&1 || mkdir
> > > -p "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0" 2>&1 && ls -d
> > > --color=never "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0"' 2>&1
> > > 2012-08-23
> > > 14:22:42|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| /vmfs/volumes/datastore2/vclvm0001-man0_3-v0
> > > |13299|5:5|reload| /vmfs/volumes/datastore2/vclvm0001-man0_3-v0
> > > 2012-08-23 14:22:42|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0,
> > > "/vmfs/volumes/datastore2/vclvm...")
> > > 2012-08-23
> > > 14:22:42|13299|5:5|reload|Linux.pm:create_directory(1480)|directory already
> > > exists on vclhv01: '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0'
> > > 2012-08-23
> > > 14:22:42|13299|5:5|reload|utils.pm:run_scp_command(5194)|attempting to copy
> > > file via SCP: '/tmp/vclvm0001-man0_3-v0.vmx' -->
> > > 'vclhv01:"/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx"'
> > > 2012-08-23 14:22:42|13299|5:5|reload|utils.pm:run_scp_command(5230)|copied
> > > file via SCP: '/tmp/vclvm0001-man0_3-v0.vmx' -->
> > > 'vclhv01:"/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx"'
> > > 2012-08-23 14:22:42|13299|5:5|reload|OS.pm:copy_file_to(2747)|copied file
> > > from management node to vclhv01: '/tmp/vclvm0001-man0_3-v0.vmx' -->
> > > vclhv01:'/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx'
> > > 2012-08-23 14:22:42|13299|5:5|reload|VMware.pm:prepare_vmx(1944)|created vmx
> > > file on VM host:
> > > /vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx
> > > 2012-08-23 14:22:42|13299|5:5|reload|VMware.pm:prepare_vmx(1947)|deleted
> > > temporary vmx file: /tmp/vclvm0001-man0_3-v0.vmx
> > > 2012-08-23 14:22:42|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, vmsetupconfig, prepared vmx file
> > > 2012-08-23
> > > 14:22:42|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:43|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:43|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:43|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:43|13299|5:5|reload|VIM_SSH.pm:get_registered_vms(835)|found 1
> > > registered VMs
> > > 2012-08-23 14:22:43|13299|5:5|reload|VMware.pm:is_vm_registered(4416)|VM is
> > > not registered:
> > > '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx'
> > > 2012-08-23
> > > 14:22:43|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > solo/registervm
> > > "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx"' 2>&1
> > > 2012-08-23
> > > 14:22:44|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| 13
> > > 2012-08-23 14:22:44|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "13")
> > > 2012-08-23 14:22:44|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd solo/registervm
> > > "/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx"
> > > 2012-08-23
> > > 14:22:44|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:44|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > |13299|5:5|reload| 13 vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)
> > > [datastore2] vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:44|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:44|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:44|13299|5:5|reload|VIM_SSH.pm:get_registered_vms(835)|found 2
> > > registered VMs
> > > 2012-08-23 14:22:44|13299|5:5|reload|VMware.pm:is_vm_registered(4411)|VM is
> > > registered:
> > > /vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx
> > > 2012-08-23 14:22:44|13299|5:5|reload|VIM_SSH.pm:vm_register(1112)|registered
> > > VM: '/vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx'
> > > 2012-08-23
> > > 14:22:44|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:45|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > |13299|5:5|reload| 13 vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)
> > > [datastore2] vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:45|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:45|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:45|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/snapshot.create 13 'register'' 2>&1
> > > 2012-08-23
> > > 14:22:47|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Create Snapshot:
> > > 2012-08-23 14:22:47|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Create Snapshot:")
> > > 2012-08-23 14:22:47|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/snapshot.create 13 'register'
> > > 2012-08-23 14:22:47|13299|5:5|reload|VIM_SSH.pm:create_snapshot(1711)|create
> > > snapshot output:
> > > |13299|5:5|reload| Create Snapshot:
> > > 2012-08-23
> > > 14:22:47|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:47|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > |13299|5:5|reload| 13 vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)
> > > [datastore2] vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:47|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:47|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:47|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/get.tasklist 13' 2>&1
> > > 2012-08-23
> > > 14:22:48|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| (ManagedObjectReference) [
> > > |13299|5:5|reload|
> > > 'vim.Task:haTask-13-vim.VirtualMachine.createSnapshot-28813'
> > > |13299|5:5|reload| ]
> > > 2012-08-23 14:22:48|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "(ManagedObjectReference) [
> > > 'vi...")
> > > 2012-08-23 14:22:48|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/get.tasklist 13
> > > 2012-08-23 14:22:48|13299|5:5|reload|VIM_SSH.pm:_wait_for_task(748)|checking
> > > status of task: haTask-13-vim.VirtualMachine.createSnapshot-28813
> > > 2012-08-23
> > > 14:22:48|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vimsvc/task_info haTask-13-vim.VirtualMachine.createSnapshot-28813' 2>&1
> > > 2012-08-23
> > > 14:22:49|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| (vim.TaskInfo) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| key =
> > > "haTask-13-vim.VirtualMachine.createSnapshot-28813",
> > > |13299|5:5|reload| task =
> > > 'vim.Task:haTask-13-vim.VirtualMachine.createSnapshot-28813',
> > > |13299|5:5|reload| description = (vmodl.LocalizableMessage) null,
> > > |13299|5:5|reload| name = "vim.VirtualMachine.createSnapshot",
> > > |13299|5:5|reload| descriptionId = "VirtualMachine.createSnapshot",
> > > |13299|5:5|reload| entity = 'vim.VirtualMachine:13',
> > > |13299|5:5|reload| entityName =
> > > "vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)",
> > > |13299|5:5|reload| state = "success",
> > > |13299|5:5|reload| cancelled = false,
> > > |13299|5:5|reload| cancelable = false,
> > > |13299|5:5|reload| error = (vmodl.MethodFault) null,
> > > |13299|5:5|reload| result = 'vim.vm.Snapshot:13-snapshot-1',
> > > |13299|5:5|reload| progress = 100,
> > > |13299|5:5|reload| reason = (vim.TaskReasonUser) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| userName = "root",
> > > |13299|5:5|reload| },
> > > |13299|5:5|reload| queueTime = "2012-08-23T18:22:45.9828Z",
> > > |13299|5:5|reload| startTime = "2012-08-23T18:22:45.983315Z",
> > > |13299|5:5|reload| completeTime = "2012-08-23T18:22:47.10892Z",
> > > |13299|5:5|reload| eventChainId = 28813,
> > > |13299|5:5|reload| changeTag = <unset>,
> > > |13299|5:5|reload| parentTaskKey = <unset>,
> > > |13299|5:5|reload| rootTaskKey = <unset>,
> > > |13299|5:5|reload| }
> > > 2012-08-23 14:22:49|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "(vim.TaskInfo) { dynamicType
> > > =...")
> > > 2012-08-23 14:22:49|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vimsvc/task_info
> > > haTask-13-vim.VirtualMachine.createSnapshot-28813
> > > 2012-08-23 14:22:49|13299|5:5|reload|VIM_SSH.pm:_wait_for_task(771)|task
> > > completed successfully: haTask-13-vim.VirtualMachine.createSnapshot-28813
> > > 2012-08-23
> > > 14:22:49|13299|5:5|reload|VIM_SSH.pm:create_snapshot(1727)|created snapshot
> > > of VM: /vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx,
> > > snapshot name: register
> > > 2012-08-23
> > > 14:22:49|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:49|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > |13299|5:5|reload| 13 vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)
> > > [datastore2] vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:49|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:49|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:49|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/power.on 13' 2>&1
> > > 2012-08-23
> > > 14:22:51|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Powering on VM:
> > > 2012-08-23 14:22:51|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Powering on VM:")
> > > 2012-08-23 14:22:51|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/power.on 13
> > > 2012-08-23
> > > 14:22:51|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/getallvms' 2>&1
> > > 2012-08-23
> > > 14:22:51|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| Vmid Name File Guest OS Version Annotation
> > > |13299|5:5|reload| 11 vclvm0009-man0:vmwarewin7-win7_x64_base2-v0 (shared)
> > > [datastore2] vclvm0009-man0_2-v0/vclvm0009-man0_2-v0.vmx windows7_64Guest
> > > vmx-08
> > > |13299|5:5|reload| 13 vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)
> > > [datastore2] vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx windows7_64Guest
> > > vmx-08
> > > 2012-08-23 14:22:51|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "Vmid Name File Guest OS
> > > Versio...")
> > > 2012-08-23 14:22:51|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/getallvms
> > > 2012-08-23
> > > 14:22:51|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vmsvc/get.tasklist 13' 2>&1
> > > 2012-08-23
> > > 14:22:52|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| (ManagedObjectReference) [
> > > |13299|5:5|reload|
> > > 'vim.Task:haTask-13-vim.VirtualMachine.createSnapshot-28813',
> > > |13299|5:5|reload| 'vim.Task:haTask-13-vim.VirtualMachine.powerOn-28824'
> > > |13299|5:5|reload| ]
> > > 2012-08-23 14:22:52|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "(ManagedObjectReference) [
> > > 'vi...")
> > > 2012-08-23 14:22:52|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vmsvc/get.tasklist 13
> > > 2012-08-23 14:22:52|13299|5:5|reload|VIM_SSH.pm:_wait_for_task(748)|checking
> > > status of task: haTask-13-vim.VirtualMachine.powerOn-28824
> > > 2012-08-23
> > > 14:22:52|13299|5:5|reload|utils.pm:run_ssh_command(4936)|executing SSH
> > > command on vclhv01:
> > > |13299|5:5|reload| /usr/bin/ssh -i /etc/vcl/vcl.key -o
> > > StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
> > > ConnectionAttempts=1 -o ConnectTimeout=10 -l root -p 22 -x vclhv01 'vim-cmd
> > > vimsvc/task_info haTask-13-vim.VirtualMachine.powerOn-28824' 2>&1
> > > 2012-08-23
> > > 14:22:53|13299|5:5|reload|utils.pm:run_ssh_command(5054)|run_ssh_command
> > > output:
> > > |13299|5:5|reload| (vim.TaskInfo) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| key = "haTask-13-vim.VirtualMachine.powerOn-28824",
> > > |13299|5:5|reload| task =
> > > 'vim.Task:haTask-13-vim.VirtualMachine.powerOn-28824',
> > > |13299|5:5|reload| description = (vmodl.LocalizableMessage) null,
> > > |13299|5:5|reload| name = "vim.VirtualMachine.powerOn",
> > > |13299|5:5|reload| descriptionId = "VirtualMachine.powerOn",
> > > |13299|5:5|reload| entity = 'vim.VirtualMachine:13',
> > > |13299|5:5|reload| entityName =
> > > "vclvm0001-man0:vmwarewin7-win7_x86_base23-v0 (shared)",
> > > |13299|5:5|reload| state = "success",
> > > |13299|5:5|reload| cancelled = false,
> > > |13299|5:5|reload| cancelable = false,
> > > |13299|5:5|reload| error = (vmodl.MethodFault) null,
> > > |13299|5:5|reload| result = <unset>,
> > > |13299|5:5|reload| progress = 100,
> > > |13299|5:5|reload| reason = (vim.TaskReasonUser) {
> > > |13299|5:5|reload| dynamicType = <unset>,
> > > |13299|5:5|reload| userName = "root",
> > > |13299|5:5|reload| },
> > > |13299|5:5|reload| queueTime = "2012-08-23T18:22:50.35271Z",
> > > |13299|5:5|reload| startTime = "2012-08-23T18:22:50.353309Z",
> > > |13299|5:5|reload| completeTime = "2012-08-23T18:22:51.171186Z",
> > > |13299|5:5|reload| eventChainId = 28824,
> > > |13299|5:5|reload| changeTag = <unset>,
> > > |13299|5:5|reload| parentTaskKey = <unset>,
> > > |13299|5:5|reload| rootTaskKey = <unset>,
> > > |13299|5:5|reload| }
> > > 2012-08-23 14:22:53|13299|5:5|reload|utils.pm:run_ssh_command(5068)|SSH
> > > command executed on vclhv01, returning (0, "(vim.TaskInfo) { dynamicType
> > > =...")
> > > 2012-08-23 14:22:53|13299|5:5|reload|VIM_SSH.pm:_run_vim_cmd(208)|executed
> > > command on VM host vclhv01: vim-cmd vimsvc/task_info
> > > haTask-13-vim.VirtualMachine.powerOn-28824
> > > 2012-08-23 14:22:53|13299|5:5|reload|VIM_SSH.pm:_wait_for_task(771)|task
> > > completed successfully: haTask-13-vim.VirtualMachine.powerOn-28824
> > > 2012-08-23 14:22:53|13299|5:5|reload|VIM_SSH.pm:vm_power_on(970)|powered on
> > > VM: /vmfs/volumes/datastore2/vclvm0001-man0_3-v0/vclvm0001-man0_3-v0.vmx
> > > 2012-08-23 14:22:53|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, startvm, registered and powered on vclvm0001-man0
> > > 2012-08-23 14:22:53|13299|5:5|reload|Version_6.pm:post_load(185)|beginning
> > > Windows version 6 post-load tasks
> > > 2012-08-23 14:22:53|13299|5:5|reload|Version_6.pm:post_load(193)|calling
> > > parent class post_load() subroutine
> > > 2012-08-23 14:22:53|13299|5:5|reload|Windows.pm:post_load(620)|beginning
> > > Windows post-load tasks on vclvm0001-man0
> > > 2012-08-23
> > > 14:22:53|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23 14:22:53|13299|5:5|reload|OS.pm:wait_for_response(696)|waiting 15
> > > seconds for vclvm0001-man0 to boot
> > > 2012-08-23 14:23:08|13299|5:5|reload|OS.pm:wait_for_response(698)|waited 15
> > > seconds for vclvm0001-man0 to boot
> > > 2012-08-23 14:23:08|13299|5:5|reload|OS.pm:wait_for_response(702)|waiting
> > > for vclvm0001-man0 to respond to SSH, maximum of 600 seconds
> > > 2012-08-23
> > > 14:23:08|13299|5:5|reload|Module.pm:code_loop_timeout(879)|waiting for
> > > vclvm0001-man0 to respond to SSH, maximum of 600 seconds
> > > 2012-08-23
> > > 14:23:09|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:23:09|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 1:
> > > waiting for vclvm0001-man0 to respond to SSH (1/599 seconds) sleeping for 15
> > > seconds
> > > 2012-08-23
> > > 14:23:24|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:23:24|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 2:
> > > waiting for vclvm0001-man0 to respond to SSH (16/584 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:23:40|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:23:40|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 3:
> > > waiting for vclvm0001-man0 to respond to SSH (32/568 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:23:56|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:23:56|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 4:
> > > waiting for vclvm0001-man0 to respond to SSH (48/552 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:24:11|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:24:11|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 5:
> > > waiting for vclvm0001-man0 to respond to SSH (63/537 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:24:27|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:24:27|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 6:
> > > waiting for vclvm0001-man0 to respond to SSH (79/521 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:24:42|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:24:42|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 7:
> > > waiting for vclvm0001-man0 to respond to SSH (94/506 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:24:58|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:24:58|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 8:
> > > waiting for vclvm0001-man0 to respond to SSH (110/490 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:25:13|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:25:13|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 9:
> > > waiting for vclvm0001-man0 to respond to SSH (125/475 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:25:29|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:25:29|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 10:
> > > waiting for vclvm0001-man0 to respond to SSH (141/459 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:25:45|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:25:45|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 11:
> > > waiting for vclvm0001-man0 to respond to SSH (157/443 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:26:00|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:26:00|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 12:
> > > waiting for vclvm0001-man0 to respond to SSH (172/428 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:26:16|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:26:16|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 13:
> > > waiting for vclvm0001-man0 to respond to SSH (188/412 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:26:31|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:26:31|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 14:
> > > waiting for vclvm0001-man0 to respond to SSH (203/397 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:26:47|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:26:47|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 15:
> > > waiting for vclvm0001-man0 to respond to SSH (219/381 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:27:02|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:27:02|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 16:
> > > waiting for vclvm0001-man0 to respond to SSH (234/366 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:27:18|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:27:18|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 17:
> > > waiting for vclvm0001-man0 to respond to SSH (250/350 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:27:34|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:27:34|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 18:
> > > waiting for vclvm0001-man0 to respond to SSH (266/334 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:27:49|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:27:49|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 19:
> > > waiting for vclvm0001-man0 to respond to SSH (281/319 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:28:05|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:28:05|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 20:
> > > waiting for vclvm0001-man0 to respond to SSH (297/303 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:28:20|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:28:20|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 21:
> > > waiting for vclvm0001-man0 to respond to SSH (312/288 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:28:36|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:28:36|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 22:
> > > waiting for vclvm0001-man0 to respond to SSH (328/272 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:28:51|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:28:51|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 23:
> > > waiting for vclvm0001-man0 to respond to SSH (343/257 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:29:07|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:29:07|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 24:
> > > waiting for vclvm0001-man0 to respond to SSH (359/241 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:29:23|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:29:23|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 25:
> > > waiting for vclvm0001-man0 to respond to SSH (375/225 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:29:38|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:29:38|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 26:
> > > waiting for vclvm0001-man0 to respond to SSH (390/210 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:29:54|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:29:54|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 27:
> > > waiting for vclvm0001-man0 to respond to SSH (406/194 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:30:09|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:30:09|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 28:
> > > waiting for vclvm0001-man0 to respond to SSH (421/179 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:30:25|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:30:25|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 29:
> > > waiting for vclvm0001-man0 to respond to SSH (437/163 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:30:41|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:30:41|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 30:
> > > waiting for vclvm0001-man0 to respond to SSH (453/147 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:30:56|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:30:56|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 31:
> > > waiting for vclvm0001-man0 to respond to SSH (468/132 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:31:12|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:31:12|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 32:
> > > waiting for vclvm0001-man0 to respond to SSH (484/116 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:31:27|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:31:27|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 33:
> > > waiting for vclvm0001-man0 to respond to SSH (499/101 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:31:43|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:31:43|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 34:
> > > waiting for vclvm0001-man0 to respond to SSH (515/85 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:31:58|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:31:58|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 35:
> > > waiting for vclvm0001-man0 to respond to SSH (530/70 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:32:14|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:32:14|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 36:
> > > waiting for vclvm0001-man0 to respond to SSH (546/54 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:32:30|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:32:30|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 37:
> > > waiting for vclvm0001-man0 to respond to SSH (562/38 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:32:45|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:32:45|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 38:
> > > waiting for vclvm0001-man0 to respond to SSH (577/23 seconds) sleeping for
> > > 15 seconds
> > > 2012-08-23
> > > 14:33:01|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:33:01|13299|5:5|reload|Module.pm:code_loop_timeout(892)|attempt 39:
> > > waiting for vclvm0001-man0 to respond to SSH (593/7 seconds) sleeping for 15
> > > seconds
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|OS.pm:is_ssh_responding(615)|vclvm0001-man0 is NOT
> > > responding to SSH, ports 22 or 24 are both closed
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|Module.pm:code_loop_timeout(904)|waiting for
> > > vclvm0001-man0 to respond to SSH, code did not return true after waiting 600
> > > seconds
> > > |13299|5:5|reload| ---- WARNING ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|OS.pm:wait_for_response(704)|failed to connect to
> > > vclvm0001-man0 via SSH after 600 seconds
> > > |13299|5:5|reload| ( 0) OS.pm, wait_for_response (line: 704)
> > > |13299|5:5|reload| (-1) Version_6.pm, wait_for_response (line: 2078)
> > > |13299|5:5|reload| (-2) Windows.pm, post_load (line: 628)
> > > |13299|5:5|reload| (-3) Version_6.pm, post_load (line: 194)
> > > |13299|5:5|reload| (-4) VMware.pm, load (line: 529)
> > > |13299|5:5|reload| (-5) new.pm, reload_image (line: 665)
> > > |13299|5:5|reload| ---- WARNING ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|Windows.pm:post_load(629)|vclvm0001-man0 never
> > > responded to SSH
> > > |13299|5:5|reload| ( 0) Windows.pm, post_load (line: 629)
> > > |13299|5:5|reload| (-1) Version_6.pm, post_load (line: 194)
> > > |13299|5:5|reload| (-2) VMware.pm, load (line: 529)
> > > |13299|5:5|reload| (-3) new.pm, reload_image (line: 665)
> > > |13299|5:5|reload| (-4) new.pm, process (line: 291)
> > > |13299|5:5|reload| (-5) vcld, make_new_child (line: 571)
> > > |13299|5:5|reload| ---- WARNING ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|Version_6.pm:post_load(198)|failed to execute
> > > parent class post_load() subroutine
> > > |13299|5:5|reload| ( 0) Version_6.pm, post_load (line: 198)
> > > |13299|5:5|reload| (-1) VMware.pm, load (line: 529)
> > > |13299|5:5|reload| (-2) new.pm, reload_image (line: 665)
> > > |13299|5:5|reload| (-3) new.pm, process (line: 291)
> > > |13299|5:5|reload| (-4) vcld, make_new_child (line: 571)
> > > |13299|5:5|reload| (-5) vcld, main (line: 350)
> > > |13299|5:5|reload| ---- WARNING ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|VMware.pm:load(533)|failed to perform OS post-load
> > > tasks on VM vclvm0001-man0 on VM host: vclhv01
> > > |13299|5:5|reload| ( 0) VMware.pm, load (line: 533)
> > > |13299|5:5|reload| (-1) new.pm, reload_image (line: 665)
> > > |13299|5:5|reload| (-2) new.pm, process (line: 291)
> > > |13299|5:5|reload| (-3) vcld, make_new_child (line: 571)
> > > |13299|5:5|reload| (-4) vcld, main (line: 350)
> > > |13299|5:5|reload| ---- WARNING ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|new.pm:reload_image(670)|vmwarewin7-win7_x86_base23-v0
> > > failed to load on vclvm0001-man0, returning
> > > |13299|5:5|reload| ( 0) new.pm, reload_image (line: 670)
> > > |13299|5:5|reload| (-1) new.pm, process (line: 291)
> > > |13299|5:5|reload| (-2) vcld, make_new_child (line: 571)
> > > |13299|5:5|reload| (-3) vcld, main (line: 350)
> > > 2012-08-23 14:33:16|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, loadimagefailed, vmwarewin7-win7_x86_base23-v0 failed to load on
> > > vclvm0001-man0
> > > |13299|5:5|reload| ---- WARNING ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|new.pm:process(338)|failed to load vclvm0001-man0
> > > with vmwarewin7-win7_x86_base23-v0
> > > |13299|5:5|reload| ( 0) new.pm, process (line: 338)
> > > |13299|5:5|reload| (-1) vcld, make_new_child (line: 571)
> > > |13299|5:5|reload| (-2) vcld, main (line: 350)
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2433)|attempting
> > > to retrieve current state of computer vclvm0001-man0 from the database
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:get_computer_state_name(2464)|retrieved
> > > current state of computer vclvm0001-man0 from the database: reloading
> > > 2012-08-23 14:33:16|13299|5:5|reload|DataStructure.pm:_automethod(836)|data
> > > structure updated, hash path:
> > > $self->request_data->{reservation}{5}{computer}{state}{name}, data
> > > identifier: computer_state_name, data:
> > > |13299|5:5|reload| : "reloading"
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|utils.pm:get_management_node_info(5494)|retrieving
> > > current management node info for 'localhost' from database, cached data is
> > > stale: 647 seconds old
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|utils.pm:get_management_node_info(5641)|retrieved
> > > management node info: 'localhost' (localhost)
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:get_computer_private_ip_address(1630)|attempting
> > > to retrieve private IP address for computer: vclvm0001-man0
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:get_computer_private_ip_address(1634)|retrieved
> > > contents of /etc/hosts on this management node, contains 120 lines
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:get_computer_private_ip_address(1694)|returning
> > > IP address from /etc/hosts file: 10.128.64.100
> > > 2012-08-23 14:33:16|13299|5:5|reload|utils.pm:is_inblockrequest(5798)|zero
> > > rows were returned from database select
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:get_image_affiliation_name(2118)|image
> > > owner id: 1
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:retrieve_user_data(1401)|attempting
> > > to retrieve and store data for user: user.id = '1'
> > > 2012-08-23
> > > 14:33:16|13299|5:5|reload|DataStructure.pm:retrieve_user_data(1464)|data has
> > > been retrieved for user: admin (id: 1)
> > > 2012-08-23 14:33:17|13299|5:5|reload|utils.pm:mail(1266)|SUCCESS -- Sending
> > > mail To: root@vcl.palmetto.clemson.edu (mailto:root@vcl.palmetto.clemson.edu), PROBLEM --
> > > localhost|5:5|reload|State.pm|vclvm0001-man0>vclhv01|vmwarewin7-win7_x86_base23-v0|vclreload
> > > |13299|5:5|reload| ---- CRITICAL ----
> > > |13299|5:5|reload| 2012-08-23
> > > 14:33:16|13299|5:5|reload|State.pm:reservation_failed(240)|reservation
> > > failed on vclvm0001-man0: process failed after trying to load or make
> > > available
> > > |13299|5:5|reload| ( 0) State.pm, reservation_failed (line: 240)
> > > |13299|5:5|reload| (-1) new.pm, process (line: 341)
> > > |13299|5:5|reload| (-2) vcld, make_new_child (line: 571)
> > > |13299|5:5|reload| (-3) vcld, main (line: 350)
> > > 2012-08-23 14:33:17|13299|5:5|reload|utils.pm:insertloadlog(3703)|inserted
> > > computer=5, failed, process failed after trying to load or make available
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|State.pm:reservation_failed(243)|inserted
> > > computerloadlog entry
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|utils.pm:update_computer_state(1587)|computer 5
> > > state updated to: failed
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|State.pm:reservation_failed(262)|computer
> > > vclvm0001-man0 (5) state set to failed
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|utils.pm:update_request_state(1545)|request 5
> > > state updated to: failed, laststate to: image
> > > 2012-08-23 14:33:17|13299|5:5|reload|State.pm:reservation_failed(275)|set
> > > request state to 'failed'/'image'
> > > 2012-08-23 14:33:17|13299|5:5|reload|utils.pm:is_inblockrequest(5798)|zero
> > > rows were returned from database select
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|State.pm:reservation_failed(293)|vclvm0001-man0 is
> > > NOT in blockcomputers table
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|State.pm:reservation_failed(296)|exiting 1
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|utils.pm:delete_computerloadlog_reservation(6434)|removing
> > > computerloadlog entries matching loadstate = begin
> > > 2012-08-23
> > > 14:33:17|13299|5:5|reload|utils.pm:delete_computerloadlog_reservation(6481)|deleted
> > > rows from computerloadlog for reservation id=5
> > > 2012-08-23 14:33:17|13299|5:5|reload|State.pm:DESTROY(929)|VCL::new process
> > > duration: 648 seconds
> > > 2012-08-23 14:33:17|13299|5:5|reload|VIM_SSH.pm:DESTROY(2123)|vim-cmd call
> > > count: 16
> > > 2012-08-23 14:33:17|20492|5:5|failed|vcld:main(256)|request deleted
> > > 
> > > --
> > > 
> > > 
> > > will
> > 
> > 
> > --
> > Aaron Peeler
> > Program Manager
> > Virtual Computing Lab
> > NC State University
> > 
> > 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.
> 




Mime
View raw message