ERROR: Unknown error returned by VMware Converter ...

puppetj · ‎11-22-2008

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.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] 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] [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.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 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] 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] ],

[#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] [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/

All

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