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 21:02:08 GMT
Will, 

>From the output below looks like you are using the same VLAN ID 832 for both networks.

Please take a look here -> https://cwiki.apache.org/confluence/display/VCL/Network+requirements
and here -> https://cwiki.apache.org/confluence/display/VCL/Network+Layout

These two docs explain network design.

Thanks.

--
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 13:55 , William Robinson wrote:

> thanks, dmitri. i think you have helped me see the issue. for the 'vm network 
> 0/1' settings, my previous thinking was that i had to use something specific to 
> the guest vm interfaces (such as interface name), not the vm host network name. 
> please bear with me as i try to confirm my new understanding. on the vm host, 
> there is:
> 
> vclhv01:~:# esxcfg-vswitch -l
> Switch Name Num Ports Used Ports Configured Ports MTU Uplinks
> vSwitch0 128 8 128 9000 vmnic0
> 
> PortGroup Name VLAN ID Used Ports Uplinks
> VM Network 832 4 vmnic0
> VCL Mgt 832 1 vmnic0
> Management Network 0 1 vmnic0
> 
> so my "vm network 0" should be "VCL Mgt" and "vm network 1" should be "VM 
> Network". is this correct, or am i still missing it?
> 
> will
> 
> 
> 
> On 08/29/2012 01:29 PM, Dmitri Chebotarov wrote:
> > Hi, Will
> > 
> > The network names are in VCL, Virtual Hosts->VM Host Profiles tab->Select a profile, click Configure Profile, then it's under VM Network 0 (this your private VCL net) and VM Network 1 (this is Public VCL net).
> > Networks listed in VM Network 0/1 fields should already exist on the ESXi host.
> > 
> > When you edit your reservation VM, click on Network adapter 1/2 and you should see on the right that Device Status is checked for 'Connected' and 'Connect at power on', also Network label should be present (Network adapter 1 should be in Private network and Network adapter 2 in Public). Port group names could be different in your case, but these need to match to values in VM host profile.
> > 
> > --
> > 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 13:18 , William Robinson wrote:
> > 
> > > i confirmed that the name under 'vms assigned to host' matches the network name
> > > (i think that is what you meant). i also cannot get the 'connect' check box to
> > > remain set. thanks!
> > > 
> > > will
> > > 
> > > On 08/29/2012 01:08 PM, Dmitri Chebotarov wrote:
> > > > 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