Hi Julius, I've seen this sometimes as well, but don't have a good explanation why. A lot of tasks happen in the capture process on the windows OS and I usually just restart to see if it was a one time issue or something bigger.
Make sure to you can ssh into the target node from your management node and then restart the image capture. You should see a restart image capture button at the bottom of you dashboard on the vcl website. Aaron On Mon, Jun 15, 2015 at 11:06 PM, Siming Deng (Julius) <[email protected]> wrote: > 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 -- Aaron Peeler Program Manager Virtual Computing Lab NC State University All electronic mail messages in connection with State business which are sent to or received by this account are subject to the NC Public Records Law and may be disclosed to third parties.
