You are viewing a plain text version of this content. The canonical link for it is here.

Posted to user@vcl.apache.org by "Basilio, Norvin" <nb...@odu.edu> on 2014/02/03 16:53:25 UTC

Re: ESXi5.5 (no SDK available)

This sort of sounds like an issue that i was helped with. My issue was that 2GB was disabled at some point. Here is a link on how to enable this feature.

http://www.virtuallyghetto.com/2012/09/2gbsparse-disk-format-no-longer-working.html

Norvin
nbasilio@odu.edu<ma...@odu.edu>

On Jan 28, 2014, at 11:29 AM, António Aragão <aa...@di.uminho.pt>> wrote:

I already solve this problem but I still unable to do an image.

I still unable to create an image it warns about the vmdk copy .... :-(

Any clues about vmhostprofile ? I only use only 1 esxi5.5 server.

There is any way to purge deleted images ?

Thanks.

2014-01-28 16:19:43|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on win7sp1, returning (0, "Key Management Service machine...")
2014-01-28 16:19:43|6019|29:29|image|Version_6.pm:run_slmgr_ckms(543)|cleared kms server
2014-01-28 16:19:44|6019|29:29|image|Windows.pm:import_registry_string(2413)|registry string contents echoed to C:/Cygwin/tmp/deactivate_149.reg
2014-01-28 16:19:44|6019|29:29|image|Windows.pm:reg_import(2869)|imported registry file: C:/Cygwin/tmp/deactivate_149.reg
2014-01-28 16:19:45|6019|29:29|image|Windows.pm:file_exists(1504)|file exists on win7sp1: 'C:/Cygwin/tmp/deactivate_149.reg'
2014-01-28 16:19:45|6019|29:29|image|Windows.pm:delete_file(1217)|attempting to delete file: 'C:/Cygwin/tmp/deactivate_149.reg'
2014-01-28 16:19:47|6019|29:29|image|Windows.pm:file_exists(1495)|file does NOT exist on win7sp1: 'C:/Cygwin/tmp/deactivate_149.reg'
2014-01-28 16:19:47|6019|29:29|image|Windows.pm:delete_file(1281)|deleted file: 'C:/Cygwin/tmp/deactivate_149.reg'
2014-01-28 16:19:47|6019|29:29|image|Version_6.pm:deactivate(813)|removed kms keys from the registry
2014-01-28 16:19:47|6019|29:29|image|Version_6.pm:pre_capture(158)|returning 1
2014-01-28 16:19:47|6019|29:29|image|7.pm:pre_capture(100)|successfully executed parent class pre_capture() subroutine
2014-01-28 16:19:47|6019|29:29|image|7.pm:pre_capture(108)|beginning Windows 7 image capture preparation tasks
2014-01-28 16:19:47|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Maintenance\WinSAT"' 2>&1
2014-01-28 16:19:48|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Maintenance\WinSAT scheduled task disabled on win7sp1
2014-01-28 16:19:48|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\RAC\RacTask"' 2>&1
2014-01-28 16:19:49|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\RAC\RacTask scheduled task disabled on win7sp1
2014-01-28 16:19:49|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Application Experience\ProgramDataUpdater"' 2>&1
2014-01-28 16:19:49|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Application Experience\ProgramDataUpdater scheduled task disabled on win7sp1
2014-01-28 16:19:49|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Application Experience\AitAgent"' 2>&1
2014-01-28 16:19:50|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Application Experience\AitAgent scheduled task disabled on win7sp1
2014-01-28 16:19:50|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Customer Experience Improvement Program\KernelCeipTask"' 2>&1
2014-01-28 16:19:51|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Customer Experience Improvement Program\KernelCeipTask scheduled task disabled on win7sp1
2014-01-28 16:19:51|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Customer Experience Improvement Program\UsbCeip"' 2>&1
2014-01-28 16:19:52|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Customer Experience Improvement Program\UsbCeip scheduled task disabled on win7sp1
2014-01-28 16:19:52|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Autochk\Proxy"' 2>&1
2014-01-28 16:19:52|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Autochk\Proxy scheduled task disabled on win7sp1
2014-01-28 16:19:52|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\WindowsBackup\ConfigNotification"' 2>&1
2014-01-28 16:19:53|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\WindowsBackup\ConfigNotification scheduled task disabled on win7sp1
2014-01-28 16:19:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\DiskDiagnostic\Microsoft-Windows-DiskDiagnosticDataCollector"' 2>&1
2014-01-28 16:19:54|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\DiskDiagnostic\Microsoft-Windows-DiskDiagnosticDataCollector scheduled task disabled on win7sp1
2014-01-28 16:19:54|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Diagnosis\Scheduled"' 2>&1
2014-01-28 16:19:55|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Diagnosis\Scheduled scheduled task disabled on win7sp1
2014-01-28 16:19:55|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Registry\RegIdleBackup"' 2>&1
2014-01-28 16:19:55|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Registry\RegIdleBackup scheduled task disabled on win7sp1
2014-01-28 16:19:55|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\Power Efficiency Diagnostics\AnalyzeSystem"' 2>&1
2014-01-28 16:19:56|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Power Efficiency Diagnostics\AnalyzeSystem scheduled task disabled on win7sp1
2014-01-28 16:19:56|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN "\Microsoft\Windows\MUI\LPRemove"' 2>&1
2014-01-28 16:19:59|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\MUI\LPRemove scheduled task disabled on win7sp1
2014-01-28 16:19:59|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/sc.exe config FDResPub start= disabled' 2>&1
2014-01-28 16:20:00|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| [SC] ChangeServiceConfig SUCCESS
2014-01-28 16:20:00|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on win7sp1, returning (0, "[SC] ChangeServiceConfig SUCCE...")
2014-01-28 16:20:00|6019|29:29|image|Windows.pm:set_service_startup_mode(3771)|FDResPub service startup mode set to disabled
2014-01-28 16:20:00|6019|29:29|image|Windows.pm:get_driver_inf_paths(9753)|attempting to locate driver .inf paths matching any class
2014-01-28 16:20:00|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 '/usr/bin/grep.exe -Eirl --include="*.[iI][nN][fF]" ".*" C:/Cygwin/home/root/VCL/Drivers' 2>&1
2014-01-28 16:20:01|6019|29:29|image|Windows.pm:get_driver_inf_paths(9777)|found 0 driver .inf paths, grep output:
2014-01-28 16:20:01|6019|29:29|image|Windows.pm:set_device_path_key(9823)|no driver .inf paths were found
2014-01-28 16:20:01|6019|29:29|image|Windows.pm:set_device_path_key(9841)|device path value: %SystemRoot%\inf
2014-01-28 16:20:01|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/reg.exe ADD "HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion" /v "DevicePath" /t REG_EXPAND_SZ /d "%SystemRoot%\inf" /f' 2>&1
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:reg_add(2762)|added registry key: HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion, output:
|6019|29:29|image| The operation completed successfully.
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:set_device_path_key(9845)|set the DevicePath registry key
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3069)|command name: post_load.cmd
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3070)|command: C:/Cygwin/home/root/VCL/Scripts/post_load.cmd >> C:/Cygwin/home/root/VCL/Logs/post_load.log
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3074)|forward to backslash: C:\Cygwin\home\root\VCL\Scripts\post_load.cmd >> C:\Cygwin\home\root\VCL\Logs\post_load.log
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3078)|escape backslashes: C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd<smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log<smb://Cygwin//home//root//VCL//Logs//post_load.log>
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3082)|escaped quotes: C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd<smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log<smb://Cygwin//home//root//VCL//Logs//post_load.log>
2014-01-28 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3097)|registry string:
|6019|29:29|image| Windows Registry Editor Version 5.00
|6019|29:29|image| [HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Run]
|6019|29:29|image| "post_load.cmd"="C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd<smb://Cygwin//home//root//VCL//Scripts//post_load.cmd>  >> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log<smb://Cygwin//home//root//VCL//Logs//post_load.log>"
2014-01-28 16:20:03|6019|29:29|image|Windows.pm:import_registry_string(2413)|registry string contents echoed to C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg
2014-01-28 16:20:04|6019|29:29|image|Windows.pm:reg_import(2869)|imported registry file: C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg
2014-01-28 16:20:04|6019|29:29|image|Windows.pm:file_exists(1504)|file exists on win7sp1: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
2014-01-28 16:20:04|6019|29:29|image|Windows.pm:delete_file(1217)|attempting to delete file: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
2014-01-28 16:20:06|6019|29:29|image|Windows.pm:file_exists(1495)|file does NOT exist on win7sp1: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
2014-01-28 16:20:06|6019|29:29|image|Windows.pm:delete_file(1281)|deleted file: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
2014-01-28 16:20:06|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3100)|added HKLM run registry value, name: post_load.cmd, command: C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd<smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log<smb://Cygwin//home//root//VCL//Logs//post_load.log>
2014-01-28 16:20:06|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/reg.exe query "HKLM\SOFTWARE\Microsoft\Windows\CurrentVersion\Run"' 2>&1
2014-01-28 16:20:07|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3111)|queried 'post_load.cmd' registry key:
|6019|29:29|image| HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Run
|6019|29:29|image| VMware User Process    REG_SZ    "C:\Program Files\VMware\VMware Tools\vmtoolsd.exe" -n vmusr
|6019|29:29|image| post_load.cmd    REG_SZ    C:\Cygwin\home\root\VCL\Scripts\post_load.cmd  >> C:\Cygwin\home\root\VCL\Logs\post_load.log
2014-01-28 16:20:07|6019|29:29|image|Windows.pm:import_registry_string(2413)|registry string contents echoed to C:/Cygwin/tmp/enable_autoadminlogon_3879.reg
2014-01-28 16:20:08|6019|29:29|image|Windows.pm:reg_import(2869)|imported registry file: C:/Cygwin/tmp/enable_autoadminlogon_3879.reg
2014-01-28 16:20:09|6019|29:29|image|Windows.pm:file_exists(1504)|file exists on win7sp1: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
2014-01-28 16:20:09|6019|29:29|image|Windows.pm:delete_file(1217)|attempting to delete file: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
2014-01-28 16:20:11|6019|29:29|image|Windows.pm:file_exists(1495)|file does NOT exist on win7sp1: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
2014-01-28 16:20:11|6019|29:29|image|Windows.pm:delete_file(1281)|deleted file: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
2014-01-28 16:20:11|6019|29:29|image|Windows.pm:enable_autoadminlogon(3380)|enabled autoadminlogon
2014-01-28 16:20:11|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/taskkill.exe /F /T /FI "IMAGENAME eq logon.scr"' 2>&1
2014-01-28 16:20:12|6019|29:29|image|Windows.pm:kill_process(7798)|process does not exist matching pattern: logon.scr
2014-01-28 16:20:12|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'echo | C:/Windows/System32/Wbem/wmic.exe NTEVENTLOG WHERE LogFileName=\"Application\" CALL ClearEventLog ; echo | C:/Windows/System32/Wbem/wmic.exe NTEVENTLOG WHERE LogFileName=\"Security\" CALL ClearEventLog ; echo | C:/Windows/System32/Wbem/wmic.exe NTEVENTLOG WHERE LogFileName=\"System\" CALL ClearEventLog' 2>&1
2014-01-28 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| Executing (\\WIN7SP1\ROOT\CIMV2:Win32_NTEventlogFile.Name="C:\\Windows\\System32\\Winevt\\Logs\\Application.evtx")->ClearEventLog()
|6019|29:29|image| Method execution successful.
|6019|29:29|image| Out Parameters:
|6019|29:29|image| instance of __PARAMETERS
|6019|29:29|image| {
|6019|29:29|image| ReturnValue = 0;
|6019|29:29|image| };
|6019|29:29|image| Executing (\\WIN7SP1\ROOT\CIMV2:Win32_NTEventlogFile.Name="C:\\Windows\\System32\\Winevt\\Logs\\Security.evtx")->ClearEventLog()
|6019|29:29|image| Method execution successful.
|6019|29:29|image| Out Parameters:
|6019|29:29|image| instance of __PARAMETERS
|6019|29:29|image| {
|6019|29:29|image| ReturnValue = 0;
|6019|29:29|image| };
|6019|29:29|image| Executing (\\WIN7SP1\ROOT\CIMV2:Win32_NTEventlogFile.Name="C:\\Windows\\System32\\Winevt\\Logs\\System.evtx")->ClearEventLog()
|6019|29:29|image| Method execution successful.
|6019|29:29|image| Out Parameters:
|6019|29:29|image| instance of __PARAMETERS
|6019|29:29|image| {
|6019|29:29|image| ReturnValue = 0;
|6019|29:29|image| };
2014-01-28 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on win7sp1, returning (0, "Executing (\\WIN7SP1\ROOT\CIMV<smb://WIN7SP1/ROOT/CIMV>...")
2014-01-28 16:20:14|6019|29:29|image|Windows.pm:clear_event_log(10722)|cleared event log: Application Security System
2014-01-28 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 'C:/Windows/System32/eventcreate.exe /T INFORMATION /L APPLICATION /SO VCL /ID 555 /D "Event log cleared by VCL"' 2>&1
2014-01-28 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| SUCCESS: An event of type 'INFORMATION' was created in the 'APPLICATION' log with 'VCL' as the source.
2014-01-28 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on win7sp1, returning (0, "SUCCESS: An event of type 'INF...")
2014-01-28 16:20:14|6019|29:29|image|Windows.pm:create_eventlog_entry(3463)|created event log entry on win7sp1: Event log cleared by VCL
2014-01-28 16:20:14|6019|29:29|image|Windows.pm:shutdown(3648)|enabling DHCP and shutting down win7sp1
2014-01-28 16:20:14|6019|29:29|image|OS.pm:get_public_interface_name(1150)|returning public interface name previously retrieved: Local Area Connection 2
2014-01-28 16:20:14|6019|29:29|image|Windows.pm:get_product_name(8812)|Windows product name previously retrieved: Windows 7 Professional
2014-01-28 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on win7sp1:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 '/bin/cygstart.exe $SYSTEMROOT/system32/cmd.exe /c "C:/Windows/System32/netsh.exe interface ip set address name=\"Local Area Connection\" source=dhcp & C:/Windows/System32/netsh.exe interface ip set dnsservers name=\"Local Area Connection\" source=dhcp & C:/Windows/System32/netsh.exe interface ip set address name=\"Local Area Connection 2\" source=dhcp & C:/Windows/System32/netsh.exe interface ip set dnsservers name=\"Local Area Connection 2\" source=dhcp & C:/Windows/System32/netsh.exe interface ip reset C:/Cygwin/home/root/VCL/Logs/ipreset.log & C:/Windows/System32/ipconfig.exe /release & C:/Windows/System32/ipconfig.exe /flushdns & C:/Windows/System32/arp.exe -d * & C:/Windows/System32/route.exe DELETE 0.0.0.0 MASK 0.0.0.0 & C:/Windows/System32/shutdown.exe /s /t 0 /f"' 2>&1
2014-01-28 16:20:15|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
2014-01-28 16:20:15|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on win7sp1, returning (0, "")
2014-01-28 16:20:15|6019|29:29|image|Windows.pm:shutdown(3702)|attempt 1/12: executed shutdown command on win7sp1
2014-01-28 16:20:15|6019|29:29|image|Module.pm:code_loop_timeout(887)|waiting for win7sp1 to NOT respond to ping, maximum of 180 seconds
2014-01-28 16:20:15|6019|29:29|image|Module.pm:code_loop_timeout(909)|attempt 1: waiting for win7sp1 to NOT respond to ping (0/180 elapsed/remaining seconds), sleeping for 15 seconds
2014-01-28 16:20:31|6019|29:29|image|Module.pm:code_loop_timeout(900)|waiting for win7sp1 to NOT respond to ping, code returned true
2014-01-28 16:20:31|6019|29:29|image|Module.pm:code_loop_timeout(887)|waiting for win7sp1 to power off, maximum of 300 seconds
2014-01-28 16:20:31|6019|29:29|image|utils.pm:get_management_node_info(5456)|retrieving current management node info for 'vclnode1.apachevcl.di.uminho.pt<http://apachevcl.di.uminho.pt>' from database, cached data is stale: 68 seconds old
2014-01-28 16:20:31|6019|29:29|image|utils.pm:get_management_node_info(5603)|retrieved management node info: 'vclnode1.apachevcl.di.uminho.pt<http://apachevcl.di.uminho.pt>' (vclnode1)
2014-01-28 16:20:32|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/getallvms
2014-01-28 16:20:33|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/power.getstate 8
2014-01-28 16:20:33|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(886)|vmsvc/power.getstate 8:
|6019|29:29|image| Retrieved runtime info
|6019|29:29|image| Powered on
2014-01-28 16:20:33|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(889)|VM is powered on: /vmfs/volumes/datastore1/win7/win7.vmx
2014-01-28 16:20:33|6019|29:29|image|Module.pm:code_loop_timeout(909)|attempt 1: waiting for win7sp1 to power off (2/298 elapsed/remaining seconds), sleeping for 15 seconds
2014-01-28 16:20:49|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/getallvms
2014-01-28 16:20:50|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/power.getstate 8
2014-01-28 16:20:50|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(886)|vmsvc/power.getstate 8:
|6019|29:29|image| Retrieved runtime info
|6019|29:29|image| Powered off
2014-01-28 16:20:50|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(893)|VM is powered off: /vmfs/volumes/datastore1/win7/win7.vmx
2014-01-28 16:20:50|6019|29:29|image|Module.pm:code_loop_timeout(900)|waiting for win7sp1 to power off, code returned true
2014-01-28 16:20:50|6019|29:29|image|7.pm:pre_capture(183)|returning 1
2014-01-28 16:20:50|6019|29:29|image|Module.pm:code_loop_timeout(887)|waiting for win7sp1 to power off, maximum of 600 seconds
2014-01-28 16:20:52|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/getallvms
2014-01-28 16:20:53|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/power.getstate 8
2014-01-28 16:20:53|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(886)|vmsvc/power.getstate 8:
|6019|29:29|image| Retrieved runtime info
|6019|29:29|image| Powered off
2014-01-28 16:20:53|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(893)|VM is powered off: /vmfs/volumes/datastore1/win7/win7.vmx
2014-01-28 16:20:53|6019|29:29|image|Module.pm:code_loop_timeout(900)|waiting for win7sp1 to power off, code returned true
2014-01-28 16:20:53|6019|29:29|image|VMware.pm:capture(733)|vmx and vmdk files will not be copied or renamed directly on the host, the VM profile disk type is dedicated and the image repository is mounted on the host
2014-01-28 16:20:53|6019|29:29|image|VMware.pm:capture(770)|vmdk will be copied directly from VM host servidor12 to the image repository in the 2gbsparse disk format
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'vmware -v' 2>&1
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| VMware ESXi 5.5.0 build-1331820
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on servidor12, returning (0, "VMware ESXi 5.5.0 build-133182...")
2014-01-28 16:20:53|6019|29:29|image|VMware.pm:get_vmhost_product_name(6634)|VMware product being used on VM host servidor12: 'VMware ESXi 5.5.0 build-1331820'
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'stat /vmfs/volumes/datastore1/win7/win7\.vmdk' 2>&1
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| File: /vmfs/volumes/datastore1/win7/win7.vmdk
|6019|29:29|image| Size: 540 Blocks: 0 IO Block: 131072 regular file
|6019|29:29|image| Device: d9c89ac4ddd90dcch/15692963071898750412d Inode: 654348996 Links: 1
|6019|29:29|image| Access: (0600/-rw-------) Uid: ( 0/ root) Gid: ( 0/ root)
|6019|29:29|image| Access: 2014-01-28 09:54:19.000000000
|6019|29:29|image| Modify: 2014-01-28 11:46:16.000000000
|6019|29:29|image| Change: 2014-01-28 11:46:16.000000000
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on servidor12, returning (0, "File: /vmfs/volumes/datastore1..."<file:///vmfs/volumes/datastore1...>)
2014-01-28 16:20:53|6019|29:29|image|Linux.pm:file_exists(1449)|'/vmfs/volumes/datastore1/win7/win7.vmdk' exists on servidor12, files: 1, directories: 0, links: 0
2014-01-28 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'stat /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0/vmwarewin7\-awp439042ff54g5tg32\-v0\.vmdk' 2>&1
2014-01-28 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| stat: can't stat '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk': No such file or directory
2014-01-28 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5030)|SSH command executed on servidor12, command:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'stat /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0/vmwarewin7\-awp439042ff54g5tg32\-v0\.vmdk' 2>&1
|6019|29:29|image| returning (1, "stat: can't stat '/vmfs/volume...")
2014-01-28 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'ls -d --color=never "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0" 2>&1 || mkdir -p "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0" 2>&1 && ls -d --color=never "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0"' 2>&1
2014-01-28 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| ls: /vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0: No such file or directory
|6019|29:29|image| /vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0
2014-01-28 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on servidor12, returning (0, "ls: /vmfs/volumes/vcl_images/v...")
2014-01-28 16:20:54|6019|29:29|image|Linux.pm:create_directory(1569)|directory created on servidor12: '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0'
2014-01-28 16:20:54|6019|29:29|image|VMware.pm:copy_vmdk(5861)|attempting to copy virtual disk using vmkfstools, disk type: 2gbsparse:
|6019|29:29|image| '/vmfs/volumes/datastore1/win7/win7.vmdk' --> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
2014-01-28 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'vmkfstools -i "/vmfs/volumes/datastore1/win7/win7.vmdk" "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk" -d 2gbsparse' 2>&1
2014-01-28 16:20:56|6019|29:29|image|OS.pm:execute(1994)|executed command: 'vmkfstools -i "/vmfs/volumes/datastore1/win7/win7.vmdk" "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk" -d 2gbsparse', exit status: 255, output:
|6019|29:29|image| Destination disk format: sparse with 2GB maximum extent size
|6019|29:29|image| Cloning disk '/vmfs/volumes/datastore1/win7/win7.vmdk'...
|6019|29:29|image| Failed to clone disk: The system cannot find the file specified (25).
|6019|29:29|image| ---- WARNING ----
|6019|29:29|image| 2014-01-28 16:20:56|6019|29:29|image|VMware.pm:copy_vmdk(5865)|failed to copy virtual disk
|6019|29:29|image| command: 'vmkfstools -i "/vmfs/volumes/datastore1/win7/win7.vmdk" "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk" -d 2gbsparse'
|6019|29:29|image| output:
|6019|29:29|image| Destination disk format: sparse with 2GB maximum extent size
|6019|29:29|image| Cloning disk '/vmfs/volumes/datastore1/win7/win7.vmdk'...
|6019|29:29|image| Failed to clone disk: The system cannot find the file specified (25).
|6019|29:29|image| ( 0) VMware.pm, copy_vmdk (line: 5865)
|6019|29:29|image| (-1) VMware.pm, capture (line: 774)
|6019|29:29|image| (-2) image.pm, process (line: 162)
|6019|29:29|image| (-3) vcld, make_new_child (line: 571)
|6019|29:29|image| (-4) vcld, main (line: 350)
2014-01-28 16:20:56|6019|29:29|image|VMware.pm:copy_vmdk(5918)|attempting to copy virtual disk using vmware-vdiskmanager, disk type: 2gbsparse:
|6019|29:29|image| '/vmfs/volumes/datastore1/win7/win7.vmdk' --> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
2014-01-28 16:20:56|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'vmware-vdiskmanager -r "/vmfs/volumes/datastore1/win7/win7.vmdk" -t 1 "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk"' 2>&1
2014-01-28 16:20:57|6019|29:29|image|OS.pm:execute(1994)|executed command: 'vmware-vdiskmanager -r "/vmfs/volumes/datastore1/win7/win7.vmdk" -t 1 "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk"', exit status: 127, output:
|6019|29:29|image| sh: vmware-vdiskmanager: not found
|6019|29:29|image| ---- WARNING ----
|6019|29:29|image| 2014-01-28 16:20:57|6019|29:29|image|VMware.pm:copy_vmdk(5990)|failed to copy virtual disk on VM host servidor12, output does not contain '100% done' or 'success', command: 'vmware-vdiskmanager -r "/vmfs/volumes/datastore1/win7/win7.vmdk" -t 1 "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk"', output:
|6019|29:29|image| sh: vmware-vdiskmanager: not found
|6019|29:29|image| ( 0) VMware.pm, copy_vmdk (line: 5990)
|6019|29:29|image| (-1) VMware.pm, capture (line: 774)
|6019|29:29|image| (-2) image.pm, process (line: 162)
|6019|29:29|image| (-3) vcld, make_new_child (line: 571)
|6019|29:29|image| (-4) vcld, main (line: 350)
|6019|29:29|image| ---- WARNING ----
|6019|29:29|image| 2014-01-28 16:20:57|6019|29:29|image|VMware.pm:copy_vmdk(6055)|failed to copy virtual disk on VM host servidor12 using any available methods:
|6019|29:29|image| '/vmfs/volumes/datastore1/win7/win7.vmdk' --> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
|6019|29:29|image| ( 0) VMware.pm, copy_vmdk (line: 6055)
|6019|29:29|image| (-1) VMware.pm, capture (line: 774)
|6019|29:29|image| (-2) image.pm, process (line: 162)
|6019|29:29|image| (-3) vcld, make_new_child (line: 571)
|6019|29:29|image| (-4) vcld, main (line: 350)
2014-01-28 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'rm -rfv /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0' 2>&1
2014-01-28 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
2014-01-28 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command executed on servidor12, returning (0, "")
2014-01-28 16:20:57|6019|29:29|image|Linux.pm:delete_file(1504)|deleted '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0' on servidor12
2014-01-28 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing SSH command on servidor12:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'stat /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0' 2>&1
2014-01-28 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|6019|29:29|image| stat: can't stat '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0': No such file or directory
2014-01-28 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5030)|SSH command executed on servidor12, command:
|6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 'stat /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0' 2>&1
|6019|29:29|image| returning (1, "stat: can't stat '/vmfs/volume...")
|6019|29:29|image| ---- WARNING ----
|6019|29:29|image| 2014-01-28 16:20:57|6019|29:29|image|VMware.pm:capture(778)|failed to copy the vmdk files to the repository mounted on the VM host after the VM was powered off: '/vmfs/volumes/datastore1/win7/win7.vmdk' --> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
|6019|29:29|image| ( 0) VMware.pm, capture (line: 778)
|6019|29:29|image| (-1) image.pm, process (line: 162)
|6019|29:29|image| (-2) vcld, make_new_child (line: 571)
|6019|29:29|image| (-3) vcld, main (line: 350)
2014-01-28 16:20:57|6019|29:29|image|VMware.pm:capture(860)|vmdk file does not need to be renamed back to the original name, vmdk file path being captured is already named as the image being captured: '/vmfs/volumes/datastore1/win7/win7.vmdk'
2014-01-28 16:20:57|6019|29:29|image|VMware.pm:capture(864)|attempting to power the VM back on so that it can be captured again
2014-01-28 16:20:58|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/getallvms
2014-01-28 16:21:04|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/power.on 8
2014-01-28 16:21:05|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/getallvms
2014-01-28 16:21:06|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vmsvc/get.tasklist 8
2014-01-28 16:21:06|6019|29:29|image|VIM_SSH.pm:_wait_for_task(750)|checking status of task: haTask-8-vim.VirtualMachine.powerOn-514559911
2014-01-28 16:21:07|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed command on VM host servidor12: vim-cmd vimsvc/task_info haTask-8-vim.VirtualMachine.powerOn-514559911
2014-01-28 16:21:07|6019|29:29|image|VIM_SSH.pm:_wait_for_task(773)|task completed successfully: haTask-8-vim.VirtualMachine.powerOn-514559911
2014-01-28 16:21:07|6019|29:29|image|VIM_SSH.pm:vm_power_on(972)|powered on VM: /vmfs/volumes/datastore1/win7/win7.vmx
|6019|29:29|image| ---- WARNING ----
|6019|29:29|image| 2014-01-28 16:21:07|6019|29:29|image|image.pm:process(166)|vmwarewin7-awp439042ff54g5tg32-v0 image failed to be captured by provisioning module
|6019|29:29|image| ( 0) image.pm, process (line: 166)
|6019|29:29|image| (-1) vcld, make_new_child (line: 571)
|6019|29:29|image| (-2) vcld, main (line: 350)
2014-01-28 16:21:07|6019|29:29|image|DataStructure.pm:get_computer_private_ip_address(1617)|returning private IP address previously retrieved: 10.100.0.210
2014-01-28 16:21:07|6019|29:29|image|utils.pm:is_inblockrequest(5760)|zero rows were returned from database select
2014-01-28 16:21:07|6019|29:29|image|DataStructure.pm:get_image_affiliation_name(2121)|image owner id: 1
2014-01-28 16:21:07|6019|29:29|image|DataStructure.pm:retrieve_user_data(1412)|attempting to retrieve and store data for user: user.id = '1'
2014-01-28 16:21:07|6019|29:29|image|DataStructure.pm:retrieve_user_data(1475)|data has been retrieved for user: admin (id: 1)
2014-01-28 16:21:07|6019|29:29|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, PROBLEM -- vclnode1|29:29|image|image.pm|win7sp1>servidor12.di.uminho.pt<http://servidor12.di.uminho.pt>|vmwarewin7-awp439042ff54g5tg32-v0|admin
|6019|29:29|image| ---- CRITICAL ----
|6019|29:29|image| 2014-01-28 16:21:07|6019|29:29|image|image.pm:reservation_failed(387)|vmwarewin7-awp439042ff54g5tg32-v0 image creation failed
|6019|29:29|image| ( 0) image.pm, reservation_failed (line: 387)
|6019|29:29|image| (-1) image.pm, process (line: 167)
|6019|29:29|image| (-2) vcld, make_new_child (line: 571)
|6019|29:29|image| (-3) vcld, main (line: 350)
2014-01-28 16:21:07|6019|29:29|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, VCL -- NOTICE DELAY Image Creation awp439042ff54g5tg
2014-01-28 16:21:07|6019|29:29|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, VCL -- NOTICE FAILED Image Creation awp439042ff54g5tg
2014-01-28 16:21:07|6019|29:29|image|utils.pm:update_request_state(1545)|request 29 state updated to: maintenance, laststate to: image
2014-01-28 16:21:07|6019|29:29|image|image.pm:reservation_failed(441)|request state set to maintenance, laststate to image
2014-01-28 16:21:07|6019|29:29|image|utils.pm:update_computer_state(1587)|computer 3 state updated to: maintenance
2014-01-28 16:21:07|6019|29:29|image|image.pm:reservation_failed(449)|win7sp1 state set to maintenance
2014-01-28 16:21:07|6019|29:29|image|image.pm:reservation_failed(456)|exiting
2014-01-28 16:21:07|6019|29:29|image|utils.pm:delete_computerloadlog_reservation(6396)|removing computerloadlog entries matching loadstate = begin
2014-01-28 16:21:07|6019|29:29|image|utils.pm:delete_computerloadlog_reservation(6443)|deleted rows from computerloadlog for reservation id=29
2014-01-28 16:21:07|6019|29:29|image|State.pm:DESTROY(929)|VCL::image process duration: 351 seconds
2014-01-28 16:21:07|6019|29:29|image|VIM_SSH.pm:DESTROY(2125)|vim-cmd call count: 17
2014-01-28 16:21:07|1577|vcld:REAPER(721)|VCL process exited for reservation 29, PID: 6019, signal: CHLD
^C


Em 27-01-2014 21:45, Andy Kurth escreveu:
Is the management node configured to use vcl.key?  Check:
Managment Nodes > Edit Management Node Information > Edit

Make sure "End Node SSH Identity Key Files" is set to /etc/vcl/vcl.key.

-Andy


On Thu, Jan 23, 2014 at 9:36 AM, António Aragão <aa...@di.uminho.pt>> wrote:
I try from the management node the ssh access and it works.


[root@vclnode1 tmp]# ssh -i /etc/vcl/vcl.key 10.100.0.210
Warning: Permanently added '10.100.0.210' (RSA) to the list of known hosts.

root@win7sp1 ~
$ exitConnection to 10.100.0.210 closed.
[root@vclnode1 tmp]# ssh -i /etc/vcl/vcl.key win7sp1
Warning: Permanently added 'win7sp1,10.100.0.210' (RSA) to the list of known hosts.

root@win7sp1 ~
$ exitConnection to win7sp1 closed.
[root@vclnode1 tmp]# ssh -i /etc/vcl/vcl.key 192.168.93.210
Warning: Permanently added '192.168.93.210' (RSA) to the list of known hosts.

root@win7sp1 ~
$ exitConnection to 192.168.93.210 closed.
[root@vclnode1 tmp]#



Em 23-01-2014 11:55, toth.aron@inf.mit.bme.hu<ma...@inf.mit.bme.hu> escreveu:

Hello,

The problem is no ssh server running on the win7 vm. The links describes how to intall and configure the windows7 vm, before capturing it.

https://cwiki.apache.org/confluence/display/VCL/Create+a+Windows+Base+Image
https://cwiki.apache.org/confluence/pages/viewpage.action?pageId=4589006

Test ssh from managenet node:
ssh -i /etc/vcl/vcl.key root@<windows7 vm private address>

2014-01-23 10:43:15|6618|13:13|image|OS.pm:is_ssh_responding(682)|win7sp1 is NOT responding to SSH, SSH command failed, port 22: open, port 24: closed
|6618|13:13|image| ---- WARNING ----
|6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|VMware.pm:capture(613)|unable to capture image, VM win7sp1 is not responding to SSH

Br,

Aaron Toth
VCL System Administrator
BUTE-MIT

-----António Aragão <aa...@di.uminho.pt> ezt írta: -----
Címzett: user@vcl.apache.org<ma...@vcl.apache.org>
Feladó: António Aragão <aa...@di.uminho.pt>
Dátum: 2014/01/23 12:14du.
Tárgy: Re: ESXi5.5 (no SDK available)

Hello,

I already do that and test the ssh and it was ok. I have put in /etc/hosts (i don't know why it doesn't use dns but ...)  I have installed sdk 5.1 it gives me more information about missing perl modules than 5.5. I try to make an image and gives me this:

----------------------------------------------------------------------------
2014-01-23 10:43:06|6614|utils.pm:insert_request(8043)|inserted new reload request into request table, request id=13
2014-01-23 10:43:06|6614|utils.pm:insert_request(8074)|inserted new reload request into reservation table, reservation id=13
2014-01-23 10:43:08|1437|13:13|image|utils.pm:reservation_being_processed(8634)|computerloadlog 'begin' entry does NOT exist for reservation 13
2014-01-23 10:43:08|1437|13:13|image|utils.pm:run_command(8706)|executed command: pgrep -fl 'vcld [0-9]+:13 ', pid: 6616, exit status: 1, output:
2014-01-23 10:43:08|1437|13:13|image|utils.pm:is_management_node_process_running(8939)|process is NOT running, identifier: 'vcld [0-9]+:13 '
2014-01-23 10:43:08|1437|13:13|image|utils.pm:reservation_being_processed(8655)|reservation is NOT currently being processed
2014-01-23 10:43:08|1437|13:13|image|vcld:main(282)|reservation 13 is NOT already being processed
2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_imagemeta_info(4576)|retrieved imagemeta info:
|1437|13:13|image| : {
|1437|13:13|image| :   "architecture" => "",
|1437|13:13|image| :   "checkuser" => 1,
|1437|13:13|image| :   "id" => 15,
|1437|13:13|image| :   "postoption" => "",
|1437|13:13|image| :   "rootaccess" => 1,
|1437|13:13|image| :   "subimages" => 0,
|1437|13:13|image| :   "sysprep" => 0,
|1437|13:13|image| :   "usergroupid" => ""
|1437|13:13|image| : }
2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_management_node_info(5456)|retrieving current management node info for 'vclnode1.apachevcl.di.uminho.pt<http://vclnode1.apachevcl.di.uminho.pt/>' from database, cached data is stale: 137 seconds old
2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_management_node_info(5603)|retrieved management node info: 'vclnode1.apachevcl.di.uminho.pt<http://vclnode1.apachevcl.di.uminho.pt/>' (vclnode1)
2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_connect_method_info(10059)|attempting to retrieve connect method info:
|1437|13:13|image| imagerevision: 14 - vmwarewin7-win7_23012014_316-v0
|1437|13:13|image| OS: 35 - vmwarewin7
|1437|13:13|image| OS type: 1 - windows
2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_connect_method_info(10123)|RDP: connectmethodid=2, OStypeid=1, OSid=NULL, imagerevisionid=NULL, disabled=0
2014-01-23 10:43:09|1437|13:13|image|vcld:main(287)|retrieved request information from database
2014-01-23 10:43:09|1437|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
2014-01-23 10:43:09|1437|13:13|image|utils.pm:update_request_state(1545)|request 13 state updated to: pending, laststate to: image
2014-01-23 10:43:09|1437|13:13|image|utils.pm:insertloadlog(3665)|inserted computer=3, begin, beginning to process, state is image
2014-01-23 10:43:09|1437|13:13|image|vcld:make_new_child(518)|loaded VCL::image module
2014-01-23 10:43:09|1437|13:13|image|vcld:make_new_child(542)|current number of forked kids: 1
2014-01-23 10:43:09|6618|13:13|image|vcld:make_new_child(558)|vcld environment variable set to 0 for this process
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: noimage, DataStructure object will contain image information for the production imagerevision of this image
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_mn_os_object(376)|VCL::Module::OS::Linux::ManagementNode module loaded
2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode object created for image noimage, address: 9b394f8
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{hostname}, data identifier: computer_hostname, data:
|6618|13:13|image| : "vclnode1.apachevcl.di.uminho.pt<http://vclnode1.apachevcl.di.uminho.pt/>"
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{NODENAME}, data identifier: computer_node_name, data:
|6618|13:13|image| : "vclnode1"
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{SHORTNAME}, data identifier: computer_short_name, data:
|6618|13:13|image| : "vclnode1"
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{IPaddress}, data identifier: computer_ip_address, data:
|6618|13:13|image| : "192.168.93.2"
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_mn_os_object(381)|VCL::Module::OS::Linux::ManagementNode OS object created, address: 9b394f8
2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(192)|VCL::image object created for state image, address: 9b27068
2014-01-23 10:43:09|6618|13:13|image|State.pm:initialize(88)|obtained a database handle for this state process, stored as $ENV{dbh}
2014-01-23 10:43:09|6618|13:13|image|State.pm:check_image_os(790)|no corrections need to be made to image OS: vmwarewin7
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6752)|reservation count: 1
2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6771)|PARENTIMAGE: 1
2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6772)|SUBIMAGE: 0
2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6790)|renamed process to 'vcld 13:13 image win7sp1>servidor12.di.uminho.pt<http://servidor12.di.uminho.pt/> vmwarewin7-win7_23012014_316-v0 admin'
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_os_object(308)|VCL::Module::OS::Windows::Version_6::7 module loaded
2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(196)|VCL::Module::OS::Windows::Version_6::7 object created for image vmwarewin7-win7_23012014_316-v0, address: 9e59210
2014-01-23 10:43:09|6618|13:13|image|Windows.pm:initialize(214)|beginning Windows module initialization
2014-01-23 10:43:09|6618|13:13|image|Windows.pm:initialize(224)|Windows module initialization complete
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_os_object(314)|VCL::Module::OS::Windows::Version_6::7 OS object created, address: 9e59210
2014-01-23 10:43:09|6618|13:13|image|State.pm:initialize(125)|computer is a VM, attempting to create VM host OS object
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_vmhost_os_object(454)|attempting to load VM host OS module: VCL::Module::OS::Linux::UnixLab (image: 1)
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_vmhost_os_object(460)|VM host OS module loaded: VCL::Module::OS::Linux::UnixLab
2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab object created for image noimage, address: 9e6c620
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_vmhost_os_object(465)|VCL::Module::OS::Linux::UnixLab OS object created, address: 9e6c620
2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_provisioning_object(519)|VCL::Module::Provisioning::VMware::VMware module loaded
2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VMware object created for computer win7sp1, address: 9e6c900
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(267)|initializing VCL::Module::Provisioning::VMware::VMware object
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_datastructure(1397)|created DataStructure object for VM host: servidor12
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(287)|VM profile assigned to servidor12: vmhostprofile1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_datastructure(1397)|created DataStructure object for VM host: servidor12
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_api_object(1500)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_api_object(1506)|loaded VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK object created for computer win7sp1, address: a562510
2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(106)|loaded VMware vSphere SDK modules
2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(150)|attempting to connect to VM host: https://servidor12.di.uminho.pt/sdk (root)
2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(168)|connected to VM host: https://servidor12.di.uminho.pt/sdk, username: 'root'
2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(182)|connected to servidor12.di.uminho.pt<http://servidor12.di.uminho.pt/>, VIM object type: Vim
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_api_object(1527)|created API object: VCL::Module::Provisioning::VMware::vSphere_SDK
2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:is_restricted(1490)|access to the VM host via the vSphere SDK is NOT restricted due to the license
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(291)|vSphere SDK object will be used to control the VM host servidor12 and the VM: win7sp1
2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(327)|VMware OS and API objects created for VM host servidor12:
|6618|13:13|image| VM host OS object type: VCL::Module::Provisioning::VMware::vSphere_SDK
|6618|13:13|image| VMware API object type: VCL::Module::Provisioning::VMware::vSphere_SDK
2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:get_vmware_product_name(1355)|VMware product being used on VM host servidor12.di.uminho.pt<http://servidor12.di.uminho.pt/>: 'VMware ESXi 5.5.0 build-1331820'
2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_resource_pool_view(2803)|single resource pool found on VM host servidor12 will be used: /ha-datacenter/servidor12.di.uminho.pt<http://servidor12.di.uminho.pt/>
2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_datacenter_view(2633)|found datacenter VM host on servidor12: ha-datacenter
2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_datastore_info(3254)|retrieved datastore info:
|6618|13:13|image| : {
|6618|13:13|image| :   "datastore1" => bless( {
|6618|13:13|image| :     "accessible" => 1,
|6618|13:13|image| :     "capacity" => "4776003633152",
|6618|13:13|image| :     "datastore" => bless( {
|6618|13:13|image| :       "type" => "Datastore",
|6618|13:13|image| :       "value" => "52d5159b-22b564a2-c0af-0025905aa03c"
|6618|13:13|image| :     }, 'ManagedObjectReference' ),
|6618|13:13|image| :     "freeSpace" => "4756159332352",
|6618|13:13|image| :     "name" => "datastore1",
|6618|13:13|image| :     "normal_path" => "/vmfs/volumes/datastore1",
|6618|13:13|image| :     "type" => "VMFS",
|6618|13:13|image| :     "uncommitted" => 0,
|6618|13:13|image| :     "url" => "/vmfs/volumes/52d5159b-22b564a2-c0af-0025905aa03c"
|6618|13:13|image| :   }, 'DatastoreSummary' )
|6618|13:13|image| : }
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_datastore_info(6760)|retrieved datastore info from VM host:
|6618|13:13|image| : {
|6618|13:13|image| :   "datastore1" => bless( {
|6618|13:13|image| :     "accessible" => 1,
|6618|13:13|image| :     "capacity" => "4776003633152",
|6618|13:13|image| :     "datastore" => bless( {
|6618|13:13|image| :       "type" => "Datastore",
|6618|13:13|image| :       "value" => "52d5159b-22b564a2-c0af-0025905aa03c"
|6618|13:13|image| :     }, 'ManagedObjectReference' ),
|6618|13:13|image| :     "freeSpace" => "4756159332352",
|6618|13:13|image| :     "name" => "datastore1",
|6618|13:13|image| :     "normal_path" => "/vmfs/volumes/datastore1",
|6618|13:13|image| :     "type" => "VMFS",
|6618|13:13|image| :     "uncommitted" => 0,
|6618|13:13|image| :     "url" => "/vmfs/volumes/52d5159b-22b564a2-c0af-0025905aa03c"
|6618|13:13|image| :   }, 'DatastoreSummary' )
|6618|13:13|image| : }
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_vmx_base_directory_path(3094)|determined vmx base directory path: /vmfs/volumes/datastore1
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:_get_datastore_names(6792)|datastore names:
|6618|13:13|image| datastore1
2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:file_exists(1978)|file (datastore root) exists: [datastore1]
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:is_vm_dedicated(4257)|VM disk mode does not need to be dedicated
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:initialize(351)|not checking if vmdk base directory exists because it is the same as the vmx base directory: /vmfs/volumes/datastore1
2014-01-23 10:43:10|6618|13:13|image|Module.pm:create_provisioning_object(525)|VCL::Module::Provisioning::VMware::VMware provisioner object created for win7sp1, address: 9e6c900
2014-01-23 10:43:10|6618|13:13|image|State.pm:initialize(154)|returning 1
2014-01-23 10:43:10|6618|13:13|image|vcld:make_new_child(568)|VCL::image object created and initialized
2014-01-23 10:43:10|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, VCL IMAGE Creation Started: vmwarewin7-win7_23012014_316-v0
2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_file_info(2525)|searching for matching file paths: base directory path: '[datastore1] vmwarewin7-win7_23012014_316-v0', search pattern: 'vmwarewin7-win7_23012014_316-v0.vmdk'
2014-01-23 10:43:12|6618|13:13|image|vSphere_SDK.pm:_get_file_info(2536)|base directory does not exist: '[datastore1] vmwarewin7-win7_23012014_316-v0'
2014-01-23 10:43:12|6618|13:13|image|vSphere_SDK.pm:find_files(2125)|matching file count: 0
2014-01-23 10:43:12|6618|13:13|image|vSphere_SDK.pm:file_exists(1997)|file does not exist: [datastore1] vmwarewin7-win7_23012014_316-v0/vmwarewin7-win7_23012014_316-v0.vmdk
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:does_image_exist(4595)|image does not exist in the shared directory on the VM host
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_repository_vmdk_base_directory_path(4140)|repository path is not configured in the VM profile
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_repository_vmdk_directory_path(4181)|image repository vmdk directory path cannot be determined because repository path is not configured in the VM profile
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:does_image_exist(4601)|image does not exist on the VM host and image repository path is not configured in the VM profile
2014-01-23 10:43:12|6618|13:13|image|image.pm:process(145)|image vmwarewin7-win7_23012014_316-v0 does not exist in the repository
2014-01-23 10:43:12|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{13}{image}{lastupdate}, data identifier: image_lastupdate, data:
|6618|13:13|image| : "2014-01-23 10:43:12"
2014-01-23 10:43:12|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{13}{imagerevision}{datecreated}, data identifier: imagerevision_date_created, data:
|6618|13:13|image| : "2014-01-23 10:43:12"
2014-01-23 10:43:12|6618|13:13|image|image.pm:process(161)|calling provisioning module's capture() subroutine
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_repository_vmdk_base_directory_path(4140)|repository path is not configured in the VM profile
2014-01-23 10:43:12|6618|13:13|image|VMware.pm:is_repository_mounted_on_vmhost(6709)|unable to determine if image repository is mounted on VM host servidor12, repository path is not configured in the VM profile
2014-01-23 10:43:15|6618|13:13|image|OS.pm:is_ssh_responding(682)|win7sp1 is NOT responding to SSH, SSH command failed, port 22: open, port 24: closed
|6618|13:13|image| ---- WARNING ----
|6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|VMware.pm:capture(613)|unable to capture image, VM win7sp1 is not responding to SSH
|6618|13:13|image| ( 0) VMware.pm, capture (line: 613)
|6618|13:13|image| (-1) image.pm<http://image.pm/>, process (line: 162)
|6618|13:13|image| (-2) vcld, make_new_child (line: 571)
|6618|13:13|image| (-3) vcld, main (line: 350)
|6618|13:13|image| ---- WARNING ----
|6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|image.pm:process(166)|vmwarewin7-win7_23012014_316-v0 image failed to be captured by provisioning module
|6618|13:13|image| ( 0) image.pm<http://image.pm/>, process (line: 166)
|6618|13:13|image| (-1) vcld, make_new_child (line: 571)
|6618|13:13|image| (-2) vcld, main (line: 350)
2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:get_computer_private_ip_address(1641)|attempting to retrieve private IP address for computer: win7sp1
2014-01-23 10:43:15|6618|13:13|image|OS.pm:get_file_contents(1897)|retrieved 4 lines from file on vclnode1: '/etc/hosts'
2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:get_computer_private_ip_address(1697)|returning IP address from /etc/hosts file: 10.100.0.210
2014-01-23 10:43:15|6618|13:13|image|utils.pm:is_inblockrequest(5760)|zero rows were returned from database select
2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:get_image_affiliation_name(2121)|image owner id: 1
2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:retrieve_user_data(1412)|attempting to retrieve and store data for user: user.id<http://user.id/> = '1'
2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:retrieve_user_data(1475)|data has been retrieved for user: admin (id: 1)
2014-01-23 10:43:15|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, PROBLEM -- vclnode1|13:13|image|image.pm<http://image.pm/>|win7sp1>servidor12.di.uminho.pt<http://servidor12.di.uminho.pt/>|vmwarewin7-win7_23012014_316-v0|admin
|6618|13:13|image| ---- CRITICAL ----
|6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|image.pm:reservation_failed(387)|vmwarewin7-win7_23012014_316-v0 image creation failed
|6618|13:13|image| ( 0) image.pm<http://image.pm/>, reservation_failed (line: 387)
|6618|13:13|image| (-1) image.pm<http://image.pm/>, process (line: 167)
|6618|13:13|image| (-2) vcld, make_new_child (line: 571)
|6618|13:13|image| (-3) vcld, main (line: 350)
2014-01-23 10:43:15|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, VCL -- NOTICE DELAY Image Creation win7_23012014_3
2014-01-23 10:43:16|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To: aaragao@di.uminho.pt<ma...@di.uminho.pt>, VCL -- NOTICE FAILED Image Creation win7_23012014_3
2014-01-23 10:43:16|6618|13:13|image|utils.pm:update_request_state(1545)|request 13 state updated to: maintenance, laststate to: image
2014-01-23 10:43:16|6618|13:13|image|image.pm:reservation_failed(441)|request state set to maintenance, laststate to image
2014-01-23 10:43:16|6618|13:13|image|utils.pm:update_computer_state(1587)|computer 3 state updated to: maintenance
2014-01-23 10:43:16|6618|13:13|image|image.pm:reservation_failed(449)|win7sp1 state set to maintenance
2014-01-23 10:43:16|6618|13:13|image|image.pm:reservation_failed(456)|exiting
2014-01-23 10:43:16|6618|13:13|image|utils.pm:delete_computerloadlog_reservation(6396)|removing computerloadlog entries matching loadstate = begin
2014-01-23 10:43:16|6618|13:13|image|utils.pm:delete_computerloadlog_reservation(6443)|deleted rows from computerloadlog for reservation id=13
2014-01-23 10:43:16|6618|13:13|image|State.pm:DESTROY(929)|VCL::image process duration: 7 seconds
2014-01-23 10:43:16|1437|vcld:REAPER(721)|VCL process exited for reservation 13, PID: 6618, signal: CHLD

I try to change vmhost profile but i cannot move VMs that assigned to host, any clue to configure the current VM Profile ? I only have this basic scenario:

1 hardware server with ESXi 5.5
1 VM configured as vclweb frontend
1 VM configured as vclnode
1 VM with Windows 7 that I'm trying to make an image

All the VMs are running on this hardware server with ESXi 5.5 which only have one datastore (/vmfs/volumes/datastore1).

Any clues ?
Em 22-01-2014 15:51, toth.aron@inf.mit.bme.hu<ma...@inf.mit.bme.hu> escreveu:

Hello,

Yes. Enable ssh on the esxi 5.5 host, and copy the cloud ssh key (/etc/vcl/vcl.key.pub) to <esxi host>:/etc/ssh/keys-root/authorized_keys on ESXi 5.5, or <esxi host>:/.ssh/authorized_keys on ESXi 5.0,5,1  for key-based ssh authentication.
For more information see:
https://cwiki.apache.org/confluence/display/VCL/VMware+Configuration

Br,

Aaron Toth
VCL System Administrator
BUTE-MIT

-----António Aragão <aa...@di.uminho.pt> ezt írta: -----
Címzett: user@vcl.apache.org<ma...@vcl.apache.org>
Feladó: António Aragão <aa...@di.uminho.pt>
Dátum: 2014/01/22 03:38du.
Tárgy: ESXi5.5 (no SDK available)

Hi,

There is a way of not using SDK ? I want to use only vim-cmd since I don't any vcenter and only ESXi 5.5 host.

Thanks.


--
<Mail Attachment.png>


--
<cartao_virtual_email_aaragao.png>

Re: ESXi5.5 (no SDK available)

Posted by António Aragão <aa...@di.uminho.pt>.

Hi Norvin Basilio,

Yes it was one of the problems. I was able to create images after 
solving this issue.

Thanks.

Em 03-02-2014 15:53, Basilio, Norvin escreveu:
> This sort of sounds like an issue that i was helped with. My issue was 
> that 2GB was disabled at some point. Here is a link on how to enable 
> this feature.
>
> http://www.virtuallyghetto.com/2012/09/2gbsparse-disk-format-no-longer-working.html
>
> Norvin
> nbasilio@odu.edu <ma...@odu.edu>
>
> On Jan 28, 2014, at 11:29 AM, António Aragão <aaragao@di.uminho.pt 
> <ma...@di.uminho.pt>> wrote:
>
>> I already solve this problem but I still unable to do an image.
>>
>> I still unable to create an image it warns about the vmdk copy .... :-(
>>
>> Any clues about vmhostprofile ? I only use only 1 esxi5.5 server.
>>
>> There is any way to purge deleted images ?
>>
>> Thanks.
>>
>> 2014-01-28 
>> 16:19:43|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on win7sp1, returning (0, "Key Management Service machine...")
>> 2014-01-28 
>> 16:19:43|6019|29:29|image|Version_6.pm:run_slmgr_ckms(543)|cleared 
>> kms server
>> 2014-01-28 
>> 16:19:44|6019|29:29|image|Windows.pm:import_registry_string(2413)|registry 
>> string contents echoed to C:/Cygwin/tmp/deactivate_149.reg
>> 2014-01-28 
>> 16:19:44|6019|29:29|image|Windows.pm:reg_import(2869)|imported 
>> registry file: C:/Cygwin/tmp/deactivate_149.reg
>> 2014-01-28 
>> 16:19:45|6019|29:29|image|Windows.pm:file_exists(1504)|file exists on 
>> win7sp1: 'C:/Cygwin/tmp/deactivate_149.reg'
>> 2014-01-28 
>> 16:19:45|6019|29:29|image|Windows.pm:delete_file(1217)|attempting to 
>> delete file: 'C:/Cygwin/tmp/deactivate_149.reg'
>> 2014-01-28 
>> 16:19:47|6019|29:29|image|Windows.pm:file_exists(1495)|file does NOT 
>> exist on win7sp1: 'C:/Cygwin/tmp/deactivate_149.reg'
>> 2014-01-28 
>> 16:19:47|6019|29:29|image|Windows.pm:delete_file(1281)|deleted file: 
>> 'C:/Cygwin/tmp/deactivate_149.reg'
>> 2014-01-28 
>> 16:19:47|6019|29:29|image|Version_6.pm:deactivate(813)|removed kms 
>> keys from the registry
>> 2014-01-28 
>> 16:19:47|6019|29:29|image|Version_6.pm:pre_capture(158)|returning 1
>> 2014-01-28 
>> 16:19:47|6019|29:29|image|7.pm:pre_capture(100)|successfully executed 
>> parent class pre_capture() subroutine
>> 2014-01-28 16:19:47|6019|29:29|image|7.pm:pre_capture(108)|beginning 
>> Windows 7 image capture preparation tasks
>> 2014-01-28 
>> 16:19:47|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Maintenance\WinSAT"' 2>&1
>> 2014-01-28 
>> 16:19:48|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Maintenance\WinSAT 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:48|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\RAC\RacTask"' 2>&1
>> 2014-01-28 
>> 16:19:49|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\RAC\RacTask 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:49|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Application Experience\ProgramDataUpdater"' 2>&1
>> 2014-01-28 
>> 16:19:49|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Application 
>> Experience\ProgramDataUpdater scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:49|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Application Experience\AitAgent"' 2>&1
>> 2014-01-28 
>> 16:19:50|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Application 
>> Experience\AitAgent scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:50|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Customer Experience Improvement 
>> Program\KernelCeipTask"' 2>&1
>> 2014-01-28 
>> 16:19:51|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Customer 
>> Experience Improvement Program\KernelCeipTask scheduled task disabled 
>> on win7sp1
>> 2014-01-28 
>> 16:19:51|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Customer Experience Improvement Program\UsbCeip"' 
>> 2>&1
>> 2014-01-28 
>> 16:19:52|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Customer 
>> Experience Improvement Program\UsbCeip scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:52|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Autochk\Proxy"' 2>&1
>> 2014-01-28 
>> 16:19:52|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Autochk\Proxy 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:52|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\WindowsBackup\ConfigNotification"' 2>&1
>> 2014-01-28 
>> 16:19:53|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\WindowsBackup\ConfigNotification 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\DiskDiagnostic\Microsoft-Windows-DiskDiagnosticDataCollector"' 
>> 2>&1
>> 2014-01-28 
>> 16:19:54|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\DiskDiagnostic\Microsoft-Windows-DiskDiagnosticDataCollector 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:54|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Diagnosis\Scheduled"' 2>&1
>> 2014-01-28 
>> 16:19:55|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Diagnosis\Scheduled 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:55|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Registry\RegIdleBackup"' 2>&1
>> 2014-01-28 
>> 16:19:55|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Registry\RegIdleBackup 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:55|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\Power Efficiency Diagnostics\AnalyzeSystem"' 2>&1
>> 2014-01-28 
>> 16:19:56|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\Power 
>> Efficiency Diagnostics\AnalyzeSystem scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:56|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/schtasks.exe /Change /DISABLE /TN 
>> "\Microsoft\Windows\MUI\LPRemove"' 2>&1
>> 2014-01-28 
>> 16:19:59|6019|29:29|image|Windows.pm:disable_scheduled_task(4084)|\Microsoft\Windows\MUI\LPRemove 
>> scheduled task disabled on win7sp1
>> 2014-01-28 
>> 16:19:59|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/sc.exe config FDResPub start= disabled' 2>&1
>> 2014-01-28 
>> 16:20:00|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| [SC] ChangeServiceConfig SUCCESS
>> 2014-01-28 
>> 16:20:00|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on win7sp1, returning (0, "[SC] ChangeServiceConfig SUCCE...")
>> 2014-01-28 
>> 16:20:00|6019|29:29|image|Windows.pm:set_service_startup_mode(3771)|FDResPub 
>> service startup mode set to disabled
>> 2014-01-28 
>> 16:20:00|6019|29:29|image|Windows.pm:get_driver_inf_paths(9753)|attempting 
>> to locate driver .inf paths matching any class
>> 2014-01-28 
>> 16:20:00|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> '/usr/bin/grep.exe -Eirl --include="*.[iI][nN][fF]" ".*" 
>> C:/Cygwin/home/root/VCL/Drivers' 2>&1
>> 2014-01-28 
>> 16:20:01|6019|29:29|image|Windows.pm:get_driver_inf_paths(9777)|found 
>> 0 driver .inf paths, grep output:
>> 2014-01-28 
>> 16:20:01|6019|29:29|image|Windows.pm:set_device_path_key(9823)|no 
>> driver .inf paths were found
>> 2014-01-28 
>> 16:20:01|6019|29:29|image|Windows.pm:set_device_path_key(9841)|device 
>> path value: %SystemRoot%\inf
>> 2014-01-28 
>> 16:20:01|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/reg.exe ADD 
>> "HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion" /v 
>> "DevicePath" /t REG_EXPAND_SZ /d "%SystemRoot%\inf" /f' 2>&1
>> 2014-01-28 16:20:02|6019|29:29|image|Windows.pm:reg_add(2762)|added 
>> registry key: 
>> HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion, output:
>> |6019|29:29|image| The operation completed successfully.
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:set_device_path_key(9845)|set 
>> the DevicePath registry key
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3069)|command 
>> name: post_load.cmd
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3070)|command: 
>> C:/Cygwin/home/root/VCL/Scripts/post_load.cmd >> 
>> C:/Cygwin/home/root/VCL/Logs/post_load.log
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3074)|forward 
>> to backslash: C:\Cygwin\home\root\VCL\Scripts\post_load.cmd >> 
>> C:\Cygwin\home\root\VCL\Logs\post_load.log
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3078)|escape 
>> backslashes: C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd 
>> <smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> 
>> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log 
>> <smb://Cygwin//home//root//VCL//Logs//post_load.log>
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3082)|escaped 
>> quotes: C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd 
>> <smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> 
>> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log 
>> <smb://Cygwin//home//root//VCL//Logs//post_load.log>
>> 2014-01-28 
>> 16:20:02|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3097)|registry 
>> string:
>> |6019|29:29|image| Windows Registry Editor Version 5.00
>> |6019|29:29|image| 
>> [HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Run]
>> |6019|29:29|image| 
>> "post_load.cmd"="C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd 
>> <smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> 
>> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log 
>> <smb://Cygwin//home//root//VCL//Logs//post_load.log>"
>> 2014-01-28 
>> 16:20:03|6019|29:29|image|Windows.pm:import_registry_string(2413)|registry 
>> string contents echoed to 
>> C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg
>> 2014-01-28 
>> 16:20:04|6019|29:29|image|Windows.pm:reg_import(2869)|imported 
>> registry file: C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg
>> 2014-01-28 
>> 16:20:04|6019|29:29|image|Windows.pm:file_exists(1504)|file exists on 
>> win7sp1: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
>> 2014-01-28 
>> 16:20:04|6019|29:29|image|Windows.pm:delete_file(1217)|attempting to 
>> delete file: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
>> 2014-01-28 
>> 16:20:06|6019|29:29|image|Windows.pm:file_exists(1495)|file does NOT 
>> exist on win7sp1: 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
>> 2014-01-28 
>> 16:20:06|6019|29:29|image|Windows.pm:delete_file(1281)|deleted file: 
>> 'C:/Cygwin/tmp/add_hklm_run_registry_key_3873.reg'
>> 2014-01-28 
>> 16:20:06|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3100)|added 
>> HKLM run registry value, name: post_load.cmd, command: 
>> C:\\Cygwin\\home\\root\\VCL\\Scripts\\post_load.cmd 
>> <smb://Cygwin//home//root//VCL//Scripts//post_load.cmd> >> 
>> C:\\Cygwin\\home\\root\\VCL\\Logs\\post_load.log 
>> <smb://Cygwin//home//root//VCL//Logs//post_load.log>
>> 2014-01-28 
>> 16:20:06|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/reg.exe query 
>> "HKLM\SOFTWARE\Microsoft\Windows\CurrentVersion\Run"' 2>&1
>> 2014-01-28 
>> 16:20:07|6019|29:29|image|Windows.pm:add_hklm_run_registry_key(3111)|queried 
>> 'post_load.cmd' registry key:
>> |6019|29:29|image| 
>> HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Run
>> |6019|29:29|image| VMware User Process    REG_SZ "C:\Program 
>> Files\VMware\VMware Tools\vmtoolsd.exe" -n vmusr
>> |6019|29:29|image| post_load.cmd    REG_SZ 
>> C:\Cygwin\home\root\VCL\Scripts\post_load.cmd  >> 
>> C:\Cygwin\home\root\VCL\Logs\post_load.log
>> 2014-01-28 
>> 16:20:07|6019|29:29|image|Windows.pm:import_registry_string(2413)|registry 
>> string contents echoed to C:/Cygwin/tmp/enable_autoadminlogon_3879.reg
>> 2014-01-28 
>> 16:20:08|6019|29:29|image|Windows.pm:reg_import(2869)|imported 
>> registry file: C:/Cygwin/tmp/enable_autoadminlogon_3879.reg
>> 2014-01-28 
>> 16:20:09|6019|29:29|image|Windows.pm:file_exists(1504)|file exists on 
>> win7sp1: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
>> 2014-01-28 
>> 16:20:09|6019|29:29|image|Windows.pm:delete_file(1217)|attempting to 
>> delete file: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
>> 2014-01-28 
>> 16:20:11|6019|29:29|image|Windows.pm:file_exists(1495)|file does NOT 
>> exist on win7sp1: 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
>> 2014-01-28 
>> 16:20:11|6019|29:29|image|Windows.pm:delete_file(1281)|deleted file: 
>> 'C:/Cygwin/tmp/enable_autoadminlogon_3879.reg'
>> 2014-01-28 
>> 16:20:11|6019|29:29|image|Windows.pm:enable_autoadminlogon(3380)|enabled 
>> autoadminlogon
>> 2014-01-28 
>> 16:20:11|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/taskkill.exe /F /T /FI "IMAGENAME eq logon.scr"' 
>> 2>&1
>> 2014-01-28 
>> 16:20:12|6019|29:29|image|Windows.pm:kill_process(7798)|process does 
>> not exist matching pattern: logon.scr
>> 2014-01-28 
>> 16:20:12|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'echo | C:/Windows/System32/Wbem/wmic.exe NTEVENTLOG WHERE 
>> LogFileName=\"Application\" CALL ClearEventLog ; echo | 
>> C:/Windows/System32/Wbem/wmic.exe NTEVENTLOG WHERE 
>> LogFileName=\"Security\" CALL ClearEventLog ; echo | 
>> C:/Windows/System32/Wbem/wmic.exe NTEVENTLOG WHERE 
>> LogFileName=\"System\" CALL ClearEventLog' 2>&1
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| Executing 
>> (\\WIN7SP1\ROOT\CIMV2:Win32_NTEventlogFile.Name="C:\\Windows\\System32\\Winevt\\Logs\\Application.evtx")->ClearEventLog()
>> |6019|29:29|image| Method execution successful.
>> |6019|29:29|image| Out Parameters:
>> |6019|29:29|image| instance of __PARAMETERS
>> |6019|29:29|image| {
>> |6019|29:29|image| ReturnValue = 0;
>> |6019|29:29|image| };
>> |6019|29:29|image| Executing 
>> (\\WIN7SP1\ROOT\CIMV2:Win32_NTEventlogFile.Name="C:\\Windows\\System32\\Winevt\\Logs\\Security.evtx")->ClearEventLog()
>> |6019|29:29|image| Method execution successful.
>> |6019|29:29|image| Out Parameters:
>> |6019|29:29|image| instance of __PARAMETERS
>> |6019|29:29|image| {
>> |6019|29:29|image| ReturnValue = 0;
>> |6019|29:29|image| };
>> |6019|29:29|image| Executing 
>> (\\WIN7SP1\ROOT\CIMV2:Win32_NTEventlogFile.Name="C:\\Windows\\System32\\Winevt\\Logs\\System.evtx")->ClearEventLog()
>> |6019|29:29|image| Method execution successful.
>> |6019|29:29|image| Out Parameters:
>> |6019|29:29|image| instance of __PARAMETERS
>> |6019|29:29|image| {
>> |6019|29:29|image| ReturnValue = 0;
>> |6019|29:29|image| };
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on win7sp1, returning (0, "Executing (\\WIN7SP1\ROOT\CIMV 
>> <smb://WIN7SP1/ROOT/CIMV>...")
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|Windows.pm:clear_event_log(10722)|cleared 
>> event log: Application Security System
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> 'C:/Windows/System32/eventcreate.exe /T INFORMATION /L APPLICATION 
>> /SO VCL /ID 555 /D "Event log cleared by VCL"' 2>&1
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| SUCCESS: An event of type 'INFORMATION' was 
>> created in the 'APPLICATION' log with 'VCL' as the source.
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on win7sp1, returning (0, "SUCCESS: An event of type 'INF...")
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|Windows.pm:create_eventlog_entry(3463)|created 
>> event log entry on win7sp1: Event log cleared by VCL
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|Windows.pm:shutdown(3648)|enabling DHCP and 
>> shutting down win7sp1
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|OS.pm:get_public_interface_name(1150)|returning 
>> public interface name previously retrieved: Local Area Connection 2
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|Windows.pm:get_product_name(8812)|Windows 
>> product name previously retrieved: Windows 7 Professional
>> 2014-01-28 
>> 16:20:14|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on win7sp1:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x win7sp1 
>> '/bin/cygstart.exe $SYSTEMROOT/system32/cmd.exe /c 
>> "C:/Windows/System32/netsh.exe interface ip set address name=\"Local 
>> Area Connection\" source=dhcp & C:/Windows/System32/netsh.exe 
>> interface ip set dnsservers name=\"Local Area Connection\" 
>> source=dhcp & C:/Windows/System32/netsh.exe interface ip set address 
>> name=\"Local Area Connection 2\" source=dhcp & 
>> C:/Windows/System32/netsh.exe interface ip set dnsservers 
>> name=\"Local Area Connection 2\" source=dhcp & 
>> C:/Windows/System32/netsh.exe interface ip reset 
>> C:/Cygwin/home/root/VCL/Logs/ipreset.log & 
>> C:/Windows/System32/ipconfig.exe /release & 
>> C:/Windows/System32/ipconfig.exe /flushdns & 
>> C:/Windows/System32/arp.exe -d * & C:/Windows/System32/route.exe 
>> DELETE 0.0.0.0 MASK 0.0.0.0 & C:/Windows/System32/shutdown.exe /s /t 
>> 0 /f"' 2>&1
>> 2014-01-28 
>> 16:20:15|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> 2014-01-28 
>> 16:20:15|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on win7sp1, returning (0, "")
>> 2014-01-28 
>> 16:20:15|6019|29:29|image|Windows.pm:shutdown(3702)|attempt 1/12: 
>> executed shutdown command on win7sp1
>> 2014-01-28 
>> 16:20:15|6019|29:29|image|Module.pm:code_loop_timeout(887)|waiting 
>> for win7sp1 to NOT respond to ping, maximum of 180 seconds
>> 2014-01-28 
>> 16:20:15|6019|29:29|image|Module.pm:code_loop_timeout(909)|attempt 1: 
>> waiting for win7sp1 to NOT respond to ping (0/180 elapsed/remaining 
>> seconds), sleeping for 15 seconds
>> 2014-01-28 
>> 16:20:31|6019|29:29|image|Module.pm:code_loop_timeout(900)|waiting 
>> for win7sp1 to NOT respond to ping, code returned true
>> 2014-01-28 
>> 16:20:31|6019|29:29|image|Module.pm:code_loop_timeout(887)|waiting 
>> for win7sp1 to power off, maximum of 300 seconds
>> 2014-01-28 
>> 16:20:31|6019|29:29|image|utils.pm:get_management_node_info(5456)|retrieving 
>> current management node info for 'vclnode1.apachevcl.di.uminho.pt 
>> <http://apachevcl.di.uminho.pt>' from database, cached data is stale: 
>> 68 seconds old
>> 2014-01-28 
>> 16:20:31|6019|29:29|image|utils.pm:get_management_node_info(5603)|retrieved 
>> management node info: 'vclnode1.apachevcl.di.uminho.pt 
>> <http://apachevcl.di.uminho.pt>' (vclnode1)
>> 2014-01-28 
>> 16:20:32|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/getallvms
>> 2014-01-28 
>> 16:20:33|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/power.getstate 8
>> 2014-01-28 
>> 16:20:33|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(886)|vmsvc/power.getstate 
>> 8:
>> |6019|29:29|image| Retrieved runtime info
>> |6019|29:29|image| Powered on
>> 2014-01-28 
>> 16:20:33|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(889)|VM is 
>> powered on: /vmfs/volumes/datastore1/win7/win7.vmx
>> 2014-01-28 
>> 16:20:33|6019|29:29|image|Module.pm:code_loop_timeout(909)|attempt 1: 
>> waiting for win7sp1 to power off (2/298 elapsed/remaining seconds), 
>> sleeping for 15 seconds
>> 2014-01-28 
>> 16:20:49|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/getallvms
>> 2014-01-28 
>> 16:20:50|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/power.getstate 8
>> 2014-01-28 
>> 16:20:50|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(886)|vmsvc/power.getstate 
>> 8:
>> |6019|29:29|image| Retrieved runtime info
>> |6019|29:29|image| Powered off
>> 2014-01-28 
>> 16:20:50|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(893)|VM is 
>> powered off: /vmfs/volumes/datastore1/win7/win7.vmx
>> 2014-01-28 
>> 16:20:50|6019|29:29|image|Module.pm:code_loop_timeout(900)|waiting 
>> for win7sp1 to power off, code returned true
>> 2014-01-28 16:20:50|6019|29:29|image|7.pm:pre_capture(183)|returning 1
>> 2014-01-28 
>> 16:20:50|6019|29:29|image|Module.pm:code_loop_timeout(887)|waiting 
>> for win7sp1 to power off, maximum of 600 seconds
>> 2014-01-28 
>> 16:20:52|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/getallvms
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/power.getstate 8
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(886)|vmsvc/power.getstate 
>> 8:
>> |6019|29:29|image| Retrieved runtime info
>> |6019|29:29|image| Powered off
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|VIM_SSH.pm:get_vm_power_state(893)|VM is 
>> powered off: /vmfs/volumes/datastore1/win7/win7.vmx
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|Module.pm:code_loop_timeout(900)|waiting 
>> for win7sp1 to power off, code returned true
>> 2014-01-28 16:20:53|6019|29:29|image|VMware.pm:capture(733)|vmx and 
>> vmdk files will not be copied or renamed directly on the host, the VM 
>> profile disk type is dedicated and the image repository is mounted on 
>> the host
>> 2014-01-28 16:20:53|6019|29:29|image|VMware.pm:capture(770)|vmdk will 
>> be copied directly from VM host servidor12 to the image repository in 
>> the 2gbsparse disk format
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'vmware -v' 2>&1
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| VMware ESXi 5.5.0 build-1331820
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on servidor12, returning (0, "VMware ESXi 5.5.0 
>> build-133182...")
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|VMware.pm:get_vmhost_product_name(6634)|VMware 
>> product being used on VM host servidor12: 'VMware ESXi 5.5.0 
>> build-1331820'
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'stat /vmfs/volumes/datastore1/win7/win7\.vmdk' 2>&1
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| File: /vmfs/volumes/datastore1/win7/win7.vmdk
>> |6019|29:29|image| Size: 540 Blocks: 0 IO Block: 131072 regular file
>> |6019|29:29|image| Device: d9c89ac4ddd90dcch/15692963071898750412d 
>> Inode: 654348996 Links: 1
>> |6019|29:29|image| Access: (0600/-rw-------) Uid: ( 0/ root) Gid: ( 
>> 0/ root)
>> |6019|29:29|image| Access: 2014-01-28 09:54:19.000000000
>> |6019|29:29|image| Modify: 2014-01-28 11:46:16.000000000
>> |6019|29:29|image| Change: 2014-01-28 11:46:16.000000000
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on servidor12, returning (0, "File: 
>> /vmfs/volumes/datastore1...")
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|Linux.pm:file_exists(1449)|'/vmfs/volumes/datastore1/win7/win7.vmdk' 
>> exists on servidor12, files: 1, directories: 0, links: 0
>> 2014-01-28 
>> 16:20:53|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'stat 
>> /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0/vmwarewin7\-awp439042ff54g5tg32\-v0\.vmdk' 
>> 2>&1
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| stat: can't stat 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk': 
>> No such file or directory
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5030)|SSH command 
>> executed on servidor12, command:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'stat 
>> /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0/vmwarewin7\-awp439042ff54g5tg32\-v0\.vmdk' 
>> 2>&1
>> |6019|29:29|image| returning (1, "stat: can't stat '/vmfs/volume...")
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'ls -d --color=never 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0" 2>&1 || 
>> mkdir -p "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0" 
>> 2>&1 && ls -d --color=never 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0"' 2>&1
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| ls: 
>> /vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0: No such 
>> file or directory
>> |6019|29:29|image| 
>> /vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on servidor12, returning (0, "ls: 
>> /vmfs/volumes/vcl_images/v...")
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|Linux.pm:create_directory(1569)|directory 
>> created on servidor12: 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0'
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|VMware.pm:copy_vmdk(5861)|attempting to 
>> copy virtual disk using vmkfstools, disk type: 2gbsparse:
>> |6019|29:29|image| '/vmfs/volumes/datastore1/win7/win7.vmdk' --> 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
>> 2014-01-28 
>> 16:20:54|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'vmkfstools -i "/vmfs/volumes/datastore1/win7/win7.vmdk" 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk" 
>> -d 2gbsparse' 2>&1
>> 2014-01-28 16:20:56|6019|29:29|image|OS.pm:execute(1994)|executed 
>> command: 'vmkfstools -i "/vmfs/volumes/datastore1/win7/win7.vmdk" 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk" 
>> -d 2gbsparse', exit status: 255, output:
>> |6019|29:29|image| Destination disk format: sparse with 2GB maximum 
>> extent size
>> |6019|29:29|image| Cloning disk 
>> '/vmfs/volumes/datastore1/win7/win7.vmdk'...
>> |6019|29:29|image| Failed to clone disk: The system cannot find the 
>> file specified (25).
>> |6019|29:29|image| ---- WARNING ----
>> |6019|29:29|image| 2014-01-28 
>> 16:20:56|6019|29:29|image|VMware.pm:copy_vmdk(5865)|failed to copy 
>> virtual disk
>> |6019|29:29|image| command: 'vmkfstools -i 
>> "/vmfs/volumes/datastore1/win7/win7.vmdk" 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk" 
>> -d 2gbsparse'
>> |6019|29:29|image| output:
>> |6019|29:29|image| Destination disk format: sparse with 2GB maximum 
>> extent size
>> |6019|29:29|image| Cloning disk 
>> '/vmfs/volumes/datastore1/win7/win7.vmdk'...
>> |6019|29:29|image| Failed to clone disk: The system cannot find the 
>> file specified (25).
>> |6019|29:29|image| ( 0) VMware.pm, copy_vmdk (line: 5865)
>> |6019|29:29|image| (-1) VMware.pm, capture (line: 774)
>> |6019|29:29|image| (-2) image.pm, process (line: 162)
>> |6019|29:29|image| (-3) vcld, make_new_child (line: 571)
>> |6019|29:29|image| (-4) vcld, main (line: 350)
>> 2014-01-28 
>> 16:20:56|6019|29:29|image|VMware.pm:copy_vmdk(5918)|attempting to 
>> copy virtual disk using vmware-vdiskmanager, disk type: 2gbsparse:
>> |6019|29:29|image| '/vmfs/volumes/datastore1/win7/win7.vmdk' --> 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
>> 2014-01-28 
>> 16:20:56|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'vmware-vdiskmanager -r "/vmfs/volumes/datastore1/win7/win7.vmdk" -t 
>> 1 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk"' 
>> 2>&1
>> 2014-01-28 16:20:57|6019|29:29|image|OS.pm:execute(1994)|executed 
>> command: 'vmware-vdiskmanager -r 
>> "/vmfs/volumes/datastore1/win7/win7.vmdk" -t 1 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk"', 
>> exit status: 127, output:
>> |6019|29:29|image| sh: vmware-vdiskmanager: not found
>> |6019|29:29|image| ---- WARNING ----
>> |6019|29:29|image| 2014-01-28 
>> 16:20:57|6019|29:29|image|VMware.pm:copy_vmdk(5990)|failed to copy 
>> virtual disk on VM host servidor12, output does not contain '100% 
>> done' or 'success', command: 'vmware-vdiskmanager -r 
>> "/vmfs/volumes/datastore1/win7/win7.vmdk" -t 1 
>> "/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk"', 
>> output:
>> |6019|29:29|image| sh: vmware-vdiskmanager: not found
>> |6019|29:29|image| ( 0) VMware.pm, copy_vmdk (line: 5990)
>> |6019|29:29|image| (-1) VMware.pm, capture (line: 774)
>> |6019|29:29|image| (-2) image.pm, process (line: 162)
>> |6019|29:29|image| (-3) vcld, make_new_child (line: 571)
>> |6019|29:29|image| (-4) vcld, main (line: 350)
>> |6019|29:29|image| ---- WARNING ----
>> |6019|29:29|image| 2014-01-28 
>> 16:20:57|6019|29:29|image|VMware.pm:copy_vmdk(6055)|failed to copy 
>> virtual disk on VM host servidor12 using any available methods:
>> |6019|29:29|image| '/vmfs/volumes/datastore1/win7/win7.vmdk' --> 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
>> |6019|29:29|image| ( 0) VMware.pm, copy_vmdk (line: 6055)
>> |6019|29:29|image| (-1) VMware.pm, capture (line: 774)
>> |6019|29:29|image| (-2) image.pm, process (line: 162)
>> |6019|29:29|image| (-3) vcld, make_new_child (line: 571)
>> |6019|29:29|image| (-4) vcld, main (line: 350)
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'rm -rfv 
>> /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0' 2>&1
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5034)|SSH command 
>> executed on servidor12, returning (0, "")
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|Linux.pm:delete_file(1504)|deleted 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0' on 
>> servidor12
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(4902)|executing 
>> SSH command on servidor12:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'stat /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0' 2>&1
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5020)|run_ssh_command 
>> output:
>> |6019|29:29|image| stat: can't stat 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0': No such 
>> file or directory
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|utils.pm:run_ssh_command(5030)|SSH command 
>> executed on servidor12, command:
>> |6019|29:29|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -o 
>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o 
>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x servidor12 
>> 'stat /vmfs/volumes/vcl_images/vmwarewin7\-awp439042ff54g5tg32\-v0' 2>&1
>> |6019|29:29|image| returning (1, "stat: can't stat '/vmfs/volume...")
>> |6019|29:29|image| ---- WARNING ----
>> |6019|29:29|image| 2014-01-28 
>> 16:20:57|6019|29:29|image|VMware.pm:capture(778)|failed to copy the 
>> vmdk files to the repository mounted on the VM host after the VM was 
>> powered off: '/vmfs/volumes/datastore1/win7/win7.vmdk' --> 
>> '/vmfs/volumes/vcl_images/vmwarewin7-awp439042ff54g5tg32-v0/vmwarewin7-awp439042ff54g5tg32-v0.vmdk'
>> |6019|29:29|image| ( 0) VMware.pm, capture (line: 778)
>> |6019|29:29|image| (-1) image.pm, process (line: 162)
>> |6019|29:29|image| (-2) vcld, make_new_child (line: 571)
>> |6019|29:29|image| (-3) vcld, main (line: 350)
>> 2014-01-28 16:20:57|6019|29:29|image|VMware.pm:capture(860)|vmdk file 
>> does not need to be renamed back to the original name, vmdk file path 
>> being captured is already named as the image being captured: 
>> '/vmfs/volumes/datastore1/win7/win7.vmdk'
>> 2014-01-28 
>> 16:20:57|6019|29:29|image|VMware.pm:capture(864)|attempting to power 
>> the VM back on so that it can be captured again
>> 2014-01-28 
>> 16:20:58|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/getallvms
>> 2014-01-28 
>> 16:21:04|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/power.on 8
>> 2014-01-28 
>> 16:21:05|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/getallvms
>> 2014-01-28 
>> 16:21:06|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vmsvc/get.tasklist 8
>> 2014-01-28 
>> 16:21:06|6019|29:29|image|VIM_SSH.pm:_wait_for_task(750)|checking 
>> status of task: haTask-8-vim.VirtualMachine.powerOn-514559911
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|VIM_SSH.pm:_run_vim_cmd(210)|executed 
>> command on VM host servidor12: vim-cmd vimsvc/task_info 
>> haTask-8-vim.VirtualMachine.powerOn-514559911
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|VIM_SSH.pm:_wait_for_task(773)|task 
>> completed successfully: haTask-8-vim.VirtualMachine.powerOn-514559911
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|VIM_SSH.pm:vm_power_on(972)|powered on VM: 
>> /vmfs/volumes/datastore1/win7/win7.vmx
>> |6019|29:29|image| ---- WARNING ----
>> |6019|29:29|image| 2014-01-28 
>> 16:21:07|6019|29:29|image|image.pm:process(166)|vmwarewin7-awp439042ff54g5tg32-v0 
>> image failed to be captured by provisioning module
>> |6019|29:29|image| ( 0) image.pm, process (line: 166)
>> |6019|29:29|image| (-1) vcld, make_new_child (line: 571)
>> |6019|29:29|image| (-2) vcld, main (line: 350)
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|DataStructure.pm:get_computer_private_ip_address(1617)|returning 
>> private IP address previously retrieved: 10.100.0.210
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|utils.pm:is_inblockrequest(5760)|zero rows 
>> were returned from database select
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|DataStructure.pm:get_image_affiliation_name(2121)|image 
>> owner id: 1
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|DataStructure.pm:retrieve_user_data(1412)|attempting 
>> to retrieve and store data for user: user.id = '1'
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|DataStructure.pm:retrieve_user_data(1475)|data 
>> has been retrieved for user: admin (id: 1)
>> 2014-01-28 16:21:07|6019|29:29|image|utils.pm:mail(1253)|SUCCESS -- 
>> Sending mail To: aaragao@di.uminho.pt, PROBLEM -- 
>> vclnode1|29:29|image|image.pm|win7sp1>servidor12.di.uminho.pt 
>> <http://servidor12.di.uminho.pt>|vmwarewin7-awp439042ff54g5tg32-v0|admin
>> |6019|29:29|image| ---- CRITICAL ----
>> |6019|29:29|image| 2014-01-28 
>> 16:21:07|6019|29:29|image|image.pm:reservation_failed(387)|vmwarewin7-awp439042ff54g5tg32-v0 
>> image creation failed
>> |6019|29:29|image| ( 0) image.pm, reservation_failed (line: 387)
>> |6019|29:29|image| (-1) image.pm, process (line: 167)
>> |6019|29:29|image| (-2) vcld, make_new_child (line: 571)
>> |6019|29:29|image| (-3) vcld, main (line: 350)
>> 2014-01-28 16:21:07|6019|29:29|image|utils.pm:mail(1253)|SUCCESS -- 
>> Sending mail To: aaragao@di.uminho.pt, VCL -- NOTICE DELAY Image 
>> Creation awp439042ff54g5tg
>> 2014-01-28 16:21:07|6019|29:29|image|utils.pm:mail(1253)|SUCCESS -- 
>> Sending mail To: aaragao@di.uminho.pt, VCL -- NOTICE FAILED Image 
>> Creation awp439042ff54g5tg
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|utils.pm:update_request_state(1545)|request 
>> 29 state updated to: maintenance, laststate to: image
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|image.pm:reservation_failed(441)|request 
>> state set to maintenance, laststate to image
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|utils.pm:update_computer_state(1587)|computer 
>> 3 state updated to: maintenance
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|image.pm:reservation_failed(449)|win7sp1 
>> state set to maintenance
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|image.pm:reservation_failed(456)|exiting
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|utils.pm:delete_computerloadlog_reservation(6396)|removing 
>> computerloadlog entries matching loadstate = begin
>> 2014-01-28 
>> 16:21:07|6019|29:29|image|utils.pm:delete_computerloadlog_reservation(6443)|deleted 
>> rows from computerloadlog for reservation id=29
>> 2014-01-28 16:21:07|6019|29:29|image|State.pm:DESTROY(929)|VCL::image 
>> process duration: 351 seconds
>> 2014-01-28 16:21:07|6019|29:29|image|VIM_SSH.pm:DESTROY(2125)|vim-cmd 
>> call count: 17
>> 2014-01-28 16:21:07|1577|vcld:REAPER(721)|VCL process exited for 
>> reservation 29, PID: 6019, signal: CHLD
>> ^C
>>
>>
>> Em 27-01-2014 21:45, Andy Kurth escreveu:
>>> Is the management node configured to use vcl.key?  Check:
>>> Managment Nodes > Edit Management Node Information > Edit
>>>
>>> Make sure "End Node SSH Identity Key Files" is set to /etc/vcl/vcl.key.
>>>
>>> -Andy
>>>
>>>
>>> On Thu, Jan 23, 2014 at 9:36 AM, António Aragão 
>>> <aaragao@di.uminho.pt <ma...@di.uminho.pt>> wrote:
>>>
>>>     I try from the management node the ssh access and it works.
>>>
>>>
>>>     [root@vclnode1 tmp]# ssh -i /etc/vcl/vcl.key 10.100.0.210
>>>     Warning: Permanently added '10.100.0.210' (RSA) to the list of
>>>     known hosts.
>>>
>>>     root@win7sp1 ~
>>>     $ exitConnection to 10.100.0.210 closed.
>>>     [root@vclnode1 tmp]# ssh -i /etc/vcl/vcl.key win7sp1
>>>     Warning: Permanently added 'win7sp1,10.100.0.210' (RSA) to the
>>>     list of known hosts.
>>>
>>>     root@win7sp1 ~
>>>     $ exitConnection to win7sp1 closed.
>>>     [root@vclnode1 tmp]# ssh -i /etc/vcl/vcl.key 192.168.93.210
>>>     Warning: Permanently added '192.168.93.210' (RSA) to the list of
>>>     known hosts.
>>>
>>>     root@win7sp1 ~
>>>     $ exitConnection to 192.168.93.210 closed.
>>>     [root@vclnode1 tmp]#
>>>
>>>
>>>
>>>     Em 23-01-2014 11:55, toth.aron@inf.mit.bme.hu
>>>     <ma...@inf.mit.bme.hu> escreveu:
>>>>     Hello,
>>>>
>>>>     The problem is no ssh server running on the win7 vm. The links describes how to intall and configure the windows7 vm, before capturing it.
>>>>
>>>>     https://cwiki.apache.org/confluence/display/VCL/Create+a+Windows+Base+Image
>>>>     https://cwiki.apache.org/confluence/pages/viewpage.action?pageId=4589006
>>>>
>>>>     Test ssh from managenet node:
>>>>     ssh -i /etc/vcl/vcl.key root@<windows7 vm private address>
>>>>
>>>>     2014-01-23 10:43:15|6618|13:13|image|OS.pm:is_ssh_responding(682)|win7sp1 is NOT responding to SSH, SSH command failed, port 22: open, port 24: closed
>>>>     |6618|13:13|image| ---- WARNING ----
>>>>     |6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|VMware.pm:capture(613)|unable to capture image, VM win7sp1 is not responding to SSH
>>>>
>>>>     Br,
>>>>
>>>>     Aaron Toth
>>>>     VCL System Administrator
>>>>     BUTE-MIT
>>>>
>>>>     -----António Aragão<aa...@di.uminho.pt>  <ma...@di.uminho.pt>  ezt írta: -----
>>>>     Címzett:user@vcl.apache.org  <ma...@vcl.apache.org>
>>>>     Feladó: António Aragão<aa...@di.uminho.pt>  <ma...@di.uminho.pt>
>>>>     Dátum: 2014/01/23 12:14du.
>>>>     Tárgy: Re: ESXi5.5 (no SDK available)
>>>>
>>>>     Hello,
>>>>
>>>>     I already do that and test the ssh and it was ok. I have put in /etc/hosts (i don't know why it doesn't use dns but ...)  I have installed sdk 5.1 it gives me more information about missing perl modules than 5.5. I try to make an image and gives me this:
>>>>
>>>>     ----------------------------------------------------------------------------
>>>>     2014-01-23 10:43:06|6614|utils.pm:insert_request(8043)|inserted new reload request into request table, request id=13
>>>>     2014-01-23 10:43:06|6614|utils.pm:insert_request(8074)|inserted new reload request into reservation table, reservation id=13
>>>>     2014-01-23 10:43:08|1437|13:13|image|utils.pm:reservation_being_processed(8634)|computerloadlog 'begin' entry does NOT exist for reservation 13
>>>>     2014-01-23 10:43:08|1437|13:13|image|utils.pm:run_command(8706)|executed command: pgrep -fl 'vcld [0-9]+:13 ', pid: 6616, exit status: 1, output:
>>>>     2014-01-23 10:43:08|1437|13:13|image|utils.pm:is_management_node_process_running(8939)|process is NOT running, identifier: 'vcld [0-9]+:13 '
>>>>     2014-01-23 10:43:08|1437|13:13|image|utils.pm:reservation_being_processed(8655)|reservation is NOT currently being processed
>>>>     2014-01-23 10:43:08|1437|13:13|image|vcld:main(282)|reservation 13 is NOT already being processed
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_imagemeta_info(4576)|retrieved imagemeta info:
>>>>     |1437|13:13|image| : {
>>>>     |1437|13:13|image| :   "architecture" => "",
>>>>     |1437|13:13|image| :   "checkuser" => 1,
>>>>     |1437|13:13|image| :   "id" => 15,
>>>>     |1437|13:13|image| :   "postoption" => "",
>>>>     |1437|13:13|image| :   "rootaccess" => 1,
>>>>     |1437|13:13|image| :   "subimages" => 0,
>>>>     |1437|13:13|image| :   "sysprep" => 0,
>>>>     |1437|13:13|image| :   "usergroupid" => ""
>>>>     |1437|13:13|image| : }
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_management_node_info(5456)|retrieving current management node info for 'vclnode1.apachevcl.di.uminho.pt  <http://vclnode1.apachevcl.di.uminho.pt/>' from database, cached data is stale: 137 seconds old
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_management_node_info(5603)|retrieved management node info: 'vclnode1.apachevcl.di.uminho.pt  <http://vclnode1.apachevcl.di.uminho.pt/>' (vclnode1)
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_connect_method_info(10059)|attempting to retrieve connect method info:
>>>>     |1437|13:13|image| imagerevision: 14 - vmwarewin7-win7_23012014_316-v0
>>>>     |1437|13:13|image| OS: 35 - vmwarewin7
>>>>     |1437|13:13|image| OS type: 1 - windows
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:get_connect_method_info(10123)|RDP: connectmethodid=2, OStypeid=1, OSid=NULL, imagerevisionid=NULL, disabled=0
>>>>     2014-01-23 10:43:09|1437|13:13|image|vcld:main(287)|retrieved request information from database
>>>>     2014-01-23 10:43:09|1437|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:update_request_state(1545)|request 13 state updated to: pending, laststate to: image
>>>>     2014-01-23 10:43:09|1437|13:13|image|utils.pm:insertloadlog(3665)|inserted computer=3, begin, beginning to process, state is image
>>>>     2014-01-23 10:43:09|1437|13:13|image|vcld:make_new_child(518)|loaded VCL::image module
>>>>     2014-01-23 10:43:09|1437|13:13|image|vcld:make_new_child(542)|current number of forked kids: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|vcld:make_new_child(558)|vcld environment variable set to 0 for this process
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: noimage, DataStructure object will contain image information for the production imagerevision of this image
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_mn_os_object(376)|VCL::Module::OS::Linux::ManagementNode module loaded
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode object created for image noimage, address: 9b394f8
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{hostname}, data identifier: computer_hostname, data:
>>>>     |6618|13:13|image| : "vclnode1.apachevcl.di.uminho.pt  <http://vclnode1.apachevcl.di.uminho.pt/>"
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{NODENAME}, data identifier: computer_node_name, data:
>>>>     |6618|13:13|image| : "vclnode1"
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{SHORTNAME}, data identifier: computer_short_name, data:
>>>>     |6618|13:13|image| : "vclnode1"
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{IPaddress}, data identifier: computer_ip_address, data:
>>>>     |6618|13:13|image| : "192.168.93.2"
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_mn_os_object(381)|VCL::Module::OS::Linux::ManagementNode OS object created, address: 9b394f8
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(192)|VCL::image object created for state image, address: 9b27068
>>>>     2014-01-23 10:43:09|6618|13:13|image|State.pm:initialize(88)|obtained a database handle for this state process, stored as $ENV{dbh}
>>>>     2014-01-23 10:43:09|6618|13:13|image|State.pm:check_image_os(790)|no corrections need to be made to image OS: vmwarewin7
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
>>>>     2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6752)|reservation count: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6771)|PARENTIMAGE: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6772)|SUBIMAGE: 0
>>>>     2014-01-23 10:43:09|6618|13:13|image|utils.pm:rename_vcld_process(6790)|renamed process to 'vcld 13:13 image win7sp1>servidor12.di.uminho.pt  <http://servidor12.di.uminho.pt/>  vmwarewin7-win7_23012014_316-v0 admin'
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 13
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_os_object(308)|VCL::Module::OS::Windows::Version_6::7 module loaded
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(196)|VCL::Module::OS::Windows::Version_6::7 object created for image vmwarewin7-win7_23012014_316-v0, address: 9e59210
>>>>     2014-01-23 10:43:09|6618|13:13|image|Windows.pm:initialize(214)|beginning Windows module initialization
>>>>     2014-01-23 10:43:09|6618|13:13|image|Windows.pm:initialize(224)|Windows module initialization complete
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_os_object(314)|VCL::Module::OS::Windows::Version_6::7 OS object created, address: 9e59210
>>>>     2014-01-23 10:43:09|6618|13:13|image|State.pm:initialize(125)|computer is a VM, attempting to create VM host OS object
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_vmhost_os_object(454)|attempting to load VM host OS module: VCL::Module::OS::Linux::UnixLab (image: 1)
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_vmhost_os_object(460)|VM host OS module loaded: VCL::Module::OS::Linux::UnixLab
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab object created for image noimage, address: 9e6c620
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_vmhost_os_object(465)|VCL::Module::OS::Linux::UnixLab OS object created, address: 9e6c620
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:create_provisioning_object(519)|VCL::Module::Provisioning::VMware::VMware module loaded
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VMware object created for computer win7sp1, address: 9e6c900
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(267)|initializing VCL::Module::Provisioning::VMware::VMware object
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_datastructure(1397)|created DataStructure object for VM host: servidor12
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(287)|VM profile assigned to servidor12: vmhostprofile1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_datastructure(1397)|created DataStructure object for VM host: servidor12
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_api_object(1500)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_api_object(1506)|loaded VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
>>>>     2014-01-23 10:43:09|6618|13:13|image|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK object created for computer win7sp1, address: a562510
>>>>     2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(106)|loaded VMware vSphere SDK modules
>>>>     2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(150)|attempting to connect to VM host:https://servidor12.di.uminho.pt/sdk  (root)
>>>>     2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(168)|connected to VM host:https://servidor12.di.uminho.pt/sdk, username: 'root'
>>>>     2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:initialize(182)|connected toservidor12.di.uminho.pt  <http://servidor12.di.uminho.pt/>, VIM object type: Vim
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:get_vmhost_api_object(1527)|created API object: VCL::Module::Provisioning::VMware::vSphere_SDK
>>>>     2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:is_restricted(1490)|access to the VM host via the vSphere SDK is NOT restricted due to the license
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(291)|vSphere SDK object will be used to control the VM host servidor12 and the VM: win7sp1
>>>>     2014-01-23 10:43:09|6618|13:13|image|VMware.pm:initialize(327)|VMware OS and API objects created for VM host servidor12:
>>>>     |6618|13:13|image| VM host OS object type: VCL::Module::Provisioning::VMware::vSphere_SDK
>>>>     |6618|13:13|image| VMware API object type: VCL::Module::Provisioning::VMware::vSphere_SDK
>>>>     2014-01-23 10:43:09|6618|13:13|image|vSphere_SDK.pm:get_vmware_product_name(1355)|VMware product being used on VM hostservidor12.di.uminho.pt  <http://servidor12.di.uminho.pt/>: 'VMware ESXi 5.5.0 build-1331820'
>>>>     2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_resource_pool_view(2803)|single resource pool found on VM host servidor12 will be used: /ha-datacenter/servidor12.di.uminho.pt  <http://servidor12.di.uminho.pt/>
>>>>     2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_datacenter_view(2633)|found datacenter VM host on servidor12: ha-datacenter
>>>>     2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_datastore_info(3254)|retrieved datastore info:
>>>>     |6618|13:13|image| : {
>>>>     |6618|13:13|image| :   "datastore1" => bless( {
>>>>     |6618|13:13|image| :     "accessible" => 1,
>>>>     |6618|13:13|image| :     "capacity" => "4776003633152",
>>>>     |6618|13:13|image| :     "datastore" => bless( {
>>>>     |6618|13:13|image| :       "type" => "Datastore",
>>>>     |6618|13:13|image| :       "value" => "52d5159b-22b564a2-c0af-0025905aa03c"
>>>>     |6618|13:13|image| :     }, 'ManagedObjectReference' ),
>>>>     |6618|13:13|image| :     "freeSpace" => "4756159332352",
>>>>     |6618|13:13|image| :     "name" => "datastore1",
>>>>     |6618|13:13|image| :     "normal_path" => "/vmfs/volumes/datastore1",
>>>>     |6618|13:13|image| :     "type" => "VMFS",
>>>>     |6618|13:13|image| :     "uncommitted" => 0,
>>>>     |6618|13:13|image| :     "url" => "/vmfs/volumes/52d5159b-22b564a2-c0af-0025905aa03c"
>>>>     |6618|13:13|image| :   }, 'DatastoreSummary' )
>>>>     |6618|13:13|image| : }
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_datastore_info(6760)|retrieved datastore info from VM host:
>>>>     |6618|13:13|image| : {
>>>>     |6618|13:13|image| :   "datastore1" => bless( {
>>>>     |6618|13:13|image| :     "accessible" => 1,
>>>>     |6618|13:13|image| :     "capacity" => "4776003633152",
>>>>     |6618|13:13|image| :     "datastore" => bless( {
>>>>     |6618|13:13|image| :       "type" => "Datastore",
>>>>     |6618|13:13|image| :       "value" => "52d5159b-22b564a2-c0af-0025905aa03c"
>>>>     |6618|13:13|image| :     }, 'ManagedObjectReference' ),
>>>>     |6618|13:13|image| :     "freeSpace" => "4756159332352",
>>>>     |6618|13:13|image| :     "name" => "datastore1",
>>>>     |6618|13:13|image| :     "normal_path" => "/vmfs/volumes/datastore1",
>>>>     |6618|13:13|image| :     "type" => "VMFS",
>>>>     |6618|13:13|image| :     "uncommitted" => 0,
>>>>     |6618|13:13|image| :     "url" => "/vmfs/volumes/52d5159b-22b564a2-c0af-0025905aa03c"
>>>>     |6618|13:13|image| :   }, 'DatastoreSummary' )
>>>>     |6618|13:13|image| : }
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_vmx_base_directory_path(3094)|determined vmx base directory path: /vmfs/volumes/datastore1
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:_get_datastore_names(6792)|datastore names:
>>>>     |6618|13:13|image| datastore1
>>>>     2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:file_exists(1978)|file (datastore root) exists: [datastore1]
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:is_vm_dedicated(4257)|VM disk mode does not need to be dedicated
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:initialize(351)|not checking if vmdk base directory exists because it is the same as the vmx base directory: /vmfs/volumes/datastore1
>>>>     2014-01-23 10:43:10|6618|13:13|image|Module.pm:create_provisioning_object(525)|VCL::Module::Provisioning::VMware::VMware provisioner object created for win7sp1, address: 9e6c900
>>>>     2014-01-23 10:43:10|6618|13:13|image|State.pm:initialize(154)|returning 1
>>>>     2014-01-23 10:43:10|6618|13:13|image|vcld:make_new_child(568)|VCL::image object created and initialized
>>>>     2014-01-23 10:43:10|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To:aaragao@di.uminho.pt  <ma...@di.uminho.pt>, VCL IMAGE Creation Started: vmwarewin7-win7_23012014_316-v0
>>>>     2014-01-23 10:43:10|6618|13:13|image|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
>>>>     2014-01-23 10:43:10|6618|13:13|image|vSphere_SDK.pm:_get_file_info(2525)|searching for matching file paths: base directory path: '[datastore1] vmwarewin7-win7_23012014_316-v0', search pattern: 'vmwarewin7-win7_23012014_316-v0.vmdk'
>>>>     2014-01-23 10:43:12|6618|13:13|image|vSphere_SDK.pm:_get_file_info(2536)|base directory does not exist: '[datastore1] vmwarewin7-win7_23012014_316-v0'
>>>>     2014-01-23 10:43:12|6618|13:13|image|vSphere_SDK.pm:find_files(2125)|matching file count: 0
>>>>     2014-01-23 10:43:12|6618|13:13|image|vSphere_SDK.pm:file_exists(1997)|file does not exist: [datastore1] vmwarewin7-win7_23012014_316-v0/vmwarewin7-win7_23012014_316-v0.vmdk
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:does_image_exist(4595)|image does not exist in the shared directory on the VM host
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_repository_vmdk_base_directory_path(4140)|repository path is not configured in the VM profile
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_repository_vmdk_directory_path(4181)|image repository vmdk directory path cannot be determined because repository path is not configured in the VM profile
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:does_image_exist(4601)|image does not exist on the VM host and image repository path is not configured in the VM profile
>>>>     2014-01-23 10:43:12|6618|13:13|image|image.pm:process(145)|image vmwarewin7-win7_23012014_316-v0 does not exist in the repository
>>>>     2014-01-23 10:43:12|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{13}{image}{lastupdate}, data identifier: image_lastupdate, data:
>>>>     |6618|13:13|image| : "2014-01-23 10:43:12"
>>>>     2014-01-23 10:43:12|6618|13:13|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{13}{imagerevision}{datecreated}, data identifier: imagerevision_date_created, data:
>>>>     |6618|13:13|image| : "2014-01-23 10:43:12"
>>>>     2014-01-23 10:43:12|6618|13:13|image|image.pm:process(161)|calling provisioning module's capture() subroutine
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_vmdk_base_directory_path_shared(3566)|using VM profile datastore path as the vmdk base directory path: datastore1
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:get_repository_vmdk_base_directory_path(4140)|repository path is not configured in the VM profile
>>>>     2014-01-23 10:43:12|6618|13:13|image|VMware.pm:is_repository_mounted_on_vmhost(6709)|unable to determine if image repository is mounted on VM host servidor12, repository path is not configured in the VM profile
>>>>     2014-01-23 10:43:15|6618|13:13|image|OS.pm:is_ssh_responding(682)|win7sp1 is NOT responding to SSH, SSH command failed, port 22: open, port 24: closed
>>>>     |6618|13:13|image| ---- WARNING ----
>>>>     |6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|VMware.pm:capture(613)|unable to capture image, VM win7sp1 is not responding to SSH
>>>>     |6618|13:13|image| ( 0) VMware.pm, capture (line: 613)
>>>>     |6618|13:13|image| (-1)image.pm  <http://image.pm/>, process (line: 162)
>>>>     |6618|13:13|image| (-2) vcld, make_new_child (line: 571)
>>>>     |6618|13:13|image| (-3) vcld, main (line: 350)
>>>>     |6618|13:13|image| ---- WARNING ----
>>>>     |6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|image.pm:process(166)|vmwarewin7-win7_23012014_316-v0 image failed to be captured by provisioning module
>>>>     |6618|13:13|image| ( 0)image.pm  <http://image.pm/>, process (line: 166)
>>>>     |6618|13:13|image| (-1) vcld, make_new_child (line: 571)
>>>>     |6618|13:13|image| (-2) vcld, main (line: 350)
>>>>     2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:get_computer_private_ip_address(1641)|attempting to retrieve private IP address for computer: win7sp1
>>>>     2014-01-23 10:43:15|6618|13:13|image|OS.pm:get_file_contents(1897)|retrieved 4 lines from file on vclnode1: '/etc/hosts'
>>>>     2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:get_computer_private_ip_address(1697)|returning IP address from /etc/hosts file: 10.100.0.210
>>>>     2014-01-23 10:43:15|6618|13:13|image|utils.pm:is_inblockrequest(5760)|zero rows were returned from database select
>>>>     2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:get_image_affiliation_name(2121)|image owner id: 1
>>>>     2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:retrieve_user_data(1412)|attempting to retrieve and store data for user:user.id  <http://user.id/>  = '1'
>>>>     2014-01-23 10:43:15|6618|13:13|image|DataStructure.pm:retrieve_user_data(1475)|data has been retrieved for user: admin (id: 1)
>>>>     2014-01-23 10:43:15|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To:aaragao@di.uminho.pt  <ma...@di.uminho.pt>, PROBLEM -- vclnode1|13:13|image|image.pm  <http://image.pm/>|win7sp1>servidor12.di.uminho.pt  <http://servidor12.di.uminho.pt/>|vmwarewin7-win7_23012014_316-v0|admin
>>>>     |6618|13:13|image| ---- CRITICAL ----
>>>>     |6618|13:13|image| 2014-01-23 10:43:15|6618|13:13|image|image.pm:reservation_failed(387)|vmwarewin7-win7_23012014_316-v0 image creation failed
>>>>     |6618|13:13|image| ( 0)image.pm  <http://image.pm/>, reservation_failed (line: 387)
>>>>     |6618|13:13|image| (-1)image.pm  <http://image.pm/>, process (line: 167)
>>>>     |6618|13:13|image| (-2) vcld, make_new_child (line: 571)
>>>>     |6618|13:13|image| (-3) vcld, main (line: 350)
>>>>     2014-01-23 10:43:15|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To:aaragao@di.uminho.pt  <ma...@di.uminho.pt>, VCL -- NOTICE DELAY Image Creation win7_23012014_3
>>>>     2014-01-23 10:43:16|6618|13:13|image|utils.pm:mail(1253)|SUCCESS -- Sending mail To:aaragao@di.uminho.pt  <ma...@di.uminho.pt>, VCL -- NOTICE FAILED Image Creation win7_23012014_3
>>>>     2014-01-23 10:43:16|6618|13:13|image|utils.pm:update_request_state(1545)|request 13 state updated to: maintenance, laststate to: image
>>>>     2014-01-23 10:43:16|6618|13:13|image|image.pm:reservation_failed(441)|request state set to maintenance, laststate to image
>>>>     2014-01-23 10:43:16|6618|13:13|image|utils.pm:update_computer_state(1587)|computer 3 state updated to: maintenance
>>>>     2014-01-23 10:43:16|6618|13:13|image|image.pm:reservation_failed(449)|win7sp1 state set to maintenance
>>>>     2014-01-23 10:43:16|6618|13:13|image|image.pm:reservation_failed(456)|exiting
>>>>     2014-01-23 10:43:16|6618|13:13|image|utils.pm:delete_computerloadlog_reservation(6396)|removing computerloadlog entries matching loadstate = begin
>>>>     2014-01-23 10:43:16|6618|13:13|image|utils.pm:delete_computerloadlog_reservation(6443)|deleted rows from computerloadlog for reservation id=13
>>>>     2014-01-23 10:43:16|6618|13:13|image|State.pm:DESTROY(929)|VCL::image process duration: 7 seconds
>>>>     2014-01-23 10:43:16|1437|vcld:REAPER(721)|VCL process exited for reservation 13, PID: 6618, signal: CHLD
>>>>
>>>>     I try to change vmhost profile but i cannot move VMs that assigned to host, any clue to configure the current VM Profile ? I only have this basic scenario:
>>>>
>>>>     1 hardware server with ESXi 5.5
>>>>     1 VM configured as vclweb frontend
>>>>     1 VM configured as vclnode
>>>>     1 VM with Windows 7 that I'm trying to make an image
>>>>
>>>>     All the VMs are running on this hardware server with ESXi 5.5 which only have one datastore (/vmfs/volumes/datastore1).
>>>>
>>>>     Any clues ?
>>>>     Em 22-01-2014 15:51,toth.aron@inf.mit.bme.hu  <ma...@inf.mit.bme.hu>  escreveu:
>>>>
>>>>     Hello,
>>>>       
>>>>     Yes. Enable ssh on the esxi 5.5 host, and copy the cloud ssh key (/etc/vcl/vcl.key.pub) to <esxi host>:/etc/ssh/keys-root/authorized_keys on ESXi 5.5, or <esxi host>:/.ssh/authorized_keys on ESXi 5.0,5,1  for key-based ssh authentication.
>>>>     For more information see:
>>>>     https://cwiki.apache.org/confluence/display/VCL/VMware+Configuration
>>>>       
>>>>     Br,
>>>>       
>>>>     Aaron Toth
>>>>     VCL System Administrator
>>>>     BUTE-MIT
>>>>
>>>>     -----António Aragão<aa...@di.uminho.pt>  <ma...@di.uminho.pt>  ezt írta: -----
>>>>     Címzett:user@vcl.apache.org  <ma...@vcl.apache.org>
>>>>     Feladó: António Aragão<aa...@di.uminho.pt>  <ma...@di.uminho.pt>
>>>>     Dátum: 2014/01/22 03:38du.
>>>>     Tárgy: ESXi5.5 (no SDK available)
>>>>
>>>>     Hi,
>>>>
>>>>     There is a way of not using SDK ? I want to use only vim-cmd since I don't any vcenter and only ESXi 5.5 host.
>>>>
>>>>     Thanks.
>>>
>>>     -- 
>>>     <Mail Attachment.png>
>>>
>>>
>>
>> -- 
>> <cartao_virtual_email_aaragao.png>
>

--