puppetj
Contributor
Contributor

ERROR: Unknown error returned by VMware Converter Agent on Step 3 : Preparing target virtual machine

I getting  ERROR: Unknown error returned by VMware Converter Agent  on Step 3 : Preparing target virtual machine
heres my log:
Log for VMware Converter Agent, pid=3960, version=3.0.3, build=build-89816, option=Release, section=1
[2008-11-22 02:27:35.027 'App' 3964 warning] Failed to create console writer
[2008-11-22 02:27:35.032 'App' 1468 info] Current working directory: C:\Program Files\VMware\VMware Converter
[2008-11-22 02:27:35.033 'Libs' 1468 info] HOSTINFO: Seeing Intel CPU, numCoresPerCPU 4 numThreadsPerCore 1.
[2008-11-22 02:27:35.033 'Libs' 1468 info] HOSTINFO: This machine has 1 physical CPUS, 4 total cores, and 4 logical CPUs.
[2008-11-22 02:27:35.033 'ThreadPool' 1468 verbose] TaskMax=40, IoMin=1, IoMax=81
[2008-11-22 02:27:35.034 'App' 1468 info] Trying ufa_agent
[2008-11-22 02:27:35.034 'App' 1468 verbose] Plugin 0 path: ufa-agent.dll
[2008-11-22 02:27:35.034 'App' 1468 verbose] Plugin 0 absolute path: C:\Program Files\VMware\VMware Converter\ufa-agent.dll
[2008-11-22 02:27:35.035 'Ufa' 1468 info] Plugin initialized
[2008-11-22 02:27:35.035 'App' 1468 info] Trying ufa_client
[2008-11-22 02:27:35.035 'App' 1468 verbose] Plugin 1 path: ufa-client.dll
[2008-11-22 02:27:35.035 'App' 1468 verbose] Plugin 1 absolute path: C:\Program Files\VMware\VMware Converter\ufa-client.dll
[2008-11-22 02:27:35.111 'App' 1468 info] [plugin,104] UfaClientPlugin Init
[2008-11-22 02:27:35.111 'App' 1468 info] Trying ufa_slave
[2008-11-22 02:27:35.111 'App' 1468 verbose] Plugin 2 path: ufa-slave.dll
[2008-11-22 02:27:35.111 'App' 1468 verbose] Plugin 2 absolute path: C:\Program Files\VMware\VMware Converter\ufa-slave.dll
[2008-11-22 02:27:35.118 'App' 1468 info] [osInfoWin32,131] Home Edition
[2008-11-22 02:27:35.118 'App' 1468 info] [osInfoWin32,269] Service Pack 1(Build 6001)
[2008-11-22 02:27:35.118 'App' 1468 verbose] [,0] System libeay32.dll library is older than our library (90709F < 9070CF)
[2008-11-22 02:27:37.209 'App' 1468 info] [plugin,126] UfaSlavePlugin Init
[2008-11-22 02:27:37.209 'App' 1468 info] Trying ufa_sysMigration
[2008-11-22 02:27:37.209 'App' 1468 verbose] Plugin 3 path: ufa-sysMigration.dll
[2008-11-22 02:27:37.209 'App' 1468 verbose] Plugin 3 absolute path: C:\Program Files\VMware\VMware Converter\ufa-sysMigration.dll
[2008-11-22 02:27:37.222 'App' 1468 info] [plugin,185] SysMigrationPlugin Init
[2008-11-22 02:27:37.222 'App' 1468 info] Trying ufa_sysReconfig
[2008-11-22 02:27:37.222 'App' 1468 verbose] Plugin 4 path: ufa-sysReconfig.dll
[2008-11-22 02:27:37.222 'App' 1468 verbose] Plugin 4 absolute path: C:\Program Files\VMware\VMware Converter\ufa-sysReconfig.dll
[2008-11-22 02:27:37.229 'App' 1468 info] [plugin,102] SysReconfigPlugin Init
[2008-11-22 02:27:37.231 'App' 1468 info] [utils,126] Using temp dir C:\Windows\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig
[2008-11-22 02:27:37.253 'App' 1468 verbose] [rdsXmlLoader,154] Loading reconfiguration data store XML from C:\Windows\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig\reconfigurationDataStore.xml ...
[2008-11-22 02:27:37.256 'App' 1468 verbose] [rdsXmlLoader,208] Populating ReconfigurationDataStore ...
[2008-11-22 02:27:37.256 'App' 1468 verbose] [rdsXmlLoader,283] Loading action LsiLogic
[2008-11-22 02:27:37.256 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_1000&dev_0030 patch: C_SYMMPI
[2008-11-22 02:27:37.256 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\symmpi patch: S_SYMMPI
[2008-11-22 02:27:37.256 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\drivers\symmpi.sys source: BASEOS sourceFileName: symmpi.sys
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,283] Loading action BuslogicMylex
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: enum\root\LEGACY_BUSLOGIC patch: L_BUSLOGIC
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: services\buslogic patch: S_BUSLOGIC
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\drivers\buslogic.sys source: VMWARE_DRIVERS_CAB sourceFileName: buslogic_for_nt.sys
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Buslogic_vmscsi
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_104b&dev_1040 patch: C_VMSCSI
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\Class\{4D36E97B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMSCSI
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\vmscsi patch: S_VMSCSI
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\drivers\vmscsi.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmscsi.sys
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Buslogic_vmscsi_ACPI
[2008-11-22 02:27:37.257 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\3&61aaa01&0&80 patch: E_VMSCSI
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Buslogic_vmscsi_NotACPI
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\2&ebb567f&0&80 patch: E_VMSCSI
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Enable_Lsi_Scsi_Driver
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\LSI_SCSI patch: S_LSI_SCSI
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Mouse_Keyboard_Common
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0303 patch: C_PS2KBD
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\Class\{4D36E96B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2KBD
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0f13 patch: C_PS2MOUSE
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0f03 patch: C_PS2MOUSE
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\Class\{4D36E96F-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2MOUSE
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Mouse_Keyboard_ISAPnP
[2008-11-22 02:27:37.258 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_8086&dev_7110&cc_0601 patch: C_ISAPNP
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Mouse_Keyboard_ACPI
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\ACPI\PNP0303 patch: E_PS2KBD
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\ACPI\PNP0F13 patch: E_PS2MOUSE
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\ACPI\PNP0F03 patch: E_PS2MOUSE
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Mouse_Keyboard_ACPI_Vista
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\ACPI\PNP0F13 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\ACPI\PNP0F03 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Mouse_Keyboard_NotACPI
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\*PNP0F13 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\*PNP0F03 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,283] Loading action NT_SVGA_Registry_Updates
[2008-11-22 02:27:37.259 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: enum\root\LEGACY_VMX_SVGA patch: E_VMX_SVGA
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Common_SVGA_Updates
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Control\Class\{4D36E968-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMX_SVGA
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: INF\vmx_svga.inf source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.inf
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\vmx_fb.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_fb.dll
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\vmx_mode.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_mode.dll
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\drivers\vmx_svga.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.sys
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,283] Loading action SVGA_Registry_Updates_APCI
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\PCI\VEN_15AD&DEV_0405&SUBSYS_040515AD&REV_00\3&61aaa01&0&78 patch: E_VMX_SVGA
[2008-11-22 02:27:37.260 'App' 1468 verbose] [rdsXmlLoader,283] Loading action SVGA_Registry_Updates_NotACPI
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\PCI\VEN_15AD&DEV_0405&SUBSYS_040515AD&REV_00\2&ebb567f&0&78 patch: E_VMX_SVGA
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Enable_ATAPI_Service
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\atapi patch: S_ATAPI
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Restore_i8042_NT5
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\i8042prt patch: SKUNKWORKS_S_I8042
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,421] File Update filePathInWinDir: system32\drivers\i8042prt.sys source: BASEOS sourceFileName: i8042prt.sys
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_vpcsrvc
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\1-driver-vpcsrvc patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\1-vpcsrvc patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\LEGACY_1-DRIVER-VPCSRVC patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\LEGACY_1-VPCSRVC patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.261 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_mrxvpc
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\MrxVPC patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_MRXVPC patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_vpcmap
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\VPCMap patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPCMAP patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_vpc-s3_NT4
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPC-S3 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_vpc-s3_NT5
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\PCI\VEN_5333&DEV_8811&SUBSYS_00000000&REV_00\3&267a616a&0&40 patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.262 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\s3legacy patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_vmsrvc
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\1-driver-vmsrvc patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-DRIVER-VMSRVC patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\1-vmsrvc patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-VMSRVC patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_netfin
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_NETFIN patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\NetFin patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_cpqdmi
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQDMI patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\CPQDMI patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.263 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_cpqalert
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQALERT patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\CPQALERT patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\EventLog\System\CPQALERT patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_cpqagent
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\EventLog\System\Snmp patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\Snmp patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Deref_ipmi
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\IPMI patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,283] Loading action RemoveUnwantedKeys
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,283] Loading action FixSetupLog
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,492] String replace Update filePathInWinDir: repair\setup.log pattern: \\system32\\ntoskrnl.exe.*\.exe value: \\system32\\ntoskrnl.exe = "ntoskrnl.exe
[2008-11-22 02:27:37.264 'App' 1468 verbose] [rdsXmlLoader,492] String replace Update filePathInWinDir: repair\setup.log pattern: \\system32\\hal.dll.*\.dll value: \\system32\\hal.dll = "hal.dll
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,283] Loading action DeleteInfCacheFile
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,451] File Delete filePathInWinDir: inf\INFCACHE.1
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Del_nexbus
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\NexBus patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,283] Loading action Del_VIService
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,371] Registry Update currentControlSetKey: Services\Virtual Iron Service patch: SKUNKWORKS_FILLER
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWin2k364Bit
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericVista64Bit
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWinXP64Bit
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWin64Bit
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition NotWin64Bit
[2008-11-22 02:27:37.265 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericVista
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWinNT5
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWin2k3
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWinXP
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWin2k
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition GenericWinNT4
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition NotWinNT4
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition NotWinVista
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition ACPI
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition NotACPI
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition MultiProc
[2008-11-22 02:27:37.266 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition InstallSVGA
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition MustUpdateCoreNTFiles
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition BuslogicMylex
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,526] Loading expression definition Buslogic_vmscsi
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration FixSetupLog
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action FixSetupLog
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration BuslogicMylex
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action BuslogicMylex
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration LsiLogic
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action LsiLogic
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Buslogic_vmscsi_ACPI
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Buslogic_vmscsi
[2008-11-22 02:27:37.267 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Buslogic_vmscsi_ACPI
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Buslogic_vmscsi_NotACPI
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Buslogic_vmscsi
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Buslogic_vmscsi_NotACPI
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Enable_Lsi_Scsi_Driver
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Enable_Lsi_Scsi_Driver
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Mouse_Keyboard_ACPI
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_Common
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_ISAPnP
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_ACPI
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Mouse_Keyboard_NotACPI
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_Common
[2008-11-22 02:27:37.268 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_ISAPnP
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_NotACPI
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Mouse_Keyboard_ACPI_Vista
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_Common
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Mouse_Keyboard_ACPI_Vista
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration SVGA_NT
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Common_SVGA_Updates
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action NT_SVGA_Registry_Updates
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration SVGA_notNT_ACPI
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Common_SVGA_Updates
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action SVGA_Registry_Updates_APCI
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration SVGA_notNT_NotACPI
[2008-11-22 02:27:37.269 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Common_SVGA_Updates
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action SVGA_Registry_Updates_NotACPI
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Enable_ATAPI_Service
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Enable_ATAPI_Service
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Restore_i8042_NT5
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Restore_i8042_NT5
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_vpcsrvc
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_vpcsrvc
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_mrxvpc
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_mrxvpc
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_vpcmap
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_vpcmap
[2008-11-22 02:27:37.270 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_vpc-s3_NT4
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_vpc-s3_NT4
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_vpc-s3_NT5
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_vpc-s3_NT5
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_vmsrvc
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_vmsrvc
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_netfin
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_netfin
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_cpqdmi
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_cpqdmi
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_cpqalert
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_cpqalert
[2008-11-22 02:27:37.271 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_cpqagent
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_cpqagent
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Deref_ipmi
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Deref_ipmi
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration RemoveUnwantedKeys
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action RemoveUnwantedKeys
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Delete_InfCacheFile
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action DeleteInfCacheFile
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Del_nexbus
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Del_nexbus
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1088] Loading reconfiguration Del_VIService
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1156] Loading reconfiguration action Del_VIService
[2008-11-22 02:27:37.272 'App' 1468 verbose] [rdsXmlLoader,1210] Loading hotfixes for WINDOWS4.0
[2008-11-22 02:27:37.273 'App' 1468 verbose] [rdsXmlLoader,1210] Loading hotfixes for WINDOWS4.0
[2008-11-22 02:27:37.273 'App' 1468 verbose] [rdsXmlLoader,1210] Loading hotfixes for WINDOWS4.0
[2008-11-22 02:27:37.273 'App' 1468 verbose] [rdsXmlLoader,1210] Loading hotfixes for WINDOWS4.0
[2008-11-22 02:27:37.273 'App' 1468 verbose] [rdsXmlLoader,1210] Loading hotfixes for WINDOWS4.0
[2008-11-22 02:27:37.274 'App' 1468 info] [utils,153] Cleaning up temp directory C:\Windows\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig ...
[2008-11-22 02:27:37.274 'App' 1468 info] Trying ufa_vmImporter
[2008-11-22 02:27:37.274 'App' 1468 verbose] Plugin 5 path: ufa-vmImporter.dll
[2008-11-22 02:27:37.274 'App' 1468 verbose] Plugin 5 absolute path: C:\Program Files\VMware\VMware Converter\ufa-vmImporter.dll
[2008-11-22 02:27:37.307 'App' 1468 info] [plugin,111] VMImporterPlugin Init
[2008-11-22 02:27:37.307 'Ufa' 1468 info] Initializing SSL context
[2008-11-22 02:27:37.307 'Libs' 1468 info] System libeay32.dll library is older than our library (90709F < 9070AF)
[2008-11-22 02:27:37.308 'App' 1468 info] Vmacore::InitSSL: doVersionCheck = true, handshakeTimeoutUs = 120000000
[2008-11-22 02:27:37.309 'Ufa' 1468 info] VMDB pipe name: \\.\pipe\vmware-ufad-p2v30-vmdb, use SSL: true
[2008-11-22 02:27:37.310 'VmdbAdapter' 1468 info] Using new VMDB VMOMI serialization format
[2008-11-22 02:27:37.310 'Ufa' 1468 info] Plugin started
[2008-11-22 02:27:37.310 'App' 1468 info] [plugin,119] UfaClientPlugin Start
[2008-11-22 02:27:37.318 'App' 1468 info] [plugin,141] UfaSlavePlugin Start
[2008-11-22 02:27:37.318 'App' 1468 info] [plugin,201] SysMigrationPlugin Start
[2008-11-22 02:27:37.318 'App' 1468 info] [plugin,119] SysReconfigPlugin Start
[2008-11-22 02:27:37.318 'App' 1468 info] [plugin,126] VMImporterPlugin Start
[2008-11-22 02:27:37.318 'App' 1468 info] [serviceWin32,418] ufad-p2v service started
[#1] [2008-11-22 02:29:22.088 'App' 848 info] Impersonating user PUPPETJ in session EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:29:22.113 'App' 1468 info] [vmImporter,140] Entering UFAD at QuerySourceInfo
[#1] [2008-11-22 02:29:22.114 'App' 1468 verbose] [vmImporter,141] source:
[#1] (sysimage.LiveSourceComputerSpec) {
[#1]    dynamicType = <unset>,
[#1]    host = "",
[#1]    port = 0,
[#1] }
[#1] [2008-11-22 02:29:22.114 'App' 1468 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:29:22.115 'App' 1468 verbose] [disposable,69] Registering disposable session object id=VmiQuerySourceInfoTask::task{0}
[#1] [2008-11-22 02:29:22.115 'App' 1468 verbose] [disposable,80] Associating disposable session object id=VmiQuerySourceInfoTask::task{0} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:29:22.134 'App' 1468 verbose] [imageProcessingTaskImpl,504] VmiQuerySourceInfoTask::task{0}: Starting execution of an image processing task
[#1] [2008-11-22 02:29:22.134 'App' 1468 verbose] [imageProcessingTaskImpl,154] VmiQuerySourceInfoTask::task{0}: SetState to running
[#1] [2008-11-22 02:29:22.134 'App' 1468 info] [vmiQuerySourceInfoTask,79] VmiQuerySourceInfoTask initialized
[#1] [2008-11-22 02:29:22.135 'App' 1468 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:29:22.135 'App' 2976 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#1] [2008-11-22 02:29:22.136 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122
[#1] [2008-11-22 02:29:22.136 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122
[#1] [2008-11-22 02:29:22.136 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122
[#1] [2008-11-22 02:29:22.136 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=8192 err=122
[#1] [2008-11-22 02:29:22.137 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=10104 size=16384 err=0
[#1] [2008-11-22 02:29:22.139 'App' 848 verbose] [disposable,69] Registering disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:29:22.139 'App' 848 verbose] [disposable,80] Associating disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[2008-11-22 02:29:22.140 'App' 1292 info] [stub,42] HostDeviceInfo: Failed to enumerate host parallel ports via the registry. Could not open device map parallel port registry key.
[#1] [2008-11-22 02:29:22.308 'App' 848 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#1] [2008-11-22 02:29:22.308 'App' 848 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#1] [2008-11-22 02:29:22.309 'App' 848 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.309 'App' 848 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.309 'App' 848 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.309 'App' 848 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.309 'App' 848 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.324 'App' 848 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume1 device name.
[#1] [2008-11-22 02:29:22.325 'App' 848 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[2008-11-22 02:29:22.325 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.325 'App' 848 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:29:22.325 'App' 848 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive0
[#1] [2008-11-22 02:29:22.326 'App' 848 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:29:22.326 'App' 848 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume1
[#1] [2008-11-22 02:29:22.326 'App' 848 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:29:22.327 'App' 848 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume2 device name.
[2008-11-22 02:29:22.327 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.327 'App' 848 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.327 'App' 848 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:29:22.327 'App' 848 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive1
[#1] [2008-11-22 02:29:22.328 'App' 848 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:29:22.328 'App' 848 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume2
[#1] [2008-11-22 02:29:22.328 'App' 848 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:29:22.329 'App' 848 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ corresponds to \Device\CdRom0 device name.
[2008-11-22 02:29:22.329 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.329 'App' 848 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.333 'App' 848 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:29:22.333 'App' 848 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:29:22.337 'App' 848 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:29:22.337 'App' 848 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\CdRom1 device name.
[2008-11-22 02:29:22.338 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.338 'App' 848 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.348 'App' 848 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:29:22.348 'App' 848 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:29:22.359 'App' 848 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:29:22.359 'App' 848 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ corresponds to \Device\HarddiskVolume3 device name.
[2008-11-22 02:29:22.359 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\: The request is not supported (50)
[#1] [2008-11-22 02:29:22.359 'App' 848 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.360 'App' 848 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\
[#1] [2008-11-22 02:29:22.360 'App' 848 warning] [attachedComputerWin32,711] [GetVolumesForComputer] None of the extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exist on disks in computer.
[#1] [2008-11-22 02:29:22.360 'App' 848 info] [attachedComputerWin32,1461] Live OS version: Windows Vista edition:  root:Windows SrvPack:1 SMP:1 64:0 Ver:6.0
[#1] [2008-11-22 02:29:22.360 'App' 848 verbose] [NetworkAdaptersInfoEnum,848] Microsoft Windows Version 6.0 Build 6001
[#1] [2008-11-22 02:29:22.370 'App' 848 verbose] [NetworkAdaptersInfoEnum,972] Connected to ROOT\CIMV2 WMI namespace
[#1] [2008-11-22 02:29:22.926 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC1: WAN Miniport (L2TP)
[#1] [2008-11-22 02:29:22.926 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC1 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.927 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC2: WAN Miniport (PPTP)
[#1] [2008-11-22 02:29:22.927 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC2 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.927 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC3: WAN Miniport (PPPOE)
[#1] [2008-11-22 02:29:22.927 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC3 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.927 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC4: WAN Miniport (IPv6)
[#1] [2008-11-22 02:29:22.927 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC4 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.928 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC5: Linksys LNE100TX(v5) Fast Ethernet Adapter
[#1] [2008-11-22 02:29:22.928 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC5 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.928 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC6: isatap.hsd1.nj.comcast.net.
[#1] [2008-11-22 02:29:22.928 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC6 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.928 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC7: WAN Miniport (IP)
[#1] [2008-11-22 02:29:22.928 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC7 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.929 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC8: Teredo Tunneling Pseudo-Interface
[#1] [2008-11-22 02:29:22.929 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC8 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.929 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC9: WAN Miniport (SSTP)
[#1] [2008-11-22 02:29:22.929 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC9 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.929 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC10: RAS Async Adapter
[#1] [2008-11-22 02:29:22.930 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC10 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.930 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC11: WAN Miniport (Network Monitor)
[#1] [2008-11-22 02:29:22.930 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC11 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.930 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC12: NVIDIA nForce Networking Controller
[#1] [2008-11-22 02:29:22.930 'App' 848 verbose] [NetworkAdaptersInfoEnum,311] NIC12 is a physical Ethernet adapter
[#1] [2008-11-22 02:29:22.931 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC13: NVIDIA nForce Networking Controller #2
[#1] [2008-11-22 02:29:22.931 'App' 848 verbose] [NetworkAdaptersInfoEnum,311] NIC13 is a physical Ethernet adapter
[#1] [2008-11-22 02:29:22.931 'App' 848 verbose] [NetworkAdaptersInfoEnum,1014] NIC14: isatap.{A7F870A6-52D7-4217-AAFA-CBBF5360AAC4}
[#1] [2008-11-22 02:29:22.931 'App' 848 verbose] [NetworkAdaptersInfoEnum,314] NIC14 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:22.931 'App' 848 verbose] [NetworkAdaptersInfoEnum,267] No more NIC to process
[#1] [2008-11-22 02:29:22.932 'App' 848 verbose] [disposable,122] object explicitly disposed: computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [diskSet,114] Cleaning up computer's child objects
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [diskSet,140] Unregistring disk id=disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [diskSet,140] Unregistring disk id=disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122
[#1] [2008-11-22 02:29:22.933 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122
[#1] [2008-11-22 02:29:22.934 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122
[#1] [2008-11-22 02:29:22.934 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=8192 err=122
[#1] [2008-11-22 02:29:22.934 'App' 848 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=10104 size=16384 err=0
[#1] [2008-11-22 02:29:22.937 'App' 848 verbose] [disposable,69] Registering disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:29:22.937 'App' 848 verbose] [disposable,80] Associating disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:29:22.937 'App' 2976 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#1] [2008-11-22 02:29:22.937 'App' 2976 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#1] [2008-11-22 02:29:22.937 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.938 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.938 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.938 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.938 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\"
[#1] [2008-11-22 02:29:22.958 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume1 device name.
[2008-11-22 02:29:22.958 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.958 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.958 'App' 2976 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:29:22.958 'App' 2976 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive0
[#1] [2008-11-22 02:29:22.959 'App' 2976 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:29:22.959 'App' 2976 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume1
[#1] [2008-11-22 02:29:22.960 'App' 2976 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:29:22.960 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume2 device name.
[2008-11-22 02:29:22.960 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.960 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.960 'App' 2976 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:29:22.960 'App' 2976 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive1
[#1] [2008-11-22 02:29:22.961 'App' 2976 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:29:22.962 'App' 2976 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume2
[#1] [2008-11-22 02:29:22.962 'App' 2976 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:29:22.962 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ corresponds to \Device\CdRom0 device name.
[2008-11-22 02:29:22.962 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.962 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.966 'App' 2976 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:29:22.966 'App' 2976 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:29:22.970 'App' 2976 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:29:22.971 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\CdRom1 device name.
[2008-11-22 02:29:22.971 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:29:22.971 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.981 'App' 2976 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:29:22.982 'App' 2976 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:29:22.992 'App' 2976 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:29:22.993 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ corresponds to \Device\HarddiskVolume3 device name.
[2008-11-22 02:29:22.993 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\: The request is not supported (50)
[#1] [2008-11-22 02:29:22.993 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:29:22.993 'App' 2976 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\
[#1] [2008-11-22 02:29:22.993 'App' 2976 warning] [attachedComputerWin32,711] [GetVolumesForComputer] None of the extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exist on disks in computer.
[#1] [2008-11-22 02:29:22.993 'App' 2976 info] [attachedComputerWin32,1461] Live OS version: Windows Vista edition:  root:Windows SrvPack:1 SMP:1 64:0 Ver:6.0
[#1] [2008-11-22 02:29:22.994 'App' 2976 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#1] [2008-11-22 02:29:22.994 'App' 2976 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#1] [2008-11-22 02:29:22.994 'App' 1468 info] [imageProcessorProxy,148] Successfully connected to UfaSysReconfig
[#1] [2008-11-22 02:29:22.994 'App' 2976 info] [ufaClientConnection,69] Successfully connected to local UfaClient.
[#1] [2008-11-22 02:29:22.995 'App' 2976 info] [ufaClientConnection,163] Opening specifed computer ...
[#1] [2008-11-22 02:29:22.995 'App' 848 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#1] [2008-11-22 02:29:22.995 'App' 2976 verbose] [computerProxy,74] Successfully connected to remote Computer.
[#1] [2008-11-22 02:29:22.995 'App' 2976 verbose] [computerProxy,94] Debug
[#1] [2008-11-22 02:29:22.995 'App' 2976 verbose] [computerProxy,120] Debug
[#1] [2008-11-22 02:29:22.995 'App' 2976 info] [ufaClientConnection,136] Info
[#1] [2008-11-22 02:29:22.996 'App' 2976 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:29:22.996 'App' 2976 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[2008-11-22 02:29:22.999 'App' 1292 info] [stub,42] HostDeviceInfo: Failed to enumerate host parallel ports via the registry. Could not open device map parallel port registry key.
[#1] [2008-11-22 02:29:23.009 'App' 2976 verbose] [NetworkAdaptersInfoEnum,848] Microsoft Windows Version 6.0 Build 6001
[#1] [2008-11-22 02:29:23.011 'App' 2976 verbose] [NetworkAdaptersInfoEnum,972] Connected to ROOT\CIMV2 WMI namespace
[#1] [2008-11-22 02:29:23.442 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC1: WAN Miniport (L2TP)
[#1] [2008-11-22 02:29:23.442 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC1 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.443 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC2: WAN Miniport (PPTP)
[#1] [2008-11-22 02:29:23.443 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC2 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.443 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC3: WAN Miniport (PPPOE)
[#1] [2008-11-22 02:29:23.443 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC3 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.444 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC4: WAN Miniport (IPv6)
[#1] [2008-11-22 02:29:23.444 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC4 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.444 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC5: Linksys LNE100TX(v5) Fast Ethernet Adapter
[#1] [2008-11-22 02:29:23.444 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC5 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.444 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC6: isatap.hsd1.nj.comcast.net.
[#1] [2008-11-22 02:29:23.444 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC6 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.445 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC7: WAN Miniport (IP)
[#1] [2008-11-22 02:29:23.445 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC7 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.445 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC8: Teredo Tunneling Pseudo-Interface
[#1] [2008-11-22 02:29:23.445 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC8 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.445 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC9: WAN Miniport (SSTP)
[#1] [2008-11-22 02:29:23.445 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC9 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.446 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC10: RAS Async Adapter
[#1] [2008-11-22 02:29:23.446 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC10 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.446 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC11: WAN Miniport (Network Monitor)
[#1] [2008-11-22 02:29:23.446 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC11 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.446 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC12: NVIDIA nForce Networking Controller
[#1] [2008-11-22 02:29:23.446 'App' 2976 verbose] [NetworkAdaptersInfoEnum,311] NIC12 is a physical Ethernet adapter
[#1] [2008-11-22 02:29:23.447 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC13: NVIDIA nForce Networking Controller #2
[#1] [2008-11-22 02:29:23.447 'App' 2976 verbose] [NetworkAdaptersInfoEnum,311] NIC13 is a physical Ethernet adapter
[#1] [2008-11-22 02:29:23.447 'App' 2976 verbose] [NetworkAdaptersInfoEnum,1014] NIC14: isatap.{A7F870A6-52D7-4217-AAFA-CBBF5360AAC4}
[#1] [2008-11-22 02:29:23.447 'App' 2976 verbose] [NetworkAdaptersInfoEnum,314] NIC14 is not a physical Ethernet adapter -- discarding
[#1] [2008-11-22 02:29:23.447 'App' 2976 verbose] [NetworkAdaptersInfoEnum,267] No more NIC to process
[#1] [2008-11-22 02:29:23.448 'App' 1468 verbose] [importSource,438] (vim.vm.device.VirtualPCNet32) {
[#1]    dynamicType = <unset>,
[#1]    key = 1009,
[#1]    deviceInfo = (vim.Description) {
[#1]       dynamicType = <unset>,
[#1]       label = "NIC1",
[#1]       summary = "NVIDIA nForce Networking Controller",
[#1]    },
[#1]    backing = (vim.vm.device.VirtualEthernetCard.LegacyNetworkBackingInfo) {
[#1]       dynamicType = <unset>,
[#1]       deviceName = "",
[#1]       useAutoDetect = <unset>,
[#1]    },
[#1]    connectable = (vim.vm.device.VirtualDevice.ConnectInfo) null,
[#1]    controllerKey = <unset>,
[#1]    unitNumber = <unset>,
[#1]    addressType = "manual",
[#1]    macAddress = "00:1D:60:63:80:8F",
[#1]    wakeOnLanEnabled = <unset>,
[#1] }
[#1] [2008-11-22 02:29:23.449 'App' 1468 verbose] [importSource,438] (vim.vm.device.VirtualPCNet32) {
[#1]    dynamicType = <unset>,
[#1]    key = 1010,
[#1]    deviceInfo = (vim.Description) {
[#1]       dynamicType = <unset>,
[#1]       label = "NIC2",
[#1]       summary = "NVIDIA nForce Networking Controller #2",
[#1]    },
[#1]    backing = (vim.vm.device.VirtualEthernetCard.LegacyNetworkBackingInfo) {
[#1]       dynamicType = <unset>,
[#1]       deviceName = "",
[#1]       useAutoDetect = <unset>,
[#1]    },
[#1]    connectable = (vim.vm.device.VirtualDevice.ConnectInfo) null,
[#1]    controllerKey = <unset>,
[#1]    unitNumber = <unset>,
[#1]    addressType = "manual",
[#1]    macAddress = "00:1D:60:63:7A:AB",
[#1]    wakeOnLanEnabled = <unset>,
[#1] }
[#1] [2008-11-22 02:29:23.449 'App' 1468 verbose] [importSource,441] 2 NICs found
[#1] [2008-11-22 02:29:23.449 'App' 2976 verbose] [disposable,122] object explicitly disposed: computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:29:23.449 'App' 2976 verbose] [diskSet,114] Cleaning up computer's child objects
[#1] [2008-11-22 02:29:23.450 'App' 2976 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:29:23.450 'App' 2976 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:29:23.450 'App' 2976 verbose] [diskSet,140] Unregistring disk id=disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:29:23.450 'App' 2976 verbose] [diskSet,140] Unregistring disk id=disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:29:23.451 'App' 1468 verbose] [vmiTask,82] VmiQuerySourceInfoTask::task{0}: returning results:
[#1] (sysimage.QuerySourceInfoResult) {
[#1]    dynamicType = <unset>,
[#1]    info = (sysimage.ComputerInfo) {
[#1]       dynamicType = <unset>,
[#1]       osInfo = (sysimage.WindowsOsInfo) {
[#1]          dynamicType = <unset>,
[#1]          family = "WINDOWS",
[#1]          displayName = "Windows Vista",
[#1]          guestOsId = "winVistaGuest",
[#1]          vendor = "Microsoft",
[#1]          edition = "",
[#1]          majorVersion = 6,
[#1]          minorVersion = 0,
[#1]          multiProcessor = true,
[#1]          osIs64Bit = false,
[#1]          osFolder = "Windows",
[#1]          bootPartition = 1769366884,
[#1]          bootDiskDeviceNumber = <unset>,
[#1]          bootDiskSignature = <unset>,
[#1]          vmxnetPresent = <unset>,
[#1]          bootPartStartingSector = <unset>,
[#1]          spLevel = 1,
[#1]       },
[#1]       prerequisites = (sysimage.Prerequisites) {
[#1]          dynamicType = <unset>,
[#1]          osInfo = (sysimage.OsInfo) {
[#1]             dynamicType = <unset>,
[#1]             family = "WINDOWS",
[#1]             displayName = "Windows Vista",
[#1]             guestOsId = "winVistaGuest",
[#1]             vendor = "Microsoft",
[#1]             edition = "",
[#1]             majorVersion = 6,
[#1]             minorVersion = 0,
[#1]          },
[#1]       },
[#1]       disks = (sysimage.DiskInfo) [
[#1]          (sysimage.DiskInfo) {
[#1]             dynamicType = <unset>,
[#1]             id = "disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}",
[#1]             capacityInBytes = 500107837440,
[#1]             deviceNumber = 0,
[#1]             diskBackingFormatVHD = false,
[#1]          },
[#1]          (sysimage.DiskInfo) {
[#1]             dynamicType = <unset>,
[#1]             id = "disk={5c822bdfe4346fded26a0a0854198ded817c6666}",
[#1]             capacityInBytes = 410101088256,
[#1]             deviceNumber = 1,
[#1]             diskBackingFormatVHD = false,
[#1]          }
[#1]       ],
[#1]       volumes = (sysimage.VolumeInfo) [
[#1]          (sysimage.VolumeInfo) {
[#1]             dynamicType = <unset>,
[#1]             id = "attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}",
[#1]             driveLetter = "d:",
[#1]             capacityInBytes = 500096991744,
[#1]             spaceUsedInBytes = 406253400576,
[#1]             systemVolume = false,
[#1]             activeVolume = true,
[#1]             filesystemUnderstood = true,
[#1]             diskDeviceNumber = 0,
[#1]          },
[#1]          (sysimage.VolumeInfo) {
[#1]             dynamicType = <unset>,
[#1]             id = "attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}",
[#1]             driveLetter = "c:",
[#1]             capacityInBytes = 410087752704,
[#1]             spaceUsedInBytes = 24635646976,
[#1]             systemVolume = true,
[#1]             activeVolume = false,
[#1]             filesystemUnderstood = true,
[#1]             diskDeviceNumber = 1,
[#1]          }
[#1]       ],
[#1]       nics = (sysimage.NicInfo) [
[#1]          (sysimage.NicInfo) {
[#1]             dynamicType = <unset>,
[#1]             network = <unset>,
[#1]          },
[#1]          (sysimage.NicInfo) {
[#1]             dynamicType = <unset>,
[#1]             network = <unset>,
[#1]          }
[#1]       ],
[#1]       rootOvfImportEnvelope = (sysimage.OvfNode) null,
[#1]    },
[#1]    compatibilityReport = (sysimage.CompatibilityWarning) [
[#1]       "IMPORT_OK_NO_INSTALL_TOOLS"
[#1]    ],
[#1] }
[#1] [2008-11-22 02:29:23.452 'App' 1468 verbose] [imageProcessingTaskImpl,154] VmiQuerySourceInfoTask::task{0}: SetState to success
[#1] [2008-11-22 02:29:23.453 'App' 1468 verbose] [imageProcessingTaskImpl,514] VmiQuerySourceInfoTask::task{0}: Image processing task has succeeded
[#1] [2008-11-22 02:29:23.477 'App' 848 verbose] [disposable,122] object explicitly disposed: VmiQuerySourceInfoTask::task{0}
[#1] [2008-11-22 02:29:23.477 'App' 848 verbose] [imageProcessingTaskImpl,123] VmiQuerySourceInfoTask::task{0} being destroyed
[#1] [2008-11-22 02:36:58.874 'App' 1468 info] [vmImporter,157] Entering UFAD at CheckDestAvailable
[#1] [2008-11-22 02:36:58.874 'App' 1468 verbose] [vmImporter,158] target:
[#1] (sysimage.HostedTargetVMSpec) {
[#1]    dynamicType = <unset>,
[#1]    vmName = "VistaQC",
[#1]    vmPath = "C:\Users\PUPPETJ",
[#1]    username = <unset>,
[#1]    password = <unset>,
[#1] }
[#1] [2008-11-22 02:36:58.874 'App' 1468 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:36:58.874 'App' 1468 verbose] [disposable,69] Registering disposable session object id=VmiCheckDestAvailableTask::task{1}
[#1] [2008-11-22 02:36:58.875 'App' 1468 verbose] [disposable,80] Associating disposable session object id=VmiCheckDestAvailableTask::task{1} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:36:58.895 'App' 848 verbose] [imageProcessingTaskImpl,504] VmiCheckDestAvailableTask::task{1}: Starting execution of an image processing task
[#1] [2008-11-22 02:36:58.895 'App' 848 verbose] [imageProcessingTaskImpl,154] VmiCheckDestAvailableTask::task{1}: SetState to running
[#1] [2008-11-22 02:36:58.895 'App' 848 info] [vmiCheckDestAvailableTask,40] VmiCheckDestAvailableTask initialized
[#1] [2008-11-22 02:36:58.897 'App' 848 verbose] [vmiTask,82] VmiCheckDestAvailableTask::task{1}: returning results:
[#1] (sysimage.CompatibilityWarning) []
[#1] [2008-11-22 02:36:58.897 'App' 848 verbose] [imageProcessingTaskImpl,154] VmiCheckDestAvailableTask::task{1}: SetState to success
[#1] [2008-11-22 02:36:58.897 'App' 848 verbose] [imageProcessingTaskImpl,514] VmiCheckDestAvailableTask::task{1}: Image processing task has succeeded
[#1] [2008-11-22 02:36:58.922 'App' 848 verbose] [disposable,122] object explicitly disposed: VmiCheckDestAvailableTask::task{1}
[#1] [2008-11-22 02:36:58.922 'App' 848 verbose] [imageProcessingTaskImpl,123] VmiCheckDestAvailableTask::task{1} being destroyed
[#1] [2008-11-22 02:37:07.359 'App' 1388 info] [vmImporter,175] Entering UFAD at GetDiskSizes
[#1] [2008-11-22 02:37:07.359 'App' 1388 verbose] [vmImporter,176] source:
[#1] (sysimage.LiveSourceComputerSpec) {
[#1]    dynamicType = <unset>,
[#1]    host = "",
[#1]    port = 0,
[#1] }
[#1] [2008-11-22 02:37:07.359 'App' 1388 verbose] [vmImporter,177] params:
[#1] (sysimage.ImportParams) {
[#1]    dynamicType = <unset>,
[#1]    targetProductVersion = "PRODUCT_WS_6X",
[#1]    diskType = "MONOLITHIC_SPARSE",
[#1]    preallocateDisks = false,
[#1]    clonePagefile = false,
[#1]    removeSystemRestore = false,
[#1]    volumesToClone = (sysimage.VolumeCloneInfo) [
[#1]       (sysimage.VolumeCloneInfo) {
[#1]          dynamicType = <unset>,
[#1]          volumeId = "attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}",
[#1]          bResize = false,
[#1]          newCapacityInBytes = <unset>,
[#1]          bSeparateDisk = false,
[#1]       }
[#1]    ],
[#1]    preserveNicsInfo = false,
[#1]    preserveHWInfo = true,
[#1]    preserveDeviceBackingInfo = false,
[#1]    keepIdentity = false,
[#1]    rootOvfExportEnvelope = (sysimage.OvfNode) null,
[#1] }
[#1] [2008-11-22 02:37:07.360 'App' 1388 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:37:07.360 'App' 1388 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:37:07.360 'App' 1388 verbose] [disposable,69] Registering disposable session object id=VmiGetDiskSizesTask::task{2}
[#1] [2008-11-22 02:37:07.360 'App' 1388 verbose] [disposable,80] Associating disposable session object id=VmiGetDiskSizesTask::task{2} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:37:07.379 'App' 1388 verbose] [imageProcessingTaskImpl,504] VmiGetDiskSizesTask::task{2}: Starting execution of an image processing task
[#1] [2008-11-22 02:37:07.379 'App' 1388 verbose] [imageProcessingTaskImpl,154] VmiGetDiskSizesTask::task{2}: SetState to running
[#1] [2008-11-22 02:37:07.379 'App' 1388 info] [vmiGetDiskSizesTask,52] VmiGetDiskSizesTask initialized
[#1] [2008-11-22 02:37:07.380 'App' 2976 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#1] [2008-11-22 02:37:07.380 'App' 1064 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122
[#1] [2008-11-22 02:37:07.380 'App' 1064 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122
[#1] [2008-11-22 02:37:07.381 'App' 1064 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122
[#1] [2008-11-22 02:37:07.381 'App' 1064 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=8192 err=122
[#1] [2008-11-22 02:37:07.381 'App' 1064 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=10104 size=16384 err=0
[#1] [2008-11-22 02:37:07.384 'App' 1064 verbose] [disposable,69] Registering disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:37:07.384 'App' 1064 verbose] [disposable,80] Associating disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:37:07.384 'App' 2976 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:37:07.385 'App' 1064 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#1] [2008-11-22 02:37:07.385 'App' 1064 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:37:07.385 'App' 1064 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:37:07.385 'App' 1064 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:37:07.385 'App' 1064 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:37:07.386 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.386 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.386 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.386 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.386 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.405 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume1 device name.
[#1] [2008-11-22 02:37:07.405 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[2008-11-22 02:37:07.405 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.406 'App' 1064 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:37:07.406 'App' 1064 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive0
[#1] [2008-11-22 02:37:07.406 'App' 1064 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:37:07.407 'App' 1064 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume1
[#1] [2008-11-22 02:37:07.407 'App' 1064 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:37:07.407 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume2 device name.
[2008-11-22 02:37:07.407 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.407 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.408 'App' 1064 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:37:07.408 'App' 1064 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive1
[#1] [2008-11-22 02:37:07.408 'App' 1064 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:37:07.409 'App' 1064 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume2
[#1] [2008-11-22 02:37:07.409 'App' 1064 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:37:07.409 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ corresponds to \Device\CdRom0 device name.
[2008-11-22 02:37:07.409 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.409 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.413 'App' 1064 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:37:07.414 'App' 1064 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:37:07.418 'App' 1064 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:37:07.418 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\CdRom1 device name.
[#1] [2008-11-22 02:37:07.418 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[2008-11-22 02:37:07.418 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.429 'App' 1064 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:37:07.429 'App' 1064 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:37:07.440 'App' 1064 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:37:07.440 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ corresponds to \Device\HarddiskVolume3 device name.
[2008-11-22 02:37:07.440 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\: The request is not supported (50)
[#1] [2008-11-22 02:37:07.440 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.440 'App' 1064 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\
[#1] [2008-11-22 02:37:07.440 'App' 1064 warning] [attachedComputerWin32,711] [GetVolumesForComputer] None of the extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exist on disks in computer.
[#1] [2008-11-22 02:37:07.441 'App' 1064 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#1] [2008-11-22 02:37:07.441 'App' 1064 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#1] [2008-11-22 02:37:07.442 'App' 1064 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:37:07.442 'App' 1064 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:37:07.442 'App' 1388 verbose] [vmiTask,82] VmiGetDiskSizesTask::task{2}: returning results:
[#1] (long) [
[#1]    24635724800
[#1] ]
[#1] [2008-11-22 02:37:07.442 'App' 1388 verbose] [imageProcessingTaskImpl,154] VmiGetDiskSizesTask::task{2}: SetState to success
[#1] [2008-11-22 02:37:07.442 'App' 1388 verbose] [imageProcessingTaskImpl,514] VmiGetDiskSizesTask::task{2}: Image processing task has succeeded
[#1] [2008-11-22 02:37:07.465 'App' 1252 verbose] [disposable,122] object explicitly disposed: VmiGetDiskSizesTask::task{2}
[#1] [2008-11-22 02:37:07.465 'App' 1388 verbose] [disposable,122] object explicitly disposed: computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:37:07.465 'App' 1388 verbose] [diskSet,114] Cleaning up computer's child objects
[#1] [2008-11-22 02:37:07.465 'App' 1388 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:37:07.465 'App' 1388 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:37:07.466 'App' 1388 verbose] [diskSet,140] Unregistring disk id=disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:37:07.466 'App' 1388 verbose] [diskSet,140] Unregistring disk id=disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:37:07.466 'App' 1252 verbose] [imageProcessingTaskImpl,123] VmiGetDiskSizesTask::task{2} being destroyed
[#1] [2008-11-22 02:37:07.479 'App' 1388 info] [vmImporter,195] Entering UFAD at CheckDestParams
[#1] [2008-11-22 02:37:07.479 'App' 1388 verbose] [vmImporter,196] source:
[#1] (sysimage.LiveSourceComputerSpec) {
[#1]    dynamicType = <unset>,
[#1]    host = "",
[#1]    port = 0,
[#1] }
[#1] [2008-11-22 02:37:07.479 'App' 1388 verbose] [vmImporter,197] target:
[#1] (sysimage.HostedTargetVMSpec) {
[#1]    dynamicType = <unset>,
[#1]    vmName = "VistaQC",
[#1]    vmPath = "C:\Users\PUPPETJ",
[#1]    username = <unset>,
[#1]    password = <unset>,
[#1] }
[#1] [2008-11-22 02:37:07.480 'App' 1388 verbose] [vmImporter,198] params:
[#1] (sysimage.ImportParams) {
[#1]    dynamicType = <unset>,
[#1]    targetProductVersion = "PRODUCT_WS_6X",
[#1]    diskType = "MONOLITHIC_SPARSE",
[#1]    preallocateDisks = false,
[#1]    clonePagefile = false,
[#1]    removeSystemRestore = false,
[#1]    volumesToClone = (sysimage.VolumeCloneInfo) [
[#1]       (sysimage.VolumeCloneInfo) {
[#1]          dynamicType = <unset>,
[#1]          volumeId = "attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}",
[#1]          bResize = false,
[#1]          newCapacityInBytes = <unset>,
[#1]          bSeparateDisk = false,
[#1]       }
[#1]    ],
[#1]    preserveNicsInfo = false,
[#1]    preserveHWInfo = true,
[#1]    preserveDeviceBackingInfo = false,
[#1]    keepIdentity = false,
[#1]    rootOvfExportEnvelope = (sysimage.OvfNode) null,
[#1] }
[#1] [2008-11-22 02:37:07.480 'App' 1388 verbose] [vmImporter,199] diskSizes:
[#1] (long) [
[#1]    24635724800
[#1] ]
[#1] [2008-11-22 02:37:07.480 'App' 1388 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:37:07.480 'App' 1388 verbose] [ufaConnection,94] Creating local adapter...
[#1] [2008-11-22 02:37:07.481 'App' 1388 verbose] [disposable,69] Registering disposable session object id=VmiCheckDestParamsTask::task{3}
[#1] [2008-11-22 02:37:07.481 'App' 1388 verbose] [disposable,80] Associating disposable session object id=VmiCheckDestParamsTask::task{3} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:37:07.499 'App' 1388 verbose] [imageProcessingTaskImpl,504] VmiCheckDestParamsTask::task{3}: Starting execution of an image processing task
[#1] [2008-11-22 02:37:07.499 'App' 1388 verbose] [imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task{3}: SetState to running
[#1] [2008-11-22 02:37:07.499 'App' 1388 info] [vmiCheckDestParamsTask,53] VmiCheckDestParamsTask initialized
[#1] [2008-11-22 02:37:07.500 'App' 2976 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#1] [2008-11-22 02:37:07.501 'App' 1252 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122
[#1] [2008-11-22 02:37:07.501 'App' 1252 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122
[#1] [2008-11-22 02:37:07.501 'App' 1252 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122
[#1] [2008-11-22 02:37:07.501 'App' 1252 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=8192 err=122
[#1] [2008-11-22 02:37:07.502 'App' 1252 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=10104 size=16384 err=0
[#1] [2008-11-22 02:37:07.504 'App' 1252 verbose] [disposable,69] Registering disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:37:07.504 'App' 1252 verbose] [disposable,80] Associating disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d} with session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[#1] [2008-11-22 02:37:07.504 'App' 2976 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#1] [2008-11-22 02:37:07.505 'App' 2976 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#1] [2008-11-22 02:37:07.505 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.505 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.505 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.505 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.505 'App' 2976 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\"
[#1] [2008-11-22 02:37:07.530 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume1 device name.
[2008-11-22 02:37:07.530 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.530 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.531 'App' 2976 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:37:07.531 'App' 2976 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive0
[#1] [2008-11-22 02:37:07.531 'App' 2976 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:37:07.532 'App' 2976 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume1
[#1] [2008-11-22 02:37:07.532 'App' 2976 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:37:07.532 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume2 device name.
[2008-11-22 02:37:07.532 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.532 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.533 'App' 2976 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\
[#1] [2008-11-22 02:37:07.533 'App' 2976 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive1
[#1] [2008-11-22 02:37:07.533 'App' 2976 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#1] [2008-11-22 02:37:07.534 'App' 2976 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume2
[#1] [2008-11-22 02:37:07.534 'App' 2976 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:37:07.534 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ corresponds to \Device\CdRom0 device name.
[2008-11-22 02:37:07.534 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.534 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.538 'App' 2976 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:37:07.539 'App' 2976 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:37:07.543 'App' 2976 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:37:07.543 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\CdRom1 device name.
[2008-11-22 02:37:07.543 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#1] [2008-11-22 02:37:07.543 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#1] [2008-11-22 02:37:07.554 'App' 2976 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ with error code 1
[#1] [2008-11-22 02:37:07.554 'App' 2976 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#1] [2008-11-22 02:37:07.565 'App' 2976 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ returned with error code 0 and device type 2
[#1] [2008-11-22 02:37:07.565 'App' 2976 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ corresponds to \Device\HarddiskVolume3 device name.
[#1] [2008-11-22 02:37:07.565 'App' 2976 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[2008-11-22 02:37:07.565 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\: The request is not supported (50)
[#1] [2008-11-22 02:37:07.565 'App' 2976 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\
[#1] [2008-11-22 02:37:07.565 'App' 2976 warning] [attachedComputerWin32,711] [GetVolumesForComputer] None of the extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exist on disks in computer.
[#1] [2008-11-22 02:37:07.566 'App' 2976 info] [attachedComputerWin32,1461] Live OS version: Windows Vista edition:  root:Windows SrvPack:1 SMP:1 64:0 Ver:6.0
[#1] [2008-11-22 02:37:07.566 'App' 1388 verbose] [vmiTask,82] VmiCheckDestParamsTask::task{3}: returning results:
[#1] (sysimage.CheckDestParmInfoResult) {
[#1]    dynamicType = <unset>,
[#1]    reconfigurationRequired = true,
[#1] }
[#1] [2008-11-22 02:37:07.566 'App' 1388 verbose] [imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task{3}: SetState to success
[#1] [2008-11-22 02:37:07.566 'App' 1388 verbose] [imageProcessingTaskImpl,514] VmiCheckDestParamsTask::task{3}: Image processing task has succeeded
[#1] [2008-11-22 02:37:07.589 'App' 1388 verbose] [disposable,122] object explicitly disposed: VmiCheckDestParamsTask::task{3}
[#1] [2008-11-22 02:37:07.589 'App' 2976 verbose] [disposable,122] object explicitly disposed: computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#1] [2008-11-22 02:37:07.589 'App' 2976 verbose] [diskSet,114] Cleaning up computer's child objects
[#1] [2008-11-22 02:37:07.589 'App' 2976 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#1] [2008-11-22 02:37:07.589 'App' 2976 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#1] [2008-11-22 02:37:07.590 'App' 2976 verbose] [diskSet,140] Unregistring disk id=disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#1] [2008-11-22 02:37:07.590 'App' 2976 verbose] [diskSet,140] Unregistring disk id=disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#1] [2008-11-22 02:37:07.590 'App' 1388 verbose] [imageProcessingTaskImpl,123] VmiCheckDestParamsTask::task{3} being destroyed
[2008-11-22 02:38:38.866 'App' 1388 verbose] CloseSession called for session id=EBCB398E-EB4A-47FF-8EAB-D0C0CA7A1FB5
[2008-11-22 02:38:38.866 'VmdbAdapter' 1388 verbose] Removed vmdb connection /db/connection/#1/
[#2] [2008-11-22 02:38:38.897 'App' 1388 info] Impersonating user PUPPETJ in session 58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:38.918 'App' 2976 info] [vmImporter,216] Entering UFAD at DoImport
[#2] [2008-11-22 02:38:38.919 'App' 2976 verbose] [vmImporter,217] source:
[#2] (sysimage.LiveSourceComputerSpec) {
[#2]    dynamicType = <unset>,
[#2]    host = "",
[#2]    port = 0,
[#2] }
[#2] [2008-11-22 02:38:38.919 'App' 2976 verbose] [vmImporter,218] target:
[#2] (sysimage.HostedTargetVMSpec) {
[#2]    dynamicType = <unset>,
[#2]    vmName = "VistaQC",
[#2]    vmPath = "C:\Users\PUPPETJ",
[#2]    username = <unset>,
[#2]    password = <unset>,
[#2] }
[#2] [2008-11-22 02:38:38.919 'App' 2976 verbose] [vmImporter,219] params:
[#2] (sysimage.ImportParams) {
[#2]    dynamicType = <unset>,
[#2]    targetProductVersion = "PRODUCT_WS_6X",
[#2]    diskType = "MONOLITHIC_SPARSE",
[#2]    preallocateDisks = false,
[#2]    clonePagefile = false,
[#2]    removeSystemRestore = true,
[#2]    nicMapping = (string) [
[#2]       "Bridged"
[#2]    ],
[#2]    volumesToClone = (sysimage.VolumeCloneInfo) [
[#2]       (sysimage.VolumeCloneInfo) {
[#2]          dynamicType = <unset>,
[#2]          volumeId = "attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}",
[#2]          bResize = false,
[#2]          newCapacityInBytes = <unset>,
[#2]          bSeparateDisk = false,
[#2]       }
[#2]    ],
[#2]    preserveNicsInfo = false,
[#2]    preserveHWInfo = true,
[#2]    preserveDeviceBackingInfo = false,
[#2]    keepIdentity = false,
[#2]    rootOvfExportEnvelope = (sysimage.OvfNode) null,
[#2] }
[#2] [2008-11-22 02:38:38.920 'App' 2976 verbose] [ufaConnection,94] Creating local adapter...
[#2] [2008-11-22 02:38:38.920 'App' 2976 verbose] [ufaConnection,94] Creating local adapter...
[#2] [2008-11-22 02:38:38.920 'App' 2976 info] [vmiImportTask,127] Successfully connected to UfaClient
[#2] [2008-11-22 02:38:38.920 'App' 2976 verbose] [disposable,69] Registering disposable session object id=VmiImportTask::task{4}
[#2] [2008-11-22 02:38:38.921 'App' 2976 verbose] [disposable,80] Associating disposable session object id=VmiImportTask::task{4} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:38.939 'App' 2976 verbose] [imageProcessingTaskImpl,504] VmiImportTask::task{4}: Starting execution of an image processing task
[#2] [2008-11-22 02:38:38.939 'App' 2976 verbose] [imageProcessingTaskImpl,154] VmiImportTask::task{4}: SetState to running
[#2] [2008-11-22 02:38:38.940 'App' 2976 info] [vmiImportTask,220] Starting VM Import...
[#2] [2008-11-22 02:38:38.940 'App' 2976 info] [vmiImportTask,133] vmiImportTask initialized
[#2] [2008-11-22 02:38:38.940 'App' 2976 info] [imageProcessingTaskStep,156] VmiImportTask::task{4} step "Clone VM" created
[#2] [2008-11-22 02:38:38.940 'App' 2976 verbose] [vmiImportTask,346] VmiImportTask::DoRun - Calling GetComputer()
[#2] [2008-11-22 02:38:38.940 'App' 1064 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#2] [2008-11-22 02:38:38.941 'App' 1388 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122
[#2] [2008-11-22 02:38:38.941 'App' 1388 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122
[#2] [2008-11-22 02:38:38.941 'App' 1388 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122
[#2] [2008-11-22 02:38:38.941 'App' 1388 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=0 size=8192 err=122
[#2] [2008-11-22 02:38:38.942 'App' 1388 verbose] [attachedDiskWin32,150] QueryDosDevice: ret=10104 size=16384 err=0
[#2] [2008-11-22 02:38:38.944 'App' 1388 verbose] [disposable,69] Registering disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#2] [2008-11-22 02:38:38.945 'App' 1388 verbose] [disposable,80] Associating disposable session object id=computer={2202622998d91b89190c31c8980df6baba3cfd5d} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:38.945 'App' 2976 verbose] [vmiImportTask,368] VmiImportTask::DoRun - Calling Clone()
[#2] [2008-11-22 02:38:38.945 'App' 2976 info] [imageProcessingTaskStep,156] VmiImportTask::task{4} step "create and clone to VM" created
[#2] [2008-11-22 02:38:38.945 'App' 1064 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#2] [2008-11-22 02:38:38.945 'App' 1064 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#2] [2008-11-22 02:38:38.946 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\"
[#2] [2008-11-22 02:38:38.946 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\"
[#2] [2008-11-22 02:38:38.946 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\"
[#2] [2008-11-22 02:38:38.946 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\"
[#2] [2008-11-22 02:38:38.946 'App' 1064 verbose] [attachedComputerWin32,553] Found volume with name "\\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\"
[#2] [2008-11-22 02:38:38.977 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume1 device name.
[#2] [2008-11-22 02:38:38.977 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[2008-11-22 02:38:38.977 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#2] [2008-11-22 02:38:38.977 'App' 1064 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\
[#2] [2008-11-22 02:38:38.977 'App' 1064 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive0
[#2] [2008-11-22 02:38:38.978 'App' 1064 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#2] [2008-11-22 02:38:38.979 'App' 1064 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume1
[#2] [2008-11-22 02:38:38.979 'App' 1064 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#2] [2008-11-22 02:38:38.979 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\HarddiskVolume2 device name.
[2008-11-22 02:38:38.979 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#2] [2008-11-22 02:38:38.979 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#2] [2008-11-22 02:38:38.979 'App' 1064 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\
[#2] [2008-11-22 02:38:38.979 'App' 1064 verbose] [attachedComputerWin32,703] [GetVolumesForComputer] extent # 1 of \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ exists on \\.\PhysicalDrive1
[#2] [2008-11-22 02:38:38.980 'App' 1064 verbose] [attachedComputerWin32,168] [GetVolumeInfo] Successfully retrieved \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ volume's legacy partition info. The partition number is 1
[#2] [2008-11-22 02:38:38.981 'App' 1064 verbose] [attachedVolumeWin32,193] Volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ correspond to device \Device\HarddiskVolume2
[#2] [2008-11-22 02:38:38.981 'App' 1064 verbose] [attachedComputerWin32,773] Mapping a recognized format volume \\?\Volume{4d08a977-6c22-11dd-a112-806e6f6e6963}\ with ID attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#2] [2008-11-22 02:38:38.981 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ corresponds to \Device\CdRom0 device name.
[2008-11-22 02:38:38.981 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\: Incorrect function (1)
[#2] [2008-11-22 02:38:38.981 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#2] [2008-11-22 02:38:38.986 'App' 1064 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ with error code 1
[#2] [2008-11-22 02:38:38.986 'App' 1064 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#2] [2008-11-22 02:38:38.990 'App' 1064 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{8830eab9-b84b-11dd-b0de-806e6f6e6963}\ returned with error code 0 and device type 2
[#2] [2008-11-22 02:38:38.990 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ corresponds to \Device\CdRom1 device name.
[#2] [2008-11-22 02:38:38.990 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[2008-11-22 02:38:38.990 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\: Incorrect function (1)
[#2] [2008-11-22 02:38:39.001 'App' 1064 warning] [attachedComputerWin32,716] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents failed for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ with error code 1
[#2] [2008-11-22 02:38:39.001 'App' 1064 verbose] [attachedComputerWin32,726] [GetVolumesForComputer] Retrieving \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ volume's device number as an alternative to retrieving volume extents typically for NT4 sources or to filter out cdrom devices.
[#2] [2008-11-22 02:38:39.012 'App' 1064 warning] [attachedComputerWin32,759] [GetVolumesForComputer] Windisk_GetVolumeDeviceNumber for \\?\Volume{4d08a97b-6c22-11dd-a112-806e6f6e6963}\ returned with error code 0 and device type 2
[#2] [2008-11-22 02:38:39.012 'App' 1064 verbose] [attachedComputerWin32,649] [GetVolumesForComputer] volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ corresponds to \Device\HarddiskVolume3 device name.
[2008-11-22 02:38:39.012 'App' 1292 info] [stub,42] error [Windisk_CheckNonFixedMediaDevice] Failed to retrieve media type for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\: The request is not supported (50)
[#2] [2008-11-22 02:38:39.012 'App' 1064 verbose] [attachedComputerWin32,679] [GetVolumesForComputer] Checking if volume \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exists on any disk in the computer using volume extents.
[#2] [2008-11-22 02:38:39.013 'App' 1064 verbose] [attachedComputerWin32,693] [GetVolumesForComputer] Windisk_GetVolumeDiskExtents returned 1 extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\
[#2] [2008-11-22 02:38:39.013 'App' 1064 warning] [attachedComputerWin32,711] [GetVolumesForComputer] None of the extents for \\?\Volume{25e66d1b-7ae0-11dd-9bd3-806e6f6e6963}\ exist on disks in computer.
[#2] [2008-11-22 02:38:39.013 'App' 1064 info] [attachedComputerWin32,1461] Live OS version: Windows Vista edition:  root:Windows SrvPack:1 SMP:1 64:0 Ver:6.0
[#2] [2008-11-22 02:38:39.013 'App' 1064 verbose] [ufaConnection,94] Creating local adapter...
[#2] [2008-11-22 02:38:39.014 'App' 1388 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#2] [2008-11-22 02:38:39.014 'App' 1388 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#2] [2008-11-22 02:38:39.014 'App' 1388 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#2] [2008-11-22 02:38:39.014 'App' 1388 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#2] [2008-11-22 02:38:39.014 'App' 1388 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#2] [2008-11-22 02:38:39.015 'App' 1388 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[#2] [2008-11-22 02:38:39.015 'App' 1388 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#2] [2008-11-22 02:38:39.015 'App' 1388 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#2] [2008-11-22 02:38:39.016 'App' 1388 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#2] [2008-11-22 02:38:39.016 'App' 2976 verbose] [vmiImportTask,426] Successfully created target storage layout
[#2] [2008-11-22 02:38:39.016 'App' 2976 info] [imageProcessingTaskStep,156] VmiImportTask::task{4} step "create VM" created
[#2] [2008-11-22 02:38:39.016 'App' 2976 info] [vmiImportTask,513] Successfully connected to UfaLocalVMCreator
[2008-11-22 02:38:39.016 'App' 1292 info] [stub,42] HostDeviceInfo: Failed to enumerate host parallel ports via the registry. Could not open device map parallel port registry key.
[#2] [2008-11-22 02:38:39.026 'App' 1388 verbose] [NetworkAdaptersInfoEnum,848] Microsoft Windows Version 6.0 Build 6001
[#2] [2008-11-22 02:38:39.030 'App' 1388 verbose] [NetworkAdaptersInfoEnum,972] Connected to ROOT\CIMV2 WMI namespace
[#2] [2008-11-22 02:38:39.528 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC1: WAN Miniport (L2TP)
[#2] [2008-11-22 02:38:39.528 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC1 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.528 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC2: WAN Miniport (PPTP)
[#2] [2008-11-22 02:38:39.528 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC2 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.528 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC3: WAN Miniport (PPPOE)
[#2] [2008-11-22 02:38:39.528 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC3 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.529 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC4: WAN Miniport (IPv6)
[#2] [2008-11-22 02:38:39.529 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC4 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.529 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC5: Linksys LNE100TX(v5) Fast Ethernet Adapter
[#2] [2008-11-22 02:38:39.529 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC5 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.529 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC6: isatap.hsd1.nj.comcast.net.
[#2] [2008-11-22 02:38:39.530 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC6 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.530 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC7: WAN Miniport (IP)
[#2] [2008-11-22 02:38:39.530 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC7 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.530 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC8: Teredo Tunneling Pseudo-Interface
[#2] [2008-11-22 02:38:39.530 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC8 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.531 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC9: WAN Miniport (SSTP)
[#2] [2008-11-22 02:38:39.531 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC9 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.531 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC10: RAS Async Adapter
[#2] [2008-11-22 02:38:39.531 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC10 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.531 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC11: WAN Miniport (Network Monitor)
[#2] [2008-11-22 02:38:39.531 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC11 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.532 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC12: NVIDIA nForce Networking Controller
[#2] [2008-11-22 02:38:39.532 'App' 1388 verbose] [NetworkAdaptersInfoEnum,311] NIC12 is a physical Ethernet adapter
[#2] [2008-11-22 02:38:39.532 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC13: NVIDIA nForce Networking Controller #2
[#2] [2008-11-22 02:38:39.532 'App' 1388 verbose] [NetworkAdaptersInfoEnum,311] NIC13 is a physical Ethernet adapter
[#2] [2008-11-22 02:38:39.532 'App' 1388 verbose] [NetworkAdaptersInfoEnum,1014] NIC14: isatap.{A7F870A6-52D7-4217-AAFA-CBBF5360AAC4}
[#2] [2008-11-22 02:38:39.532 'App' 1388 verbose] [NetworkAdaptersInfoEnum,314] NIC14 is not a physical Ethernet adapter -- discarding
[#2] [2008-11-22 02:38:39.533 'App' 1388 verbose] [NetworkAdaptersInfoEnum,267] No more NIC to process
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1875] scsi controller 0 has key=1005 and ID=None
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1875] ide controller 0 has key=200 and ID=None
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1875] ide controller 1 has key=201 and ID=None
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.534 'App' 1388 verbose] [localVMCreator,1705] Adding a NIC with legacy backing type "bridged"
[#2] [2008-11-22 02:38:39.534 'App' 1388 info] [localVMCreator,1788] FixupBacking for USBcontroller
[#2] [2008-11-22 02:38:39.535 'App' 1388 info] [localVMCreator,1875] USBcontroller controller 0 has key=1008 and ID=None
[#2] [2008-11-22 02:38:39.535 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.535 'App' 1388 info] [localVMCreator,1788] FixupBacking for cdrom
[#2] [2008-11-22 02:38:39.535 'App' 1388 info] [localVMCreator,1880] Device cdrom has key=1000 and ID=0 CtrlKey=200
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1903] Devicebacking F:
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1981] Setting cdrom to Autodetect
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1880] Device cdrom has key=1001 and ID=1 CtrlKey=200
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1903] Devicebacking G:
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1981] Setting cdrom to Autodetect
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1788] FixupBacking for disk
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1880] Device disk has key=1004 and ID=0 CtrlKey=1005
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1903] Devicebacking PhysicalDrive0
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1880] Device disk has key=1006 and ID=1 CtrlKey=1005
[#2] [2008-11-22 02:38:39.536 'App' 1388 info] [localVMCreator,1903] Devicebacking PhysicalDrive1
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1880] Device disk has key=1007 and ID=2 CtrlKey=1005
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1903] Devicebacking PhysicalDrive2
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1788] FixupBacking for ide
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1875] ide controller 0 has key=200 and ID=None
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1875] ide controller 1 has key=201 and ID=None
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1788] FixupBacking for scsi
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1875] scsi controller 0 has key=1005 and ID=None
[#2] [2008-11-22 02:38:39.537 'App' 1388 info] [localVMCreator,1947] no backing
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,1788] FixupBacking for sound
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,1880] Device sound has key=1002 and ID=None CtrlKey=None
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,1903] Devicebacking Speakers (Creative SB Audigy 4
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,1880] Device sound has key=1003 and ID=None CtrlKey=None
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,1903] Devicebacking SPDIF Out (Creative SB Audigy 4
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,2041] CPU info: physHost 4 VM 4
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,151] Alerts:
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,154] There are more virtual CPUs in the VM than there are  allowed on target VM.  Setting number of virtual CPUs for the target.
[#2] [2008-11-22 02:38:39.538 'App' 1388 info] [localVMCreator,154] VM memory not a multiple of 4.Setting VM memory to 3328 MB.
[#2] [2008-11-22 02:38:39.539 'App' 848 info] [localVMCreator,275] Creating Local VM (VistaQC)  TargetProduct PRODUCT_WS_60
[#2] [2008-11-22 02:38:39.540 'App' 848 info] [localVMCreator,917] Target config file: C:\Users\PUPPETJ\VistaQC\VistaQC.vmx
[#2] [2008-11-22 02:38:39.541 'App' 848 verbose] [localVMCreator,1060] Got a NIC: (vim.vm.device.VirtualEthernetCard) {
[#2]    dynamicType = <unset>,
[#2]    key = 1010,
[#2]    deviceInfo = (vim.Description) null,
[#2]    backing = (vim.vm.device.VirtualEthernetCard.LegacyNetworkBackingInfo) {
[#2]       dynamicType = <unset>,
[#2]       deviceName = "bridged",
[#2]       useAutoDetect = <unset>,
[#2]    },
[#2]    connectable = (vim.vm.device.VirtualDevice.ConnectInfo) null,
[#2]    controllerKey = <unset>,
[#2]    unitNumber = <unset>,
[#2]    addressType = <unset>,
[#2]    macAddress = <unset>,
[#2]    wakeOnLanEnabled = <unset>,
[#2] }
[#2] [2008-11-22 02:38:39.542 'App' 848 verbose] [localVMCreator,1216] Creating the CD-ROM drive: (vim.vm.device.VirtualCdrom) {
[#2]    dynamicType = <unset>,
[#2]    key = 1000,
[#2]    deviceInfo = (vim.Description) null,
[#2]    backing = (vim.vm.device.VirtualCdrom.AtapiBackingInfo) {
[#2]       dynamicType = <unset>,
[#2]       deviceName = "auto-detect",
[#2]       useAutoDetect = <unset>,
[#2]    },
[#2]    connectable = (vim.vm.device.VirtualDevice.ConnectInfo) null,
[#2]    controllerKey = 200,
[#2]    unitNumber = 0,
[#2] }
[#2] [2008-11-22 02:38:39.542 'App' 848 verbose] [localVMCreator,1216] Creating the CD-ROM drive: (vim.vm.device.VirtualCdrom) {
[#2]    dynamicType = <unset>,
[#2]    key = 1001,
[#2]    deviceInfo = (vim.Description) null,
[#2]    backing = (vim.vm.device.VirtualCdrom.AtapiBackingInfo) {
[#2]       dynamicType = <unset>,
[#2]       deviceName = "auto-detect",
[#2]       useAutoDetect = <unset>,
[#2]    },
[#2]    connectable = (vim.vm.device.VirtualDevice.ConnectInfo) null,
[#2]    controllerKey = 200,
[#2]    unitNumber = 1,
[#2] }
[#2] [2008-11-22 02:38:39.543 'App' 848 info] [localVMCreator,624] Creating Local Disks
[#2] [2008-11-22 02:38:39.543 'App' 848 verbose] [localVMCreator,742] Creating Local Disk; Disk name= C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk; Mode = persistent; Is2GBSplit = No; preallocate space = No; is disk Flat = No; is disk Compressed = No; size = 400485409
[#2] [2008-11-22 02:38:39.543 'App' 848 verbose] [,0] DISKLIB-LIB   : CREATE: "C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk" -- monolithicSparse capacity=800970818 (381.9 GB) adapter=lsilogic grainSize=128
[#2] [2008-11-22 02:38:40.349 'App' 848 verbose] [,0] UUID: SMBIOS UUID is reported as 'ac 15 5d 4c 55 99 db 11-91 46 0a a4 68 c3 32 0a'.
[#2] [2008-11-22 02:38:40.352 'App' 848 verbose] [,0] DISKLIB-LINK  : "C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk" : creation successful.
[#2] [2008-11-22 02:38:41.541 'App' 848 verbose] [,0] DISKLIB-DSCPTR: Opened [0]: "VistaQC.vmdk" (0x2)
[#2] [2008-11-22 02:38:41.543 'App' 848 verbose] [,0] DISKLIB-LINK  : Opened 'C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk' (0x2): monolithicSparse, 800970818 sectors / 381.9 GB.
[#2] [2008-11-22 02:38:41.544 'App' 848 verbose] [,0] DISKLIB-LIB   : Opened "C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk" (flags 0x2).
[#2] [2008-11-22 02:38:41.544 'App' 848 verbose] [diskHandleWrapper,233] Setting DDB property virtualHWVersion to 6.
[#2] [2008-11-22 02:38:41.549 'App' 848 verbose] [diskHandleWrapper,233] Setting DDB property toolsVersion to 0.
[#2] [2008-11-22 02:38:41.552 'App' 2976 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create VM" 100% completed
[#2] [2008-11-22 02:38:41.552 'App' 2976 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 1% completed
[#2] [2008-11-22 02:38:41.552 'App' 2976 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 1% completed
[#2] [2008-11-22 02:38:41.552 'App' 2976 info] [imageProcessingTaskStep,194] VmiImportTask::task{4} step "create VM" destroyed
[#2] [2008-11-22 02:38:41.552 'App' 2976 verbose] [vmiImportTask,442] Successfully created target VM Spec
[#2] [2008-11-22 02:38:41.552 'App' 2976 info] [imageProcessingTaskStep,156] VmiImportTask::task{4} step "clone to VM" created
[#2] [2008-11-22 02:38:41.553 'App' 1388 info] [sysMigration,244] Starting cloning...
[#2] [2008-11-22 02:38:41.553 'App' 1388 verbose] [sysMigration,250] targetStorageLayout:
[#2] (sysimage.TargetStorageLayout) {
[#2]    dynamicType = <unset>,
[#2]    diskToDiskMigration = false,
[#2]    disks = (sysimage.TargetStorageLayout.TargetDiskInfo) [
[#2]       (sysimage.TargetStorageLayout.TargetDiskInfo) {
[#2]          dynamicType = <unset>,
[#2]          dimensions = (sysimage.DiskSizes) {
[#2]             dynamicType = <unset>,
[#2]             capacityInBytes = 410097058816,
[#2]             cloneSizeInBytes = 0,
[#2]             blockSize = 512,
[#2]             chs = (vim.host.DiskDimensions.Chs) {
[#2]                dynamicType = <unset>,
[#2]                cylinder = 49858,
[#2]                head = 255,
[#2]                sector = 63,
[#2]             },
[#2]          },
[#2]          sourceDiskDeviceNumber = <unset>,
[#2]          sourceDiskSpec = <unset>,
[#2]          signature = -360322427,
[#2]          destinationDisk = (sysimage.DiskSpec) null,
[#2]          attachPoint = (sysimage.DiskAttachPoint) {
[#2]             dynamicType = <unset>,
[#2]             busType = "SCSI",
[#2]             bus = 0,
[#2]             unit = 0,
[#2]             origin = 2,
[#2]             history = (sysimage.DiskAttachPoint) null,
[#2]          },
[#2]          sourceDiskLayout = (sysimage.DiskLayoutInfo) null,
[#2]          creationOptions = (sysimage.DiskCreationOptions) {
[#2]             dynamicType = <unset>,
[#2]             diskType = "MONOLITHIC_SPARSE",
[#2]             preallocateDisks = false,
[#2]             thinProvisioning = false,
[#2]             virtualHWVersion = 6,
[#2]             adapterType = "lsilogic",
[#2]          },
[#2]       }
[#2]    ],
[#2]    volumes = (sysimage.TargetStorageLayout.TargetVolumeInfo) [
[#2]       (sysimage.TargetStorageLayout.TargetVolumeInfo) {
[#2]          dynamicType = <unset>,
[#2]          sourceInfo = (sysimage.TargetStorageLayout.SourceVolumeAttributes) {
[#2]             dynamicType = <unset>,
[#2]             volumeId = "attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}",
[#2]             diskSignature = -360322427,
[#2]             startingOffset = 63,
[#2]             capacity = 410087752704,
[#2]             spaceUsed = 24635745280,
[#2]             partitionType = 7,
[#2]             partitionNumber = 1,
[#2]             windowsUniqueId = (byte) [
[#2]                -123,
[#2]                -22,
[#2]                -123,
[#2]                -22,
[#2]                0,
[#2]                126,
[#2]                0,
[#2]                0,
[#2]                0,
[#2]                0,
[#2]                0,
[#2]                0
[#2]             ],
[#2]             diskDeviceNumber = 1,
[#2]             primary = true,
[#2]             active = false,
[#2]          },
[#2]          capacityInBytes = 410087752704,
[#2]          targetDiskIndex = 0,
[#2]       }
[#2]    ],
[#2]    targetProductVersion = "PRODUCT_WS_60",
[#2] }
[#2] [2008-11-22 02:38:41.555 'App' 1388 verbose] [sysMigration,254] maxBandwidth: <unset>
[#2] [2008-11-22 02:38:41.555 'App' 1388 verbose] [sysMigration,255] clonePagefile: false
[#2] [2008-11-22 02:38:41.555 'App' 1388 verbose] [sysMigration,256] removeSystemRestore: true
[#2] [2008-11-22 02:38:41.555 'App' 1388 verbose] [sysMigration,257] updateBootInit: true
[#2] [2008-11-22 02:38:41.555 'App' 1388 verbose] [sysMigration,258] updateDriveLetters: true
[#2] [2008-11-22 02:38:41.555 'App' 1388 verbose] [ufaConnection,94] Creating local adapter...
[#2] [2008-11-22 02:38:41.555 'App' 1064 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#2] [2008-11-22 02:38:41.556 'App' 1388 info] [cloneTask,346] Parsing the VM spec to retrieve disk specs...
[#2] [2008-11-22 02:38:41.556 'App' 1388 info] [localParser,100] Parsing local vmx file C:\Users\PUPPETJ\VistaQC\VistaQC.vmx
[#2] [2008-11-22 02:38:41.563 'App' 1388 verbose] [localParser,209] Adding controller for IDE bus 0 with key 200.
[#2] [2008-11-22 02:38:41.564 'App' 1388 info] [localParser,451] LocalVirtualDiskSpec: Name=C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk
[#2] [2008-11-22 02:38:41.564 'App' 1388 verbose] [cloneTask,368] Disk Name: C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk
[#2] [2008-11-22 02:38:41.564 'App' 1388 verbose] [disposable,69] Registering disposable session object id=CloneTask::task{5}
[#2] [2008-11-22 02:38:41.564 'App' 1388 verbose] [disposable,80] Associating disposable session object id=CloneTask::task{5} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:41.565 'App' 2976 verbose] [imageProcessingTaskWrapper,109] Successfully connected to CloneTask::task{5}
[#2] [2008-11-22 02:38:41.565 'App' 2976 verbose] [imageProcessingTaskWrapper,817] Start managed object method for task CloneTask::task{5}
[#2] [2008-11-22 02:38:41.565 'App' 2976 info] [imageProcessingTaskStep,407] VmiImportTask::task{4} step "clone to VM" in progress...
[#2] [2008-11-22 02:38:41.565 'App' 1388 verbose] [imageProcessingTaskImpl,504] CloneTask::task{5}: Starting execution of an image processing task
[#2] [2008-11-22 02:38:41.565 'App' 2976 verbose] [imageProcessingTaskWrapper,474] Waiting for completion of task CloneTask::task{5}
[#2] [2008-11-22 02:38:41.565 'App' 1388 verbose] [imageProcessingTaskImpl,154] CloneTask::task{5}: SetState to running
[#2] [2008-11-22 02:38:41.565 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:41.565 'App' 1388 info] [cloneTask,165] Starting the cloning process...
[#2] [2008-11-22 02:38:41.566 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:41.566 'App' 1388 info] [cloneTask,174] Preparing for cloning...
[#2] [2008-11-22 02:38:41.566 'App' 1388 info] [volumeMappingInfoBuilder,97] Initializing VolumeMappingInfoBuilder...
[2008-11-22 02:38:41.566 'App' 1252 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:41.566 'App' 848 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name C:\ length 3
[2008-11-22 02:38:41.566 'App' 1252 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 0% completed
[#2] [2008-11-22 02:38:41.566 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:41.566 'App' 848 warning] [attachedComputerWin32,1081] [GetVolumeDeviceName] volume name \\?\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}\ length 49
[#2] [2008-11-22 02:38:41.566 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:41.567 'App' 1388 info] [volumeMappingInfoBuilder,111] Active volume device name \Device\HarddiskVolume1
[#2] [2008-11-22 02:38:41.567 'App' 1388 info] [volumeMappingInfoBuilder,120] Active volume disk number 0 part number 1
[#2] [2008-11-22 02:38:41.567 'App' 1064 verbose] [diskSet,324] GetDisks: disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#2] [2008-11-22 02:38:41.567 'App' 1064 verbose] [diskSet,324] GetDisks: disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#2] [2008-11-22 02:38:41.569 'App' 1388 info] [cloneTask,180] Prepared...
[#2] [2008-11-22 02:38:41.569 'App' 1388 info] [volumeBasedCloneTask,163] Performing volume-based cloning...
[#2] [2008-11-22 02:38:41.569 'App' 1388 info] [cloneTask,382] Opening the target computer...
[#2] [2008-11-22 02:38:41.569 'App' 2024 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#2] [2008-11-22 02:38:42.538 'App' 1252 verbose] [,0] DISKLIB-DSCPTR: Opened [0]: "VistaQC.vmdk" (0xa)
[#2] [2008-11-22 02:38:42.538 'App' 1252 verbose] [,0] DISKLIB-LINK  : Opened 'C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk' (0xa): monolithicSparse, 800970818 sectors / 381.9 GB.
[#2] [2008-11-22 02:38:42.538 'App' 1252 verbose] [,0] DISKLIB-LIB   : Opened "C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk" (flags 0xa).
[#2] [2008-11-22 02:38:44.460 'App' 1252 verbose] [disposable,69] Registering disposable session object id=computer={1385dcf8af22fbec88a44b0d443564885837c82a}
[#2] [2008-11-22 02:38:44.460 'App' 1252 verbose] [disposable,80] Associating disposable session object id=computer={1385dcf8af22fbec88a44b0d443564885837c82a} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:44.460 'App' 1388 info] [cloneTask,427] Getting disks...
[#2] [2008-11-22 02:38:44.461 'App' 2024 verbose] [diskSet,324] GetDisks: disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[#2] [2008-11-22 02:38:44.461 'App' 1388 verbose] [cloneTask,439] Disk 0 id=disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[#2] [2008-11-22 02:38:44.461 'App' 1388 info] [volumeBasedCloneTask,450] Partitioning disk disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[2008-11-22 02:38:44.461 'App' 1292 info] [stub,42] No geometry.biosSectors defined,
[2008-11-22 02:38:44.461 'App' 1292 info] [stub,42] No geometry.biosHeads defined,
[2008-11-22 02:38:44.461 'App' 1292 info] [stub,42] No geometry.biosCylinders defined,
[2008-11-22 02:38:44.473 'App' 1292 info] [stub,42] No geometry.biosSectors defined,
[2008-11-22 02:38:44.475 'App' 1292 info] [stub,42] No geometry.biosHeads defined,
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ---------------------------
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ....Creating MBR Partition Table ....
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ..... Partition Entry: 0 .....
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] .....Current Partition Offset 0: .....
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Boot Indicator: 0
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Partition Type Id 0X7
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Starting Head 1
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Starting Sector 1
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Starting Cylinder 0
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Ending Head 254
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Ending Sector 63
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Ending Cylinder 1023
[2008-11-22 02:38:44.477 'App' 1292 info] [stub,42] ...Relative Sectors 63
[2008-11-22 02:38:44.478 'App' 1292 info] [stub,42] ...Total Sectors 800952642
[2008-11-22 02:38:44.478 'App' 1292 info] [stub,42] --------------------------
[#2] [2008-11-22 02:38:44.487 'App' 1388 info] [cloneTask,457] Getting volumes for computer id=computer={1385dcf8af22fbec88a44b0d443564885837c82a}
[#2] [2008-11-22 02:38:44.488 'App' 1388 verbose] [cloneTask,469] Volume 0 id=virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}
[#2] [2008-11-22 02:38:44.488 'App' 1388 info] [imageProcessingTaskStep,156] CloneTask::task{5} step "clone volumes" created
[#2] [2008-11-22 02:38:44.488 'App' 1388 info] [cloneTask,488] Getting volumes for the source computer...
[#2] [2008-11-22 02:38:44.489 'App' 1388 verbose] [cloneTask,498] Volume 0 id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#2] [2008-11-22 02:38:44.489 'App' 1388 verbose] [cloneTask,498] Volume 1 id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#2] [2008-11-22 02:38:44.489 'App' 1388 info] [volumeBasedCloneTask,266] Start cloning source volume attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2} to destination disk 0 partition 1
[#2] [2008-11-22 02:38:44.490 'App' 1252 info] [attachedVolumeWin32,269] Creating snapshot for volume attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[2008-11-22 02:38:44.490 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:44.490 'App' 1252 verbose] [snapshotVolumeFactory,148] Creating snapshot using vss
[#2] [2008-11-22 02:38:44.490 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:44.490 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:38:44.490 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:44.491 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:44.491 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.222 'App' 1252 verbose] [disposable,69] Registering disposable session object id=disposer={snapshot1={attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}}}
[#2] [2008-11-22 02:38:48.222 'App' 1252 verbose] [disposable,80] Associating disposable session object id=disposer={snapshot1={attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}}} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:48.222 'App' 1252 info] [attachedVolumeWin32,285] Finished creating snapshotsnapshot1={attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}}
[#2] [2008-11-22 02:38:48.222 'App' 1388 info] [cloneTask,765] Cloning volume snapshot1={attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}} into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}
[#2] [2008-11-22 02:38:48.223 'App' 1388 info] [imageProcessingTaskStep,156] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" created
[2008-11-22 02:38:48.223 'App' 1252 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.223 'App' 848 verbose] [disposable,69] Registering disposable session object id=BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.223 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.223 'App' 848 verbose] [disposable,80] Associating disposable session object id=BlockLevelVolumeCloning::task{6} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 02:38:48.223 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.223 'App' 848 info] [volumeWin32,754] Scheduling BlockLevelVolumeCloningTask for execution...
[#2] [2008-11-22 02:38:48.223 'App' 1388 verbose] [imageProcessingTaskWrapper,109] Successfully connected to BlockLevelVolumeCloning::task{6}
[2008-11-22 02:38:48.223 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.224 'App' 1388 verbose] [imageProcessingTaskWrapper,817] Start managed object method for task BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.224 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.224 'App' 1388 info] [imageProcessingTaskStep,407] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" in progress...
[#2] [2008-11-22 02:38:48.224 'App' 1252 verbose] [imageProcessingTaskImpl,504] BlockLevelVolumeCloning::task{6}: Starting execution of an image processing task
[#2] [2008-11-22 02:38:48.224 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.224 'App' 1388 verbose] [imageProcessingTaskWrapper,474] Waiting for completion of task BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.224 'App' 1252 verbose] [imageProcessingTaskImpl,154] BlockLevelVolumeCloning::task{6}: SetState to running
[#2] [2008-11-22 02:38:48.224 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.225 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:38:48.225 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.225 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.225 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:38:48.225 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:38:48.225 'App' 1468 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 0% completed
[2008-11-22 02:38:48.225 'App' 1468 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 0% completed
[#2] [2008-11-22 02:38:48.225 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:38:48.225 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.226 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.226 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:38:48.226 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:38:48.226 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.226 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.226 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:38:48.251 'App' 1252 warning] [blockLevelVolumeCloningTaskImpl,764] Unable to get fileextents for \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1\\hibernat.sys. Error: 2
[#2] [2008-11-22 02:38:48.251 'App' 1252 warning] [blockLevelVolumeCloningTaskImpl,764] Unable to get fileextents for \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1\\hiberfil.sys. Error: 5
[#2] [2008-11-22 02:38:48.251 'App' 1252 warning] [blockLevelVolumeCloningTaskImpl,781] Unable to get bad blocks extents for \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1\. Error: 5
[#2] [2008-11-22 02:38:48.251 'App' 1252 warning] [blockLevelVolumeCloningTaskImpl,631] Unable to get file extents for \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1\System Volume Information\1{3808876b-c176-4e48-b7ae-04046e6cc752}. Error: 5
[#2] [2008-11-22 02:47:41.307 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 50000 numMergedIOs = 0 numSplitIOs = 0
[2008-11-22 02:48:58.674 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 02:48:58.674 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:48:58.674 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 02:48:58.674 'App' 848 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 1% completed
[#2] [2008-11-22 02:48:58.674 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:48:58.674 'App' 848 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 1% completed
[#2] [2008-11-22 02:48:58.674 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:48:58.674 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:48:58.674 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:48:58.675 'App' 1064 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 1% completed
[#2] [2008-11-22 02:48:58.675 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:48:58.675 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:48:58.675 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:48:58.675 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:48:58.675 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:46.839 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 02:52:46.840 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:46.840 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 02:52:46.840 'App' 1064 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 2% completed
[#2] [2008-11-22 02:52:46.840 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:52:46.840 'App' 1064 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 2% completed
[#2] [2008-11-22 02:52:46.840 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:46.840 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:52:46.840 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:46.840 'App' 2024 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 2% completed
[2008-11-22 02:52:46.841 'App' 2024 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 2% completed
[2008-11-22 02:52:46.841 'App' 2024 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 2% completed
[2008-11-22 02:52:46.841 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:46.841 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:46.841 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:46.841 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:46.841 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:46.841 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 02:52:46.841 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:46.841 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:46.842 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:46.842 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:46.842 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:48.410 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 02:52:48.411 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:48.411 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 02:52:48.411 'App' 2024 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 3% completed
[#2] [2008-11-22 02:52:48.411 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 02:52:48.411 'App' 2024 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 3% completed
[#2] [2008-11-22 02:52:48.411 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:48.411 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 02:52:48.411 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:48.411 'App' 1064 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 3% completed
[2008-11-22 02:52:48.411 'App' 1064 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 3% completed
[2008-11-22 02:52:48.412 'App' 1064 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 3% completed
[2008-11-22 02:52:48.412 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:48.412 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:48.412 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:48.412 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:48.412 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 02:52:48.412 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 02:52:48.412 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:48.412 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:48.413 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:52:48.413 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 02:52:48.413 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 02:55:19.291 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 100000 numMergedIOs = 0 numSplitIOs = 0
[2008-11-22 03:00:15.441 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 03:00:15.441 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:00:15.441 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 03:00:15.441 'App' 3056 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 4% completed
[#2] [2008-11-22 03:00:15.441 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:00:15.441 'App' 3056 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 4% completed
[#2] [2008-11-22 03:00:15.441 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:00:15.441 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:00:15.441 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:00:15.441 'App' 2024 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 4% completed
[2008-11-22 03:00:15.442 'App' 2024 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 4% completed
[2008-11-22 03:00:15.442 'App' 2024 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 4% completed
[2008-11-22 03:00:15.442 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:00:15.442 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:00:15.442 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:00:15.442 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:00:15.442 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:00:15.443 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 03:00:15.443 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:00:15.443 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:00:15.443 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:00:15.443 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:00:15.443 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:04:41.850 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 150000 numMergedIOs = 0 numSplitIOs = 0
[2008-11-22 03:11:19.510 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 03:11:19.510 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:11:19.510 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 03:11:19.510 'App' 848 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 5% completed
[#2] [2008-11-22 03:11:19.510 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:11:19.510 'App' 848 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 5% completed
[#2] [2008-11-22 03:11:19.511 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:11:19.511 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:11:19.511 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:11:19.511 'App' 1468 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 5% completed
[2008-11-22 03:11:19.511 'App' 1468 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 5% completed
[2008-11-22 03:11:19.511 'App' 1468 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 5% completed
[2008-11-22 03:11:19.511 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:11:19.511 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:11:19.511 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:11:19.511 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:11:19.512 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:11:19.512 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[2008-11-22 03:11:19.512 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:11:19.512 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:11:19.512 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:11:19.512 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:11:19.512 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:12:39.007 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 200000 numMergedIOs = 0 numSplitIOs = 0
[2008-11-22 03:16:31.610 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:16:31.610 'App' 1064 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 50% completed
[2008-11-22 03:16:31.610 'App' 1064 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 50% completed
[2008-11-22 03:16:31.611 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:16:31.611 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:16:31.611 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:16:31.611 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:16:31.611 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:16:31.611 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:16:31.611 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:16:31.611 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:16:31.611 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 50% completed
[#2] [2008-11-22 03:16:31.612 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:16:31.612 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 50% completed
[2008-11-22 03:16:31.612 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 50% completed
[#2] [2008-11-22 03:16:31.612 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 03:16:31.612 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:16:31.612 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:16:31.612 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:16:31.612 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:16:31.612 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:16:31.613 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:16:31.613 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:18:04.721 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 250000 numMergedIOs = 0 numSplitIOs = 0
[2008-11-22 03:30:33.851 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:30:33.851 'App' 1064 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 51% completed
[2008-11-22 03:30:33.851 'App' 1064 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 51% completed
[2008-11-22 03:30:33.851 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:30:33.851 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:30:33.851 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:30:33.851 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:30:33.851 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:30:33.852 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:30:33.852 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:30:33.852 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:30:33.852 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:30:33.852 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 51% completed
[2008-11-22 03:30:33.852 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 51% completed
[2008-11-22 03:30:33.852 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 51% completed
[#2] [2008-11-22 03:30:33.852 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 03:30:33.853 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:30:33.853 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:30:33.853 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:30:33.853 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:30:33.853 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:30:33.853 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:30:33.853 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:30:40.833 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 300000 numMergedIOs = 0 numSplitIOs = 0
[#2] [2008-11-22 03:38:11.505 'App' 1252 verbose] [,0] DISKLIB-LIB   :numIOs = 350000 numMergedIOs = 0 numSplitIOs = 0
[2008-11-22 03:39:07.982 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:07.982 'App' 848 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 52% completed
[2008-11-22 03:39:07.982 'App' 848 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 52% completed
[2008-11-22 03:39:07.982 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:07.982 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:07.982 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:07.982 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:07.982 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:39:07.983 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:07.983 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:07.983 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:07.983 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 52% completed
[#2] [2008-11-22 03:39:07.983 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:07.983 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 52% completed
[2008-11-22 03:39:07.984 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 52% completed
[#2] [2008-11-22 03:39:07.984 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 03:39:07.984 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:07.984 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:07.984 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:07.984 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:07.984 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:07.985 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:07.985 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:39:41.425 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:41.425 'App' 2024 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" 100% completed
[2008-11-22 03:39:41.425 'App' 2024 verbose] [imageProcessingTaskStep,305] CloneTask::task{5} step "clone volumes" 100% completed
[2008-11-22 03:39:41.425 'App' 2024 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:41.425 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:41.425 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:41.425 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:41.425 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:39:41.426 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:41.426 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:41.426 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:41.426 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "clone to VM" 100% completed
[#2] [2008-11-22 03:39:41.426 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:41.426 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "create and clone to VM" 100% completed
[2008-11-22 03:39:41.426 'App' 3056 verbose] [imageProcessingTaskStep,305] VmiImportTask::task{4} step "Clone VM" 100% completed
[#2] [2008-11-22 03:39:41.427 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[2008-11-22 03:39:41.427 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:41.427 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:41.427 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:41.427 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:41.427 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:41.427 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:41.427 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:44.999 'App' 1252 info] [blockLevelVolumeCloningTaskImpl,1000] Block level cloning completed...
[#2] [2008-11-22 03:39:44.999 'App' 1252 verbose] [imageProcessingTaskImpl,154] BlockLevelVolumeCloning::task{6}: SetState to success
[#2] [2008-11-22 03:39:44.999 'App' 1252 verbose] [imageProcessingTaskImpl,514] BlockLevelVolumeCloning::task{6}: Image processing task has succeeded
[#2] [2008-11-22 03:39:45.000 'App' 1252 verbose] [imageProcessingTaskWrapper,886] Remote task BlockLevelVolumeCloning::task{6} completed
[2008-11-22 03:39:45.000 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:45.000 'App' 1388 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelVolumeCloning::task{6}
[2008-11-22 03:39:45.000 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.000 'App' 1388 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:45.000 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.000 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:39:45.000 'App' 1252 verbose] [imageProcessingTaskWrapper,749] Got an update from BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:45.000 'App' 1388 verbose] [imageProcessingTaskWrapper,515] Cleaning up remote task BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:45.001 'App' 1388 verbose] [imageProcessingTaskWrapper,1201] Disposing task wrapper for task BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:45.001 'App' 1252 verbose] [disposable,122] object explicitly disposed: BlockLevelVolumeCloning::task{6}
[#2] [2008-11-22 03:39:45.001 'App' 1252 verbose] [imageProcessingTaskImpl,123] BlockLevelVolumeCloning::task{6} being destroyed
[#2] [2008-11-22 03:39:45.001 'App' 1388 info] [imageProcessingTaskStep,429] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" succeeded
[#2] [2008-11-22 03:39:45.001 'App' 1388 info] [imageProcessingTaskStep,194] CloneTask::task{5} step "Cloning into volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}" destroyed
[#2] [2008-11-22 03:39:45.002 'App' 1252 verbose] [disposable,122] object explicitly disposed: disposer={snapshot1={attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}}}
[#2] [2008-11-22 03:39:45.030 'App' 1388 info] [imageProcessingTaskStep,194] CloneTask::task{5} step "clone volumes" destroyed
[#2] [2008-11-22 03:39:45.030 'App' 1388 info] [cloneTask,184] Preparing for post postcloning...
[#2] [2008-11-22 03:39:45.030 'App' 1388 info] [volumeMappingInfoBuilder,249] Post-processing target computer in VolumeMappingInfoBuilder...
[#2] [2008-11-22 03:39:45.030 'App' 1252 verbose] [diskSet,324] GetDisks: disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[#2] [2008-11-22 03:39:45.040 'App' 1388 info] [cloneTask,186] Prepared...
[#2] [2008-11-22 03:39:45.040 'App' 1388 info] [cloneTask,427] Getting disks...
[#2] [2008-11-22 03:39:45.040 'App' 1468 verbose] [diskSet,324] GetDisks: disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[2008-11-22 03:39:45.040 'App' 1252 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.040 'App' 1388 verbose] [cloneTask,439] Disk 0 id=disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[#2] [2008-11-22 03:39:45.040 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.040 'App' 1388 info] [cloneTask,457] Getting volumes for computer id=computer={1385dcf8af22fbec88a44b0d443564885837c82a}
[#2] [2008-11-22 03:39:45.040 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.041 'App' 1388 verbose] [cloneTask,469] Volume 0 id=virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}
[2008-11-22 03:39:45.041 'App' 1468 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.041 'App' 1388 info] [cloneTask,1316] Adjusting boot sector of the partition 1 of the disk number 0
[#2] [2008-11-22 03:39:45.041 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.041 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.041 'App' 1388 info] [cloneTask,189] Finished updating target boot sectors
[#2] [2008-11-22 03:39:45.041 'App' 1388 verbose] [volumeMappingInfoBuilder,766] Adjusting boot.ini: Didn't find target for source volume 1 on the disk 0 Probably this volume was not selected for cloning.
[#2] [2008-11-22 03:39:45.041 'App' 1388 warning] [cloneTask,250] No active volumes in the target computer
[2008-11-22 03:39:45.041 'App' 1292 info] [stub,42] Trying to adjust the head, sector and LBA value of the boot sector to 255 63 63
[#2] [2008-11-22 03:39:45.042 'App' 1388 info] [cloneTask,1420] Preserving drive letters
[2008-11-22 03:39:45.042 'App' 1292 info] [stub,42] No need to adjust geometry values in the boot sector. Geometry info remains the same
[#2] [2008-11-22 03:39:45.042 'App' 1388 verbose] [volumeMappingInfoBuilder,974] VolumeMappingInfo size: 2
[#2] [2008-11-22 03:39:45.042 'App' 1388 verbose] [volumeMappingInfoBuilder,1076] Volume 0 position has changed, need to remap
[#2] [2008-11-22 03:39:45.042 'App' 1388 verbose] [cloneTask,1442] Drive letter remapping size: 1
[2008-11-22 03:39:45.042 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.042 'App' 1388 verbose] [volumeMappingInfoBuilder,775] Adjusting boot.ini: Target volume 1 on disk 0 correspond source volume 1 on the disk 1
[#2] [2008-11-22 03:39:45.042 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.043 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[2008-11-22 03:39:45.043 'App' 3056 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.043 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:45.043 'App' 2976 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:46.922 'App' 1064 verbose] [disposable,69] Registering disposable session object id=fileRegistry={virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}},Windows\system32\config\SYSTEM
[#2] [2008-11-22 03:39:46.922 'App' 1064 verbose] [disposable,80] Associating disposable session object id=fileRegistry={virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}},Windows\system32\config\SYSTEM with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 03:39:46.923 'App' 1064 info] [,0] Info[Windisk_PreserveDriveLetterEx]: deleted key: \??\Volume{4d08a976-6c22-11dd-a112-806e6f6e6963}
[#2] [2008-11-22 03:39:46.923 'App' 1064 info] [,0] Info[Windisk_PreserveDriveLetterEx]: deleted key: \DosDevices\D:
[#2] [2008-11-22 03:39:46.923 'App' 1064 info] [registry,864] Preserving drive letters OK
[#2] [2008-11-22 03:39:46.923 'App' 1388 info] [cloneTask,1463] Done preserving drive letters.
[#2] [2008-11-22 03:39:46.923 'App' 1064 verbose] [disposable,122] object explicitly disposed: fileRegistry={virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}},Windows\system32\config\SYSTEM
[#2] [2008-11-22 03:39:47.504 'App' 1388 verbose] [volumeMappingInfoBuilder,775] Adjusting boot.ini: Target volume 1 on disk 0 correspond source volume 1 on the disk 1
[#2] [2008-11-22 03:39:47.550 'App' 1388 info] [cloneTask,302] Succeeded in clearing dirty boot flag in \\.\vstor2-p2v30-85EA85EA007E00000000000001000000\\Windows\bootstat.dat
[#2] [2008-11-22 03:39:47.550 'App' 1064 verbose] [disposable,122] object explicitly disposed: computer={1385dcf8af22fbec88a44b0d443564885837c82a}
[#2] [2008-11-22 03:39:47.550 'App' 1064 verbose] [diskSet,114] Cleaning up computer's child objects
[#2] [2008-11-22 03:39:47.550 'App' 1064 verbose] [diskSet,130] Unregistring volume id=virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}
[#2] [2008-11-22 03:39:49.987 'App' 1064 verbose] [diskSet,140] Unregistring disk id=disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[#2] [2008-11-22 03:39:49.991 'App' 1388 info] [cloneTask,324] Cloning succeeded.
[#2] [2008-11-22 03:39:49.991 'App' 1388 verbose] [imageProcessingTaskImpl,154] CloneTask::task{5}: SetState to success
[#2] [2008-11-22 03:39:49.991 'App' 1388 verbose] [imageProcessingTaskImpl,514] CloneTask::task{5}: Image processing task has succeeded
[#2] [2008-11-22 03:39:49.991 'App' 1388 verbose] [imageProcessingTaskWrapper,886] Remote task CloneTask::task{5} completed
[2008-11-22 03:39:49.991 'App' 1064 verbose] [imageProcessingTaskWrapper,749] Got an update from CloneTask::task{5}
[#2] [2008-11-22 03:39:49.991 'App' 2976 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task{5}
[#2] [2008-11-22 03:39:49.991 'App' 2976 verbose] [imageProcessingTaskWrapper,515] Cleaning up remote task CloneTask::task{5}
[#2] [2008-11-22 03:39:49.991 'App' 2976 verbose] [imageProcessingTaskWrapper,1201] Disposing task wrapper for task CloneTask::task{5}
[#2] [2008-11-22 03:39:49.992 'App' 1388 verbose] [disposable,122] object explicitly disposed: CloneTask::task{5}
[#2] [2008-11-22 03:39:49.992 'App' 1388 verbose] [imageProcessingTaskImpl,123] CloneTask::task{5} being destroyed
[#2] [2008-11-22 03:39:49.992 'App' 2976 info] [imageProcessingTaskStep,429] VmiImportTask::task{4} step "clone to VM" succeeded
[#2] [2008-11-22 03:39:49.992 'App' 2976 info] [imageProcessingTaskStep,194] VmiImportTask::task{4} step "clone to VM" destroyed
[#2] [2008-11-22 03:39:49.992 'App' 2976 info] [imageProcessingTaskStep,194] VmiImportTask::task{4} step "create and clone to VM" destroyed
[#2] [2008-11-22 03:39:49.992 'App' 2976 info] [vmiImportTask,373] VM Import succeeded.
[#2] [2008-11-22 03:39:49.992 'App' 2976 info] [imageProcessingTaskStep,194] VmiImportTask::task{4} step "Clone VM" destroyed
[#2] [2008-11-22 03:39:49.993 'App' 2976 verbose] [vmiTask,82] VmiImportTask::task{4}: returning results:
[#2] (sysimage.HostedSourceVMSpec) {
[#2]    dynamicType = <unset>,
[#2]    vmxFilePath = "C:\Users\PUPPETJ\VistaQC\VistaQC.vmx",
[#2] }
[#2] [2008-11-22 03:39:49.993 'App' 2976 verbose] [imageProcessingTaskImpl,154] VmiImportTask::task{4}: SetState to success
[#2] [2008-11-22 03:39:49.993 'App' 2976 verbose] [imageProcessingTaskImpl,514] VmiImportTask::task{4}: Image processing task has succeeded
[#2] [2008-11-22 03:39:50.017 'App' 1388 verbose] [disposable,122] object explicitly disposed: VmiImportTask::task{4}
[#2] [2008-11-22 03:39:50.017 'App' 1064 verbose] [disposable,122] object explicitly disposed: computer={2202622998d91b89190c31c8980df6baba3cfd5d}
[#2] [2008-11-22 03:39:50.017 'App' 1064 verbose] [diskSet,114] Cleaning up computer's child objects
[#2] [2008-11-22 03:39:50.017 'App' 1064 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},1}
[#2] [2008-11-22 03:39:50.017 'App' 1064 verbose] [diskSet,130] Unregistring volume id=attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}
[#2] [2008-11-22 03:39:50.018 'App' 1064 verbose] [diskSet,140] Unregistring disk id=disk={9ba2498e79b590fd75d62d8b65fa945c367c0fea}
[#2] [2008-11-22 03:39:50.018 'App' 1064 verbose] [diskSet,140] Unregistring disk id=disk={5c822bdfe4346fded26a0a0854198ded817c6666}
[#2] [2008-11-22 03:39:50.018 'App' 1388 verbose] [imageProcessingTaskImpl,123] VmiImportTask::task{4} being destroyed
[#2] [2008-11-22 03:39:50.029 'App' 1064 info] [vmImporter,235] Entering UFAD at DoReconfig
[#2] [2008-11-22 03:39:50.029 'App' 1064 verbose] [vmImporter,236] target:
[#2] (sysimage.HostedSourceVMSpec) {
[#2]    dynamicType = <unset>,
[#2]    vmxFilePath = "C:\Users\PUPPETJ\VistaQC\VistaQC.vmx",
[#2] }
[#2] [2008-11-22 03:39:50.030 'App' 1064 verbose] [vmImporter,237] params:
[#2] (sysimage.ReconfigParams) {
[#2]    dynamicType = <unset>,
[#2]    prerequisites = (sysimage.Prerequisites) {
[#2]       dynamicType = <unset>,
[#2]       osInfo = (sysimage.OsInfo) {
[#2]          dynamicType = <unset>,
[#2]          family = "NO_OS",
[#2]          displayName = "",
[#2]          guestOsId = "",
[#2]          vendor = "",
[#2]          edition = "",
[#2]          majorVersion = 0,
[#2]          minorVersion = 0,
[#2]       },
[#2]    },
[#2] }
[#2] [2008-11-22 03:39:50.030 'App' 1064 verbose] [ufaConnection,94] Creating local adapter...
[#2] [2008-11-22 03:39:50.030 'App' 1064 verbose] [disposable,69] Registering disposable session object id=VmiReconfigTask::task{7}
[#2] [2008-11-22 03:39:50.030 'App' 1064 verbose] [disposable,80] Associating disposable session object id=VmiReconfigTask::task{7} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 03:39:50.050 'App' 1064 verbose] [imageProcessingTaskImpl,504] VmiReconfigTask::task{7}: Starting execution of an image processing task
[#2] [2008-11-22 03:39:50.050 'App' 1064 verbose] [imageProcessingTaskImpl,154] VmiReconfigTask::task{7}: SetState to running
[#2] [2008-11-22 03:39:50.050 'App' 1064 info] [imageProcessingTaskStep,156] VmiReconfigTask::task{7} step "Reconfigure VM" created
[#2] [2008-11-22 03:39:50.051 'App' 1064 verbose] [ufaConnection,94] Creating local adapter...
[#2] [2008-11-22 03:39:50.051 'App' 2976 info] [localParser,100] Parsing local vmx file C:\Users\PUPPETJ\VistaQC\VistaQC.vmx
[#2] [2008-11-22 03:39:50.089 'App' 2976 verbose] [localParser,209] Adding controller for IDE bus 0 with key 200.
[#2] [2008-11-22 03:39:50.089 'App' 2976 info] [localParser,451] LocalVirtualDiskSpec: Name=C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk
[#2] [2008-11-22 03:39:50.089 'App' 2976 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#2] [2008-11-22 03:39:51.347 'App' 1252 verbose] [,0] DISKLIB-DSCPTR: Opened [0]: "VistaQC.vmdk" (0xa)
[#2] [2008-11-22 03:39:51.349 'App' 1252 verbose] [,0] DISKLIB-LINK  : Opened 'C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk' (0xa): monolithicSparse, 800970818 sectors / 381.9 GB.
[#2] [2008-11-22 03:39:51.349 'App' 1252 verbose] [,0] DISKLIB-LIB   : Opened "C:\Users\PUPPETJ\VistaQC\VistaQC.vmdk" (flags 0xa).
[#2] [2008-11-22 03:39:53.256 'App' 1252 verbose] [disposable,69] Registering disposable session object id=computer={1385dcf8af22fbec88a44b0d443564885837c82a}
[#2] [2008-11-22 03:39:53.256 'App' 1252 verbose] [disposable,80] Associating disposable session object id=computer={1385dcf8af22fbec88a44b0d443564885837c82a} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 03:39:53.257 'App' 1064 info] [imageProcessorProxy,148] Successfully connected to UfaSysReconfig
[#2] [2008-11-22 03:39:53.257 'App' 1064 info] [vmiReconfigTask,98] Windows Directory in the local machine: C:\Windows
[#2] [2008-11-22 03:39:53.257 'App' 1064 info] [imageProcessorProxy,232] Processing computer...
[#2] [2008-11-22 03:39:53.257 'App' 2976 info] [sysReconfig,247] SysReconfigImpl::ProcessComputer...
[#2] [2008-11-22 03:39:53.257 'App' 2976 info] [ufaClientConnection,69] Successfully connected to local UfaClient.
[#2] [2008-11-22 03:39:53.257 'App' 2976 info] [ufaClientConnection,163] Opening specifed computer ...
[#2] [2008-11-22 03:39:53.257 'App' 1252 verbose] [slaveConnection,189] Creating local connection to the co-located slave
[#2] [2008-11-22 03:39:53.258 'App' 2976 verbose] [computerProxy,74] Successfully connected to remote Computer.
[#2] [2008-11-22 03:39:53.258 'App' 2976 verbose] [disposable,69] Registering disposable session object id=UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.258 'App' 2976 verbose] [disposable,80] Associating disposable session object id=UfaSysReconfig::task{1}::task{8} with session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[#2] [2008-11-22 03:39:53.258 'App' 2976 info] [sysReconfig,297] Scheduling SysReconfigTask for execution...
[#2] [2008-11-22 03:39:53.258 'App' 1064 verbose] [imageProcessingTaskWrapper,109] Successfully connected to UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.258 'App' 1064 verbose] [imageProcessingTaskWrapper,817] Start managed object method for task UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.259 'App' 1064 info] [imageProcessingTaskStep,407] VmiReconfigTask::task{7} step "Reconfigure VM" in progress...
[#2] [2008-11-22 03:39:53.259 'App' 2976 verbose] [imageProcessingTaskImpl,504] UfaSysReconfig::task{1}::task{8}: Starting execution of an image processing task
[#2] [2008-11-22 03:39:53.259 'App' 1064 verbose] [imageProcessingTaskWrapper,474] Waiting for completion of task UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.259 'App' 2976 verbose] [imageProcessingTaskImpl,154] UfaSysReconfig::task{1}::task{8}: SetState to running
[#2] [2008-11-22 03:39:53.259 'App' 1064 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.259 'App' 2976 info] [sysReconfigTaskImpl,138] Starting system reconfiguration ...
[#2] [2008-11-22 03:39:53.259 'App' 1064 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from UfaSysReconfig::task{1}::task{8}
[2008-11-22 03:39:53.260 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from UfaSysReconfig::task{1}::task{8}
[2008-11-22 03:39:53.260 'App' 848 verbose] [imageProcessingTaskStep,305] VmiReconfigTask::task{7} step "Reconfigure VM" 0% completed
[#2] [2008-11-22 03:39:53.260 'App' 1064 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.260 'App' 1064 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from UfaSysReconfig::task{1}::task{8}
[2008-11-22 03:39:53.260 'App' 1388 verbose] [imageProcessingTaskWrapper,749] Got an update from UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.260 'App' 1064 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.260 'App' 1064 verbose] [imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:53.261 'App' 2976 info] [utils,126] Using temp dir C:\Windows\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig
[#2] [2008-11-22 03:39:53.261 'App' 2976 verbose] [reconfigurationTransaction,70] Debug
[#2] [2008-11-22 03:39:53.261 'App' 2976 verbose] [computerProxy,146] Retrieving the system volume info ...
[#2] [2008-11-22 03:39:53.279 'App' 848 verbose] [diskSet,191] 1 volumes found
[#2] [2008-11-22 03:39:53.279 'App' 848 verbose] [diskSet,358] Volume: idx=0 sysId=7 active=N
[#2] [2008-11-22 03:39:53.279 'App' 848 verbose] [diskSet,198] 0 potential active volumes found
[#2] [2008-11-22 03:39:53.279 'App' 848 verbose] [diskSet,548] Guessing which volume is system volume...
[#2] [2008-11-22 03:39:53.280 'App' 848 verbose] [diskSet,587] Populating OS from volume virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}
[#2] [2008-11-22 03:39:54.130 'App' 848 verbose] [extendedOsInfo,442] Just about to open the BCD Hive...
[#2] [2008-11-22 03:39:54.137 'App' 848 info] [extendedOsInfo,197] Getting extended OS info by parsing boot.ini and/or analyzing the registry failed
[#2] [2008-11-22 03:39:54.165 'App' 848 info] [extendedOsInfo,207] msdos.sys is non-existent or inaccessible
[#2] [2008-11-22 03:39:54.165 'App' 848 error] [extendedOsInfo,220] FAILED parsing NTFS or Win9x_DOS partition
[#2] [2008-11-22 03:39:54.174 'App' 2976 info] [utils,153] Cleaning up temp directory C:\Windows\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig ...
[#2] [2008-11-22 03:39:54.175 'App' 2976 error] [imageProcessingTaskImpl,552] UfaSysReconfig::task{1}::task{8}: Image processing task has failed with MethodFault::Exception: sysimage.fault.OsVersionNotFound
[#2] [2008-11-22 03:39:54.175 'App' 2976 verbose] [imageProcessingTaskImpl,154] UfaSysReconfig::task{1}::task{8}: SetState to error
[#2] [2008-11-22 03:39:54.175 'App' 2976 verbose] [imageProcessingTaskWrapper,886] Remote task UfaSysReconfig::task{1}::task{8} completed
[#2] [2008-11-22 03:39:54.175 'App' 1064 verbose] [imageProcessingTaskWrapper,437] Waiting for updates from UfaSysReconfig::task{1}::task{8}
[2008-11-22 03:39:54.175 'App' 848 verbose] [imageProcessingTaskWrapper,749] Got an update from UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:54.175 'App' 1064 verbose] [imageProcessingTaskWrapper,515] Cleaning up remote task UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:54.176 'App' 1064 verbose] [imageProcessingTaskWrapper,1201] Disposing task wrapper for task UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:54.176 'App' 2976 verbose] [disposable,122] object explicitly disposed: UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:54.176 'App' 2976 verbose] [computerProxy,94] Debug
[#2] [2008-11-22 03:39:54.176 'App' 2976 verbose] [computerProxy,120] Debug
[#2] [2008-11-22 03:39:54.176 'App' 2976 info] [ufaClientConnection,136] Info
[#2] [2008-11-22 03:39:54.176 'App' 2976 verbose] [imageProcessingTaskImpl,123] UfaSysReconfig::task{1}::task{8} being destroyed
[#2] [2008-11-22 03:39:54.176 'App' 1064 error] [imageProcessingTaskStep,423] VmiReconfigTask::task{7} step "Reconfigure VM" failed
[#2] [2008-11-22 03:39:54.176 'App' 1064 verbose] [imageProcessingTaskWrapper,1117] Reporting image processing task failure for task UfaSysReconfig::task{1}::task{8}
[#2] [2008-11-22 03:39:54.177 'App' 1064 error] [imageProcessingTaskWrapper,1124] ImageProcessingTask FAILED. Fault name: sysimage.fault.OsVersionNotFound
[#2] [2008-11-22 03:39:54.177 'App' 2976 verbose] [disposable,122] object explicitly disposed: computer={1385dcf8af22fbec88a44b0d443564885837c82a}
[#2] [2008-11-22 03:39:54.177 'App' 2976 verbose] [diskSet,114] Cleaning up computer's child objects
[#2] [2008-11-22 03:39:54.177 'App' 2976 verbose] [diskSet,130] Unregistring volume id=virtVol={computer={1385dcf8af22fbec88a44b0d443564885837c82a},0}
[#2] [2008-11-22 03:39:57.003 'App' 2976 verbose] [diskSet,140] Unregistring disk id=disk={6af959fe1142c9a147b79ca7414bbbedcec4071f}
[#2] [2008-11-22 03:39:57.004 'App' 1064 info] [imageProcessingTaskStep,194] VmiReconfigTask::task{7} step "Reconfigure VM" destroyed
[#2] [2008-11-22 03:39:57.004 'App' 1064 error] [vmiReconfigTask,140] Reconfiguration failed
[#2] [2008-11-22 03:39:57.005 'App' 1064 error] [imageProcessingTaskImpl,552] VmiReconfigTask::task{7}: Image processing task has failed with MethodFault::Exception: sysimage.fault.ReconfigFault
[#2] [2008-11-22 03:39:57.005 'App' 1064 verbose] [imageProcessingTaskImpl,154] VmiReconfigTask::task{7}: SetState to error
[#2] [2008-11-22 03:39:57.028 'App' 2976 verbose] [disposable,122] object explicitly disposed: VmiReconfigTask::task{7}
[#2] [2008-11-22 03:39:57.028 'App' 2976 verbose] [imageProcessingTaskImpl,123] VmiReconfigTask::task{7} being destroyed
[2008-11-22 03:39:57.055 'App' 3056 verbose] CloseSession called for session id=58BD7D22-4DE3-44DF-B6E8-B2E63657CE70
[2008-11-22 03:39:57.055 'VmdbAdapter' 3056 verbose] Removed vmdb connection /db/connection/#2/
0 Kudos
0 Replies