Hi,there
I’m setting up vcl environment recently and now stuck at capturing base
image which is a windows7 64bit. And I get an error like this:
pm:import_registry_string|2542|failed to echo registry string contents
to C:/Cygwin/tmp/disable_autoadminlogon_383.reg, exit status: 1, output:
|5114|7|7|image|Windows.pm:import_registry_string|2542| bash:
C:/Cygwin/tmp/disable_autoadminlogon_383.reg: No such file or directory
I think maybe I loss something in the setup but nothing found after I
almost pulled a half of my hair:( I use RHEL 6.6, ESXi 5.5 and VCL 2.4.2
Can anyone tell me what’s the wrong here and how to fix it? Here’s the
log info and I appreciate any help!
Thanks!
log info when capturing base image:
node info: 'localhost' (localhost)
============================================================================
VCL Management Node Daemon (vcld) | 2015-06-12 20:48:55
============================================================================
bin path: /usr/local/vcl-2.4.2/bin
config file: /etc/vcl/vcld.conf
log file: /var/log/vcld.log
pid file: /var/run/vcld.pid
daemon mode: 1
setup mode: 0
verbose mode: 1
============================================================================
Created VCL daemon process: 5049
2015-06-12 20:48:55|5049|||vcld|utils.pm:rename_vcld_process|6115|renamed
process to 'vcld'
2015-06-12 20:48:55|5049|||vcld|vcld:main|120|retrieved management node
information from database
2015-06-12 20:48:55|5049|||vcld|vcld:main|133|management_node_id environment
variable set: 1
2015-06-12 20:48:55|5049|||vcld|vcld:main|141|management node checkin interval
is 10 seconds
2015-06-12 20:48:55|5049|||vcld|vcld:main|142|vcld started on localhost
2015-06-12 20:48:55|5049|||vcld|vcld:main|164|vcld environment variable set to
1 for this process
2015-06-12 20:48:55|5005|||vcld|utils.pm:run_command|8945|executed command:
service vcld restart, exit status: 0, output:
|5005|||vcld|utils.pm:run_command|8945| Shutting down vcld daemon:
[60G[[0;32m OK [0;39m]
|5005|||vcld|utils.pm:run_command|8945| Starting vcld daemon: [60G[[0;32m OK
[0;39m]
|5005|||vcld|utils.pm:run_command|8945| [60G[[0;32m OK [0;39m]
2015-06-12 20:48:55|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:48:55
2015-06-12 20:49:05|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:49:05
2015-06-12 20:49:07|5005|||vcld|utils.pm:get_computer_ids|7947|1 computer was
found matching argument: 192.168.0.106, returning computer ID: 3
2015-06-12
20:49:07|5005|||vcld|utils.pm:get_production_imagerevision_info|3714|retrieved
info from database for production revision for image identifier '1', production
image: 'noimage'
2015-06-12 20:49:07|5005|||vcld|utils.pm:get_computer_info|7202|retrieved info
for computer: ESXi_host1 (2)
2015-06-12 20:49:07|5005|||vcld|utils.pm:get_vmhost_info|4042|retrieved VM host
1 info, computer: ESXi_host1
2015-06-12 20:49:07|5005|||vcld|utils.pm:get_computer_info|7202|retrieved info
for computer: WIN7_VM_1 (3)
2015-06-12
20:49:07|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300|retrieved
info for OSinstalltypes mapped to provisioning ID 7: : {
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| : 4 => {
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| : "id" =>
4,
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| : "name"
=> "vmware",
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| :
"provisioningOSinstalltype" => {
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| :
"OSinstalltypeid" => 4,
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| :
"provisioningid" => 7
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| : }
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| : }
|5005|||vcld|utils.pm:get_provisioning_osinstalltype_info|13300| : }
2015-06-12
20:49:07|5005|||vcld|utils.pm:get_computer_current_private_ip_address|13100|retrieved
private IP address for computer WIN7_VM_1 (3) from database: 192.168.0.106
2015-06-12
20:49:07|5005|||vcld|utils.pm:determine_remote_connection_target|13384|private
IP address is set in database for WIN7_VM_1, it will be used as the remote
connection target: 192.168.0.106
2015-06-12 20:49:07|5005|||vcld|utils.pm:nmap_port|2167|port 22 is open on
192.168.0.106 (WIN7_VM_1)
2015-06-12 20:49:07|5005|||vcld|utils.pm:nmap_port|2167|port 24 is open on
192.168.0.106 (WIN7_VM_1)
2015-06-12 20:49:07|5005|||vcld|utils.pm:(eval)|4241|waiting up to 30 seconds
for SSH process to finish
2015-06-12 20:49:08|5005|||vcld|utils.pm:get_request_by_computerid|5189|3 is
not assigned to any reservations
2015-06-12 20:49:08|5005|||vcld|utils.pm:setup_get_array_choice|10258|choices
argument:
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Generic Linux (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| OSX Snow Leopard (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Ubuntu (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows 2003 Server (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows 7 (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows 8.x (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows Server 2008 (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows Server 2012 (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows Vista (VMware)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows XP (VMware ESX)
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Windows XP (VMware)
2015-06-12 20:49:13|5005|||vcld|utils.pm:setup_get_array_choice|10258|choices
argument:
|5005|||vcld|utils.pm:setup_get_array_choice|10258| x86
|5005|||vcld|utils.pm:setup_get_array_choice|10258| x86_64
2015-06-12 20:49:14|5005|||vcld|utils.pm:setup_get_array_choice|10258|choices
argument:
|5005|||vcld|utils.pm:setup_get_array_choice|10258| Yes
|5005|||vcld|utils.pm:setup_get_array_choice|10258| No
2015-06-12 20:49:15|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:49:15
2015-06-12 20:49:22|5005|||vcld|utils.pm:xmlrpc_call|9190|RPC::XML version
supports useragent options, setting verify_hostname to 0
2015-06-12 20:49:22|5005|||vcld|utils.pm:xmlrpc_call|9202|created RPC::XML
client object:
|5005|||vcld|utils.pm:xmlrpc_call|9202| URL:
https://localhost/vcl/index.php?mode=xmlrpccall
|5005|||vcld|utils.pm:xmlrpc_call|9202| username: vclsystem@Local
2015-06-12 20:49:23|5005|||vcld|utils.pm:xmlrpc_call|9247|called
RPC::XML::Client::send_request:
|5005|||vcld|utils.pm:xmlrpc_call|9247| arguments:
XMLRPCfinishBaseImageCapture, 1, 20, 1
|5005|||vcld|utils.pm:xmlrpc_call|9247| response value:
|5005|||vcld|utils.pm:xmlrpc_call|9247| : {
|5005|||vcld|utils.pm:xmlrpc_call|9247| : "status" => "success"
|5005|||vcld|utils.pm:xmlrpc_call|9247| : }
2015-06-12 20:49:23|5005|||vcld|utils.pm:insert_request|8052|inserted new
image/image request into request table, request id=7
2015-06-12 20:49:23|5005|||vcld|utils.pm:insert_request|8083|inserted new
reservation for request 7: 7
2015-06-12 20:49:25|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:49:25
2015-06-12 20:49:25|5049|7|7|image|utils.pm:run_command|8945|executed command:
ps -e -o pid,args | grep -P "vcld .\|[0-9]+\|[0-9]+\|7\|", exit status: 1,
output:
2015-06-12
20:49:25|5049|7|7|image|utils.pm:is_management_node_process_running|9358|process
is NOT running, identifier: 'vcld .\|[0-9]+\|[0-9]+\|7\|'
2015-06-12
20:49:25|5049|7|7|image|utils.pm:reservation_being_processed|8904|reservation 7
is NOT currently being processed
|5049|7|7|image|utils.pm:reservation_being_processed|8904| reservation ID: 7
|5049|7|7|image|utils.pm:reservation_being_processed|8904| parent reservation
ID: 7
|5049|7|7|image|utils.pm:reservation_being_processed|8904| reservation
computerloadlog 'begin' entry exists: no
|5049|7|7|image|utils.pm:reservation_being_processed|8904| parent reservation
computerloadlog 'begin' entry exists: no
|5049|7|7|image|utils.pm:reservation_being_processed|8904| reservation process
running: no
2015-06-12 20:49:25|5049|7|7|image|vcld:main|294|reservation 7 is NOT already
being processed
2015-06-12 20:49:25|5049|7|7|image|utils.pm:get_imagemeta_info|3826|retrieved
imagemeta info:
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : {
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "architecture" => "",
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "checkuser" => 1,
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "id" => 7,
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "postoption" => "",
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "rootaccess" => 1,
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "sethostname" => "",
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "subimages" => 0,
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : "sysprep" => 0
|5049|7|7|image|utils.pm:get_imagemeta_info|3826| : }
2015-06-12
20:49:25|5049|7|7|image|utils.pm:get_production_imagerevision_info|3714|retrieved
info from database for production revision for image identifier '1',
production image: 'noimage'
2015-06-12 20:49:25|5049|7|7|image|utils.pm:get_computer_info|7202|retrieved
info for computer: ESXi_host1 (2)
2015-06-12 20:49:25|5049|7|7|image|utils.pm:get_vmhost_info|4042|retrieved VM
host 1 info, computer: ESXi_host1
2015-06-12 20:49:25|5049|7|7|image|utils.pm:get_computer_info|7202|retrieved
info for computer: WIN7_VM_1 (3)
2015-06-12
20:49:25|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928|retrieved
connect method info for reservation 7:
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : {
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : 2 => {
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"RETRIEVAL_TIME" => "1434156565",
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"connectmethodmap" => {
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"OSid" => undef,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"OStypeid" => 1,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"autoprovisioned" => undef,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"connectmethodid" => 2,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"disabled" => 0,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"imagerevisionid" => undef
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : },
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"connectmethodport" => {
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : 2
=> {
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"connectmethodid" => 2,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"id" => 2,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"port" => 3389,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"protocol" => "TCP"
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : }
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : },
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"description" => "Remote Desktop for Windows",
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : "id"
=> 2,
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"name" => "RDP",
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"servicename" => "TermService",
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| :
"startupscript" => undef
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : }
|5049|7|7|image|utils.pm:get_reservation_connect_method_info|10928| : }
2015-06-12 20:49:25|5049|7|7|image|vcld:main|300|retrieved request information
from database
2015-06-12
20:49:25|5049|7|7|image|utils.pm:delete_computerloadlog_reservation|5714|deleted
rows from computerloadlog matching loadstate regex 'exited' for reservation
IDs: 7
2015-06-12 20:49:25|5049|7|7|image|utils.pm:insertloadlog|2737|inserted 'begin'
computerloadlog entry
2015-06-12 20:49:25|5049|7|7|image|vcld:make_new_child|514|request will be
processed by image.pm
2015-06-12 20:49:25|5049|7|7|image|vcld:make_new_child|536|created child
process 5114 for reservation 7, state: image, current number of forked kids: 1
2015-06-12 20:49:25|5114|7|7|image|vcld:make_new_child|556|vcld environment
variable set to 0 for this process
2015-06-12
20:49:25|5114|7|7|image|DataStructure.pm:is_parent_reservation|1101|this is the
parent reservation
2015-06-12
20:49:25|5114|7|7|image|utils.pm:rename_vcld_process|6077|reservation count: 1
2015-06-12
20:49:25|5114|7|7|image|utils.pm:rename_vcld_process|6096|PARENTIMAGE: 1
2015-06-12 20:49:25|5114|7|7|image|utils.pm:rename_vcld_process|6097|SUBIMAGE: 0
2015-06-12 20:49:25|5114|7|7|image|utils.pm:rename_vcld_process|6115|renamed
process to 'vcld '|5114|7|7|image|' WIN7_VM_1>ESXi_host1 vmwarewin7-WIN7VM18-v0
admin'
2015-06-12 20:49:25|5114|7|7|image|vcld:make_new_child|575|loaded VCL::image
module
2015-06-12 20:49:25|5114|7|7|image|Module.pm:new|184|VCL::image object created
for state image, address: 30055a8
2015-06-12 20:49:25|5114|7|7|image|DataStructure.pm:_initialize|705|image
identifier argument was specified: noimage, DataStructure object will contain
image information for the production imagerevision of this image
2015-06-12
20:49:25|5114|7|7|image|utils.pm:get_production_imagerevision_info|3714|retrieved
info from database for production revision for image identifier 'noimage',
production image: 'noimage'
2015-06-12 20:49:25|5114|7|7|image|DataStructure.pm:_initialize|735|retrieved
data for imagerevision ID: 1
2015-06-12 20:49:25|5114|7|7|image|DataStructure.pm:_initialize|751|retrieved
data for image ID: 1
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_mn_os_object|481|VCL::Module::OS::Linux::ManagementNode
module loaded
2015-06-12
20:49:26|5114|7|7|image|Module.pm:new|195|VCL::Module::OS::Linux::ManagementNode
object created, address: 36329e0
2015-06-12
20:49:26|5114|7|7|image|utils.pm:hostname_to_ip_address|13128|resolved IP
address from hostname localhost --> 127.0.0.1
2015-06-12
20:49:26|5114|7|7|image|DataStructure.pm:set_computer_private_ip_address|1624|private
IP address of localhost set to 127.0.0.1
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_mn_os_object|486|VCL::Module::OS::Linux::ManagementNode
OS object created, address: 36329e0
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_mn_os|1032|storing reference
to managment node OS object (address: 36329e0) in this VCL::image object
(address: 30055a8)
2015-06-12 20:49:26|5114|7|7|image|State.pm:initialize|86|initializing
VCL::Module::State object
2015-06-12
20:49:26|5114|7|7|image|DataStructure.pm:is_parent_reservation|1101|this is the
parent reservation
2015-06-12 20:49:26|5114|7|7|image|State.pm:initialize|103|obtained a database
handle for this state process, stored as $ENV{dbh}
2015-06-12
20:49:26|5114|7|7|image|utils.pm:update_reservation_lastcheck|5421|updated
reservation.lastcheck to '2015-06-12 20:49:26' for reservation IDs: 7
2015-06-12 20:49:26|5114|7|7|image|utils.pm:update_request_state|1393|request 7
state updated to pending/image
2015-06-12
20:49:26|5114|7|7|image|DataStructure.pm:is_parent_reservation|1101|this is the
parent reservation
2015-06-12
20:49:26|5114|7|7|image|DataStructure.pm:is_parent_reservation|1101|this is the
parent reservation
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_os_object|420|VCL::Module::OS::Windows::Version_6::7
module loaded
2015-06-12
20:49:26|5114|7|7|image|Module.pm:new|188|VCL::Module::OS::Windows::Version_6::7
object created for image vmwarewin7-WIN7VM18-v0, address: 2f55288
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_mn_os|1032|storing reference
to managment node OS object (address: 36329e0) in this
VCL::Module::OS::Windows::Version_6::7 object (address: 2f55288)
2015-06-12 20:49:26|5114|7|7|image|Windows.pm:initialize|212|beginning Windows
module initialization
2015-06-12 20:49:26|5114|7|7|image|Windows.pm:initialize|222|Windows module
initialization complete
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_os_object|433|VCL::Module::OS::Windows::Version_6::7
OS object created, address: 2f55288
2015-06-12 20:49:26|5005|||vcld|Module.pm:DESTROY|1880|destroying
VCL::Module::OS::Linux::ManagementNode object, address: 406a748
2015-06-12 20:49:26|5005|||vcld|Module.pm:DESTROY|1880|destroying VCL::Module
object, address: 3ec3d88
2015-06-12 20:49:26|5005|||vcld|State.pm:DESTROY|1046|VCL::image destructor
called, address: 3501640
2015-06-12 20:49:26|5005|||vcld|Module.pm:DESTROY|1880|destroying VCL::image
object, address: 3501640
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|667|computer
identifier argument was specified, retrieving data for computer: 2
2015-06-12 20:49:26|5005|||vcld|Module.pm:DESTROY|1880|destroying
VCL::Module::Provisioning::VMware::VMware object, address: 275ebd8
2015-06-12 20:49:26|5005|||vcld|Module.pm:DESTROY|1880|destroying
VCL::Module::OS::Windows object, address: 406a7a8
2015-06-12 20:49:26|5114|7|7|image|utils.pm:get_computer_info|7202|retrieved
info for computer: ESXi_host1 (2)
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|705|image
identifier argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|735|retrieved
data for imagerevision ID: 1
2015-06-12 20:49:26|5005|||vcld|Module.pm:DESTROY|1880|destroying
VCL::Module::OS::Linux::ManagementNode object, address: 3501580
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|751|retrieved
data for image ID: 1
2015-06-12 20:49:26|5114|7|7|image|Module.pm:create_vmhost_os_object|564|VM
host OS image Perl package is VCL::Module::OS::Linux::UnixLab, most likely will
not work correctly, changing to Linux
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_vmhost_os_object|569|attempting to
load VM host OS module: VCL::Module::OS::Linux (image: 1)
2015-06-12 20:49:26|5114|7|7|image|Module.pm:create_vmhost_os_object|575|VM
host OS module loaded: VCL::Module::OS::Linux
2015-06-12 20:49:26|5114|7|7|image|Module.pm:new|188|VCL::Module::OS::Linux
object created for image noimage, address: 3659a38
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_mn_os|1032|storing reference
to managment node OS object (address: 36329e0) in this VCL::Module::OS::Linux
object (address: 3659a38)
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_vmhost_os_object|588|VCL::Module::OS::Linux
OS object created, address: 3659a38
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_vmhost_os|1067|storing
reference to VM host OS object (address: 3659a38) in this VCL::image object
(address: 30055a8)
2015-06-12
20:49:26|5114|7|7|image|Module.pm:create_provisioning_object|719|VCL::Module::Provisioning::VMware::VMware
module loaded
2015-06-12
20:49:26|5114|7|7|image|Module.pm:new|192|VCL::Module::Provisioning::VMware::VMware
object created for computer WIN7_VM_1, address: 2f55420
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_mn_os|1032|storing reference
to managment node OS object (address: 36329e0) in this
VCL::Module::Provisioning::VMware::VMware object (address: 2f55420)
2015-06-12 20:49:26|5114|7|7|image|VMware.pm:initialize|271|initializing
VCL::Module::Provisioning::VMware::VMware object
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|667|computer
identifier argument was specified, retrieving data for computer: 2
2015-06-12 20:49:26|5114|7|7|image|utils.pm:get_computer_info|7202|retrieved
info for computer: ESXi_host1 (2)
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|705|image
identifier argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|735|retrieved
data for imagerevision ID: 1
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|751|retrieved
data for image ID: 1
2015-06-12
20:49:26|5114|7|7|image|VMware.pm:get_vmhost_datastructure|1233|created
DataStructure object for VM host: ESXi_host1
2015-06-12 20:49:26|5114|7|7|image|VMware.pm:initialize|290|VM profile assigned
to ESXi_host1: VMware ESXi - local storage
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|667|computer
identifier argument was specified, retrieving data for computer: 2
2015-06-12 20:49:26|5114|7|7|image|utils.pm:get_computer_info|7202|retrieved
info for computer: ESXi_host1 (2)
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|705|image
identifier argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|735|retrieved
data for imagerevision ID: 1
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|751|retrieved
data for image ID: 1
2015-06-12
20:49:26|5114|7|7|image|VMware.pm:get_vmhost_datastructure|1233|created
DataStructure object for VM host: ESXi_host1
2015-06-12
20:49:26|5114|7|7|image|VMware.pm:get_vmhost_api_object|1336|attempting to load
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2015-06-12 20:49:26|5114|7|7|image|VMware.pm:get_vmhost_api_object|1342|loaded
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2015-06-12
20:49:26|5114|7|7|image|Module.pm:new|192|VCL::Module::Provisioning::VMware::vSphere_SDK
object created for computer WIN7_VM_1, address: 433c2a0
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_mn_os|1032|storing reference
to managment node OS object (address: 36329e0) in this
VCL::Module::Provisioning::VMware::vSphere_SDK object (address: 433c2a0)
2015-06-12 20:49:26|5114|7|7|image|vSphere_SDK.pm:initialize|98|vSphere SDK for
Perl does not appear to be installed on this managment node, unable to load
VMware vSphere SDK Perl modules, error:
|5114|7|7|image|vSphere_SDK.pm:initialize|98| Can't locate VMware/VIRuntime.pm
in @INC (@INC contains: /usr/local/vcl-2.4.2/bin/../../../..
/usr/local/vcl-2.4.2/bin/../.. /usr/local/vcl-2.4.2/bin/../../..
/usr/local/vcl-2.4.2/bin/../../../../.. /usr/local/vcl-2.4.2/bin/..
/usr/local/vcl-2.4.2/bin/../lib /usr/local/lib64/perl5 /usr/local/share/perl5
/usr/lib64/perl5/vendor_perl /usr/share/perl5/vendor_perl /usr/lib64/perl5
/usr/share/perl5 .) at (eval 228) line 1.
|5114|7|7|image|vSphere_SDK.pm:initialize|98| BEGIN failed--compilation aborted
at (eval 228) line 1.
2015-06-12 20:49:26|5114|7|7|image|VMware.pm:get_vmhost_api_object|1357|API
object could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
2015-06-12
20:49:26|5114|7|7|image|utils.pm:get_computer_current_private_ip_address|13100|retrieved
private IP address for computer ESXi_host1 (2) from database: 192.168.0.102
2015-06-12
20:49:26|5114|7|7|image|utils.pm:determine_remote_connection_target|13384|private
IP address is set in database for ESXi_host1, it will be used as the remote
connection target: 192.168.0.102
2015-06-12 20:49:26|5114|7|7|image|utils.pm:nmap_port|2167|port 22 is open on
192.168.0.102 (ESXi_host1)
2015-06-12 20:49:26|5114|7|7|image|utils.pm:nmap_port|2167|port 24 is open on
192.168.0.102 (ESXi_host1)
2015-06-12 20:49:26|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 10
seconds for SSH process to finish
2015-06-12 20:49:26|5114|7|7|image|OS.pm:is_ssh_responding|955|ESXi_host1 is
responding to SSH, port 22: open, port 24: open
2015-06-12 20:49:26|5114|7|7|image|VMware.pm:initialize|307|OS on VM host
ESXi_host1 will be controlled using VCL::Module::OS::Linux OS object
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|667|computer
identifier argument was specified, retrieving data for computer: 2
2015-06-12 20:49:26|5114|7|7|image|utils.pm:get_computer_info|7202|retrieved
info for computer: ESXi_host1 (2)
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|705|image
identifier argument was specified: 1, DataStructure object will contain image
information for the production imagerevision of this image
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|735|retrieved
data for imagerevision ID: 1
2015-06-12 20:49:26|5114|7|7|image|DataStructure.pm:_initialize|751|retrieved
data for image ID: 1
2015-06-12
20:49:26|5114|7|7|image|VMware.pm:get_vmhost_datastructure|1233|created
DataStructure object for VM host: ESXi_host1
2015-06-12
20:49:26|5114|7|7|image|VMware.pm:get_vmhost_api_object|1336|attempting to load
VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2015-06-12 20:49:26|5114|7|7|image|VMware.pm:get_vmhost_api_object|1342|loaded
VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2015-06-12
20:49:26|5114|7|7|image|Module.pm:new|192|VCL::Module::Provisioning::VMware::VIM_SSH
object created for computer WIN7_VM_1, address: 41adcb8
2015-06-12 20:49:26|5114|7|7|image|Module.pm:set_mn_os|1032|storing reference
to managment node OS object (address: 36329e0) in this
VCL::Module::Provisioning::VMware::VIM_SSH object (address: 41adcb8)
2015-06-12 20:49:26|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.102 (ESXi_host1): 'vim-cmd ; vmware-vim-cmd'
2015-06-12 20:49:27|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'vim-cmd ; vmware-vim-cmd', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| Commands available under /:
|5114|7|7|image|utils.pm:run_ssh_command|4365| hbrsvc/ internalsvc/
solo/ vmsvc/
|5114|7|7|image|utils.pm:run_ssh_command|4365| hostsvc/ proxysvc/
vimsvc/ help
|5114|7|7|image|utils.pm:run_ssh_command|4365| sh: vmware-vim-cmd: not found
2015-06-12 20:49:27|5114|7|7|image|VIM_SSH.pm:initialize|141|VIM executable
available on VM host: vim-cmd
2015-06-12
20:49:27|5114|7|7|image|VIM_SSH.pm:initialize|143|VCL::Module::Provisioning::VMware::VIM_SSH
object initialized
2015-06-12 20:49:27|5114|7|7|image|VMware.pm:get_vmhost_api_object|1364|created
API object: VCL::Module::Provisioning::VMware::VIM_SSH
2015-06-12 20:49:27|5114|7|7|image|VMware.pm:initialize|316|VM host ESXi_host1
will be controlled using vim-cmd via SSH
2015-06-12 20:49:27|5114|7|7|image|VMware.pm:initialize|335|VMware OS and API
objects created for VM host ESXi_host1:
|5114|7|7|image|VMware.pm:initialize|335| VM host OS object type:
VCL::Module::OS::Linux
|5114|7|7|image|VMware.pm:initialize|335| VMware API object type:
VCL::Module::Provisioning::VMware::VIM_SSH
2015-06-12 20:49:27|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.102 (ESXi_host1): 'vmware -v'
2015-06-12 20:49:27|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'vmware -v', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| VMware ESXi 5.5.0 build-2068190
2015-06-12
20:49:27|5114|7|7|image|VMware.pm:get_vmhost_product_name|6544|VMware product
being used on VM host ESXi_host1: 'VMware ESXi 5.5.0 build-2068190'
2015-06-12 20:49:27|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:28|5114|7|7|image|VIM_SSH.pm:_run_vim_cmd|274|executed command
on VM host ESXi_host1: vim-cmd hostsvc/datastore/listsummary, exit status: 0
2015-06-12 20:49:28|5114|7|7|image|VMware.pm:get_datastore_info|6680|retrieved
datastore info from VM host: datastore1
2015-06-12
20:49:28|5114|7|7|image|VMware.pm:get_vmx_base_directory_path|2921|determined
vmx base directory path: /vmfs/volumes/datastore1
2015-06-12
20:49:29|5114|7|7|image|Linux.pm:file_exists|1606|'/vmfs/volumes/datastore1'
exists on ESXi_host1, files: 0, directories: 0, links: 1
2015-06-12 20:49:29|5114|7|7|image|VMware.pm:is_vm_dedicated|4025|VM disk mode
does not need to be dedicated
2015-06-12 20:49:29|5114|7|7|image|VMware.pm:initialize|363|not checking if
vmdk base directory exists because it is the same as the vmx base directory:
/vmfs/volumes/datastore1
2015-06-12
20:49:29|5114|7|7|image|Module.pm:create_provisioning_object|732|VCL::Module::Provisioning::VMware::VMware
provisioning object created for WIN7_VM_1, address: 2f55420
2015-06-12 20:49:29|5114|7|7|image|vcld:make_new_child|584|VCL::image object
created and initialized
2015-06-12 20:49:29|5114|7|7|image|VMware.pm:does_image_exist|4368|image does
NOT exist in datastore on VM host ESXi_host1:
/vmfs/volumes/datastore1/vmwarewin7-WIN7VM18-v0/vmwarewin7-WIN7VM18-v0.vmdk
2015-06-12 20:49:29|5114|7|7|image|VMware.pm:does_image_exist|4379|image does
NOT exist with truncated name in datastore on VM host ESXi_host1:
/vmfs/volumes/datastore1/vmwarewin7-WIN7VM18-v0/vmwarewin7-WIN7VM18-v0.vmdk
2015-06-12
20:49:29|5114|7|7|image|VMware.pm:is_repository_mounted_on_vmhost|6630|image
repository is NOT mounted on VM host ESXi_host1: /vcl_repository
2015-06-12 20:49:29|5114|7|7|image|VMware.pm:does_image_exist|4415|image does
NOT exist in image repository mounted on management node:
/vcl_repository/vmwarewin7-WIN7VM18-v0/vmwarewin7-WIN7VM18-v0.vmdk
2015-06-12 20:49:30|5114|7|7|image|VMware.pm:does_image_exist|4424|image does
NOT exist with truncated name in image repository mounted on management node:
/vcl_repository/vmwarewin7-WIN7VM18-v0/vmwarewin7-WIN7VM18-v0.vmdk
2015-06-12 20:49:30|5114|7|7|image|image.pm:process|155|image
vmwarewin7-WIN7VM18-v0 does not exist in the repository
2015-06-12 20:49:30|5114|7|7|image|image.pm:process|178|calling provisioning
module's capture() subroutine
2015-06-12
20:49:30|5114|7|7|image|VMware.pm:is_repository_mounted_on_vmhost|6630|image
repository is NOT mounted on VM host ESXi_host1: /vcl_repository
2015-06-12
20:49:30|5114|7|7|image|utils.pm:get_computer_current_private_ip_address|13100|retrieved
private IP address for computer WIN7_VM_1 (3) from database: 192.168.0.106
2015-06-12
20:49:30|5114|7|7|image|utils.pm:determine_remote_connection_target|13384|private
IP address is set in database for WIN7_VM_1, it will be used as the remote
connection target: 192.168.0.106
2015-06-12 20:49:30|5114|7|7|image|utils.pm:nmap_port|2167|port 22 is open on
192.168.0.106 (WIN7_VM_1)
2015-06-12 20:49:30|5114|7|7|image|utils.pm:nmap_port|2167|port 24 is open on
192.168.0.106 (WIN7_VM_1)
2015-06-12 20:49:30|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 10
seconds for SSH process to finish
2015-06-12 20:49:31|5114|7|7|image|OS.pm:is_ssh_responding|955|WIN7_VM_1 is
responding to SSH, port 22: open, port 24: open
2015-06-12 20:49:32|5114|7|7|image|OS.pm:get_os_type|2815|Windows OS is
currently installed on WIN7_VM_1, output:
|5114|7|7|image|OS.pm:get_os_type|2815| CYGWIN_NT-6.1 systemuser-PC
2.0.4(0.287/5/3) 2015-06-09 12:22 x86_64 Cygwin
2015-06-12
20:49:32|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1018|'windows' OS
type currently installed on WIN7_VM_1 matches the OS type of the image assigned
to this reservation
2015-06-12 20:49:32|5114|7|7|image|Windows.pm:is_64_bit|8795|64-bit Windows OS
detected, PROCESSOR_IDENTIFIER: AMD64 Family 21 Model 2 Stepping 0, AuthenticAMD
2015-06-12 20:49:33|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/Windows/Sysnative'
2015-06-12 20:49:33|5114|7|7|image|Windows.pm:get_system32_path|8841|64-bit
Windows OS installed on WIN7_VM_1, C: Windows Sysnative not found, using
C:/Windows/System32
2015-06-12
20:49:33|5114|7|7|image|Windows.pm:get_network_configuration|5713|attempting to
retrieve network configuration from WIN7_VM_1
2015-06-12
20:49:33|5114|7|7|image|DataStructure.pm:get_computer_private_ip_address|1530|private
IP address for WIN7_VM_1 (3) stored in this object matches IP address stored
in %ENV: 192.168.0.106
2015-06-12
20:49:33|5114|7|7|image|Windows.pm:get_network_configuration|5723|attempting to
retrieve network configuration information from WIN7_VM_1
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5742|ran ipconfig
on WIN7_VM_1:
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Windows IP
Configuration
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Host Name . . . . .
. . . . . . . : systemuser-PC
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Primary Dns Suffix
. . . . . . . :
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Node Type . . . . .
. . . . . . . : Hybrid
|5114|7|7|image|Windows.pm:get_network_configuration|5742| IP Routing Enabled.
. . . . . . . : No
|5114|7|7|image|Windows.pm:get_network_configuration|5742| WINS Proxy Enabled.
. . . . . . . : No
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DNS Suffix Search
List. . . . . . : uncc.edu
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Ethernet adapter
Local Area Connection 2:
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Connection-specific
DNS Suffix . :
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Description . . . .
. . . . . . . : Intel(R) PRO/1000 MT Network Connection #2
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Physical Address. .
. . . . . . . : 00-0C-29-BC-75-9F
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCP Enabled. . . .
. . . . . . . : Yes
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Autoconfiguration
Enabled . . . . : Yes
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Link-local IPv6
Address . . . . . : fe80::d977:6ff2:1baf:ff06%14(Preferred)
|5114|7|7|image|Windows.pm:get_network_configuration|5742| IPv4 Address. . . .
. . . . . . . : 192.168.0.106(Preferred)
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Subnet Mask . . . .
. . . . . . . : 255.255.255.0
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Lease Obtained. . .
. . . . . . . : Friday, June 12, 2015 8:36:48 PM
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Lease Expires . . .
. . . . . . . : Saturday, June 13, 2015 8:36:48 PM
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Default Gateway . .
. . . . . . . :
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCP Server . . . .
. . . . . . . : 192.168.0.1
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCPv6 IAID . . . .
. . . . . . . : 318770217
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCPv6 Client DUID.
. . . . . . . : 00-01-00-01-1C-F1-CE-8B-00-0C-29-BC-75-95
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DNS Servers . . . .
. . . . . . . : 10.23.0.20
|5114|7|7|image|Windows.pm:get_network_configuration|5742| 10.23.0.30
|5114|7|7|image|Windows.pm:get_network_configuration|5742| 8.8.8.8
|5114|7|7|image|Windows.pm:get_network_configuration|5742| NetBIOS over Tcpip.
. . . . . . . : Enabled
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Ethernet adapter
Local Area Connection:
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Connection-specific
DNS Suffix . : uncc.edu
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Description . . . .
. . . . . . . : Intel(R) PRO/1000 MT Network Connection
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Physical Address. .
. . . . . . . : 00-0C-29-BC-75-95
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCP Enabled. . . .
. . . . . . . : Yes
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Autoconfiguration
Enabled . . . . : Yes
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Link-local IPv6
Address . . . . . : fe80::44f1:b425:3c72:cf17%11(Preferred)
|5114|7|7|image|Windows.pm:get_network_configuration|5742| IPv4 Address. . . .
. . . . . . . : 10.18.198.99(Preferred)
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Subnet Mask . . . .
. . . . . . . : 255.255.254.0
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Lease Obtained. . .
. . . . . . . : Friday, June 12, 2015 8:36:46 PM
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Lease Expires . . .
. . . . . . . : Friday, June 12, 2015 10:36:42 PM
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Default Gateway . .
. . . . . . . : 10.18.198.1
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCP Server . . . .
. . . . . . . : 10.23.0.30
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCPv6 IAID . . . .
. . . . . . . : 234884137
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCPv6 Client DUID.
. . . . . . . : 00-01-00-01-1C-F1-CE-8B-00-0C-29-BC-75-95
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DNS Servers . . . .
. . . . . . . : 10.23.0.20
|5114|7|7|image|Windows.pm:get_network_configuration|5742| 10.23.0.30
|5114|7|7|image|Windows.pm:get_network_configuration|5742| NetBIOS over Tcpip.
. . . . . . . : Enabled
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Tunnel adapter
isatap.{752EE16F-7FAD-4A1C-8592-27557852145C}:
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Media State . . . .
. . . . . . . : Media disconnected
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Connection-specific
DNS Suffix . :
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Description . . . .
. . . . . . . : Microsoft ISATAP Adapter
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Physical Address. .
. . . . . . . : 00-00-00-00-00-00-00-E0
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCP Enabled. . . .
. . . . . . . : No
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Autoconfiguration
Enabled . . . . : Yes
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Tunnel adapter
isatap.uncc.edu:
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Media State . . . .
. . . . . . . : Media disconnected
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Connection-specific
DNS Suffix . : uncc.edu
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Description . . . .
. . . . . . . : Microsoft ISATAP Adapter #2
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Physical Address. .
. . . . . . . : 00-00-00-00-00-00-00-E0
|5114|7|7|image|Windows.pm:get_network_configuration|5742| DHCP Enabled. . . .
. . . . . . . : No
|5114|7|7|image|Windows.pm:get_network_configuration|5742| Autoconfiguration
Enabled . . . . : Yes
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5771|found
interface: Local Area Connection 2
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5771|found
interface: Local Area Connection
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5771|found
interface: isatap.{752EE16F-7FAD-4A1C-8592-27557852145C}
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5771|found
interface: isatap.uncc.edu
2015-06-12
20:49:34|5114|7|7|image|DataStructure.pm:get_computer_private_ip_address|1530|private
IP address for WIN7_VM_1 (3) stored in this object matches IP address stored
in %ENV: 192.168.0.106
2015-06-12
20:49:34|5114|7|7|image|OS.pm:get_private_interface_name|1446|determined
private interface name: Local Area Connection 2 (192.168.0.106)
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5706|returning
network configuration previously retrieved
2015-06-12 20:49:34|5114|7|7|image|OS.pm:get_mac_address|1765|returning private
MAC address: 00:0C:29:BC:75:9F
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5706|returning
network configuration previously retrieved
2015-06-12
20:49:34|5114|7|7|image|DataStructure.pm:get_computer_private_ip_address|1530|private
IP address for WIN7_VM_1 (3) stored in this object matches IP address stored
in %ENV: 192.168.0.106
2015-06-12 20:49:34|5114|7|7|image|OS.pm:get_public_interface_name|1547|'Local
Area Connection' could potententially be public interface, not assigned private
IP address
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5706|returning
network configuration previously retrieved
2015-06-12
20:49:34|5114|7|7|image|DataStructure.pm:get_computer_private_ip_address|1530|private
IP address for WIN7_VM_1 (3) stored in this object matches IP address stored
in %ENV: 192.168.0.106
2015-06-12
20:49:34|5114|7|7|image|OS.pm:_get_public_interface_name_helper|1629|'Local
Area Connection' is more likely the public interface, it is NOT assigned the
private IP address: 192.168.0.106
2015-06-12
20:49:34|5114|7|7|image|OS.pm:get_public_interface_name|1508|interface
'isatap.uncc.edu' ignored because its name contains 'isatap'
2015-06-12
20:49:34|5114|7|7|image|OS.pm:get_public_interface_name|1508|interface
'isatap.{752EE16F-7FAD-4A1C-8592-27557852145C}' ignored because its name
contains 'isatap'
2015-06-12
20:49:34|5114|7|7|image|OS.pm:get_public_interface_name|1565|determined the
public interface name: 'Local Area Connection'
2015-06-12
20:49:34|5114|7|7|image|Windows.pm:get_network_configuration|5706|returning
network configuration previously retrieved
2015-06-12 20:49:34|5114|7|7|image|OS.pm:get_mac_address|1765|returning public
MAC address: 00:0C:29:BC:75:95
2015-06-12 20:49:34|5114|7|7|image|VMware.pm:get_vmx_file_paths|5040|attempting
to find existing vmx files on the VM host
2015-06-12
20:49:34|5114|7|7|image|VMware.pm:get_vmx_base_directory_path|2921|determined
vmx base directory path: /vmfs/volumes/datastore1
2015-06-12 20:49:34|5114|7|7|image|OS.pm:find_files|3337|files found under
/vmfs/volumes/datastore1/ matching '*.vmx': 4
2015-06-12 20:49:34|5114|7|7|image|VMware.pm:get_vmx_file_paths|5046|found 4
vmx files under /vmfs/volumes/datastore1
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5046|
/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5046|
/vmfs/volumes/datastore1/Test VM2 (linux-64bit)/Test VM2 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5046|
/vmfs/volumes/datastore1/Test VM3 (linux-64bit)/Test VM3 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5046|
/vmfs/volumes/datastore1/Test VM4 (win7-64bit)/Test VM4 (win7-64bit).vmx
2015-06-12 20:49:34|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:35|5114|7|7|image|VIM_SSH.pm:_run_vim_cmd|274|executed command
on VM host ESXi_host1: vim-cmd vmsvc/getallvms, exit status: 0
2015-06-12 20:49:35|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:36|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:49:35
2015-06-12 20:49:36|5114|7|7|image|VIM_SSH.pm:_run_vim_cmd|274|executed command
on VM host ESXi_host1: vim-cmd hostsvc/datastore/listsummary, exit status: 0
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_datastore_info|6680|retrieved
datastore info from VM host: datastore1
2015-06-12 20:49:36|5114|7|7|image|VIM_SSH.pm:get_registered_vms|1073|found 4
registered VMs
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_file_paths|5050|found 4
registered vmx files
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5050|
/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5050|
/vmfs/volumes/datastore1/Test VM2 (linux-64bit)/Test VM2 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5050|
/vmfs/volumes/datastore1/Test VM3 (linux-64bit)/Test VM3 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5050|
/vmfs/volumes/datastore1/Test VM4 (win7-64bit)/Test VM4 (win7-64bit).vmx
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_file_paths|5055|found 4
vmx files on VM host
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5055|
/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5055|
/vmfs/volumes/datastore1/Test VM2 (linux-64bit)/Test VM2 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5055|
/vmfs/volumes/datastore1/Test VM3 (linux-64bit)/Test VM3 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_vmx_file_paths|5055|
/vmfs/volumes/datastore1/Test VM4 (win7-64bit)/Test VM4 (win7-64bit).vmx
2015-06-12
20:49:36|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1041|retrieved vmx
file paths currently residing on the VM host:
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1041|
/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmx
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1041|
/vmfs/volumes/datastore1/Test VM2 (linux-64bit)/Test VM2 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1041|
/vmfs/volumes/datastore1/Test VM3 (linux-64bit)/Test VM3 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1041|
/vmfs/volumes/datastore1/Test VM4 (win7-64bit)/Test VM4 (win7-64bit).vmx
2015-06-12
20:49:36|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1048|sorted vmx file
paths so that directories containing WIN7_VM_1 are checked first:
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1048|
/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmx
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1048|
/vmfs/volumes/datastore1/Test VM2 (linux-64bit)/Test VM2 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1048|
/vmfs/volumes/datastore1/Test VM3 (linux-64bit)/Test VM3 (linux-64bit).vmx
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1048|
/vmfs/volumes/datastore1/Test VM4 (win7-64bit)/Test VM4 (win7-64bit).vmx
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_info|5116|attempting to
retrieve info from vmx file: /vmfs/volumes/datastore1/Test VM1
(win7-64bit)/Test VM1 (win7-64bit).vmx
2015-06-12 20:49:36|5114|7|7|image|OS.pm:get_file_contents|2341|retrieved 92
lines from file on ESXi_host1: '/vmfs/volumes/datastore1/Test VM1
(win7-64bit)/Test VM1 (win7-64bit).vmx'
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_info|5157|vmx file does
not contain a computer_id value, attempting to determine matching computer
2015-06-12
20:49:36|5114|7|7|image|VMware.pm:_get_vmx_file_path_computer_name|7327|computer
name could not be determined from directory name: 'Test VM1 (win7-64bit)'
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_info|5171|unable to
determine computer name from vmx file path: '/vmfs/volumes/datastore1/Test VM1
(win7-64bit)/Test VM1 (win7-64bit).vmx'
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_info|5185|ignoring ide1:0,
filename property does not end with .vmdk:
/vmfs/volumes/55565309-e4f9242e-315a-d4ae52e8de56/iso/SW_DVD5_SA_Win_Ent_7w_SP1_64BIT_English_-2_MLF_X17-58882.ISO
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_info|5193|scsi0:0 mode
property not set, setting default value: persistent
2015-06-12 20:49:36|5114|7|7|image|VMware.pm:get_vmx_info|5202|vmdk path
appears to be relative: Test VM1 (win7-64bit).vmdk, prepending the vmx
directory: /vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1
(win7-64bit).vmdk
2015-06-12
20:49:36|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079|comparing MAC
addresses
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079| used by WIN7_VM_1:
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079| 000c29bc7595
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079| 000c29bc759f
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079| configured in Test VM1
(win7-64bit).vmx:
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079| 000c29bc7595
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1079| 000c29bc759f
2015-06-12 20:49:36|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:37|5114|7|7|image|VIM_SSH.pm:_run_vim_cmd|274|executed command
on VM host ESXi_host1: vim-cmd vmsvc/getallvms, exit status: 0
2015-06-12 20:49:37|5114|7|7|image|VIM_SSH.pm:get_registered_vms|1073|found 4
registered VMs
2015-06-12 20:49:37|5114|7|7|image|VMware.pm:is_vm_registered|4191|VM is
registered: /vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1
(win7-64bit).vmx
2015-06-12 20:49:37|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:38|5114|7|7|image|VIM_SSH.pm:_run_vim_cmd|274|executed command
on VM host ESXi_host1: vim-cmd vmsvc/getallvms, exit status: 0
2015-06-12 20:49:38|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:39|5114|7|7|image|VIM_SSH.pm:_run_vim_cmd|274|executed command
on VM host ESXi_host1: vim-cmd vmsvc/power.getstate 1, exit status: 0
2015-06-12
20:49:39|5114|7|7|image|VIM_SSH.pm:get_vm_power_state|1122|vmsvc/power.getstate
1:
|5114|7|7|image|VIM_SSH.pm:get_vm_power_state|1122| Retrieved runtime info
|5114|7|7|image|VIM_SSH.pm:get_vm_power_state|1122| Powered on
2015-06-12 20:49:39|5114|7|7|image|VIM_SSH.pm:get_vm_power_state|1125|VM is
powered on: /vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1
(win7-64bit).vmx
2015-06-12
20:49:39|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1100|found matching
MAC address between WIN7_VM_1 (powered on) and Test VM1 (win7-64bit).vmx:
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1100| 000c29bc7595
|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1100| 000c29bc759f
2015-06-12
20:49:39|5114|7|7|image|VMware.pm:get_active_vmx_file_path|1112|found vmx file
being used by WIN7_VM_1 (powered on): /vmfs/volumes/datastore1/Test VM1
(win7-64bit)/Test VM1 (win7-64bit).vmx
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:check_file_paths|3808|successfully
retrieved vmx file path components:
|5114|7|7|image|VMware.pm:check_file_paths|3808| VM profile VM path:
'datastore1'
|5114|7|7|image|VMware.pm:check_file_paths|3808| VM profile datastore path:
'datastore1'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmx file path:
'/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmx'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmx directory path:
'/vmfs/volumes/datastore1/Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmx base directory path:
'/vmfs/volumes/datastore1'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmx directory name:
'Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmx file name:
'Test VM1 (win7-64bit).vmx'
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:set_vmx_file_path|3111|set
overridden vmx file path: '/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test
VM1 (win7-64bit).vmx'
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:get_vmx_info|5112|returning
previously retrieved info from vmx file: /vmfs/volumes/datastore1/Test VM1
(win7-64bit)/Test VM1 (win7-64bit).vmx
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:capture|686|vmx info for VM
WIN7_VM_1 being captured:
|5114|7|7|image|VMware.pm:capture|686| : {
|5114|7|7|image|VMware.pm:capture|686| : ".encoding" => "UTF-8",
|5114|7|7|image|VMware.pm:capture|686| : "checkpoint.vmstate" => "",
|5114|7|7|image|VMware.pm:capture|686| : "cleanshutdown" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "config.version" => 8,
|5114|7|7|image|VMware.pm:capture|686| : "cpuid.corespersocket" => 3,
|5114|7|7|image|VMware.pm:capture|686| : "displayname" => "Test VM1
(win7-64bit)",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.addresstype" =>
"generated",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.generatedaddress" =>
"00:0c:29:bc:75:95",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.generatedaddressoffset"
=> 0,
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.networkname" => "VM
Network",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.pcislotnumber" => 32,
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet0.virtualdev" => "e1000",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.addresstype" =>
"generated",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.generatedaddress" =>
"00:0c:29:bc:75:9f",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.generatedaddressoffset"
=> 10,
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.networkname" => "VM
Network 2",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.pcislotnumber" => 34,
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "ethernet1.virtualdev" => "e1000",
|5114|7|7|image|VMware.pm:capture|686| : "evccompatibilitymode" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "extendedconfigfile" => "Test VM1
(win7-64bit).vmxf",
|5114|7|7|image|VMware.pm:capture|686| : "floppy0.clientdevice" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "floppy0.filename" =>
"vmware-null-remote-floppy",
|5114|7|7|image|VMware.pm:capture|686| : "floppy0.startconnected" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "guestcpuid.0" =>
"0000000d68747541444d416369746e65",
|5114|7|7|image|VMware.pm:capture|686| : "guestcpuid.1" =>
"00600f200003080096983203178bfbff",
|5114|7|7|image|VMware.pm:capture|686| : "guestcpuid.80000001" =>
"00600f203000000000010be92bd3fbff",
|5114|7|7|image|VMware.pm:capture|686| : "guestos" => "windows7-64",
|5114|7|7|image|VMware.pm:capture|686| : "hostcpuid.0" =>
"0000000d68747541444d416369746e65",
|5114|7|7|image|VMware.pm:capture|686| : "hostcpuid.1" =>
"00600f20000c08003698320b178bfbff",
|5114|7|7|image|VMware.pm:capture|686| : "hostcpuid.80000001" =>
"00600f203000000001ebbfff2fd3fbff",
|5114|7|7|image|VMware.pm:capture|686| : "hpet0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "ide1:0.devicetype" => "cdrom-image",
|5114|7|7|image|VMware.pm:capture|686| : "ide1:0.filename" =>
"/vmfs/volumes/55565309-e4f9242e-315a-d4ae52e8de56/iso/SW_DVD5_SA_Win_Ent_7w_SP1_64BIT_English_-2_MLF_X17-58882.ISO",
|5114|7|7|image|VMware.pm:capture|686| : "ide1:0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "ide1:0.startconnected" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "memsize" => 6144,
|5114|7|7|image|VMware.pm:capture|686| : "numvcpus" => 6,
|5114|7|7|image|VMware.pm:capture|686| : "nvram" => "Test VM1
(win7-64bit).nvram",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge0.pcislotnumber" => 17,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge4.functions" => 8,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge4.pcislotnumber" => 21,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge4.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge4.virtualdev" =>
"pcieRootPort",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge5.functions" => 8,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge5.pcislotnumber" => 22,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge5.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge5.virtualdev" =>
"pcieRootPort",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge6.functions" => 8,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge6.pcislotnumber" => 23,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge6.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge6.virtualdev" =>
"pcieRootPort",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge7.functions" => 8,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge7.pcislotnumber" => 24,
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge7.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "pcibridge7.virtualdev" =>
"pcieRootPort",
|5114|7|7|image|VMware.pm:capture|686| : "replay.filename" => "",
|5114|7|7|image|VMware.pm:capture|686| : "replay.supported" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "sched.swap.derivedname" =>
"/vmfs/volumes/55565309-e4f9242e-315a-d4ae52e8de56/Test VM1 (win7-64bit)/Test
VM1 (win7-64bit)-bcc51c1a.vswp",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0.pcislotnumber" => 160,
|5114|7|7|image|VMware.pm:capture|686| : "scsi0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0.saswwid" => "50 05 05 65 de
a2 1d e0",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0.virtualdev" => "lsisas1068",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0:0.devicetype" =>
"scsi-hardDisk",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0:0.filename" => "Test VM1
(win7-64bit).vmdk",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0:0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "scsi0:0.redo" => "",
|5114|7|7|image|VMware.pm:capture|686| : "softpoweroff" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "svga.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "svga.vramsize" => 8388608,
|5114|7|7|image|VMware.pm:capture|686| : "tools.remindinstall" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "tools.synctime" => "FALSE",
|5114|7|7|image|VMware.pm:capture|686| : "toolscripts.afterpoweron" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "toolscripts.afterresume" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "toolscripts.beforepoweroff" =>
"TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "toolscripts.beforesuspend" =>
"TRUE",
|5114|7|7|image|VMware.pm:capture|686| :
"toolsinstallmanager.lastinstallerror" => 0,
|5114|7|7|image|VMware.pm:capture|686| : "toolsinstallmanager.updatecounter"
=> 1,
|5114|7|7|image|VMware.pm:capture|686| : "usercpuid.0" =>
"0000000d68747541444d416369746e65",
|5114|7|7|image|VMware.pm:capture|686| : "usercpuid.1" =>
"00600f20000c080096983203178bfbff",
|5114|7|7|image|VMware.pm:capture|686| : "usercpuid.80000001" =>
"00600f203000000000010be92bd3fbff",
|5114|7|7|image|VMware.pm:capture|686| : "uuid.bios" => "56 4d 59 75 de a2 1d
e0-61 2d f3 db b1 bc 75 95",
|5114|7|7|image|VMware.pm:capture|686| : "uuid.location" => "56 4d 59 75 de
a2 1d e0-61 2d f3 db b1 bc 75 95",
|5114|7|7|image|VMware.pm:capture|686| : "vc.uuid" => "52 53 5a 9a d1 7b d8
a0-3a 06 f9 b2 d3 6a 8b 6f",
|5114|7|7|image|VMware.pm:capture|686| : "virtualhw.productcompatibility" =>
"hosted",
|5114|7|7|image|VMware.pm:capture|686| : "virtualhw.version" => 8,
|5114|7|7|image|VMware.pm:capture|686| : "vmci0.id" => "-1313049195",
|5114|7|7|image|VMware.pm:capture|686| : "vmci0.pcislotnumber" => 33,
|5114|7|7|image|VMware.pm:capture|686| : "vmci0.present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "vmdk" => {
|5114|7|7|image|VMware.pm:capture|686| : "scsi0:0" => {
|5114|7|7|image|VMware.pm:capture|686| : "devicetype" => "scsi-hardDisk",
|5114|7|7|image|VMware.pm:capture|686| : "mode" => "persistent",
|5114|7|7|image|VMware.pm:capture|686| : "present" => "TRUE",
|5114|7|7|image|VMware.pm:capture|686| : "redo" => "",
|5114|7|7|image|VMware.pm:capture|686| : "vmdk_directory_path" =>
"/vmfs/volumes/datastore1/Test VM1 (win7-64bit)",
|5114|7|7|image|VMware.pm:capture|686| : "vmdk_file_base_name" => "Test
VM1 (win7-64bit)",
|5114|7|7|image|VMware.pm:capture|686| : "vmdk_file_name" => "Test VM1
(win7-64bit).vmdk",
|5114|7|7|image|VMware.pm:capture|686| : "vmdk_file_path" =>
"/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmdk"
|5114|7|7|image|VMware.pm:capture|686| : }
|5114|7|7|image|VMware.pm:capture|686| : },
|5114|7|7|image|VMware.pm:capture|686| : "vmotion.checkpointfbsize" =>
8388608,
|5114|7|7|image|VMware.pm:capture|686| : "vmx_directory_path" =>
"/vmfs/volumes/datastore1/Test VM1 (win7-64bit)",
|5114|7|7|image|VMware.pm:capture|686| : "vmx_file_name" => "Test VM1
(win7-64bit).vmx"
|5114|7|7|image|VMware.pm:capture|686| : }
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:capture|705|vmdk file path
configured for VM WIN7_VM_1 being captured: /vmfs/volumes/datastore1/Test VM1
(win7-64bit)/Test VM1 (win7-64bit).vmdk
2015-06-12
20:49:39|5114|7|7|image|VMware.pm:_get_datastore_names|6711|datastore names:
datastore1
2015-06-12
20:49:39|5114|7|7|image|VMware.pm:get_vmdk_base_directory_path_dedicated|3381|using
VM profile datastore path as the vmdk base directory path: datastore1
2015-06-12
20:49:39|5114|7|7|image|VMware.pm:_get_datastore_names|6711|datastore names:
datastore1
2015-06-12
20:49:39|5114|7|7|image|VMware.pm:get_vmdk_base_directory_path_dedicated|3381|using
VM profile datastore path as the vmdk base directory path: datastore1
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:check_file_paths|3808|successfully
retrieved vmdk file path components:
|5114|7|7|image|VMware.pm:check_file_paths|3808| VM profile VM path:
'datastore1'
|5114|7|7|image|VMware.pm:check_file_paths|3808| VM profile datastore path:
'datastore1'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmdk file path:
'/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmdk'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmdk directory path:
'/vmfs/volumes/datastore1/Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmdk base directory path:
'/vmfs/volumes/datastore1'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmdk directory name:
'Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmdk file name:
'Test VM1 (win7-64bit).vmdk'
|5114|7|7|image|VMware.pm:check_file_paths|3808| vmdk file prefix:
'Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| dedicated vmdk file path:
'/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test VM1 (win7-64bit).vmdk'
|5114|7|7|image|VMware.pm:check_file_paths|3808| dedicated vmdk directory path:
'/vmfs/volumes/datastore1/Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| dedicated vmdk directory name:
'Test VM1 (win7-64bit)'
|5114|7|7|image|VMware.pm:check_file_paths|3808| shared vmdk file path:
'/vmfs/volumes/datastore1/vmwarewin7-WIN7VM18-v0/vmwarewin7-WIN7VM18-v0.vmdk'
|5114|7|7|image|VMware.pm:check_file_paths|3808| shared vmdk directory path:
'/vmfs/volumes/datastore1/vmwarewin7-WIN7VM18-v0'
|5114|7|7|image|VMware.pm:check_file_paths|3808| shared vmdk directory name:
'vmwarewin7-WIN7VM18-v0'
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:set_vmdk_file_path|3741|set
overridden vmdk file path: '/vmfs/volumes/datastore1/Test VM1 (win7-64bit)/Test
VM1 (win7-64bit).vmdk'
2015-06-12 20:49:39|5114|7|7|image|VMware.pm:capture|729|mode of vmdk to be
captured is valid: persistent
2015-06-12 20:49:40|5114|7|7|image|7.pm:pre_capture|99|calling parent class
pre_capture() subroutine
2015-06-12 20:49:40|5114|7|7|image|Version_6.pm:pre_capture|170|calling parent
class pre_capture() subroutine
2015-06-12 20:49:40|5114|7|7|image|OS.pm:pre_capture|90|beginning common image
capture preparation tasks
2015-06-12 20:49:40|5114|7|7|image|utils.pm:nmap_port|2167|port 22 is open on
192.168.0.106 (WIN7_VM_1)
2015-06-12 20:49:40|5114|7|7|image|utils.pm:nmap_port|2167|port 24 is open on
192.168.0.106 (WIN7_VM_1)
2015-06-12 20:49:40|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 10
seconds for SSH process to finish
2015-06-12 20:49:41|5114|7|7|image|OS.pm:is_ssh_responding|955|WIN7_VM_1 is
responding to SSH, port 22: open, port 24: open
2015-06-12 20:49:41|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:49:41|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:49:43|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:49:43|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'C:/Cygwin/home/root/VCL'
2015-06-12 20:49:44|5114|7|7|image|OS.pm:create_text_file|2217|created text
file on WIN7_VM_1: ~/currentimage.txt
2015-06-12
20:49:44|5114|7|7|image|Module.pm:get_package_hierarchy|1245|returning for
VCL::Module::OS::Windows::Version_6::7:
|5114|7|7|image|Module.pm:get_package_hierarchy|1245|
VCL::Module::OS::Windows::Version_6::7
|5114|7|7|image|Module.pm:get_package_hierarchy|1245|
VCL::Module::OS::Windows::Version_6
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module::OS::Windows
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module::OS
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module
2015-06-12
20:49:44|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory: /usr/local/vcl-2.4.2/bin/../tools/Windows_7
2015-06-12
20:49:44|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory:
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6
2015-06-12
20:49:44|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory: /usr/local/vcl-2.4.2/bin/../tools/Windows
2015-06-12
20:49:44|5114|7|7|image|OS.pm:get_source_configuration_directories|442|source
configuration directory is not defined for VCL::Module::OS
2015-06-12
20:49:44|5114|7|7|image|OS.pm:get_source_configuration_directories|442|source
configuration directory is not defined for VCL::Module
2015-06-12 20:49:44|5114|7|7|image|Windows.pm:is_64_bit|8765|64-bit Windows OS
previously detected
2015-06-12 20:49:44|5114|7|7|image|Windows.pm:is_64_bit|8765|64-bit Windows OS
previously detected
2015-06-12 20:49:44|5114|7|7|image|OS.pm:get_tools_file_paths|3451|attempting
for find tools files:
|5114|7|7|image|OS.pm:get_tools_file_paths|3451| pattern: /Scripts/pre_capture/
|5114|7|7|image|OS.pm:get_tools_file_paths|3451| architecture: x86_64
|5114|7|7|image|OS.pm:get_tools_file_paths|3451| other architecture: x86
2015-06-12 20:49:45|5114|7|7|image|OS.pm:find_files|3317|base directory does
not exist on WIN7_VM_1: C:/Cygwin/home/root/VCL/
2015-06-12 20:49:45|5114|7|7|image|OS.pm:find_files|3337|files found under
C:/Cygwin/home/root/VCL/ matching '*': 0
2015-06-12 20:49:45|5114|7|7|image|OS.pm:find_files|3337|files found under
/usr/local/vcl-2.4.2/bin/../tools/Windows/ matching '*': 13
2015-06-12 20:49:45|5114|7|7|image|OS.pm:find_files|3337|files found under
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/ matching '*': 3
2015-06-12 20:49:45|5114|7|7|image|OS.pm:find_files|3337|files found under
/usr/local/vcl-2.4.2/bin/../tools/Windows_7/ matching '*': 0
2015-06-12 20:49:45|5114|7|7|image|OS.pm:get_tools_file_paths|3523|determined
list of tools files intended for WIN7_VM_1, pattern: /Scripts/pre_capture/,
architecture: x86_64:
2015-06-12
20:49:45|5114|7|7|image|OS.pm:run_management_node_tools_scripts|3712|no custom
scripts reside on this management node for vmwarewin7-WIN7VM18-v0
2015-06-12 20:49:45|5114|7|7|image|OS.pm:pre_capture|135|completed common image
capture preparation tasks
2015-06-12 20:49:45|5114|7|7|image|Windows.pm:pre_capture|275|beginning Windows
image capture preparation tasks on WIN7_VM_1
2015-06-12 20:49:45|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/netsh.exe
advfirewall firewall delete rule name=all dir=in protocol=TCP localport=3389'
2015-06-12 20:49:46|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:49:46
2015-06-12 20:49:46|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/netsh.exe advfirewall firewall delete rule name=all dir=in
protocol=TCP localport=3389', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| No rules match the specified
criteria.
2015-06-12 20:49:46|5114|7|7|image|Version_6.pm:firewall_disable_rdp|1251|no
firewall rules exist which enable RDP
2015-06-12 20:49:46|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/qwinsta.exe'
2015-06-12 20:49:46|5114|7|7|image|utils.pm:(eval)|4241|waiting up to 60
seconds for SSH process to finish
2015-06-12 20:49:47|5114|7|7|image|OS.pm:execute|2501|executed command:
'C:/Windows/System32/qwinsta.exe', exit status: 0, output:
|5114|7|7|image|OS.pm:execute|2501| SESSIONNAME USERNAME
ID STATE TYPE DEVICE
|5114|7|7|image|OS.pm:execute|2501| >services
0 Disc
|5114|7|7|image|OS.pm:execute|2501| console
1 Conn
|5114|7|7|image|OS.pm:execute|2501| rdp-tcp
65536 Listen
2015-06-12
20:49:47|5114|7|7|image|Module.pm:get_package_hierarchy|1245|returning for
VCL::Module::OS::Windows::Version_6::7:
|5114|7|7|image|Module.pm:get_package_hierarchy|1245|
VCL::Module::OS::Windows::Version_6::7
|5114|7|7|image|Module.pm:get_package_hierarchy|1245|
VCL::Module::OS::Windows::Version_6
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module::OS::Windows
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module::OS
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module
2015-06-12
20:49:47|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory: /usr/local/vcl-2.4.2/bin/../tools/Windows_7
2015-06-12
20:49:47|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory:
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6
2015-06-12
20:49:47|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory: /usr/local/vcl-2.4.2/bin/../tools/Windows
2015-06-12
20:49:47|5114|7|7|image|OS.pm:get_source_configuration_directories|442|source
configuration directory is not defined for VCL::Module::OS
2015-06-12
20:49:47|5114|7|7|image|OS.pm:get_source_configuration_directories|442|source
configuration directory is not defined for VCL::Module
2015-06-12
20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7747|checking if
any security templates exist in:
/usr/local/vcl-2.4.2/bin/../tools/Windows/Security
2015-06-12 20:49:47|5114|7|7|image|utils.pm:run_command|8945|executed command:
find /usr/local/vcl-2.4.2/bin/../tools/Windows/Security -name "*.inf" 2>&1 |
sort -f, exit status: 0, output:
|5114|7|7|image|utils.pm:run_command|8945|
/usr/local/vcl-2.4.2/bin/../tools/Windows/Security/Windows_Security_Policies.inf
2015-06-12 20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7756|ran
find, output:
|5114|7|7|image|Windows.pm:apply_security_templates|7756|
/usr/local/vcl-2.4.2/bin/../tools/Windows/Security/Windows_Security_Policies.inf
2015-06-12
20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7747|checking if
any security templates exist in:
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security
2015-06-12 20:49:47|5114|7|7|image|utils.pm:run_command|8945|executed command:
find /usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security -name "*.inf"
2>&1 | sort -f, exit status: 0, output:
|5114|7|7|image|utils.pm:run_command|8945|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/My_Computer_Reg_Perms.inf
|5114|7|7|image|utils.pm:run_command|8945|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Security_Center_Reg_Perms.inf
|5114|7|7|image|utils.pm:run_command|8945|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Sysprep_Reg_Perms.inf
2015-06-12 20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7756|ran
find, output:
|5114|7|7|image|Windows.pm:apply_security_templates|7756|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/My_Computer_Reg_Perms.inf
|5114|7|7|image|Windows.pm:apply_security_templates|7756|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Security_Center_Reg_Perms.inf
|5114|7|7|image|Windows.pm:apply_security_templates|7756|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Sysprep_Reg_Perms.inf
2015-06-12
20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7747|checking if
any security templates exist in:
/usr/local/vcl-2.4.2/bin/../tools/Windows_7/Security
2015-06-12 20:49:47|5114|7|7|image|utils.pm:run_command|8945|executed command:
find /usr/local/vcl-2.4.2/bin/../tools/Windows_7/Security -name "*.inf" 2>&1 |
sort -f, exit status: 0, output:
|5114|7|7|image|utils.pm:run_command|8945| find:
`/usr/local/vcl-2.4.2/bin/../tools/Windows_7/Security': No such file or
directory
2015-06-12
20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7752|path does not
exist: /usr/local/vcl-2.4.2/bin/../tools/Windows_7/Security
2015-06-12
20:49:47|5114|7|7|image|Windows.pm:apply_security_templates|7771|security
templates will be applied in this order:
|5114|7|7|image|Windows.pm:apply_security_templates|7771|
/usr/local/vcl-2.4.2/bin/../tools/Windows/Security/Windows_Security_Policies.inf
|5114|7|7|image|Windows.pm:apply_security_templates|7771|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/My_Computer_Reg_Perms.inf
|5114|7|7|image|Windows.pm:apply_security_templates|7771|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Security_Center_Reg_Perms.inf
|5114|7|7|image|Windows.pm:apply_security_templates|7771|
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Sysprep_Reg_Perms.inf
2015-06-12 20:49:47|5114|7|7|image|Windows.pm:create_directory|1233|attempting
to create directory: 'C:/Cygwin/home/root/VCL/Security'
2015-06-12 20:49:47|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'cmd.exe /c "mkdir
\"C:/Cygwin/home/root/VCL/Security\""'
2015-06-12 20:49:48|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'cmd.exe /c "mkdir \"C:/Cygwin/home/root/VCL/Security\""', output:
2015-06-12 20:49:48|5114|7|7|image|Windows.pm:create_directory|1239|created
directory on WIN7_VM_1: 'C:/Cygwin/home/root/VCL/Security'
2015-06-12 20:49:48|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: 'C:/Cygwin/home/root/VCL/Security'
2015-06-12 20:49:48|5114|7|7|image|Windows.pm:create_directory|1260|verified
directory exists on WIN7_VM_1: 'C:/Cygwin/home/root/VCL/Security'
2015-06-12
20:49:48|5114|7|7|image|Windows.pm:apply_security_templates|7792|attempting to
copy file to: C:/Cygwin/home/root/VCL/Security/1_Windows_Security_Policies.inf
2015-06-12 20:49:48|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows/Security/Windows_Security_Policies.inf'
-->
'192.168.0.106:C:/Cygwin/home/root/VCL/Security/1_Windows_Security_Policies.inf'
2015-06-12 20:49:49|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows/Security/Windows_Security_Policies.inf'
-->
'192.168.0.106:C:/Cygwin/home/root/VCL/Security/1_Windows_Security_Policies.inf'
2015-06-12
20:49:49|5114|7|7|image|Windows.pm:apply_security_templates|7794|copied file:
WIN7_VM_1:C:/Cygwin/home/root/VCL/Security/1_Windows_Security_Policies.inf
2015-06-12 20:49:51|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: '$SYSTEMROOT/security/Logs/1_Windows_Security_Policies.log'
2015-06-12 20:49:51|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: '$SYSTEMROOT/security/Logs/1_Windows_Security_Policies.log'
2015-06-12 20:49:52|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: '$SYSTEMROOT/security/Logs/1_Windows_Security_Policies.log'
2015-06-12 20:49:52|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'$SYSTEMROOT/security/Logs/1_Windows_Security_Policies.log'
2015-06-12 20:49:55|5114|7|7|image|Windows.pm:apply_security_templates|7823|ran
secedit.exe to apply Windows_Security_Policies.inf
2015-06-12
20:49:55|5114|7|7|image|Windows.pm:apply_security_templates|7792|attempting to
copy file to: C:/Cygwin/home/root/VCL/Security/2_My_Computer_Reg_Perms.inf
2015-06-12 20:49:55|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/My_Computer_Reg_Perms.inf'
-->
'192.168.0.106:C:/Cygwin/home/root/VCL/Security/2_My_Computer_Reg_Perms.inf'
2015-06-12 20:49:56|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:49:56
2015-06-12 20:49:56|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/My_Computer_Reg_Perms.inf'
-->
'192.168.0.106:C:/Cygwin/home/root/VCL/Security/2_My_Computer_Reg_Perms.inf'
2015-06-12
20:49:56|5114|7|7|image|Windows.pm:apply_security_templates|7794|copied file:
WIN7_VM_1:C:/Cygwin/home/root/VCL/Security/2_My_Computer_Reg_Perms.inf
2015-06-12
20:49:56|5114|7|7|image|utils.pm:get_management_node_info|4589|retrieving
current management node info for 'localhost' from database, cached data is
stale: 61 seconds old
2015-06-12 20:49:56|5114|7|7|image|utils.pm:get_variable|12093|variable
'cluster_inuse_check' is not set in the database
2015-06-12 20:49:56|5114|7|7|image|utils.pm:get_variable|12093|variable
'user_password_length' is not set in the database
2015-06-12 20:49:56|5114|7|7|image|utils.pm:get_variable|12093|variable
'user_password_spchar' is not set in the database
2015-06-12
20:49:56|5114|7|7|image|utils.pm:get_management_node_info|4751|retrieved
management node info: 'localhost' (localhost)
2015-06-12 20:49:57|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: '$SYSTEMROOT/security/Logs/2_My_Computer_Reg_Perms.log'
2015-06-12 20:49:57|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: '$SYSTEMROOT/security/Logs/2_My_Computer_Reg_Perms.log'
2015-06-12 20:49:59|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: '$SYSTEMROOT/security/Logs/2_My_Computer_Reg_Perms.log'
2015-06-12 20:49:59|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'$SYSTEMROOT/security/Logs/2_My_Computer_Reg_Perms.log'
2015-06-12 20:50:01|5114|7|7|image|Windows.pm:apply_security_templates|7823|ran
secedit.exe to apply My_Computer_Reg_Perms.inf
2015-06-12
20:50:01|5114|7|7|image|Windows.pm:apply_security_templates|7792|attempting to
copy file to: C:/Cygwin/home/root/VCL/Security/3_Security_Center_Reg_Perms.inf
2015-06-12 20:50:01|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Security_Center_Reg_Perms.inf'
-->
'192.168.0.106:C:/Cygwin/home/root/VCL/Security/3_Security_Center_Reg_Perms.inf'
2015-06-12 20:50:02|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Security_Center_Reg_Perms.inf'
-->
'192.168.0.106:C:/Cygwin/home/root/VCL/Security/3_Security_Center_Reg_Perms.inf'
2015-06-12
20:50:02|5114|7|7|image|Windows.pm:apply_security_templates|7794|copied file:
WIN7_VM_1:C:/Cygwin/home/root/VCL/Security/3_Security_Center_Reg_Perms.inf
2015-06-12 20:50:04|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: '$SYSTEMROOT/security/Logs/3_Security_Center_Reg_Perms.log'
2015-06-12 20:50:04|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: '$SYSTEMROOT/security/Logs/3_Security_Center_Reg_Perms.log'
2015-06-12 20:50:05|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: '$SYSTEMROOT/security/Logs/3_Security_Center_Reg_Perms.log'
2015-06-12 20:50:05|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'$SYSTEMROOT/security/Logs/3_Security_Center_Reg_Perms.log'
2015-06-12 20:50:06|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:50:06
2015-06-12 20:50:08|5114|7|7|image|Windows.pm:apply_security_templates|7823|ran
secedit.exe to apply Security_Center_Reg_Perms.inf
2015-06-12
20:50:08|5114|7|7|image|Windows.pm:apply_security_templates|7792|attempting to
copy file to: C:/Cygwin/home/root/VCL/Security/4_Sysprep_Reg_Perms.inf
2015-06-12 20:50:08|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Sysprep_Reg_Perms.inf'
--> '192.168.0.106:C:/Cygwin/home/root/VCL/Security/4_Sysprep_Reg_Perms.inf'
2015-06-12 20:50:08|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP:
'/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/Security/Sysprep_Reg_Perms.inf'
--> '192.168.0.106:C:/Cygwin/home/root/VCL/Security/4_Sysprep_Reg_Perms.inf'
2015-06-12
20:50:08|5114|7|7|image|Windows.pm:apply_security_templates|7794|copied file:
WIN7_VM_1:C:/Cygwin/home/root/VCL/Security/4_Sysprep_Reg_Perms.inf
2015-06-12 20:50:10|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: '$SYSTEMROOT/security/Logs/4_Sysprep_Reg_Perms.log'
2015-06-12 20:50:10|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: '$SYSTEMROOT/security/Logs/4_Sysprep_Reg_Perms.log'
2015-06-12 20:50:11|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: '$SYSTEMROOT/security/Logs/4_Sysprep_Reg_Perms.log'
2015-06-12 20:50:11|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'$SYSTEMROOT/security/Logs/4_Sysprep_Reg_Perms.log'
2015-06-12 20:50:13|5114|7|7|image|Windows.pm:apply_security_templates|7823|ran
secedit.exe to apply Sysprep_Reg_Perms.inf
2015-06-12 20:50:13|5114|7|7|image|Windows.pm:set_password|2161|setting
password of Administrator to cl0udy on WIN7_VM_1
2015-06-12 20:50:13|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/net.exe user
Administrator 'cl0udy''
2015-06-12 20:50:14|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/net.exe user Administrator 'cl0udy'', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| The command completed
successfully.
2015-06-12 20:50:14|5114|7|7|image|Windows.pm:set_password|2163|password
changed to 'cl0udy' for user 'Administrator' on WIN7_VM_1
2015-06-12
20:50:14|5114|7|7|image|Windows.pm:get_service_configuration|4053|retrieving
service configuration information from the registry
2015-06-12 20:50:14|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'cygpath.exe -m /'
2015-06-12 20:50:15|5114|7|7|image|OS.pm:execute|2501|executed command:
'cygpath.exe -m /', exit status: 0, output:
|5114|7|7|image|OS.pm:execute|2501| C:/cygwin64
2015-06-12
20:50:15|5114|7|7|image|Windows.pm:get_cygwin_installation_directory_path|12098|determined
Cygwin installation directory path on WIN7_VM_1: C:/cygwin64
2015-06-12 20:50:15|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'cmd.exe /c "del /Q
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\" 2>NUL &
C:/Windows/System32/reg.exe EXPORT
HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Services
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\" && type
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\" >
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg\" && del /Q
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\""'
2015-06-12 20:50:16|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:50:16
2015-06-12 20:50:16|5114|7|7|image|OS.pm:execute|2501|executed command:
'cmd.exe /c "del /Q \"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\" 2>NUL &
C:/Windows/System32/reg.exe EXPORT
HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Services
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\" && type
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\" >
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg\" && del /Q
\"C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg.tmp\""', exit status: 0, output:
|5114|7|7|image|OS.pm:execute|2501| The operation completed successfully.
2015-06-12 20:50:16|5114|7|7|image|Windows.pm:reg_export|3033|exported registry
key HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Services to file:
C:\\cygwin64\\tmp\\services_WIN7_VM_1.reg
2015-06-12 20:50:16|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP: '192.168.0.106:"C:/cygwin64/tmp/services_WIN7_VM_1.reg"' -->
'/tmp/vcl/services_WIN7_VM_1.reg'
2015-06-12 20:50:17|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP: '192.168.0.106:"C:/cygwin64/tmp/services_WIN7_VM_1.reg"' -->
'/tmp/vcl/services_WIN7_VM_1.reg'
2015-06-12 20:50:17|5114|7|7|image|OS.pm:copy_file_from|3247|copied file from
WIN7_VM_1 to management node:
WIN7_VM_1:'C:/cygwin64/tmp/services_WIN7_VM_1.reg' -->
'/tmp/vcl/services_WIN7_VM_1.reg'
2015-06-12 20:50:17|5114|7|7|image|OS.pm:get_file_contents|2341|retrieved 26184
lines from file on localhost: '/tmp/vcl/services_WIN7_VM_1.reg'
2015-06-12 20:50:18|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: 'C:/cygwin64/tmp/services_WIN7_VM_1.reg'
2015-06-12 20:50:18|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: 'C:/cygwin64/tmp/services_WIN7_VM_1.reg'
2015-06-12 20:50:19|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/cygwin64/tmp/services_WIN7_VM_1.reg'
2015-06-12 20:50:19|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'C:/cygwin64/tmp/services_WIN7_VM_1.reg'
2015-06-12 20:50:19|5114|7|7|image|Linux.pm:delete_file|1661|deleted
'/tmp/vcl/services_WIN7_VM_1.reg' on localhost
2015-06-12
20:50:20|5114|7|7|image|Windows.pm:get_services_using_login_id|4144|services
found using login ID 'Administrator' (0):
2015-06-12
20:50:23|5114|7|7|image|Windows.pm:get_scheduled_task_info|4308|found 82
scheduled tasks
2015-06-12 20:50:23|5114|7|7|image|Windows.pm:set_password|2205|changed
password for user: Administrator
2015-06-12 20:50:23|5114|7|7|image|Windows.pm:set_password|2161|setting
password of root to cl0udy on WIN7_VM_1
2015-06-12 20:50:23|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/net.exe user root
'cl0udy''
2015-06-12 20:50:24|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/net.exe user root 'cl0udy'', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| The command completed
successfully.
2015-06-12 20:50:24|5114|7|7|image|Windows.pm:set_password|2163|password
changed to 'cl0udy' for user 'root' on WIN7_VM_1
2015-06-12
20:50:24|5114|7|7|image|Windows.pm:get_services_using_login_id|4144|services
found using login ID 'root' (1): sshd
2015-06-12 20:50:24|5114|7|7|image|Windows.pm:set_password|2184|sshd service is
configured to run as root, updating service credentials
2015-06-12 20:50:24|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/sc.exe config sshd
obj= ".\root" password= "cl0udy"'
2015-06-12 20:50:25|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/sc.exe config sshd obj= ".\root" password= "cl0udy"',
output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| [SC] ChangeServiceConfig SUCCESS
2015-06-12
20:50:25|5114|7|7|image|Windows.pm:set_service_credentials|4012|changed logon
credentials for 'sshd' service to root (cl0udy) on WIN7_VM_1
2015-06-12 20:50:25|5114|7|7|image|Windows.pm:set_password|2205|changed
password for user: root
2015-06-12 20:50:25|5114|7|7|image|Windows.pm:delete_user|2089|attempting to
delete user admin from WIN7_VM_1
2015-06-12 20:50:25|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/net.exe user admin
/DELETE'
2015-06-12 20:50:25|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/net.exe user admin /DELETE', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| The user name could not be found.
|5114|7|7|image|utils.pm:run_ssh_command|4365| More help is available by typing
NET HELPMSG 2221.
2015-06-12 20:50:25|5114|7|7|image|Windows.pm:delete_user|2094|user admin was
not deleted because user does not exist
2015-06-12 20:50:26|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:50:26
2015-06-12 20:50:26|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/Documents and Settings/admin'
2015-06-12 20:50:26|5114|7|7|image|Windows.pm:delete_file|1293|file not deleted
because it does not exist: 'C:/Documents and Settings/admin'
2015-06-12 20:50:26|5114|7|7|image|Windows.pm:delete_user|2110|deleted profile
for user admin from WIN7_VM_1
2015-06-12 20:50:27|5114|7|7|image|Windows.pm:set_file_owner|1640|set root as
the owner of /home/root, files and directories modified: 12
2015-06-12 20:50:27|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/netsh.exe interface
ip show address name="Local Area Connection"'
2015-06-12 20:50:28|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/netsh.exe interface ip show address name="Local Area
Connection"', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| Configuration for interface
"Local Area Connection"
|5114|7|7|image|utils.pm:run_ssh_command|4365| DHCP enabled:
Yes
|5114|7|7|image|utils.pm:run_ssh_command|4365| IP Address:
10.18.198.99
|5114|7|7|image|utils.pm:run_ssh_command|4365| Subnet Prefix:
10.18.198.0/23 (mask 255.255.254.0)
|5114|7|7|image|utils.pm:run_ssh_command|4365| Default Gateway:
10.18.198.1
|5114|7|7|image|utils.pm:run_ssh_command|4365| Gateway Metric:
0
|5114|7|7|image|utils.pm:run_ssh_command|4365| InterfaceMetric:
10
2015-06-12 20:50:28|5114|7|7|image|Windows.pm:is_dhcp_enabled|6023|DHCP is
enabled on interface 'Local Area Connection'
2015-06-12 20:50:28|5114|7|7|image|Windows.pm:enable_dhcp|5963|DHCP is already
enabled on interface 'Local Area Connection'
2015-06-12
20:50:28|5114|7|7|image|Module.pm:get_package_hierarchy|1245|returning for
VCL::Module::OS::Windows::Version_6::7:
|5114|7|7|image|Module.pm:get_package_hierarchy|1245|
VCL::Module::OS::Windows::Version_6::7
|5114|7|7|image|Module.pm:get_package_hierarchy|1245|
VCL::Module::OS::Windows::Version_6
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module::OS::Windows
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module::OS
|5114|7|7|image|Module.pm:get_package_hierarchy|1245| VCL::Module
2015-06-12
20:50:28|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory: /usr/local/vcl-2.4.2/bin/../tools/Windows_7
2015-06-12
20:50:28|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory:
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6
2015-06-12
20:50:28|5114|7|7|image|OS.pm:get_source_configuration_directories|446|package
source configuration directory: /usr/local/vcl-2.4.2/bin/../tools/Windows
2015-06-12
20:50:28|5114|7|7|image|OS.pm:get_source_configuration_directories|442|source
configuration directory is not defined for VCL::Module::OS
2015-06-12
20:50:28|5114|7|7|image|OS.pm:get_source_configuration_directories|442|source
configuration directory is not defined for VCL::Module
2015-06-12 20:50:28|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/Windows/System32/GroupPolicy/User/Scripts/*VCL*'
2015-06-12 20:50:30|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/VCL'
2015-06-12 20:50:30|5114|7|7|image|Windows.pm:delete_file|1293|file not deleted
because it does not exist: 'C:/VCL'
2015-06-12 20:50:30|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'C:/Windows/System32/schtasks.exe /Delete
/F /TN "VCL Startup Configuration"'
2015-06-12 20:50:31|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'C:/Windows/System32/schtasks.exe /Delete /F /TN "VCL Startup Configuration"',
output:
|5114|7|7|image|utils.pm:run_ssh_command|4365| ERROR: The system cannot find
the file specified.
2015-06-12
20:50:31|5114|7|7|image|Windows.pm:delete_scheduled_task|3353|scheduled task
'VCL Startup Configuration' does not exist on WIN7_VM_1
2015-06-12 20:50:31|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: '$SYSTEMDRIVE/Documents and Settings/root/Application
Data/VCL'
2015-06-12 20:50:31|5114|7|7|image|Windows.pm:delete_file|1293|file not deleted
because it does not exist: '$SYSTEMDRIVE/Documents and
Settings/root/Application Data/VCL'
2015-06-12
20:50:31|5114|7|7|image|Windows.pm:delete_capture_configuration_files|6147|attempting
to remove old configuration directory if it exists: C:/Cygwin/home/root/VCL
2015-06-12 20:50:32|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:32|5114|7|7|image|Windows.pm:delete_file|1300|attempting to
delete file: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:34|5114|7|7|image|Windows.pm:file_exists|1570|file does NOT
exist on WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:34|5114|7|7|image|Windows.pm:delete_file|1364|deleted file:
'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:34|5114|7|7|image|Windows.pm:create_directory|1233|attempting
to create directory: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:34|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'cmd.exe /c "mkdir
\"C:/Cygwin/home/root/VCL\""'
2015-06-12 20:50:34|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'cmd.exe /c "mkdir \"C:/Cygwin/home/root/VCL\""', output:
2015-06-12 20:50:34|5114|7|7|image|Windows.pm:create_directory|1239|created
directory on WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:35|5114|7|7|image|Windows.pm:file_exists|1579|file exists on
WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12 20:50:35|5114|7|7|image|Windows.pm:create_directory|1260|verified
directory exists on WIN7_VM_1: 'C:/Cygwin/home/root/VCL'
2015-06-12
20:50:35|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6931|copying
image capture configuration files from
/usr/local/vcl-2.4.2/bin/../tools/Windows to WIN7_VM_1
2015-06-12 20:50:35|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP: '/usr/local/vcl-2.4.2/bin/../tools/Windows/*' -->
'192.168.0.106:C:/Cygwin/home/root/VCL'
2015-06-12 20:50:36|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:50:36
2015-06-12 20:50:39|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP: '/usr/local/vcl-2.4.2/bin/../tools/Windows/*' -->
'192.168.0.106:C:/Cygwin/home/root/VCL'
2015-06-12
20:50:39|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6933|copied
/usr/local/vcl-2.4.2/bin/../tools/Windows directory to
WIN7_VM_1:C:/Cygwin/home/root/VCL
2015-06-12
20:50:39|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6935|setting
permissions of C:/Cygwin/home/root/VCL to 777 on WIN7_VM_1
2015-06-12 20:50:39|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): '/usr/bin/chmod.exe -R 777
C:/Cygwin/home/root/VCL'
2015-06-12 20:50:40|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'/usr/bin/chmod.exe -R 777 C:/Cygwin/home/root/VCL', output:
2015-06-12
20:50:40|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6931|copying
image capture configuration files from
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6 to WIN7_VM_1
2015-06-12 20:50:40|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP: '/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/*' -->
'192.168.0.106:C:/Cygwin/home/root/VCL'
2015-06-12 20:50:43|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP: '/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6/*' -->
'192.168.0.106:C:/Cygwin/home/root/VCL'
2015-06-12
20:50:43|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6933|copied
/usr/local/vcl-2.4.2/bin/../tools/Windows_Version_6 directory to
WIN7_VM_1:C:/Cygwin/home/root/VCL
2015-06-12
20:50:43|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6935|setting
permissions of C:/Cygwin/home/root/VCL to 777 on WIN7_VM_1
2015-06-12 20:50:43|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): '/usr/bin/chmod.exe -R 777
C:/Cygwin/home/root/VCL'
2015-06-12 20:50:43|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'/usr/bin/chmod.exe -R 777 C:/Cygwin/home/root/VCL', output:
2015-06-12
20:50:43|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6931|copying
image capture configuration files from
/usr/local/vcl-2.4.2/bin/../tools/Windows_7 to WIN7_VM_1
2015-06-12 20:50:43|5114|7|7|image|utils.pm:run_scp_command|4504|attempting to
copy file via SCP: '/usr/local/vcl-2.4.2/bin/../tools/Windows_7/*' -->
'192.168.0.106:C:/Cygwin/home/root/VCL'
2015-06-12 20:50:46|5114|7|7|image|utils.pm:run_scp_command|4542|copied file
via SCP: '/usr/local/vcl-2.4.2/bin/../tools/Windows_7/*' -->
'192.168.0.106:C:/Cygwin/home/root/VCL'
2015-06-12
20:50:46|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6933|copied
/usr/local/vcl-2.4.2/bin/../tools/Windows_7 directory to
WIN7_VM_1:C:/Cygwin/home/root/VCL
2015-06-12
20:50:46|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6935|setting
permissions of C:/Cygwin/home/root/VCL to 777 on WIN7_VM_1
2015-06-12 20:50:46|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): '/usr/bin/chmod.exe -R 777
C:/Cygwin/home/root/VCL'
2015-06-12 20:50:46|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:50:46
2015-06-12 20:50:46|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'/usr/bin/chmod.exe -R 777 C:/Cygwin/home/root/VCL', output:
2015-06-12 20:50:48|5114|7|7|image|Windows.pm:set_file_owner|1640|set root as
the owner of C:/Cygwin/home/root/VCL, files and directories modified: 41
2015-06-12
20:50:49|5114|7|7|image|Windows.pm:search_and_replace_in_files|6836|found files
matching pattern 'WINDOWS_ROOT_PASSWORD' in C:/Cygwin/home/root/VCL:
|5114|7|7|image|Windows.pm:search_and_replace_in_files|6836|
C:/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd
2015-06-12
20:50:49|5114|7|7|image|Windows.pm:search_and_replace_in_files|6863|replaced
'WINDOWS_ROOT_PASSWORD' with 'cl0udy' in
C:/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd
2015-06-12 20:50:49|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'cygpath.exe -u
"C:/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd"'
2015-06-12 20:50:50|5114|7|7|image|utils.pm:run_ssh_command|4365|command:
'cygpath.exe -u "C:/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd"', output:
|5114|7|7|image|utils.pm:run_ssh_command|4365|
/cygdrive/c/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd
2015-06-12
20:50:50|5114|7|7|image|Windows.pm:get_cygwin_unix_file_path|12158|determined
Cygwin/Unix-style path: C:/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd
--> /cygdrive/c/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd
2015-06-12 20:50:50|5114|7|7|image|utils.pm:run_ssh_command|4226|executing SSH
command on 192.168.0.106 (WIN7_VM_1): 'sed -i -r "s/\r*$/\r/"
/cygdrive/c/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd'
2015-06-12 20:50:51|5114|7|7|image|utils.pm:run_ssh_command|4365|command: 'sed
-i -r "s/\r*$/\r/"
/cygdrive/c/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd', output:
2015-06-12 20:50:51|5114|7|7|image|OS.pm:set_text_file_line_endings|2292|set
windows-style line endings for file:
/cygdrive/c/Cygwin/home/root/VCL/Scripts/autologon_enable.cmd
2015-06-12
20:50:51|5114|7|7|image|Windows.pm:copy_capture_configuration_files|6952|set
the Windows root password in configuration files
|5114|7|7|image|Windows.pm:import_registry_string|2542| ---- WARNING ----
|5114|7|7|image|Windows.pm:import_registry_string|2542| 2015-06-12
20:50:52|5114|7|7|image|Windows.pm:import_registry_string|2542|failed to echo
registry string contents to C:/Cygwin/tmp/disable_autoadminlogon_383.reg, exit
status: 1, output:
|5114|7|7|image|Windows.pm:import_registry_string|2542| bash:
C:/Cygwin/tmp/disable_autoadminlogon_383.reg: No such file or directory
|5114|7|7|image|Windows.pm:import_registry_string|2542| ( 0) Windows.pm,
import_registry_string (line: 2542)
|5114|7|7|image|Windows.pm:import_registry_string|2542| (-1) Windows.pm,
disable_autoadminlogon (line: 3511)
|5114|7|7|image|Windows.pm:import_registry_string|2542| (-2) Windows.pm,
pre_capture (line: 383)
|5114|7|7|image|Windows.pm:import_registry_string|2542| (-3) Version_6.pm,
pre_capture (line: 171)
|5114|7|7|image|Windows.pm:import_registry_string|2542| (-4) 7.pm, pre_capture
(line: 100)
|5114|7|7|image|Windows.pm:import_registry_string|2542| (-5) VMware.pm, capture
(line: 752)
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| ---- WARNING ----
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| 2015-06-12
20:50:52|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516|failed to
disable autoadminlogon
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| ( 0) Windows.pm,
disable_autoadminlogon (line: 3516)
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| (-1) Windows.pm,
pre_capture (line: 383)
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| (-2) Version_6.pm,
pre_capture (line: 171)
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| (-3) 7.pm, pre_capture
(line: 100)
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| (-4) VMware.pm, capture
(line: 752)
|5114|7|7|image|Windows.pm:disable_autoadminlogon|3516| (-5) image.pm, process
(line: 179)
|5114|7|7|image|Windows.pm:pre_capture|384| ---- WARNING ----
|5114|7|7|image|Windows.pm:pre_capture|384| 2015-06-12
20:50:52|5114|7|7|image|Windows.pm:pre_capture|384|unable to disable
autoadminlogon
|5114|7|7|image|Windows.pm:pre_capture|384| ( 0) Windows.pm, pre_capture (line:
384)
|5114|7|7|image|Windows.pm:pre_capture|384| (-1) Version_6.pm, pre_capture
(line: 171)
|5114|7|7|image|Windows.pm:pre_capture|384| (-2) 7.pm, pre_capture (line: 100)
|5114|7|7|image|Windows.pm:pre_capture|384| (-3) VMware.pm, capture (line: 752)
|5114|7|7|image|Windows.pm:pre_capture|384| (-4) image.pm, process (line: 179)
|5114|7|7|image|Windows.pm:pre_capture|384| (-5) vcld, make_new_child (line:
587)
|5114|7|7|image|Version_6.pm:pre_capture|175| ---- WARNING ----
|5114|7|7|image|Version_6.pm:pre_capture|175| 2015-06-12
20:50:52|5114|7|7|image|Version_6.pm:pre_capture|175|failed to execute parent
class pre_capture() subroutine
|5114|7|7|image|Version_6.pm:pre_capture|175| ( 0) Version_6.pm, pre_capture
(line: 175)
|5114|7|7|image|Version_6.pm:pre_capture|175| (-1) 7.pm, pre_capture (line: 100)
|5114|7|7|image|Version_6.pm:pre_capture|175| (-2) VMware.pm, capture (line:
752)
|5114|7|7|image|Version_6.pm:pre_capture|175| (-3) image.pm, process (line: 179)
|5114|7|7|image|Version_6.pm:pre_capture|175| (-4) vcld, make_new_child (line:
587)
|5114|7|7|image|Version_6.pm:pre_capture|175| (-5) vcld, main (line: 348)
|5114|7|7|image|7.pm:pre_capture|104| ---- WARNING ----
|5114|7|7|image|7.pm:pre_capture|104| 2015-06-12
20:50:52|5114|7|7|image|7.pm:pre_capture|104|failed to execute parent class
pre_capture() subroutine
|5114|7|7|image|7.pm:pre_capture|104| ( 0) 7.pm, pre_capture (line: 104)
|5114|7|7|image|7.pm:pre_capture|104| (-1) VMware.pm, capture (line: 752)
|5114|7|7|image|7.pm:pre_capture|104| (-2) image.pm, process (line: 179)
|5114|7|7|image|7.pm:pre_capture|104| (-3) vcld, make_new_child (line: 587)
|5114|7|7|image|7.pm:pre_capture|104| (-4) vcld, main (line: 348)
|5114|7|7|image|VMware.pm:capture|753| ---- WARNING ----
|5114|7|7|image|VMware.pm:capture|753| 2015-06-12
20:50:52|5114|7|7|image|VMware.pm:capture|753|failed to complete OS module's
pre_capture tasks
|5114|7|7|image|VMware.pm:capture|753| ( 0) VMware.pm, capture (line: 753)
|5114|7|7|image|VMware.pm:capture|753| (-1) image.pm, process (line: 179)
|5114|7|7|image|VMware.pm:capture|753| (-2) vcld, make_new_child (line: 587)
|5114|7|7|image|VMware.pm:capture|753| (-3) vcld, main (line: 348)
|5114|7|7|image|image.pm:process|183| ---- WARNING ----
|5114|7|7|image|image.pm:process|183| 2015-06-12
20:50:52|5114|7|7|image|image.pm:process|183|vmwarewin7-WIN7VM18-v0 image
failed to be captured by provisioning module
|5114|7|7|image|image.pm:process|183| ( 0) image.pm, process (line: 183)
|5114|7|7|image|image.pm:process|183| (-1) vcld, make_new_child (line: 587)
|5114|7|7|image|image.pm:process|183| (-2) vcld, main (line: 348)
2015-06-12
20:50:52|5114|7|7|image|DataStructure.pm:get_computer_state_name|2012|retrieved
current state of computer WIN7_VM_1 from the database: available
|5114|7|7|image|image.pm:reservation_failed|423| ---- CRITICAL ----
|5114|7|7|image|image.pm:reservation_failed|423| 2015-06-12
20:50:52|5114|7|7|image|image.pm:reservation_failed|423|vmwarewin7-WIN7VM18-v0
image creation failed
|5114|7|7|image|image.pm:reservation_failed|423| ( 0) image.pm,
reservation_failed (line: 423)
|5114|7|7|image|image.pm:reservation_failed|423| (-1) image.pm, process (line:
184)
|5114|7|7|image|image.pm:reservation_failed|423| (-2) vcld, make_new_child
(line: 587)
|5114|7|7|image|image.pm:reservation_failed|423| (-3) vcld, main (line: 348)
2015-06-12 20:50:52|5114|7|7|image|utils.pm:mail|1273|SUCCESS -- Sending mail
To: root@localhost, VCL -- NOTICE DELAY Image Creation WIN7VM1
2015-06-12 20:50:52|5114|7|7|image|utils.pm:update_request_state|1393|request 7
state updated to maintenance/image
2015-06-12 20:50:52|5114|7|7|image|image.pm:reservation_failed|496|request
state set to maintenance, laststate to image
2015-06-12 20:50:52|5114|7|7|image|utils.pm:update_computer_state|1452|computer
3 state updated to: maintenance
2015-06-12 20:50:52|5114|7|7|image|image.pm:reservation_failed|504|WIN7_VM_1
state set to maintenance
2015-06-12 20:50:52|5114|7|7|image|image.pm:reservation_failed|511|exiting
2015-06-12 20:50:52|5114|7|7|image|State.pm:DESTROY|1046|VCL::image destructor
called, address: 30055a8
2015-06-12 20:50:52|5114|7|7|image|State.pm:state_exit|864|beginning state
module exit tasks
|5114|7|7|image|State.pm:state_exit|864| request state argument: <not specified>
|5114|7|7|image|State.pm:state_exit|864| computer state argument: <not
specified>
|5114|7|7|image|State.pm:state_exit|864| log ending argument: <not specified>
2015-06-12
20:50:52|5114|7|7|image|DataStructure.pm:is_parent_reservation|1101|this is the
parent reservation
2015-06-12
20:50:52|5114|7|7|image|utils.pm:delete_computerloadlog_reservation|5714|deleted
rows from computerloadlog for reservation IDs: 7
2015-06-12 20:50:52|5114|7|7|image|utils.pm:insertloadlog|2737|inserted
'exited' computerloadlog entry
2015-06-12 20:50:52|5114|7|7|image|State.pm:state_exit|1015|calling subroutine:
VCL::Module::State::DESTROY, skipping call to exit
2015-06-12 20:50:52|5114|7|7|image|Module.pm:DESTROY|1880|destroying VCL::image
object, address: 30055a8
2015-06-12 20:50:52|5114|7|7|image|State.pm:DESTROY|1099|VCL::image process
duration: 86 seconds
2015-06-12 20:50:52|5114|7|7|image|Module.pm:DESTROY|1880|destroying
VCL::Module::Provisioning::VMware::VIM_SSH object, address: 41adcb8
2015-06-12 20:50:52|5114|7|7|image|VIM_SSH.pm:DESTROY|2762|vim-cmd call count: 6
2015-06-12 20:50:52|5114|7|7|image|Module.pm:DESTROY|1880|destroying
VCL::Module::Provisioning::VMware::VMware object, address: 2f55420
2015-06-12 20:50:52|5114|7|7|image|Module.pm:DESTROY|1880|destroying
VCL::Module::OS::Windows::Version_6::7 object, address: 2f55288
2015-06-12 20:50:52|5114|7|7|image|Module.pm:DESTROY|1880|destroying
VCL::Module::OS::Linux object, address: 3659a38
2015-06-12 20:50:52|5114|7|7|image|Module.pm:DESTROY|1880|destroying
VCL::Module::OS::Linux::ManagementNode object, address: 36329e0
2015-06-12 20:50:53|5049|||vcld|vcld:REAPER|741|VCL process exited for
reservation 7, PID: 5114, signal: CHLD
2015-06-12 20:50:56|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:50:56
2015-06-12 20:50:56|5049|||vcld|vcld:main|153|retrieving management node info
for '1', cached data is stale: 121 seconds old
2015-06-12
20:50:56|5049|||vcld|utils.pm:get_management_node_info|4589|retrieving current
management node info for 'localhost' from database, cached data is stale: 121
seconds old
2015-06-12 20:50:56|5049|||vcld|utils.pm:get_variable|12093|variable
'cluster_inuse_check' is not set in the database
2015-06-12 20:50:56|5049|||vcld|utils.pm:get_variable|12093|variable
'user_password_length' is not set in the database
2015-06-12 20:50:56|5049|||vcld|utils.pm:get_variable|12093|variable
'user_password_spchar' is not set in the database
2015-06-12
20:50:56|5049|||vcld|utils.pm:get_management_node_info|4751|retrieved
management node info: 'localhost' (localhost)
2015-06-12 20:51:06|5049|||vcld|vcld:main|175|lastcheckin time updated for
management node 1: 2015-06-12 20:51:06
