- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
importing livestate image fails with error (log attached)
Trying to import Win2003 image made by Livestate, import almost finished but fails at last step
\[2006-03-23 09:34:57.906 'App' 3520 info] Current working directory: C:\Program Files\VMware\VMware Virtual Machine Importer 2
\[2006-03-23 09:34:57.906 'ThreadPool' 3520 verbose] Setting COM threading model to MTA
\[2006-03-23 09:34:57.906 'App' 3520 info] Trying ufa-agent
\[2006-03-23 09:34:57.906 'App' 3520 info] Trying ufa-client
\[2006-03-23 09:34:57.906 'App' 3520 info] Trying ufa-slave
\[2006-03-23 09:34:57.906 'App' 3520 info] Trying ufa-sysMigration
\[2006-03-23 09:34:57.936 'App' 3520 info] Trying ufa-sysReconfig
\[2006-03-23 09:34:57.936 'App' 3520 info] Trying ufa-vmImporter
\[2006-03-23 09:34:57.936 'App' 3520 verbose] Plugin 0 path: ufa-agent.dll
\[2006-03-23 09:34:57.936 'App' 3520 verbose] Plugin 0 absolute path: C:\Program Files\VMware\VMware Virtual Machine Importer 2\ufa-agent.dll
\[2006-03-23 09:34:58.266 'Ufa' 3520 info] Soap port: 8085
\[2006-03-23 09:34:58.286 'Ufa' 3520 info] Plugin initialized
\[2006-03-23 09:34:58.286 'App' 3520 verbose] Plugin 1 path: ufa-client.dll
\[2006-03-23 09:34:58.296 'App' 3520 verbose] Plugin 1 absolute path: C:\Program Files\VMware\VMware Virtual Machine Importer 2\ufa-client.dll
\[2006-03-23 09:35:02.122 'App' 3520 info] \[plugin,102] UfaClientPlugin Init
\[2006-03-23 09:35:02.122 'App' 3520 verbose] Plugin 2 path: ufa-slave.dll
\[2006-03-23 09:35:02.122 'App' 3520 verbose] Plugin 2 absolute path: C:\Program Files\VMware\VMware Virtual Machine Importer 2\ufa-slave.dll
\[2006-03-23 09:35:03.243 'App' 3520 info] \[osInfoWin32,97] Microsoft Windows Server 2003 "R2"
\[2006-03-23 09:35:03.243 'App' 3520 info] \[osInfoWin32,182] Standard Edition
\[2006-03-23 09:35:03.243 'App' 3520 info] \[osInfoWin32,272] Service Pack 1(Build 3790)
\[2006-03-23 09:35:03.253 'App' 3520 info] \[plugin,81] UfaSlavePlugin Init
\[2006-03-23 09:35:03.253 'App' 3520 verbose] Plugin 3 path: ufa-sysMigration.dll
\[2006-03-23 09:35:03.253 'App' 3520 verbose] Plugin 3 absolute path: C:\Program Files\VMware\VMware Virtual Machine Importer 2\ufa-sysMigration.dll
\[2006-03-23 09:35:04.585 'App' 3520 info] \[plugin,163] SysMigrationPlugin Init
\[2006-03-23 09:35:04.585 'App' 3520 verbose] Plugin 4 path: ufa-sysReconfig.dll
\[2006-03-23 09:35:04.585 'App' 3520 verbose] Plugin 4 absolute path: C:\Program Files\VMware\VMware Virtual Machine Importer 2\ufa-sysReconfig.dll
\[2006-03-23 09:35:04.696 'App' 3520 info] \[plugin,102] SysReconfigPlugin Init
\[2006-03-23 09:35:04.746 'App' 3520 info] \[utils,121] Using temp dir C:\WINDOWS\TEMP\vmware-SYSTEM\sysReconfig
\[2006-03-23 09:35:04.876 'App' 3520 verbose] \[rdsXmlLoader,145] Loading reconfiguration data store XML from C:\WINDOWS\TEMP\vmware-SYSTEM\sysReconfig\reconfigurationDataStore.xml ...
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,199] Populating ReconfigurationDataStore ...
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action LsiLogic
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_1000&dev_0030 patch: C_SYMMPI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\symmpi patch: S_SYMMPI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: system32\drivers\symmpi.sys source: BASEOS sourceFileName: symmpi.sys
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action BuslogicMylex
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: enum\root\LEGACY_BUSLOGIC patch: L_BUSLOGIC
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: services\buslogic patch: S_BUSLOGIC
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: system32\drivers\buslogic.sys source: VMWARE_DRIVERS_CAB sourceFileName: buslogic_for_nt.sys
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Buslogic_vmscsi
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_104b&dev_1040 patch: C_VMSCSI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\Class\{4D36E97B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMSCSI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\vmscsi patch: S_VMSCSI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: system32\drivers\vmscsi.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmscsi.sys
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Buslogic_vmscsi_ACPI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\3&61aaa01&0&80 patch: E_VMSCSI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Buslogic_vmscsi_NotACPI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\2&ebb567f&0&80 patch: E_VMSCSI
\[2006-03-23 09:35:04.886 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Mouse_Keyboard
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0303 patch: C_PS2KBD
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\Class\{4D36E96B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2KBD
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\ACPI\PNP0303 patch: E_PS2KBD
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0f13 patch: C_PS2MOUSE
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\Class\{4D36E96F-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2MOUSE
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\ACPI\PNP0F13 patch: E_PS2MOUSE
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_8086&dev_7110&cc_0601 patch: C_ISAPNP
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action NT_SVGA_Registry_Updates
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: enum\root\LEGACY_VMX_SVGA patch: E_VMX_SVGA
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Common_SVGA_Updates
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Control\Class\{4D36E968-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMX_SVGA
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: INF\vmx_svga.inf source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.inf
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: system32\vmx_fb.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_fb.dll
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: system32\vmx_mode.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_mode.dll
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,410] File Update filePathInWinDir: system32\drivers\vmx_svga.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.sys
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action SVGA_Registry_Updates_APCI
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\PCI\VEN_15AD&DEV_0405&SUBSYS_040515AD&REV_00\3&61aaa01&0&78 patch: E_VMX_SVGA
\[2006-03-23 09:35:04.896 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action SVGA_Registry_Updates_NotACPI
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\PCI\VEN_15AD&DEV_0405&SUBSYS_040515AD&REV_00\2&ebb567f&0&78 patch: E_VMX_SVGA
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Restore_i8042_NT5
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\i8042prt patch: SKUNKWORKS_S_I8042
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_vpcsrvc
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\1-driver-vpcsrvc patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\1-vpcsrvc patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\LEGACY_1-DRIVER-VPCSRVC patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\LEGACY_1-VPCSRVC patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_mrxvpc
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\MrxVPC patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_MRXVPC patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_vpcmap
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\VPCMap patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPCMAP patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_vpc-s3_NT4
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPC-S3 patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.906 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_vpc-s3_NT5
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\PCI\VEN_5333&DEV_8811&SUBSYS_00000000&REV_00\3&267a616a&0&40 patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\s3legacy patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_vmsrvc
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\1-driver-vmsrvc patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-DRIVER-VMSRVC patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\1-vmsrvc patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-VMSRVC patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_netfin
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_NETFIN patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\NetFin patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.916 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_cpqdmi
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQDMI patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\CPQDMI patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_cpqalert
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQALERT patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\CPQALERT patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\EventLog\System\CPQALERT patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action Deref_cpqagent
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\EventLog\System\Snmp patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,360] Registry Update currentControlSetKey: Services\Snmp patch: SKUNKWORKS_FILLER
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action PreserveDriveLetters
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action RemoveUnwantedKeys
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,274] Loading action FixSetupLog
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,451] String replace Update filePathInWinDir: repair\setup.log pattern:
system32
ntoskrnl.exe.*\.exe value:
system32
ntoskrnl.exe = "ntoskrnl.exe
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,451] String replace Update filePathInWinDir: repair\setup.log pattern:
system32
hal.dll.*\.dll value:
system32
hal.dll = "hal.dll
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition GenericWin2k3
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition GenericWinXP
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition GenericWin2k
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition GenericWinNT4
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition NotWinNT4
\[2006-03-23 09:35:04.926 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition ACPI
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition NotACPI
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition MultiProc
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition InstallSVGA
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition MustUpdateCoreNTFiles
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition GuestOsIncludesLsiLogicDriver
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition BuslogicMylex
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,485] Loading expression definition Buslogic_vmscsi
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration FixSetupLog
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action FixSetupLog
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration BuslogicMylex
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action BuslogicMylex
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration LsiLogic
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action LsiLogic
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Buslogic_vmscsi_ACPI
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Buslogic_vmscsi
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Buslogic_vmscsi_ACPI
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Buslogic_vmscsi_NotACPI
\[2006-03-23 09:35:04.936 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Buslogic_vmscsi
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Buslogic_vmscsi_NotACPI
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Mouse_Keyboard_notNT_ACPI
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Mouse_Keyboard
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration SVGA_NT
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Common_SVGA_Updates
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action NT_SVGA_Registry_Updates
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration SVGA_notNT_ACPI
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Common_SVGA_Updates
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action SVGA_Registry_Updates_APCI
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration SVGA_notNT_NotACPI
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Common_SVGA_Updates
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action SVGA_Registry_Updates_NotACPI
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Restore_i8042_NT5
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Restore_i8042_NT5
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_vpcsrvc
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_vpcsrvc
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_mrxvpc
\[2006-03-23 09:35:04.946 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_mrxvpc
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_vpcmap
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_vpcmap
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_vpc-s3_NT4
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_vpc-s3_NT4
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_vpc-s3_NT5
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_vpc-s3_NT5
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_vmsrvc
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_vmsrvc
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_netfin
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_netfin
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_cpqdmi
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_cpqdmi
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_cpqalert
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_cpqalert
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration Deref_cpqagent
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action Deref_cpqagent
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration PreserveDriveLetters
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action PreserveDriveLetters
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1032] Loading reconfiguration RemoveUnwantedKeys
\[2006-03-23 09:35:04.956 'App' 3520 verbose] \[rdsXmlLoader,1100] Loading reconfiguration action RemoveUnwantedKeys
\[2006-03-23 09:35:04.966 'App' 3520 verbose] \[rdsXmlLoader,1154] Loading hotfixes for WINDOWS4.0
\[2006-03-23 09:35:04.966 'App' 3520 verbose] \[rdsXmlLoader,1154] Loading hotfixes for WINDOWS4.0
\[2006-03-23 09:35:04.966 'App' 3520 verbose] \[rdsXmlLoader,1154] Loading hotfixes for WINDOWS4.0
\[2006-03-23 09:35:04.966 'App' 3520 verbose] \[rdsXmlLoader,1154] Loading hotfixes for WINDOWS4.0
\[2006-03-23 09:35:04.966 'App' 3520 verbose] \[rdsXmlLoader,1154] Loading hotfixes for WINDOWS4.0
\[2006-03-23 09:35:04.966 'App' 3520 info] \[utils,148] Cleaning up temp directory C:\WINDOWS\TEMP\vmware-SYSTEM\sysReconfig ...
\[2006-03-23 09:35:04.966 'App' 3520 warning] \[utils,151] Unable to cleanup and remove temp directory C:\WINDOWS\TEMP\vmware-SYSTEM\sysReconfig
\[2006-03-23 09:35:04.966 'App' 3520 verbose] Plugin 5 path: ufa-vmImporter.dll
\[2006-03-23 09:35:04.966 'App' 3520 verbose] Plugin 5 absolute path: C:\Program Files\VMware\VMware Virtual Machine Importer 2\ufa-vmImporter.dll
\[2006-03-23 09:35:05.036 'App' 3520 info] \[plugin,106] VMImporterPlugin Init
\[2006-03-23 09:35:05.036 'Ufa' 3520 info] Vmdb port: 9005
\[2006-03-23 09:35:05.036 'Ufa' 3520 info] Plugin started
\[2006-03-23 09:35:05.036 'App' 3520 info] \[plugin,117] UfaClientPlugin Start
\[2006-03-23 09:35:05.066 'App' 3520 info] \[plugin,99] UfaSlavePlugin Start
\[2006-03-23 09:35:05.066 'App' 3520 info] \[plugin,179] SysMigrationPlugin Start
\[2006-03-23 09:35:05.066 'App' 3520 info] \[plugin,119] SysReconfigPlugin Start
\[2006-03-23 09:35:05.066 'App' 3520 info] \[plugin,121] VMImporterPlugin Start
\[2006-03-23 09:35:05.066 'App' 3520 info] \[serviceWin32,380] ufad-vmi service started
\[2006-03-23 09:46:37.619 'App' 2896 info] Current working directory: C:\Documents and Settings\gregory\Desktop
\[2006-03-23 09:46:37.619 'App' 2896 info] \[vmiSupport,139] VMImporter UI logger created
\[2006-03-23 09:46:37.639 'ClientConnection' 2896 info] Connecting to ufa-client on localhost:9005
\[2006-03-23 09:46:37.659 'VmdbSvc' 2896 verbose] Connected to stream: \[class Vmacore::System::TCPClientSocketWin32:0191EFC0]
\[2006-03-23 09:46:37.659 'BaseLibs' 2568 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/
\[2006-03-23 09:46:37.679 'App' 2896 info] \[vmiSupport,143] UFA connection established
\[2006-03-23 09:46:49.750 'App' 3520 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:46:49.800 'App' 3520 verbose] \[imageProcessingTaskImpl,551] Starting execution of an image processing task...
\[2006-03-23 09:46:49.800 'App' 3520 info] \[vmiQueryOsTask,107] vmiQueryOsTask initialized
\[2006-03-23 09:46:49.800 'App' 3520 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:46:49.800 'App' 3520 verbose] \[vmiUtils,76] VmiImportTask::GetComputerSpec - Instantiating V2iDisk
\[2006-03-23 09:46:49.800 'App' 3520 verbose] \[vmiUtils,81] VmiImportTask::GetComputerSpec - Calling v2id.wrapDiskImage()
\[2006-03-23 09:46:49.780 'App' 2896 verbose] \[imageProcessingTaskWrapper,70] Successfully connected to VmiQueryOsTask::task\{0}
\[2006-03-23 09:46:49.800 'App' 2896 verbose] \[imageProcessingTaskWrapper,621] Start managed object method...
\[2006-03-23 09:46:50.301 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:46:50.311 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[03/23/06 09:47:01] Status: (vmount:601) vmount2 service started 2.0.0 build-21909
\[2006-03-23 09:47:04.115 'App' 2604 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:47:04.586 'App' 2604 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:04.616 'App' 2604 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y
\[2006-03-23 09:47:04.616 'App' 2604 verbose] \[virtualVolumeWin32,181] Mounting virtual volume virtVol=\{computer=,1}... \[03/23/06 09:47:04] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:47:04] Error: (VmountPartitionList:136) partition table is non-existent or corrupt \[03/23/06 09:47:04] Error: (VmountLocator:114) Vmount2.Vmount2Disk: partition table is non-existent or corrupt \[03/23/06 09:47:04] Error: (VmountLocator:114) Vmount2.Vmount2Disk2: Cannot access layout on disk \[03/23/06 09:47:04] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed \[03/23/06 09:47:04] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:47:04] Status: (VmountDevice:1518) device of type volume with offset 32256 was created \[03/23/06 09:47:04] Error: (VmountLocator:114) Vmount2.Vmount2Device: no drive letter currently assigned \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:06.950 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:06.950 'App' 2604 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:06.950 'App' 2604 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:06.960 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:06.960 'App' 2604 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:06.960 'App' 2604 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:06.980 'App' 2604 info] \[diskSet,238] OS resides on partition 1 , disk path D:\drive-0.vmdk \[2006-03-23 09:47:06.980 'App' 2604 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y \[2006-03-23 09:47:06.980 'App' 2604 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:06.990 'App' 2604 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:06.990 'App' 2604 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:06.990 'App' 2604 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:06.990 'App' 2604 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:06.990 'App' 2604 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:06.990 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:06.990 'App' 2604 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:06.990 'App' 2604 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:07.000 'App' 2604 info] \[diskSet,238] OS resides on partition 1 , disk path D:\drive-0.vmdk \[2006-03-23 09:47:07.000 'App' 2604 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y \[2006-03-23 09:47:07.000 'App' 2604 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:07.000 'App' 2604 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:07.000 'App' 2604 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:07.010 'App' 2604 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:07.010 'App' 2604 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:07.010 'App' 2604 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:07.010 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:09.444 'App' 2604 info] \[extendedOsInfo,676] ParseNTRegistry FOUND: Microsoft Windows Server 2003 root:WIN2003\ SrvPack:1 SMP:0 64:0 Ver:5.2 \[2006-03-23 09:47:09.454 'Vmsvc' 2604 info] VMServices Plugin initializing \[2006-03-23 09:47:09.665 'VmMisc' 2604 info] Max supported virtual machines: 1200 \[2006-03-23 09:47:09.665 'App' 2604 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer=,1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:47:09.785 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:47:09.785 'App' 2604 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:47:09.815 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:47:09.815 'App' 2604 info] \[extendedOsInfo,151] Returning OS as winNetStandard
\[2006-03-23 09:47:09.815 'App' 3520 info] \[imageProcessorProxy,151] Successfully connected to UfaSysReconfig
\[2006-03-23 09:47:09.815 'App' 2604 info] \[ufaClientConnection,56] Successfully connected to local UfaClient.
\[2006-03-23 09:47:09.815 'App' 2604 info] \[ufaClientConnection,150] Opening specifed computer ...
\[2006-03-23 09:47:09.815 'App' 2256 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:47:09.815 'App' 2604 verbose] \[computerProxy,67] Successfully connected to remote Computer.
\[2006-03-23 09:47:09.815 'App' 2604 verbose] \[computerProxy,248] Connecting to the system volume...
\[2006-03-23 09:47:09.815 'App' 2604 verbose] \[computerProxy,139] Retrieving the system volume info ...
\[2006-03-23 09:47:09.815 'App' 1452 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[extendedOsInfo,351] \[boot loader]
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[extendedOsInfo,351] timeout=30
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[extendedOsInfo,351] \[operating systems]
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[disposable,109] object explicitly disposed: localFile=
\[2006-03-23 09:47:09.825 'App' 1452 verbose] \[disposable,45] disposable object being destroyed: localFile=
\[2006-03-23 09:47:09.825 'App' 1452 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard
\[2006-03-23 09:47:09.825 'App' 1452 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard
\[2006-03-23 09:47:09.835 'App' 1452 info] \[diskSet,238] OS resides on partition 1 , disk path D:\drive-0.vmdk
\[2006-03-23 09:47:09.835 'App' 2604 verbose] \[volumeProxy,51] Successfully connected to remote volume.
\[2006-03-23 09:47:10.686 'App' 2604 verbose] \[computerProxy,87] Debug
\[2006-03-23 09:47:10.686 'App' 2604 verbose] \[computerProxy,113] Debug
\[2006-03-23 09:47:10.686 'App' 2604 verbose] \[volumeProxy,71] Debug
\[2006-03-23 09:47:10.686 'App' 2604 info] \[ufaClientConnection,123] Info
\[2006-03-23 09:47:10.686 'App' 2604 verbose] \[disposable,109] object explicitly disposed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:10.686 'App' 2604 verbose] \[disposable,109] object explicitly disposed: virtVol=\{computer=,1} \[2006-03-23 09:47:11.017 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: virtVol=\{computer=,1}
\[2006-03-23 09:47:11.017 'App' 2604 verbose] \[disposable,109] object explicitly disposed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 09:47:11.017 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 09:47:11.047 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:11.047 'App' 3520 info] \[vmiUtils,219] Deleting D:\drive-0.vmdk
\[2006-03-23 09:47:11.047 'App' 3520 info] \[vmiUtils,224] Deleting D:\drive-0.vmdk.rdonly
\[2006-03-23 09:47:11.057 'App' 3520 verbose] \[imageProcessingTaskImpl,560] Image processing task has succeeded...
\[2006-03-23 09:47:11.087 'App' 1452 verbose] \[disposable,109] object explicitly disposed: VmiQueryOsTask::task\{0}
\[2006-03-23 09:47:11.087 'App' 1452 verbose] \[disposable,45] disposable object being destroyed: VmiQueryOsTask::task\{0}
\[2006-03-23 09:47:11.097 'App' 1452 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:47:11.257 'App' 1452 verbose] \[imageProcessingTaskImpl,551] Starting execution of an image processing task...
\[2006-03-23 09:47:11.257 'App' 1452 info] \[vmiIsImportAcceptableTask,87] VmiIsImportAcceptableTask initialized
\[2006-03-23 09:47:11.257 'App' 1452 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:47:11.257 'App' 1452 verbose] \[vmiUtils,76] VmiImportTask::GetComputerSpec - Instantiating V2iDisk
\[2006-03-23 09:47:11.257 'App' 1452 verbose] \[vmiUtils,81] VmiImportTask::GetComputerSpec - Calling v2id.wrapDiskImage()
\[03/23/06 09:47:11] Status: (VmountDevice:147) device of type volume with offset 32256 was closed
\[03/23/06 09:47:11] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed
\[2006-03-23 09:47:11.057 'App' 2568 verbose] \[imageProcessingTaskWrapper,697] Task completed.
\[2006-03-23 09:47:11.107 'App' 2896 verbose] \[imageProcessingTaskWrapper,70] Successfully connected to VmiIsImportAcceptableTask::task\{1}
\[2006-03-23 09:47:11.257 'App' 2896 verbose] \[imageProcessingTaskWrapper,621] Start managed object method...
\[2006-03-23 09:47:11.758 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:47:11.768 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:47:12.209 'App' 2256 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:47:12.590 'App' 2256 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:12.620 'App' 2256 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y
\[2006-03-23 09:47:12.620 'App' 2256 verbose] \[virtualVolumeWin32,181] Mounting virtual volume virtVol=\{computer=,1}... \[03/23/06 09:47:12] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:47:12] Error: (VmountPartitionList:136) partition table is non-existent or corrupt \[03/23/06 09:47:12] Error: (VmountLocator:114) Vmount2.Vmount2Disk: partition table is non-existent or corrupt \[03/23/06 09:47:12] Error: (VmountLocator:114) Vmount2.Vmount2Disk2: Cannot access layout on disk \[03/23/06 09:47:12] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed \[03/23/06 09:47:12] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:47:12] Status: (VmountDevice:1518) device of type volume with offset 32256 was created \[03/23/06 09:47:12] Error: (VmountLocator:114) Vmount2.Vmount2Device: no drive letter currently assigned \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:13.561 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:13.561 'App' 2256 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:13.571 'App' 2256 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:13.571 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:13.571 'App' 2256 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:13.571 'App' 2256 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:13.581 'App' 2256 info] \[diskSet,238] OS resides on partition 1 , disk path D:\drive-0.vmdk \[2006-03-23 09:47:13.592 'App' 2256 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y \[2006-03-23 09:47:13.592 'App' 2256 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:13.592 'App' 2256 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:13.602 'App' 2256 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:13.602 'App' 2256 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:13.602 'App' 2256 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:13.602 'App' 2256 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:13.602 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:16.346 'App' 2256 info] \[extendedOsInfo,676] ParseNTRegistry FOUND: Microsoft Windows Server 2003 root:WIN2003\ SrvPack:1 SMP:0 64:0 Ver:5.2 \[2006-03-23 09:47:16.346 'App' 2256 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer=,1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:47:16.436 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:47:16.436 'App' 2256 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:47:16.466 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:47:16.466 'App' 2256 info] \[extendedOsInfo,151] Returning OS as winNetStandard
\[2006-03-23 09:47:16.466 'App' 1452 info] \[localImportSource,134] Source VM name is "DC1"
\[2006-03-23 09:47:16.466 'App' 832 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:47:16.466 'App' 2604 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:47:16.466 'App' 2604 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:16.466 'App' 3520 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:16.487 'App' 1452 info] \[localImportSource,147] Source capacity=20020331520 Source space used=20020331520
\[2006-03-23 09:47:16.497 'App' 832 verbose] \[disposable,109] object explicitly disposed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:16.497 'App' 832 verbose] \[disposable,109] object explicitly disposed: virtVol=\{computer=,1} \[2006-03-23 09:47:16.627 'App' 832 verbose] \[disposable,45] disposable object being destroyed: virtVol=\{computer=,1}
\[2006-03-23 09:47:16.627 'App' 832 verbose] \[disposable,109] object explicitly disposed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 09:47:16.627 'App' 832 verbose] \[disposable,45] disposable object being destroyed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 09:47:16.677 'App' 832 verbose] \[disposable,45] disposable object being destroyed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:16.677 'App' 1452 info] \[vmiUtils,219] Deleting D:\drive-0.vmdk
\[2006-03-23 09:47:16.677 'App' 1452 info] \[vmiUtils,224] Deleting D:\drive-0.vmdk.rdonly
\[2006-03-23 09:47:16.677 'App' 1452 verbose] \[imageProcessingTaskImpl,560] Image processing task has succeeded...
\[2006-03-23 09:47:16.727 'App' 2604 verbose] \[disposable,109] object explicitly disposed: VmiIsImportAcceptableTask::task\{1}
\[2006-03-23 09:47:16.727 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: VmiIsImportAcceptableTask::task\{1}
\[03/23/06 09:47:16] Status: (VmountDevice:147) device of type volume with offset 32256 was closed
\[03/23/06 09:47:16] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed
\[2006-03-23 09:47:16.677 'App' 2568 verbose] \[imageProcessingTaskWrapper,697] Task completed.
\[2006-03-23 09:47:48.231 'App' 2604 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:47:48.271 'App' 2604 verbose] \[imageProcessingTaskImpl,551] Starting execution of an image processing task...
\[2006-03-23 09:47:48.271 'App' 2604 info] \[vmiIsImportAcceptableTask,87] VmiIsImportAcceptableTask initialized
\[2006-03-23 09:47:48.271 'App' 2604 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:47:48.271 'App' 2604 verbose] \[vmiUtils,76] VmiImportTask::GetComputerSpec - Instantiating V2iDisk
\[2006-03-23 09:47:48.271 'App' 2604 verbose] \[vmiUtils,81] VmiImportTask::GetComputerSpec - Calling v2id.wrapDiskImage()
\[2006-03-23 09:47:48.251 'App' 2896 verbose] \[imageProcessingTaskWrapper,70] Successfully connected to VmiIsImportAcceptableTask::task\{2}
\[2006-03-23 09:47:48.271 'App' 2896 verbose] \[imageProcessingTaskWrapper,621] Start managed object method...
\[2006-03-23 09:47:48.772 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:47:48.792 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:47:49.183 'App' 2256 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:47:49.594 'App' 2256 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:49.624 'App' 2256 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y
\[2006-03-23 09:47:49.624 'App' 2256 verbose] \[virtualVolumeWin32,181] Mounting virtual volume virtVol=\{computer=,1}... \[03/23/06 09:47:49] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:47:49] Error: (VmountPartitionList:136) partition table is non-existent or corrupt \[03/23/06 09:47:49] Error: (VmountLocator:114) Vmount2.Vmount2Disk: partition table is non-existent or corrupt \[03/23/06 09:47:49] Error: (VmountLocator:114) Vmount2.Vmount2Disk2: Cannot access layout on disk \[03/23/06 09:47:49] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed \[03/23/06 09:47:49] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:47:49] Status: (VmountDevice:1518) device of type volume with offset 32256 was created \[03/23/06 09:47:49] Error: (VmountLocator:114) Vmount2.Vmount2Device: no drive letter currently assigned \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:50.505 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:50.505 'App' 2256 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:50.505 'App' 2256 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:50.515 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:50.515 'App' 2256 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:47:50.515 'App' 2256 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:47:50.525 'App' 2256 info] \[diskSet,238] OS resides on partition 1 , disk path D:\drive-0.vmdk \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:47:50.535 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:47:52.028 'App' 2256 info] \[extendedOsInfo,676] ParseNTRegistry FOUND: Microsoft Windows Server 2003 root:WIN2003\ SrvPack:1 SMP:0 64:0 Ver:5.2 \[2006-03-23 09:47:52.028 'App' 2256 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer=,1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:47:52.128 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:47:52.128 'App' 2256 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:47:52.158 'App' 2256 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:47:52.158 'App' 2256 info] \[extendedOsInfo,151] Returning OS as winNetStandard
\[2006-03-23 09:47:52.158 'App' 2604 info] \[localImportSource,134] Source VM name is "DC1"
\[2006-03-23 09:47:52.158 'App' 832 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:47:52.158 'App' 3524 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:47:52.158 'App' 3524 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:52.158 'App' 3520 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:52.188 'App' 2604 info] \[localImportSource,147] Source capacity=20020331520 Source space used=20020331520
\[2006-03-23 09:47:52.188 'App' 2604 info] \[localImportDestination,122] Available space=68316696576 Required space=20020331520
\[2006-03-23 09:47:52.188 'App' 832 verbose] \[disposable,109] object explicitly disposed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:52.188 'App' 832 verbose] \[disposable,109] object explicitly disposed: virtVol=\{computer=,1} \[2006-03-23 09:47:52.338 'App' 832 verbose] \[disposable,45] disposable object being destroyed: virtVol=\{computer=,1}
\[2006-03-23 09:47:52.338 'App' 832 verbose] \[disposable,109] object explicitly disposed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 09:47:52.348 'App' 832 verbose] \[disposable,45] disposable object being destroyed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 09:47:52.389 'App' 832 verbose] \[disposable,45] disposable object being destroyed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:47:52.389 'App' 2604 info] \[vmiUtils,219] Deleting D:\drive-0.vmdk
\[2006-03-23 09:47:52.399 'App' 2604 info] \[vmiUtils,224] Deleting D:\drive-0.vmdk.rdonly
\[2006-03-23 09:47:52.399 'App' 2604 verbose] \[imageProcessingTaskImpl,560] Image processing task has succeeded...
\[2006-03-23 09:47:52.439 'App' 832 verbose] \[disposable,109] object explicitly disposed: VmiIsImportAcceptableTask::task\{2}
\[2006-03-23 09:47:52.439 'App' 832 verbose] \[disposable,45] disposable object being destroyed: VmiIsImportAcceptableTask::task\{2}
\[03/23/06 09:47:52] Status: (VmountDevice:147) device of type volume with offset 32256 was closed
\[03/23/06 09:47:52] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed
\[2006-03-23 09:47:52.399 'App' 2568 verbose] \[imageProcessingTaskWrapper,697] Task completed.
\[2006-03-23 09:48:08.126 'App' 832 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,79] Successfully connected to UfaClient
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,918] Importing from local source D:\DC1.sv2i
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,922] Importing to local destination DC1@E:\virtual machines
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,947] Creating imported VM for target PRODUCT_WS_500
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,954] Creating a full clone
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,966] Creating disks as MONOLITHIC_FLAT
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,990] fileCloning is 0
\[2006-03-23 09:48:08.136 'App' 832 info] \[vmiImportTask,998] preallocateDisks not specified; defaulting to FALSE
\[2006-03-23 09:48:08.156 'App' 832 info] \[vmiImportTask,1004] maxBandwidth not specified; throttling disabled
\[2006-03-23 09:48:08.166 'App' 832 info] \[vmiImportTask,1010] thinProvisioning not specified; defaulting to FALSE
\[2006-03-23 09:48:08.196 'App' 832 verbose] \[imageProcessingTaskImpl,551] Starting execution of an image processing task...
\[2006-03-23 09:48:08.196 'App' 832 info] \[vmiImportTask,382] Starting VM Import...
\[2006-03-23 09:48:08.196 'App' 832 info] \[vmiImportTask,333] vmiImportTask initialized
\[2006-03-23 09:48:08.196 'App' 832 verbose] \[vmiImportTask,456] VmiImportTask::DoRun - Calling GetComputerSpec(D:\DC1.sv2i)
\[2006-03-23 09:48:08.196 'App' 832 verbose] \[vmiUtils,76] VmiImportTask::GetComputerSpec - Instantiating V2iDisk
\[2006-03-23 09:48:08.196 'App' 832 verbose] \[vmiUtils,81] VmiImportTask::GetComputerSpec - Calling v2id.wrapDiskImage()
\[2006-03-23 09:48:08.176 'App' 2896 verbose] \[imageProcessingTaskWrapper,70] Successfully connected to VmiImportTask::task\{3}
\[2006-03-23 09:48:08.196 'App' 2896 verbose] \[imageProcessingTaskWrapper,621] Start managed object method...
\[2006-03-23 09:48:08.697 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:08.707 'App' 2896 info] \[vmiSupport,477] import 0% complete.
\[2006-03-23 09:48:08.707 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:09.157 'App' 832 verbose] \[vmiImportTask,464] VmiImportTask::DoRun - Calling CreateComputerObject()
\[2006-03-23 09:48:09.157 'App' 3524 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:48:09.588 'App' 3524 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:48:09.608 'App' 832 verbose] \[vmiImportTask,496] VmiImportTask::DoRun - Calling Clone()
\[2006-03-23 09:48:09.608 'App' 3524 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:48:09.608 'App' 2256 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:48:09.608 'App' 2256 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:48:09.618 'App' 1452 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:48:09.638 'App' 832 verbose] \[vmiImportTask,661] Successfully created target storage layout
\[2006-03-23 09:48:09.638 'App' 832 info] \[vmiImportTask,269] Successfully connected to UfaLocalVMCreator
\[2006-03-23 09:48:09.638 'App' 3524 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y
\[2006-03-23 09:48:09.638 'App' 3524 verbose] \[virtualVolumeWin32,181] Mounting virtual volume virtVol=\{computer=,1}... \[03/23/06 09:48:09] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:48:09] Error: (VmountPartitionList:136) partition table is non-existent or corrupt \[03/23/06 09:48:09] Error: (VmountLocator:114) Vmount2.Vmount2Disk: partition table is non-existent or corrupt \[03/23/06 09:48:09] Error: (VmountLocator:114) Vmount2.Vmount2Disk2: Cannot access layout on disk \[03/23/06 09:48:09] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed \[03/23/06 09:48:09] Status: (VmountDisk:234) disk D:\drive-0.vmdk was opened \[03/23/06 09:48:09] Status: (VmountDevice:1518) device of type volume with offset 32256 was created \[03/23/06 09:48:09] Error: (VmountLocator:114) Vmount2.Vmount2Device: no drive letter currently assigned \[2006-03-23 09:48:10.600 'App' 3524 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:48:10.610 'App' 3524 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:48:10.610 'App' 3524 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:48:10.610 'App' 3524 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:48:10.620 'App' 3524 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:48:10.620 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:48:10.620 'App' 3524 info] \[extendedOsInfo,415] SwagNTInfoFromString has Windows Server 2003, Standard \[2006-03-23 09:48:10.620 'App' 3524 info] \[extendedOsInfo,149] Returning OS as display Windows Server 2003, Standard \[2006-03-23 09:48:10.630 'App' 3524 info] \[diskSet,238] OS resides on partition 1 , disk path D:\drive-0.vmdk \[2006-03-23 09:48:10.630 'App' 3524 verbose] \[diskSet,296] Volume: idx=0 sysId=7 active=Y \[2006-03-23 09:48:10.630 'App' 3524 verbose] \[extendedOsInfo,351] \[boot loader] \[2006-03-23 09:48:10.630 'App' 3524 verbose] \[extendedOsInfo,351] timeout=30 \[2006-03-23 09:48:10.630 'App' 3524 verbose] \[extendedOsInfo,351] default=multi(0)disk(0)rdisk(0)partition(1)\WIN2003 \[2006-03-23 09:48:10.630 'App' 3524 verbose] \[extendedOsInfo,351] \[operating systems] \[2006-03-23 09:48:10.630 'App' 3524 verbose] \[extendedOsInfo,351] multi(0)disk(0)rdisk(0)partition(1)\WIN2003="Windows Server 2003, Standard" /noexecute=optout /fastdetect \[2006-03-23 09:48:10.640 'App' 3524 verbose] \[disposable,109] object explicitly disposed: localFile= \[2006-03-23 09:48:10.640 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: localFile= \[2006-03-23 09:48:12.764 'App' 3524 info] \[extendedOsInfo,676] ParseNTRegistry FOUND: Microsoft Windows Server 2003 root:WIN2003\ SrvPack:1 SMP:0 64:0 Ver:5.2 \[2006-03-23 09:48:12.764 'App' 3524 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer=,1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:48:12.854 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SYSTEM
\[2006-03-23 09:48:12.854 'App' 3524 verbose] \[disposable,109] object explicitly disposed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:48:12.874 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: fileRegistry=\{virtVol={computer={2412d4e6e6877d384d65ad16594963c16161fae},1}},WIN2003\system32\config\SOFTWARE
\[2006-03-23 09:48:12.874 'App' 3524 info] \[extendedOsInfo,151] Returning OS as winNetStandard
\[2006-03-23 09:48:12.874 'App' 1452 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:48:12.894 'App' 2604 verbose] Connected to stream: \[class Vmacore::System::TCPClientSocketWin32:057DC4F0]
\[2006-03-23 09:48:12.894 'BaseLibs' 3524 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/
\[2006-03-23 09:48:12.904 'App' 2604 verbose] \[slaveConnection,215] Creating vmdb connection to a remote ufad at localhost:9005
\[2006-03-23 09:48:12.914 'App' 3520 verbose] \[attachedDiskWin32,255] QueryDosDevice: ret=0 size=1024 err=122
\[2006-03-23 09:48:12.914 'App' 3520 verbose] \[attachedDiskWin32,255] QueryDosDevice: ret=0 size=2048 err=122
\[2006-03-23 09:48:12.914 'App' 3520 verbose] \[attachedDiskWin32,255] QueryDosDevice: ret=0 size=4096 err=122
\[2006-03-23 09:48:12.914 'App' 3520 verbose] \[attachedDiskWin32,255] QueryDosDevice: ret=6214 size=8192 err=0
\[2006-03-23 09:48:13.275 'App' 3520 verbose] \[diskSet,263] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}
\[2006-03-23 09:48:13.275 'App' 3520 verbose] \[diskSet,263] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}
\[2006-03-23 09:48:13.275 'App' 3520 verbose] \[diskSet,263] GetDisks: disk=\{961925382f53577de35e883c6987f0bc8486d21}
\[2006-03-23 09:48:13.605 'App' 3040 warning] \[attachedComputerWin32,319] 1
\[2006-03-23 09:48:13.625 'App' 2604 info] \[computer,438] Discovered 3 volumes
\[2006-03-23 09:48:13.625 'App' 1452 info] \[localVMCreator,1627] FixupBacking for VirtualIDEController
\[2006-03-23 09:48:13.625 'App' 1452 info] \[localVMCreator,1549] FixupBacking for ide
\[2006-03-23 09:48:13.625 'App' 1452 info] \[localVMCreator,1627] FixupBacking for VirtualIDEController
\[2006-03-23 09:48:13.625 'App' 1452 info] \[localVMCreator,1800] CPU info: physHost 1 VM 1
\[2006-03-23 09:48:13.625 'App' 2604 verbose] \[disposable,109] object explicitly disposed: computerProxy=\{5885f934cb9d1aedb66f102344e47378767cf6a7}
\[2006-03-23 09:48:13.625 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: computerProxy=\{5885f934cb9d1aedb66f102344e47378767cf6a7}
\[2006-03-23 09:48:13.625 'App' 2604 verbose] \[disposable,109] object explicitly disposed: volumeProxy=\{eca45bc0d862e30834cef7f8f82068b82f2d2d34}
\[2006-03-23 09:48:13.625 'App' 3520 verbose] \[disposable,109] object explicitly disposed: attVol=\{computer=,1} \[2006-03-23 09:48:13.635 'App' 3520 verbose] \[disposable,45] disposable object being destroyed: attVol=\{computer=,1}
\[2006-03-23 09:48:13.645 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: volumeProxy=\{eca45bc0d862e30834cef7f8f82068b82f2d2d34}
\[2006-03-23 09:48:13.645 'App' 2604 verbose] \[disposable,109] object explicitly disposed: volumeProxy=\{4a99b0d93ee5d7bff540326142e2e6951ccd42e4}
\[2006-03-23 09:48:13.645 'App' 3520 verbose] \[disposable,109] object explicitly disposed: attVol=\{computer=,2} \[2006-03-23 09:48:13.645 'App' 3520 verbose] \[disposable,45] disposable object being destroyed: attVol=\{computer=,2}
\[2006-03-23 09:48:13.655 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: volumeProxy=\{4a99b0d93ee5d7bff540326142e2e6951ccd42e4}
\[2006-03-23 09:48:13.655 'App' 2604 verbose] \[disposable,109] object explicitly disposed: volumeProxy=\{597c7cf2b0782adc652b5bffd7dc1630aed6733e}
\[2006-03-23 09:48:13.655 'App' 3040 verbose] \[disposable,109] object explicitly disposed: attVol=\{computer=,3} \[2006-03-23 09:48:13.655 'App' 3040 verbose] \[disposable,45] disposable object being destroyed: attVol=\{computer=,3}
\[2006-03-23 09:48:13.665 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: volumeProxy=\{597c7cf2b0782adc652b5bffd7dc1630aed6733e}
\[2006-03-23 09:48:13.665 'App' 2604 verbose] \[disposable,109] object explicitly disposed: diskProxy=\{9cfc92b4887347e41ed2e6da122ae35725688f0}
\[2006-03-23 09:48:13.665 'App' 3040 verbose] \[disposable,109] object explicitly disposed: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}
\[2006-03-23 09:48:13.675 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: diskProxy=\{9cfc92b4887347e41ed2e6da122ae35725688f0}
\[2006-03-23 09:48:13.675 'App' 2604 verbose] \[disposable,109] object explicitly disposed: diskProxy=\{b85738c6e105c259afe088b44ad46096bc387d55}
\[2006-03-23 09:48:13.675 'App' 3520 verbose] \[disposable,109] object explicitly disposed: disk=\{961925382f53577de35e883c6987f0bc8486d21}
\[2006-03-23 09:48:13.685 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: diskProxy=\{b85738c6e105c259afe088b44ad46096bc387d55}
\[2006-03-23 09:48:13.685 'App' 2604 verbose] \[disposable,109] object explicitly disposed: diskProxy=\{6503b14946b738eff9d74b35c4d09b10c5c9a6b1}
\[2006-03-23 09:48:13.685 'App' 3524 verbose] \[disposable,109] object explicitly disposed: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}
\[2006-03-23 09:48:13.695 'App' 2604 verbose] Shutting down VMDB service...
\[2006-03-23 09:48:13.695 'App' 2604 verbose] Unregistering callback...
\[2006-03-23 09:48:13.695 'App' 2604 verbose] ...done
\[2006-03-23 09:48:13.695 'ThreadPool' 3524 warning] ERROR_NETNAME_DELETED received on handle 000006EC
\[2006-03-23 09:48:13.695 'ThreadPool' 3520 warning] ERROR_NETNAME_DELETED received on handle 0000024C
\[2006-03-23 09:48:13.695 'VmdbSvc' 3520 verbose] IVmdbPipe2Impl operation completed with error: The specified network name is no longer available.
\[2006-03-23 09:48:13.695 'App' 3524 verbose] \[disposable,109] object implicitly disposed: computer=\{d7e40f6d3ddd078441daab8676a2ed13d2fe8bd2}
\[2006-03-23 09:48:13.695 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: computer=\{d7e40f6d3ddd078441daab8676a2ed13d2fe8bd2}
\[2006-03-23 09:48:13.695 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}
\[2006-03-23 09:48:13.705 'App' 2604 verbose] \[disposable,45] disposable object being destroyed: diskProxy=\{6503b14946b738eff9d74b35c4d09b10c5c9a6b1}
\[2006-03-23 09:48:13.705 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}
\[2006-03-23 09:48:13.705 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: disk=\{961925382f53577de35e883c6987f0bc8486d21}
\[2006-03-23 09:48:13.705 'VmdbAdapter' 3524 verbose] Removed vmdb connection /db/connection/#2/
\[2006-03-23 09:48:13.705 'App' 1452 info] \[localVMCreator,459] Creating Local VM (DC1) TargetProduct PRODUCT_WS_500
\[2006-03-23 09:48:13.765 'App' 1452 info] \[localVMCreator,725] Creating Local Disks
\[2006-03-23 09:48:13.765 'App' 1452 verbose] \[localVMCreator,831] Creating Local Disk; Disk name= E:\virtual machines\DC1\DC1.vmdk; Mode = persistent; Is2GBSplit = No; preallocate space = No; is disk Flat = Yes; size = 19551105
\[2006-03-23 09:48:13.846 'App' 1452 verbose] \[diskHandleWrapper,232] Setting DDB property virtualHWVersion to 4.
\[2006-03-23 09:48:13.846 'App' 1452 verbose] \[diskHandleWrapper,232] Setting DDB property toolsVersion to 0.
\[2006-03-23 09:48:13.846 'App' 832 verbose] \[imageProcessingTaskStep,294] create VM: 100% completed
\[2006-03-23 09:48:13.846 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 5% completed
\[2006-03-23 09:48:13.846 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 4% completed
\[2006-03-23 09:48:13.846 'App' 832 verbose] \[vmiImportTask,679] Successfully created target VM Spec
\[2006-03-23 09:48:13.856 'App' 3524 info] \[sysMigration,162] Starting cloning...
\[2006-03-23 09:48:13.856 'App' 3524 verbose] \[ufaConnection,87] Creating local adapter...
\[2006-03-23 09:48:13.856 'App' 3520 verbose] \[slaveConnection,183] Creating local connection to the co-located slave
\[2006-03-23 09:48:13.856 'App' 3524 info] \[cloneTask,119] Parsing the VM spec to retrieve disk specs...
\[2006-03-23 09:48:13.856 'App' 2896 info] \[vmiSupport,477] import 4% complete.
\[2006-03-23 09:48:13.856 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.036 'Ufa-foundry' 3524 verbose] Completed opening virtual machine.
\[2006-03-23 09:48:14.056 'Ufa-foundry' 3524 verbose] Time to gather config: 20 (msecs)
\[2006-03-23 09:48:14.066 'App' 3524 info] \[foundryParser,517] LocalVirtualDiskSpec: Name=E:\virtual machines\DC1\DC1.vmdk
\[2006-03-23 09:48:14.066 'App' 3524 verbose] \[cloneTask,153] Disk Name: E:\virtual machines\DC1\DC1.vmdk
\[2006-03-23 09:48:14.066 'Ufa-foundry' 3524 verbose] Close vmHandle called
\[2006-03-23 09:48:14.086 'App' 832 verbose] \[imageProcessingTaskWrapper,70] Successfully connected to CloneTask::task\{4}
\[2006-03-23 09:48:14.086 'App' 832 verbose] \[imageProcessingTaskWrapper,621] Start managed object method...
\[2006-03-23 09:48:14.086 'App' 2256 verbose] \[imageProcessingTaskImpl,551] Starting execution of an image processing task...
\[2006-03-23 09:48:14.096 'App' 2256 info] \[cloneTask,86] Starting the cloning process...
\[2006-03-23 09:48:14.096 'App' 2256 info] \[diskBasedCloneTask,69] Performing disk-based block-level cloning...
\[2006-03-23 09:48:14.096 'App' 2256 info] \[cloneTask,306] Getting disks for the source computer...
\[2006-03-23 09:48:14.096 'App' 3524 verbose] \[diskSet,263] GetDisks: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:48:14.096 'App' 2256 verbose] \[cloneTask,315] Disk 0 id=disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 09:48:14.096 'App' 2256 verbose] \[diskBasedCloneTask,85] Cloning 1 disks...
\[2006-03-23 09:48:14.096 'App' 2256 verbose] \[diskBasedCloneTask,104] Cloning 0 disk of 1...
\[2006-03-23 09:48:14.096 'App' 2256 info] \[cloneTask,539] Cloning disk disk=\{5e5668d9c135ee75dd116d57075dc57226cef0} into disk E:\virtual machines\DC1\DC1.vmdk
\[2006-03-23 09:48:14.096 'App' 3524 info] \[diskWin32,314] Disk::CloneTo...
\[2006-03-23 09:48:14.096 'App' 3524 info] \[diskWin32,321] Scheduling BlockLevelCloningTask for execution...
\[2006-03-23 09:48:14.096 'App' 2256 verbose] \[imageProcessingTaskWrapper,70] Successfully connected to BlockLevelCloning::task\{5}
\[2006-03-23 09:48:14.096 'App' 2256 verbose] \[imageProcessingTaskWrapper,621] Start managed object method...
\[2006-03-23 09:48:14.106 'App' 1452 verbose] \[imageProcessingTaskImpl,551] Starting execution of an image processing task...
\[2006-03-23 09:48:14.106 'App' 1452 info] \[blockLevelCloningTaskImpl,233] Starting Block level cloning ...
\[2006-03-23 09:48:14.346 'App' 1452 verbose] \[nfcCallbacks,65] BlockLevelCloningTaskImpl::CopyStartCallback: Just started copying into: E:\virtual machines\DC1\DC1.vmdk
\[2006-03-23 09:48:14.587 'App' 832 info] \[imageProcessingTaskStep,387] clone to VM in progress...
\[2006-03-23 09:48:14.587 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.587 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 0% completed
\[2006-03-23 09:48:14.587 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.607 'App' 2256 info] \[imageProcessingTaskStep,387] Cloning into disk E:\virtual machines\DC1\DC1.vmdk in progress...
\[2006-03-23 09:48:14.607 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.607 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 0% completed
\[2006-03-23 09:48:14.607 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 0% completed
\[2006-03-23 09:48:14.607 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.607 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.607 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.106 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.126 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:14.627 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:32.187 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 1% completed
\[2006-03-23 09:48:32.187 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:32.187 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 1% completed
\[2006-03-23 09:48:32.187 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 1% completed
\[2006-03-23 09:48:32.187 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:32.197 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 1% completed
\[2006-03-23 09:48:32.197 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:32.197 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:32.207 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:32.217 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.647 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 2% completed
\[2006-03-23 09:48:49.647 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.647 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 2% completed
\[2006-03-23 09:48:49.647 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 2% completed
\[2006-03-23 09:48:49.657 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.657 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 2% completed
\[2006-03-23 09:48:49.657 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 6% completed
\[2006-03-23 09:48:49.657 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 5% completed
\[2006-03-23 09:48:49.657 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.667 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.667 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.657 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:48:49.677 'App' 2896 info] \[vmiSupport,477] import 5% complete.
\[2006-03-23 09:48:49.677 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.667 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 3% completed
\[2006-03-23 09:49:06.677 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 3% completed
\[2006-03-23 09:49:06.677 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 3% completed
\[2006-03-23 09:49:06.677 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.677 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.687 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.687 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 3% completed
\[2006-03-23 09:49:06.687 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 7% completed
\[2006-03-23 09:49:06.687 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 6% completed
\[2006-03-23 09:49:06.687 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.687 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.687 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.687 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:06.707 'App' 2896 info] \[vmiSupport,477] import 6% complete.
\[2006-03-23 09:49:06.707 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.079 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 4% completed
\[2006-03-23 09:49:25.079 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 4% completed
\[2006-03-23 09:49:25.089 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 4% completed
\[2006-03-23 09:49:25.089 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.089 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.089 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.099 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 4% completed
\[2006-03-23 09:49:25.099 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 8% completed
\[2006-03-23 09:49:25.099 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 7% completed
\[2006-03-23 09:49:25.099 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.109 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.109 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.099 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.119 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:25.139 'App' 2896 info] \[vmiSupport,477] import 7% complete.
\[2006-03-23 09:49:25.139 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.375 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 5% completed
\[2006-03-23 09:49:46.385 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 5% completed
\[2006-03-23 09:49:46.385 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 5% completed
\[2006-03-23 09:49:46.385 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.385 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.395 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.405 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 5% completed
\[2006-03-23 09:49:46.405 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 9% completed
\[2006-03-23 09:49:46.405 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 8% completed
\[2006-03-23 09:49:46.405 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.405 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.415 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.395 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.415 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:49:46.435 'App' 2896 info] \[vmiSupport,477] import 8% complete.
\[2006-03-23 09:49:46.435 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.370 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 6% completed
\[2006-03-23 09:50:06.370 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 6% completed
\[2006-03-23 09:50:06.370 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 6% completed
\[2006-03-23 09:50:06.370 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.370 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.380 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.380 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 6% completed
\[2006-03-23 09:50:06.380 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 10% completed
\[2006-03-23 09:50:06.380 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 9% completed
\[2006-03-23 09:50:06.380 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.380 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.380 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.380 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:06.400 'App' 2896 info] \[vmiSupport,477] import 9% complete.
\[2006-03-23 09:50:06.400 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.303 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 7% completed
\[2006-03-23 09:50:25.313 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 7% completed
\[2006-03-23 09:50:25.313 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 7% completed
\[2006-03-23 09:50:25.313 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.313 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.313 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.323 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 7% completed
\[2006-03-23 09:50:25.323 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 11% completed
\[2006-03-23 09:50:25.323 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 10% completed
\[2006-03-23 09:50:25.323 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.323 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.333 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.333 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:25.353 'App' 2896 info] \[vmiSupport,477] import 10% complete.
\[2006-03-23 09:50:25.353 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.434 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 8% completed
\[2006-03-23 09:50:43.444 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 8% completed
\[2006-03-23 09:50:43.444 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 8% completed
\[2006-03-23 09:50:43.444 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.444 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.444 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.454 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 8% completed
\[2006-03-23 09:50:43.454 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 12% completed
\[2006-03-23 09:50:43.454 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 11% completed
\[2006-03-23 09:50:43.454 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.464 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.464 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.454 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:50:43.484 'App' 2896 info] \[vmiSupport,477] import 11% complete.
\[2006-03-23 09:50:43.484 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.152 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 9% completed
\[2006-03-23 09:51:02.162 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 9% completed
\[2006-03-23 09:51:02.162 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 9% completed
\[2006-03-23 09:51:02.162 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.162 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.182 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.182 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 9% completed
\[2006-03-23 09:51:02.192 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 13% completed
\[2006-03-23 09:51:02.192 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 12% completed
\[2006-03-23 09:51:02.192 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.192 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.192 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.172 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.182 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:02.202 'App' 2896 info] \[vmiSupport,477] import 12% complete.
\[2006-03-23 09:51:02.202 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.156 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 10% completed
\[2006-03-23 09:51:25.166 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 11% completed
\[2006-03-23 09:51:25.166 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 10% completed
\[2006-03-23 09:51:25.166 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 10% completed
\[2006-03-23 09:51:25.166 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.166 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.176 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 11% completed
\[2006-03-23 09:51:25.176 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 11% completed
\[2006-03-23 09:51:25.176 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.176 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 10% completed
\[2006-03-23 09:51:25.176 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 14% completed
\[2006-03-23 09:51:25.176 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 13% completed
\[2006-03-23 09:51:25.176 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.186 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.186 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 11% completed
\[2006-03-23 09:51:25.186 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 15% completed
\[2006-03-23 09:51:25.186 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 14% completed
\[2006-03-23 09:51:25.196 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.196 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.216 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.176 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.206 'App' 2896 info] \[vmiSupport,477] import 13% complete.
\[2006-03-23 09:51:25.206 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:25.226 'App' 2896 info] \[vmiSupport,477] import 14% complete.
\[2006-03-23 09:51:25.226 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.395 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 12% completed
\[2006-03-23 09:51:45.405 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 12% completed
\[2006-03-23 09:51:45.405 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 12% completed
\[2006-03-23 09:51:45.405 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.405 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.425 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.425 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 12% completed
\[2006-03-23 09:51:45.425 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 16% completed
\[2006-03-23 09:51:45.425 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 15% completed
\[2006-03-23 09:51:45.435 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.435 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.435 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.415 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.435 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:51:45.525 'App' 2896 info] \[vmiSupport,477] import 15% complete.
\[2006-03-23 09:51:45.525 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.065 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 13% completed
\[2006-03-23 09:52:06.065 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 13% completed
\[2006-03-23 09:52:06.065 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 13% completed
\[2006-03-23 09:52:06.065 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.065 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.075 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.085 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 13% completed
\[2006-03-23 09:52:06.085 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 17% completed
\[2006-03-23 09:52:06.085 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 16% completed
\[2006-03-23 09:52:06.085 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.095 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.105 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.075 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.095 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:06.115 'App' 2896 info] \[vmiSupport,477] import 16% complete.
\[2006-03-23 09:52:06.115 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.361 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 14% completed
\[2006-03-23 09:52:23.371 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 14% completed
\[2006-03-23 09:52:23.371 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 14% completed
\[2006-03-23 09:52:23.371 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.371 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.371 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.381 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 14% completed
\[2006-03-23 09:52:23.381 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 18% completed
\[2006-03-23 09:52:23.381 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 17% completed
\[2006-03-23 09:52:23.381 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.381 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.391 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.391 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:23.411 'App' 2896 info] \[vmiSupport,477] import 17% complete.
\[2006-03-23 09:52:23.411 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.066 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 15% completed
\[2006-03-23 09:52:41.066 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 15% completed
\[2006-03-23 09:52:41.066 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 15% completed
\[2006-03-23 09:52:41.066 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.076 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.076 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.086 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 15% completed
\[2006-03-23 09:52:41.086 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 19% completed
\[2006-03-23 09:52:41.086 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 18% completed
\[2006-03-23 09:52:41.086 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.096 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.096 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.076 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.106 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:41.127 'App' 2896 info] \[vmiSupport,477] import 18% complete.
\[2006-03-23 09:52:41.127 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.652 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 16% completed
\[2006-03-23 09:52:58.662 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 16% completed
\[2006-03-23 09:52:58.662 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 16% completed
\[2006-03-23 09:52:58.662 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.662 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.672 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.682 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 16% completed
\[2006-03-23 09:52:58.682 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 20% completed
\[2006-03-23 09:52:58.682 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 19% completed
\[2006-03-23 09:52:58.682 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.682 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.702 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.672 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.692 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:52:58.712 'App' 2896 info] \[vmiSupport,477] import 19% complete.
\[2006-03-23 09:52:58.712 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:14.665 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 17% completed
\[2006-03-23 09:53:14.665 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 17% completed
\[2006-03-23 09:53:14.665 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 17% completed
\[2006-03-23 09:53:14.665 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:14.675 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:14.675 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:14.685 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 17% completed
\[2006-03-23 09:53:14.685 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 21% completed
\[2006-03-23 09:53:14.685 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:14.685 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:14.695 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.408 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 18% completed
\[2006-03-23 09:53:30.408 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 18% completed
\[2006-03-23 09:53:30.408 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 18% completed
\[2006-03-23 09:53:30.408 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.408 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.418 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.418 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 18% completed
\[2006-03-23 09:53:30.418 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 22% completed
\[2006-03-23 09:53:30.418 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 20% completed
\[2006-03-23 09:53:30.428 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.428 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.428 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.428 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:30.458 'App' 2896 info] \[vmiSupport,477] import 20% complete.
\[2006-03-23 09:53:30.458 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.837 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 19% completed
\[2006-03-23 09:53:49.847 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 19% completed
\[2006-03-23 09:53:49.847 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 19% completed
\[2006-03-23 09:53:49.847 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.847 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.857 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.867 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 19% completed
\[2006-03-23 09:53:49.867 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 23% completed
\[2006-03-23 09:53:49.867 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 21% completed
\[2006-03-23 09:53:49.867 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.867 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.877 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.857 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:53:49.887 'App' 2896 info] \[vmiSupport,477] import 21% complete.
\[2006-03-23 09:53:49.887 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.125 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 20% completed
\[2006-03-23 09:54:09.135 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 20% completed
\[2006-03-23 09:54:09.135 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 20% completed
\[2006-03-23 09:54:09.135 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.135 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.135 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.135 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 20% completed
\[2006-03-23 09:54:09.135 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 24% completed
\[2006-03-23 09:54:09.135 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 22% completed
\[2006-03-23 09:54:09.135 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.145 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.145 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.145 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:09.165 'App' 2896 info] \[vmiSupport,477] import 22% complete.
\[2006-03-23 09:54:09.165 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:26.740 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 21% completed
\[2006-03-23 09:54:26.740 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 21% completed
\[2006-03-23 09:54:26.740 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 21% completed
\[2006-03-23 09:54:26.740 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:26.740 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:26.751 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:26.751 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 21% completed
\[2006-03-23 09:54:26.751 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:26.751 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:26.771 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.431 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 22% completed
\[2006-03-23 09:54:47.441 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 22% completed
\[2006-03-23 09:54:47.441 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 22% completed
\[2006-03-23 09:54:47.441 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.441 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.451 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.451 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 22% completed
\[2006-03-23 09:54:47.451 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 25% completed
\[2006-03-23 09:54:47.451 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 23% completed
\[2006-03-23 09:54:47.461 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.461 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.471 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.451 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:54:47.481 'App' 2896 info] \[vmiSupport,477] import 23% complete.
\[2006-03-23 09:54:47.481 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.201 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 23% completed
\[2006-03-23 09:55:08.201 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 23% completed
\[2006-03-23 09:55:08.201 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 23% completed
\[2006-03-23 09:55:08.211 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.211 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.221 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.221 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 23% completed
\[2006-03-23 09:55:08.221 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 26% completed
\[2006-03-23 09:55:08.221 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 24% completed
\[2006-03-23 09:55:08.221 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.231 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.231 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.211 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.241 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:08.251 'App' 2896 info] \[vmiSupport,477] import 24% complete.
\[2006-03-23 09:55:08.251 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.748 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 24% completed
\[2006-03-23 09:55:26.748 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 24% completed
\[2006-03-23 09:55:26.748 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 24% completed
\[2006-03-23 09:55:26.748 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.758 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.758 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.768 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 24% completed
\[2006-03-23 09:55:26.768 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 27% completed
\[2006-03-23 09:55:26.768 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 25% completed
\[2006-03-23 09:55:26.768 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.768 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.768 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.758 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.778 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:26.788 'App' 2896 info] \[vmiSupport,477] import 25% complete.
\[2006-03-23 09:55:26.788 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.679 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 25% completed
\[2006-03-23 09:55:54.689 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 25% completed
\[2006-03-23 09:55:54.689 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 25% completed
\[2006-03-23 09:55:54.689 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.689 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.699 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.709 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 25% completed
\[2006-03-23 09:55:54.709 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 28% completed
\[2006-03-23 09:55:54.709 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 26% completed
\[2006-03-23 09:55:54.709 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.719 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.719 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.699 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.729 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:55:54.749 'App' 2896 info] \[vmiSupport,477] import 26% complete.
\[2006-03-23 09:55:54.749 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.934 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 26% completed
\[2006-03-23 09:56:18.944 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 26% completed
\[2006-03-23 09:56:18.944 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 26% completed
\[2006-03-23 09:56:18.944 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.944 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.944 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.954 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 26% completed
\[2006-03-23 09:56:18.954 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 29% completed
\[2006-03-23 09:56:18.954 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 27% completed
\[2006-03-23 09:56:18.954 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.964 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.964 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.954 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.974 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:18.984 'App' 2896 info] \[vmiSupport,477] import 27% complete.
\[2006-03-23 09:56:18.984 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.873 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 27% completed
\[2006-03-23 09:56:38.873 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 27% completed
\[2006-03-23 09:56:38.873 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 27% completed
\[2006-03-23 09:56:38.873 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.873 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.883 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.893 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 27% completed
\[2006-03-23 09:56:38.893 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 30% completed
\[2006-03-23 09:56:38.893 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 28% completed
\[2006-03-23 09:56:38.893 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.893 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.893 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.883 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.893 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:56:38.913 'App' 2896 info] \[vmiSupport,477] import 28% complete.
\[2006-03-23 09:56:38.913 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.464 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 28% completed
\[2006-03-23 09:57:13.464 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 28% completed
\[2006-03-23 09:57:13.474 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 28% completed
\[2006-03-23 09:57:13.474 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.474 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.484 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.484 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 28% completed
\[2006-03-23 09:57:13.484 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 31% completed
\[2006-03-23 09:57:13.484 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 29% completed
\[2006-03-23 09:57:13.484 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.484 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.494 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.474 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.494 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:13.504 'App' 2896 info] \[vmiSupport,477] import 29% complete.
\[2006-03-23 09:57:13.504 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.041 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 29% completed
\[2006-03-23 09:57:39.041 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 29% completed
\[2006-03-23 09:57:39.041 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 29% completed
\[2006-03-23 09:57:39.041 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.041 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.041 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.051 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 29% completed
\[2006-03-23 09:57:39.051 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 32% completed
\[2006-03-23 09:57:39.051 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 30% completed
\[2006-03-23 09:57:39.061 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.061 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.061 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.051 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.061 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:57:39.081 'App' 2896 info] \[vmiSupport,477] import 30% complete.
\[2006-03-23 09:57:39.081 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.507 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 30% completed
\[2006-03-23 09:58:03.517 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 30% completed
\[2006-03-23 09:58:03.517 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 30% completed
\[2006-03-23 09:58:03.517 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.517 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.517 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.517 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 30% completed
\[2006-03-23 09:58:03.527 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 33% completed
\[2006-03-23 09:58:03.527 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 31% completed
\[2006-03-23 09:58:03.527 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.527 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.527 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.527 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:03.547 'App' 2896 info] \[vmiSupport,477] import 31% complete.
\[2006-03-23 09:58:03.547 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.082 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 31% completed
\[2006-03-23 09:58:21.092 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 31% completed
\[2006-03-23 09:58:21.092 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 31% completed
\[2006-03-23 09:58:21.092 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.092 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.092 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.102 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 31% completed
\[2006-03-23 09:58:21.102 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 34% completed
\[2006-03-23 09:58:21.102 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 32% completed
\[2006-03-23 09:58:21.102 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.102 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.102 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.102 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:21.122 'App' 2896 info] \[vmiSupport,477] import 32% complete.
\[2006-03-23 09:58:21.122 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:38.998 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 32% completed
\[2006-03-23 09:58:38.998 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 32% completed
\[2006-03-23 09:58:38.998 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 32% completed
\[2006-03-23 09:58:39.008 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.008 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.008 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.028 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 32% completed
\[2006-03-23 09:58:39.028 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 35% completed
\[2006-03-23 09:58:39.028 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 33% completed
\[2006-03-23 09:58:39.028 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.028 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.038 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.018 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.038 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:39.058 'App' 2896 info] \[vmiSupport,477] import 33% complete.
\[2006-03-23 09:58:39.058 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.226 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 33% completed
\[2006-03-23 09:58:58.236 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 33% completed
\[2006-03-23 09:58:58.236 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 33% completed
\[2006-03-23 09:58:58.236 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.236 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.236 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.236 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 33% completed
\[2006-03-23 09:58:58.236 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 36% completed
\[2006-03-23 09:58:58.246 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 34% completed
\[2006-03-23 09:58:58.246 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.246 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.246 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.246 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:58:58.266 'App' 2896 info] \[vmiSupport,477] import 34% complete.
\[2006-03-23 09:58:58.266 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.151 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 34% completed
\[2006-03-23 09:59:15.151 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 34% completed
\[2006-03-23 09:59:15.151 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 34% completed
\[2006-03-23 09:59:15.151 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.151 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.151 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.161 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 34% completed
\[2006-03-23 09:59:15.161 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 37% completed
\[2006-03-23 09:59:15.161 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 35% completed
\[2006-03-23 09:59:15.161 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.161 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.171 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.161 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:15.181 'App' 2896 info] \[vmiSupport,477] import 35% complete.
\[2006-03-23 09:59:15.181 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.979 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 35% completed
\[2006-03-23 09:59:40.979 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 35% completed
\[2006-03-23 09:59:40.979 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 35% completed
\[2006-03-23 09:59:40.979 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.979 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.979 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.999 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 35% completed
\[2006-03-23 09:59:40.999 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 38% completed
\[2006-03-23 09:59:40.999 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 36% completed
\[2006-03-23 09:59:40.999 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.999 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.989 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:40.999 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:41.009 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 09:59:41.019 'App' 2896 info] \[vmiSupport,477] import 36% complete.
\[2006-03-23 09:59:41.019 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.353 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 36% completed
\[2006-03-23 10:00:11.353 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 36% completed
\[2006-03-23 10:00:11.353 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 36% completed
\[2006-03-23 10:00:11.353 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.353 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.363 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.363 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 36% completed
\[2006-03-23 10:00:11.363 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 39% completed
\[2006-03-23 10:00:11.363 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 37% completed
\[2006-03-23 10:00:11.363 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.373 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.373 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.363 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:11.373 'App' 2896 info] \[vmiSupport,477] import 37% complete.
\[2006-03-23 10:00:11.373 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.262 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 37% completed
\[2006-03-23 10:00:31.262 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 37% completed
\[2006-03-23 10:00:31.262 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 37% completed
\[2006-03-23 10:00:31.262 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.262 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.312 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.312 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 37% completed
\[2006-03-23 10:00:31.312 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 40% completed
\[2006-03-23 10:00:31.312 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 38% completed
\[2006-03-23 10:00:31.312 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.332 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.332 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.312 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:31.382 'App' 2896 info] \[vmiSupport,477] import 38% complete.
\[2006-03-23 10:00:31.382 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:49.478 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 38% completed
\[2006-03-23 10:00:49.478 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 38% completed
\[2006-03-23 10:00:49.488 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 38% completed
\[2006-03-23 10:00:49.488 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:49.488 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:49.488 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:49.509 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 38% completed
\[2006-03-23 10:00:49.509 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 41% completed
\[2006-03-23 10:00:49.509 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:49.499 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:00:49.529 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.878 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 39% completed
\[2006-03-23 10:01:09.888 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 39% completed
\[2006-03-23 10:01:09.888 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 39% completed
\[2006-03-23 10:01:09.888 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.888 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.898 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.898 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 39% completed
\[2006-03-23 10:01:09.898 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 42% completed
\[2006-03-23 10:01:09.898 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 39% completed
\[2006-03-23 10:01:09.898 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.908 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.918 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.898 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:09.918 'App' 2896 info] \[vmiSupport,477] import 39% complete.
\[2006-03-23 10:01:09.918 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.637 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 40% completed
\[2006-03-23 10:01:29.637 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 40% completed
\[2006-03-23 10:01:29.637 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 40% completed
\[2006-03-23 10:01:29.637 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.637 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.657 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.657 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 40% completed
\[2006-03-23 10:01:29.657 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 43% completed
\[2006-03-23 10:01:29.657 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 40% completed
\[2006-03-23 10:01:29.657 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.667 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.667 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.647 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.657 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:29.677 'App' 2896 info] \[vmiSupport,477] import 40% complete.
\[2006-03-23 10:01:29.677 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:48.344 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 41% completed
\[2006-03-23 10:01:48.344 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 41% completed
\[2006-03-23 10:01:48.344 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 41% completed
\[2006-03-23 10:01:48.344 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:48.354 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:48.354 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 41% completed
\[2006-03-23 10:01:48.354 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:48.354 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:48.354 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:01:48.374 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.843 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 42% completed
\[2006-03-23 10:02:07.853 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.853 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 42% completed
\[2006-03-23 10:02:07.853 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 42% completed
\[2006-03-23 10:02:07.853 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.863 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 42% completed
\[2006-03-23 10:02:07.873 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 44% completed
\[2006-03-23 10:02:07.873 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 41% completed
\[2006-03-23 10:02:07.873 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.873 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.873 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.873 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.863 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.883 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:07.893 'App' 2896 info] \[vmiSupport,477] import 41% complete.
\[2006-03-23 10:02:07.893 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.356 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 43% completed
\[2006-03-23 10:02:30.366 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.366 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 43% completed
\[2006-03-23 10:02:30.366 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 43% completed
\[2006-03-23 10:02:30.366 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.366 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 43% completed
\[2006-03-23 10:02:30.366 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 45% completed
\[2006-03-23 10:02:30.366 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 42% completed
\[2006-03-23 10:02:30.376 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.386 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.386 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.376 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:30.406 'App' 2896 info] \[vmiSupport,477] import 42% complete.
\[2006-03-23 10:02:30.406 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.840 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 44% completed
\[2006-03-23 10:02:53.840 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 44% completed
\[2006-03-23 10:02:53.840 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 44% completed
\[2006-03-23 10:02:53.840 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.840 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.860 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.860 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 44% completed
\[2006-03-23 10:02:53.860 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 46% completed
\[2006-03-23 10:02:53.860 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 43% completed
\[2006-03-23 10:02:53.860 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.870 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.870 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.850 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.870 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:02:53.890 'App' 2896 info] \[vmiSupport,477] import 43% complete.
\[2006-03-23 10:02:53.890 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.304 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 45% completed
\[2006-03-23 10:03:17.314 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 45% completed
\[2006-03-23 10:03:17.314 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 45% completed
\[2006-03-23 10:03:17.314 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.314 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.334 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.334 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 45% completed
\[2006-03-23 10:03:17.334 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 47% completed
\[2006-03-23 10:03:17.334 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 44% completed
\[2006-03-23 10:03:17.334 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.344 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.344 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.324 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.344 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:17.364 'App' 2896 info] \[vmiSupport,477] import 44% complete.
\[2006-03-23 10:03:17.364 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.381 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 46% completed
\[2006-03-23 10:03:42.381 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 46% completed
\[2006-03-23 10:03:42.381 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 46% completed
\[2006-03-23 10:03:42.381 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.381 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.381 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.391 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 46% completed
\[2006-03-23 10:03:42.391 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 48% completed
\[2006-03-23 10:03:42.391 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 45% completed
\[2006-03-23 10:03:42.391 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.391 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.391 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.401 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:03:42.411 'App' 2896 info] \[vmiSupport,477] import 45% complete.
\[2006-03-23 10:03:42.411 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.871 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 47% completed
\[2006-03-23 10:04:16.871 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 47% completed
\[2006-03-23 10:04:16.871 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 47% completed
\[2006-03-23 10:04:16.871 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.871 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.881 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.881 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 47% completed
\[2006-03-23 10:04:16.881 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 49% completed
\[2006-03-23 10:04:16.881 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 46% completed
\[2006-03-23 10:04:16.881 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.941 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.941 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.931 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:16.951 'App' 2896 info] \[vmiSupport,477] import 46% complete.
\[2006-03-23 10:04:16.951 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:42.989 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 48% completed
\[2006-03-23 10:04:42.999 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 48% completed
\[2006-03-23 10:04:42.999 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 48% completed
\[2006-03-23 10:04:42.999 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:42.999 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:42.999 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:43.009 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 48% completed
\[2006-03-23 10:04:43.009 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 50% completed
\[2006-03-23 10:04:43.009 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 47% completed
\[2006-03-23 10:04:43.009 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:43.019 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:43.019 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:43.009 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:43.019 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:04:43.039 'App' 2896 info] \[vmiSupport,477] import 47% complete.
\[2006-03-23 10:04:43.039 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:06.984 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 49% completed
\[2006-03-23 10:05:06.994 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 49% completed
\[2006-03-23 10:05:06.994 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 49% completed
\[2006-03-23 10:05:06.994 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:06.994 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:06.994 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:06.994 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 49% completed
\[2006-03-23 10:05:07.004 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 51% completed
\[2006-03-23 10:05:07.004 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 48% completed
\[2006-03-23 10:05:07.004 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:07.004 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:07.004 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:07.004 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:07.024 'App' 2896 info] \[vmiSupport,477] import 48% complete.
\[2006-03-23 10:05:07.024 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.883 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 50% completed
\[2006-03-23 10:05:26.883 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 50% completed
\[2006-03-23 10:05:26.883 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 50% completed
\[2006-03-23 10:05:26.893 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.893 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.893 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.903 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 50% completed
\[2006-03-23 10:05:26.903 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 52% completed
\[2006-03-23 10:05:26.903 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 49% completed
\[2006-03-23 10:05:26.903 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.903 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.913 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.893 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.913 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:26.923 'App' 2896 info] \[vmiSupport,477] import 49% complete.
\[2006-03-23 10:05:26.923 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.400 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 51% completed
\[2006-03-23 10:05:45.410 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 51% completed
\[2006-03-23 10:05:45.410 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 51% completed
\[2006-03-23 10:05:45.410 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.410 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.410 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.430 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 51% completed
\[2006-03-23 10:05:45.430 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 53% completed
\[2006-03-23 10:05:45.430 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 50% completed
\[2006-03-23 10:05:45.430 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.430 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.430 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.420 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.450 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:05:45.460 'App' 2896 info] \[vmiSupport,477] import 50% complete.
\[2006-03-23 10:05:45.460 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.368 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 52% completed
\[2006-03-23 10:06:04.368 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 52% completed
\[2006-03-23 10:06:04.368 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 52% completed
\[2006-03-23 10:06:04.368 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.368 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.378 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.388 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 52% completed
\[2006-03-23 10:06:04.388 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 54% completed
\[2006-03-23 10:06:04.388 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 51% completed
\[2006-03-23 10:06:04.388 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.388 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.398 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.378 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:04.408 'App' 2896 info] \[vmiSupport,477] import 51% complete.
\[2006-03-23 10:06:04.408 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.212 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 53% completed
\[2006-03-23 10:06:21.212 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 53% completed
\[2006-03-23 10:06:21.212 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 53% completed
\[2006-03-23 10:06:21.212 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.212 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.222 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.232 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 53% completed
\[2006-03-23 10:06:21.232 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 55% completed
\[2006-03-23 10:06:21.232 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 52% completed
\[2006-03-23 10:06:21.232 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.232 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.242 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.222 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.232 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:21.242 'App' 2896 info] \[vmiSupport,477] import 52% complete.
\[2006-03-23 10:06:21.242 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.809 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 54% completed
\[2006-03-23 10:06:39.809 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 54% completed
\[2006-03-23 10:06:39.809 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 54% completed
\[2006-03-23 10:06:39.809 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.819 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.819 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.829 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 54% completed
\[2006-03-23 10:06:39.829 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 56% completed
\[2006-03-23 10:06:39.829 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 53% completed
\[2006-03-23 10:06:39.829 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.829 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.829 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.819 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.839 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:39.849 'App' 2896 info] \[vmiSupport,477] import 53% complete.
\[2006-03-23 10:06:39.849 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.685 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 55% completed
\[2006-03-23 10:06:57.685 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 55% completed
\[2006-03-23 10:06:57.685 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 55% completed
\[2006-03-23 10:06:57.685 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.695 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.695 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.705 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 55% completed
\[2006-03-23 10:06:57.705 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 57% completed
\[2006-03-23 10:06:57.705 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 54% completed
\[2006-03-23 10:06:57.705 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.705 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.705 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.695 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.715 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:06:57.725 'App' 2896 info] \[vmiSupport,477] import 54% complete.
\[2006-03-23 10:06:57.725 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.855 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 56% completed
\[2006-03-23 10:07:17.865 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 56% completed
\[2006-03-23 10:07:17.865 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 56% completed
\[2006-03-23 10:07:17.865 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.865 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.865 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.875 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 56% completed
\[2006-03-23 10:07:17.875 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 58% completed
\[2006-03-23 10:07:17.875 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 55% completed
\[2006-03-23 10:07:17.875 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.875 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.875 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.875 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:17.895 'App' 2896 info] \[vmiSupport,477] import 55% complete.
\[2006-03-23 10:07:17.895 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.968 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 57% completed
\[2006-03-23 10:07:33.968 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 57% completed
\[2006-03-23 10:07:33.968 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 57% completed
\[2006-03-23 10:07:33.968 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.968 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.988 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.988 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 57% completed
\[2006-03-23 10:07:33.988 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 59% completed
\[2006-03-23 10:07:33.988 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 56% completed
\[2006-03-23 10:07:33.988 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.988 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.988 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:33.978 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:34.008 'App' 2896 info] \[vmiSupport,477] import 56% complete.
\[2006-03-23 10:07:34.008 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.622 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 58% completed
\[2006-03-23 10:07:50.632 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 58% completed
\[2006-03-23 10:07:50.632 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 58% completed
\[2006-03-23 10:07:50.632 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.632 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.632 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.652 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 58% completed
\[2006-03-23 10:07:50.652 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 60% completed
\[2006-03-23 10:07:50.652 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 57% completed
\[2006-03-23 10:07:50.652 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.663 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.673 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.642 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.663 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:07:50.683 'App' 2896 info] \[vmiSupport,477] import 57% complete.
\[2006-03-23 10:07:50.683 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:09.280 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 59% completed
\[2006-03-23 10:08:09.280 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 59% completed
\[2006-03-23 10:08:09.280 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 59% completed
\[2006-03-23 10:08:09.280 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:09.280 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:09.280 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:09.290 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 59% completed
\[2006-03-23 10:08:09.290 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 61% completed
\[2006-03-23 10:08:09.290 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:09.290 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:09.310 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.117 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 60% completed
\[2006-03-23 10:08:28.127 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 60% completed
\[2006-03-23 10:08:28.127 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 60% completed
\[2006-03-23 10:08:28.127 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.127 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.137 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.147 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 60% completed
\[2006-03-23 10:08:28.147 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 62% completed
\[2006-03-23 10:08:28.147 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 58% completed
\[2006-03-23 10:08:28.147 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.147 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.157 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.147 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:28.167 'App' 2896 info] \[vmiSupport,477] import 58% complete.
\[2006-03-23 10:08:28.167 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:45.162 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 61% completed
\[2006-03-23 10:08:45.172 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 61% completed
\[2006-03-23 10:08:45.172 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 61% completed
\[2006-03-23 10:08:45.172 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:45.172 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:45.172 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:45.192 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 61% completed
\[2006-03-23 10:08:45.192 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:45.182 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:08:45.232 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.490 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 62% completed
\[2006-03-23 10:09:04.500 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 62% completed
\[2006-03-23 10:09:04.500 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 62% completed
\[2006-03-23 10:09:04.500 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.500 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.500 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.500 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 62% completed
\[2006-03-23 10:09:04.500 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 63% completed
\[2006-03-23 10:09:04.500 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 59% completed
\[2006-03-23 10:09:04.510 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.510 'App' 2256 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.510 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.510 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:04.530 'App' 2896 info] \[vmiSupport,477] import 59% complete.
\[2006-03-23 10:09:04.530 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:07.274 'App' 1452 verbose] \[imageProcessingTaskStep,294] copy local->local: 100% completed
\[2006-03-23 10:09:07.274 'App' 1452 error] \[blockLevelCloningTaskImpl,518] File transfer failed.
Src error: File error -- Failed to read the file: An error was detected
\[2006-03-23 10:09:07.274 'App' 1452 error] \[imageProcessingTaskImpl,581] Image processing task has failed with a fault: sysimage.fault.NfcConnectionFault
\[2006-03-23 10:09:07.274 'App' 1452 verbose] \[imageProcessingTaskWrapper,697] Task completed.
\[2006-03-23 10:09:07.274 'App' 2256 verbose] \[imageProcessingTaskStep,294] Cloning into disk E:\virtual machines\DC1\DC1.vmdk: 100% completed
\[2006-03-23 10:09:07.284 'App' 2256 verbose] \[imageProcessingTaskStep,294] cloning target disks: 100% completed
\[2006-03-23 10:09:07.284 'App' 2256 error] \[imageProcessingTaskStep,394] Cloning into disk E:\virtual machines\DC1\DC1.vmdk failed
\[2006-03-23 10:09:07.284 'App' 2256 verbose] \[imageProcessingTaskWrapper,827] Reporting image processing task failure...
\[2006-03-23 10:09:07.284 'App' 832 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:07.284 'App' 2256 error] \[imageProcessingTaskWrapper,834] ImageProcessingTask FAILED. Fault name: sysimage.fault.NfcConnectionFault
\[2006-03-23 10:09:07.284 'App' 1452 verbose] \[disposable,109] object explicitly disposed: BlockLevelCloning::task\{5}
\[2006-03-23 10:09:07.284 'App' 1452 verbose] \[disposable,45] disposable object being destroyed: BlockLevelCloning::task\{5}
\[2006-03-23 10:09:07.284 'App' 2256 error] \[cloneTask,93] Cloning failed!
\[2006-03-23 10:09:07.284 'App' 2256 error] \[imageProcessingTaskImpl,581] Image processing task has failed with a fault: sysimage.fault.NfcConnectionFault
\[2006-03-23 10:09:07.294 'App' 2256 verbose] \[imageProcessingTaskWrapper,697] Task completed.
\[2006-03-23 10:09:07.294 'App' 832 verbose] \[imageProcessingTaskStep,294] clone to VM: 100% completed
\[2006-03-23 10:09:07.294 'App' 832 verbose] \[imageProcessingTaskStep,294] create and clone to VM: 100% completed
\[2006-03-23 10:09:07.294 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 95% completed
\[2006-03-23 10:09:07.294 'App' 832 error] \[imageProcessingTaskStep,394] clone to VM failed
\[2006-03-23 10:09:07.294 'App' 832 verbose] \[imageProcessingTaskWrapper,827] Reporting image processing task failure...
\[2006-03-23 10:09:07.304 'App' 832 error] \[imageProcessingTaskWrapper,834] ImageProcessingTask FAILED. Fault name: sysimage.fault.NfcConnectionFault
\[2006-03-23 10:09:07.304 'App' 1452 verbose] \[disposable,109] object explicitly disposed: CloneTask::task\{4}
\[2006-03-23 10:09:07.304 'App' 1452 verbose] \[disposable,45] disposable object being destroyed: CloneTask::task\{4}
\[2006-03-23 10:09:07.304 'App' 832 error] \[vmiImportTask,711] Cloning failed
\[2006-03-23 10:09:07.304 'App' 832 verbose] \[imageProcessingTaskStep,294] Clone and reconfigure VM: 100% completed
\[2006-03-23 10:09:07.304 'App' 1452 verbose] \[disposable,109] object explicitly disposed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 10:09:07.314 'App' 3520 verbose] \[disposable,109] object explicitly disposed: virtVol=\{computer=,1} \[2006-03-23 10:09:07.505 'App' 3520 verbose] \[disposable,45] disposable object being destroyed: virtVol=\{computer=,1}
\[2006-03-23 10:09:07.505 'App' 3520 verbose] \[disposable,109] object explicitly disposed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 10:09:07.505 'App' 3520 verbose] \[disposable,45] disposable object being destroyed: computer=\{2412d4e6e6877d384d65ad16594963c16161fae}
\[2006-03-23 10:09:07.555 'App' 3520 verbose] \[disposable,45] disposable object being destroyed: disk=\{5e5668d9c135ee75dd116d57075dc57226cef0}
\[2006-03-23 10:09:07.555 'App' 832 info] \[vmiUtils,219] Deleting D:\drive-0.vmdk
\[2006-03-23 10:09:07.565 'App' 832 info] \[vmiUtils,224] Deleting D:\drive-0.vmdk.rdonly
\[2006-03-23 10:09:07.565 'App' 832 error] \[imageProcessingTaskImpl,581] Image processing task has failed with a fault: sysimage.fault.CloneFault
\[2006-03-23 10:09:07.665 'App' 3524 verbose] \[disposable,109] object explicitly disposed: VmiImportTask::task\{3}
\[2006-03-23 10:09:07.665 'App' 3524 verbose] \[disposable,45] disposable object being destroyed: VmiImportTask::task\{3}
\[2006-03-23 10:09:07.815 'App' 3524 info] \[vmImporter,279] Found UFAD log at .\vmware-ufad.log-0.log
\[2006-03-23 10:09:07.815 'App' 3524 info] \[vmImporter,302] Found VMOUNT log at C:\WINDOWS\TEMP\vmware-vmount.log
\[03/23/06 10:09:07] Status: (VmountDevice:147) device of type volume with offset 32256 was closed
\[03/23/06 10:09:07] Status: (VmountDisk:414) disk D:\drive-0.vmdk was closed
\[2006-03-23 10:09:07.294 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:07.314 'App' 2896 info] \[vmiSupport,477] import 95% complete.
\[2006-03-23 10:09:07.314 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:07.334 'App' 2896 info] \[vmiSupport,477] import 100% complete.
\[2006-03-23 10:09:07.334 'App' 2896 verbose] \[imageProcessingTaskWrapper,358] Waiting for updates...
\[2006-03-23 10:09:07.575 'App' 1464 verbose] \[imageProcessingTaskWrapper,697] Task completed.