vCenter

 View Only
  • 1.  Converter Failure

    Posted Jun 18, 2007 06:05 PM

    I am attempting to convert a Windows 2000 SP 3 machine. Looks like the error that may be stopping it is an NFC error. Any ideas?

    I tried the migration a few times.

    Agent log:

    Log for VMware Converter, pid=1348, version=3.0.0, build=build-39557, option=Release, section=2

    \[2007-06-17 12:11:21.000 'App' 1336 warning] Failed to create console writer

    \[??] \[2007-06-17 12:11:21.015 'App' 1444 info] Current working directory: C:\Program Files\VMware\VMware Converter

    \[??] \[2007-06-17 12:11:21.015 'ThreadPool' 1444 verbose] TaskMax=40, IoMin=1, IoMax=81

    \[2007-06-17 12:11:21.031 'App' 1444 info] Trying ufa-agent

    \[2007-06-17 12:11:21.031 'App' 1444 info] Trying ufa-client

    \[2007-06-17 12:11:21.031 'App' 1444 info] Trying ufa-slave

    \[2007-06-17 12:11:21.031 'App' 1444 info] Trying ufa-sysMigration

    \[2007-06-17 12:11:21.031 'App' 1444 info] Trying ufa-sysReconfig

    \[2007-06-17 12:11:21.031 'App' 1444 info] Trying ufa-vmImporter

    \[2007-06-17 12:11:21.031 'App' 1444 verbose] Plugin 0 path: ufa-agent.dll

    \[2007-06-17 12:11:21.031 'App' 1444 verbose] Plugin 0 absolute path: C:\Program Files\VMware\VMware Converter\ufa-agent.dll

    \[2007-06-17 12:11:22.367 'Ufa' 1444 info] Plugin initialized

    \[2007-06-17 12:11:22.367 'App' 1444 verbose] Plugin 1 path: ufa-client.dll

    \[2007-06-17 12:11:22.367 'App' 1444 verbose] Plugin 1 absolute path: C:\Program Files\VMware\VMware Converter\ufa-client.dll

    \[2007-06-17 12:11:22.523 'App' 1444 info] \[plugin,104] UfaClientPlugin Init

    \[2007-06-17 12:11:22.523 'App' 1444 verbose] Plugin 2 path: ufa-slave.dll

    \[2007-06-17 12:11:22.523 'App' 1444 verbose] Plugin 2 absolute path: C:\Program Files\VMware\VMware Converter\ufa-slave.dll

    \[2007-06-17 12:11:23.335 'App' 1444 info] \[osInfoWin32,112] Microsoft Windows 2000

    \[2007-06-17 12:11:23.335 'App' 1444 info] \[osInfoWin32,193] Server

    \[2007-06-17 12:11:23.335 'App' 1444 info] \[osInfoWin32,269] Service Pack 4(Build 2195)

    \[2007-06-17 12:11:23.335 'App' 1444 info] \[plugin,117] UfaSlavePlugin Init

    \[2007-06-17 12:11:23.335 'App' 1444 verbose] Plugin 3 path: ufa-sysMigration.dll

    \[2007-06-17 12:11:23.335 'App' 1444 verbose] Plugin 3 absolute path: C:\Program Files\VMware\VMware Converter\ufa-sysMigration.dll

    \[??] \[2007-06-17 12:11:23.335 'App' 1724 info] \[stub,42] Using system libcrypto, version 90709F

    \[??] \[2007-06-17 12:11:23.335 'App' 1724 info] \[stub,42] NFC connection accept timeout: 180000 milliseconds

    \[??] \[2007-06-17 12:11:23.335 'App' 1724 info] \[stub,42] NFC request timeout: 180000 milliseconds

    \[??] \[2007-06-17 12:11:23.335 'App' 1724 info] \[stub,42] NFC read timeout: 60000 milliseconds

    \[??] \[2007-06-17 12:11:23.335 'App' 1724 info] \[stub,42] NFC write timeout: 600000 milliseconds

    \[2007-06-17 12:11:23.742 'App' 1444 info] \[,0] NFC connection accept timeout: 180000 milliseconds

    \[2007-06-17 12:11:23.742 'App' 1444 info] \[,0] NFC request timeout: 180000 milliseconds

    \[2007-06-17 12:11:23.742 'App' 1444 info] \[,0] NFC read timeout: 60000 milliseconds

    \[2007-06-17 12:11:23.742 'App' 1444 info] \[,0] NFC write timeout: 600000 milliseconds

    \[2007-06-17 12:11:23.757 'App' 1444 info] \[,0] Using system libcrypto, version 90709F

    \[2007-06-17 12:11:23.757 'App' 1444 info] \[plugin,166] SysMigrationPlugin Init

    \[2007-06-17 12:11:23.757 'App' 1444 verbose] Plugin 4 path: ufa-sysReconfig.dll

    \[2007-06-17 12:11:23.757 'App' 1444 verbose] Plugin 4 absolute path: C:\Program Files\VMware\VMware Converter\ufa-sysReconfig.dll

    \[2007-06-17 12:11:24.226 'App' 1444 info] \[plugin,102] SysReconfigPlugin Init

    \[2007-06-17 12:11:24.257 'App' 1444 info] \[utils,126] Using temp dir C:\WINNT\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig

    \[2007-06-17 12:11:24.851 'App' 1444 verbose] \[rdsXmlLoader,153] Loading reconfiguration data store XML from C:\WINNT\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig\reconfigurationDataStore.xml ...

    \[2007-06-17 12:11:24.867 'App' 1444 verbose] \[rdsXmlLoader,207] Populating ReconfigurationDataStore ...

    \[2007-06-17 12:11:24.867 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action LsiLogic

    \[2007-06-17 12:11:24.867 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_1000&dev_0030 patch: C_SYMMPI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\symmpi patch: S_SYMMPI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\symmpi.sys source: BASEOS sourceFileName: symmpi.sys

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action BuslogicMylex

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: enum\root\LEGACY_BUSLOGIC patch: L_BUSLOGIC

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: services\buslogic patch: S_BUSLOGIC

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\buslogic.sys source: VMWARE_DRIVERS_CAB sourceFileName: buslogic_for_nt.sys

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Buslogic_vmscsi

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_104b&dev_1040 patch: C_VMSCSI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E97B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMSCSI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\vmscsi patch: S_VMSCSI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\vmscsi.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmscsi.sys

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Buslogic_vmscsi_ACPI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\3&61aaa01&0&80 patch: E_VMSCSI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Buslogic_vmscsi_NotACPI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\2&ebb567f&0&80 patch: E_VMSCSI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Mouse_Keyboard

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0303 patch: C_PS2KBD

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E96B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2KBD

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\ACPI\PNP0303 patch: E_PS2KBD

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0f13 patch: C_PS2MOUSE

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E96F-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2MOUSE

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\ACPI\PNP0F13 patch: E_PS2MOUSE

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_8086&dev_7110&cc_0601 patch: C_ISAPNP

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action NT_SVGA_Registry_Updates

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: enum\root\LEGACY_VMX_SVGA patch: E_VMX_SVGA

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Common_SVGA_Updates

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E968-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMX_SVGA

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: INF\vmx_svga.inf source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.inf

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\vmx_fb.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_fb.dll

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\vmx_mode.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_mode.dll

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\vmx_svga.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.sys

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action SVGA_Registry_Updates_APCI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_15AD&DEV_0405&SUBSYS_040515AD&REV_00\3&61aaa01&0&78 patch: E_VMX_SVGA

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action SVGA_Registry_Updates_NotACPI

    \[2007-06-17 12:11:24.882 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_15AD&DEV_0405&SUBSYS_040515AD&REV_00\2&ebb567f&0&78 patch: E_VMX_SVGA

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Restore_i8042_NT5

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\i8042prt patch: SKUNKWORKS_S_I8042

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_vpcsrvc

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-driver-vpcsrvc patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-vpcsrvc patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\LEGACY_1-DRIVER-VPCSRVC patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\LEGACY_1-VPCSRVC patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_mrxvpc

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\MrxVPC patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_MRXVPC patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_vpcmap

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\VPCMap patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPCMAP patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_vpc-s3_NT4

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPC-S3 patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_vpc-s3_NT5

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_5333&DEV_8811&SUBSYS_00000000&REV_00\3&267a616a&0&40 patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\s3legacy patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_vmsrvc

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-driver-vmsrvc patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-DRIVER-VMSRVC patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-vmsrvc patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-VMSRVC patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_netfin

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_NETFIN patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\NetFin patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_cpqdmi

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQDMI patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\CPQDMI patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_cpqalert

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQALERT patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\CPQALERT patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\EventLog\System\CPQALERT patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action Deref_cpqagent

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\EventLog\System\Snmp patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.898 'App' 1444 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\Snmp patch: SKUNKWORKS_FILLER

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action PreserveDriveLetters

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action RemoveUnwantedKeys

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,282] Loading action FixSetupLog

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,459] String replace Update filePathInWinDir: repair\setup.log pattern:
    system32
    ntoskrnl.exe.*\.exe value:
    system32
    ntoskrnl.exe = "ntoskrnl.exe

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,459] String replace Update filePathInWinDir: repair\setup.log pattern:
    system32
    hal.dll.*\.dll value:
    system32
    hal.dll = "hal.dll

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin2k364Bit

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWinXP64Bit

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin64Bit

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition NotWin64Bit

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin2k3

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWinXP

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin2k

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWinNT4

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition NotWinNT4

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition ACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition NotACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition MultiProc

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition InstallSVGA

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition MustUpdateCoreNTFiles

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition GuestOsIncludesLsiLogicDriver

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition BuslogicMylex

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,493] Loading expression definition Buslogic_vmscsi

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration FixSetupLog

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action FixSetupLog

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration BuslogicMylex

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action BuslogicMylex

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration LsiLogic

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action LsiLogic

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Buslogic_vmscsi_ACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi_ACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Buslogic_vmscsi_NotACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi_NotACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Mouse_Keyboard_notNT_ACPI

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Mouse_Keyboard

    \[2007-06-17 12:11:24.914 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration SVGA_NT

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Common_SVGA_Updates

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action NT_SVGA_Registry_Updates

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration SVGA_notNT_ACPI

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Common_SVGA_Updates

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action SVGA_Registry_Updates_APCI

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration SVGA_notNT_NotACPI

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Common_SVGA_Updates

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action SVGA_Registry_Updates_NotACPI

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Restore_i8042_NT5

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Restore_i8042_NT5

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpcsrvc

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpcsrvc

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_mrxvpc

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_mrxvpc

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpcmap

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpcmap

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpc-s3_NT4

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpc-s3_NT4

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpc-s3_NT5

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpc-s3_NT5

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vmsrvc

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vmsrvc

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_netfin

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_netfin

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_cpqdmi

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_cpqdmi

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_cpqalert

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_cpqalert

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_cpqagent

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_cpqagent

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration PreserveDriveLetters

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action PreserveDriveLetters

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1055] Loading reconfiguration RemoveUnwantedKeys

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action RemoveUnwantedKeys

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

    \[2007-06-17 12:11:24.929 'App' 1444 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

    \[2007-06-17 12:11:24.945 'App' 1444 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

    \[2007-06-17 12:11:24.945 'App' 1444 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

    \[2007-06-17 12:11:24.945 'App' 1444 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

    \[2007-06-17 12:11:24.945 'App' 1444 info] \[utils,153] Cleaning up temp directory C:\WINNT\TEMP\vmware-temp\vmware-SYSTEM\sysReconfig ...

    \[2007-06-17 12:11:24.945 'App' 1444 verbose] Plugin 5 path: ufa-vmImporter.dll

    \[2007-06-17 12:11:24.945 'App' 1444 verbose] Plugin 5 absolute path: C:\Program Files\VMware\VMware Converter\ufa-vmImporter.dll

    \[2007-06-17 12:11:25.710 'App' 1444 info] \[plugin,111] VMImporterPlugin Init

    \[2007-06-17 12:11:25.710 'Ufa' 1444 info] Initializing SSL context

    \[2007-06-17 12:11:25.726 'BaseLibs' 1444 info] Using system libcrypto, version 90709F

    \[2007-06-17 12:11:25.835 'Ufa' 1444 info] VMDB pipe name:
    .\pipe\vmware-ufad-p2v30-vmdb, use SSL: true

    \[2007-06-17 12:11:25.835 'VmdbAdapter' 1444 info] Using new VMDB VMOMI serialization format

    \[2007-06-17 12:11:25.835 'Ufa' 1444 info] Plugin started

    \[2007-06-17 12:11:25.835 'App' 1444 info] \[plugin,119] UfaClientPlugin Start

    \[2007-06-17 12:11:25.835 'App' 1444 info] \[plugin,132] UfaSlavePlugin Start

    \[2007-06-17 12:11:25.835 'App' 1444 info] \[plugin,182] SysMigrationPlugin Start

    \[2007-06-17 12:11:25.835 'App' 1444 info] \[plugin,119] SysReconfigPlugin Start

    \[2007-06-17 12:11:25.835 'App' 1444 info] \[plugin,126] VMImporterPlugin Start

    \[2007-06-17 12:11:25.835 'App' 1444 info] \[serviceWin32,411] ufad-p2v service started

    \[#1] \[2007-06-17 12:14:58.945 'App' 1444 info] Impersonating user WinAdmin in session D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:14:58.945 'Vmsvc' 1444 info] VMServices Plugin initializing

    \[#1] \[2007-06-17 12:14:58.976 'App' 1444 info] \[,0] Msg_Reset:

    \[#1] \[msg.dictionary.load.statFailed] Unable to get information about file "C:\Documents and Settings\All Users.WINNT\Application Data\VMware\VMware Converter\config.ini": The system cannot find the file specified.

    \[#1] \----


    \[#1] \[2007-06-17 12:14:58.976 'App' 1444 info] \[,0] PREF Optional preferences file not found at C:\Documents and Settings\All Users.WINNT\Application Data\VMware\VMware Converter\config.ini. Using default values.

    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] Msg_Reset:

    \[#1] \[msg.dictionary.load.statFailed] Unable to get information about file "C:\Documents and Settings\All Users.WINNT\Application Data\VMware\VMware Converter\settings.ini": The system cannot find the file specified.

    \[#1] \----


    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] PREF Optional preferences file not found at C:\Documents and Settings\All Users.WINNT\Application Data\VMware\VMware Converter\settings.ini. Using default values.

    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] Msg_Reset:

    \[#1] \[msg.dictionary.load.statFailed] Unable to get information about file "C:\Documents and Settings\All Users.WINNT\Application Data\VMware\VMware Converter\config.ini": The system cannot find the file specified.

    \[#1] \----


    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] PREF Optional preferences file not found at C:\Documents and Settings\All Users.WINNT\Application Data\VMware\VMware Converter\config.ini. Using default values.

    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] Msg_Reset:

    \[#1] \----


    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] PREF Failed to find user preference file name.

    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] Msg_Reset:

    \[#1] \----


    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] PREF Failed to load user preferences.

    \[#1] \[2007-06-17 12:14:58.992 'App' 1444 info] \[,0] Using system libcrypto, version 90709F

    \[#1] \[2007-06-17 12:14:59.242 'App' 1444 warning] \[,0] VMHS: Failed to load vmnetMgr.dll

    \[#1] \[2007-06-17 12:14:59.242 'VmMisc' 1444 info] Max supported virtual machines: 1200

    \[??] \[2007-06-17 12:14:59.242 'App' 1352 info] \[,0] HOSTINFO 208654539688 @ 699320000Hz -> 0 @ 1000000Hz

    \[??] \[2007-06-17 12:14:59.242 'App' 1352 info] \[,0] HOSTINFO ((x * 3144516466) >> 41) + -298367756

    \[#1] \[2007-06-17 12:14:59.257 'App' 1912 info] \[vmImporter,133] Entering UFAD at QuerySourceInfo

    \[#1] \[2007-06-17 12:14:59.257 'App' 1912 verbose] \[vmImporter,134] source:

    \[#1] (sysimage.LiveSourceComputerSpec) {

    \[#1] dynamicType = ,

    \[#1] dynamicProperty = (vmodl.DynamicProperty) [],

    \[#1] host = "swifin01.ottawa.zarlink.com",

    \[#1] port = 0

    \[#1] }

    \[#1] \[2007-06-17 12:14:59.273 'App' 1912 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:14:59.273 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=VmiQuerySourceInfoTask::task\{0}

    \[#1] \[2007-06-17 12:14:59.273 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=VmiQuerySourceInfoTask::task\{0} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:14:59.289 'App' 2132 verbose] \[imageProcessingTaskImpl,504] VmiQuerySourceInfoTask::task\{0}: Starting execution of an image processing task

    \[#1] \[2007-06-17 12:14:59.289 'App' 2132 verbose] \[imageProcessingTaskImpl,154] VmiQuerySourceInfoTask::task\{0}: SetState to running

    \[#1] \[2007-06-17 12:14:59.289 'App' 2132 info] \[vmiQuerySourceInfoTask,79] VmiQuerySourceInfoTask initialized

    \[#1] \[2007-06-17 12:14:59.289 'App' 2132 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:14:59.289 'App' 2132 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

    \[#1] \[2007-06-17 12:14:59.289 'App' 2392 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#1] \[2007-06-17 12:14:59.289 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

    \[#1] \[2007-06-17 12:14:59.289 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

    \[#1] \[2007-06-17 12:14:59.304 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=3894 size=4096 err=0

    \[#1] \[2007-06-17 12:15:00.367 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:15:00.367 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:00.523 'App' 1444 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88bb-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:00.523 'App' 1444 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b8-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:00.523 'App' 1444 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b9-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:00.523 'App' 1444 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88ba-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:00.523 'App' 1444 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{a4365ee6-d08d-11da-bee9-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:00.523 'App' 1444 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:00.539 'App' 1444 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 1 at sector 63.

    \[#1] \[2007-06-17 12:15:01.945 'App' 1444 verbose] \[attachedComputerWin32,356] Accounted for candidate on disk 1 at sector 63

    \[#1] \[2007-06-17 12:15:03.867 'App' 1444 warning] \[attachedComputerWin32,567] 1

    \[#1] \[2007-06-17 12:15:05.117 'App' 1444 warning] \[attachedComputerWin32,567] 1

    \[??] \[2007-06-17 12:15:05.367 'App' 1724 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ is not supported .... Trying NT method.

    \[#1] \[2007-06-17 12:15:05.367 'App' 1444 verbose] \[diskSet,196] 4 volumes found

    \[??] \[2007-06-17 12:15:05.367 'App' 1724 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ Volume's disk number

    \[#1] \[2007-06-17 12:15:05.367 'App' 1444 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:05.367 'App' 1444 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#1] \[2007-06-17 12:15:05.367 'App' 1444 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#1] \[2007-06-17 12:15:05.367 'App' 1444 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:05.367 'App' 1444 verbose] \[diskSet,203] 1 potential active volumes found

    \[#1] \[2007-06-17 12:15:05.445 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:05.445 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:05.460 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[attachedVolumeWin32,363] Opening host registry with path="SYSTEM"

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[attachedVolumeWin32,363] Opening host registry with path="SOFTWARE"

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:1 64:0 Ver:5.0

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#1] \[2007-06-17 12:15:05.476 'App' 1444 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedComputerWin32,780] Detected 2 NICs

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:B0:D0:68:BF:AA

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedComputerWin32,815] NIC1 has description "Intel 8255x-based Integrated Fast Ethernet"

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedComputerWin32,788] NIC2 is non-ethernet - discarding

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[disposable,121] object explicitly disposed: computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,119] Cleaning up computer's child objects

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,3} \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,4}

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[diskSet,145] Unregistring disk id=disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

    \[#1] \[2007-06-17 12:15:05.492 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=3894 size=4096 err=0

    \[#1] \[2007-06-17 12:15:06.460 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:15:06.460 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:06.460 'App' 2392 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88bb-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:06.460 'App' 2392 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b8-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:06.460 'App' 2392 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b9-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:06.460 'App' 2392 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88ba-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:06.460 'App' 2392 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{a4365ee6-d08d-11da-bee9-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:06.460 'App' 2392 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:15:06.507 'App' 2392 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 1 at sector 63.

    \[#1] \[2007-06-17 12:15:07.898 'App' 2392 verbose] \[attachedComputerWin32,356] Accounted for candidate on disk 1 at sector 63

    \[#1] \[2007-06-17 12:15:09.804 'App' 2392 warning] \[attachedComputerWin32,567] 1

    \[#1] \[2007-06-17 12:15:11.054 'App' 2392 warning] \[attachedComputerWin32,567] 1

    \[??] \[2007-06-17 12:15:11.304 'App' 1724 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ is not supported .... Trying NT method.

    \[#1] \[2007-06-17 12:15:11.304 'App' 2392 verbose] \[diskSet,196] 4 volumes found

    \[??] \[2007-06-17 12:15:11.304 'App' 1724 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ Volume's disk number

    \[#1] \[2007-06-17 12:15:11.304 'App' 2392 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:11.304 'App' 2392 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#1] \[2007-06-17 12:15:11.304 'App' 2392 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#1] \[2007-06-17 12:15:11.304 'App' 2392 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:11.304 'App' 2392 verbose] \[diskSet,203] 1 potential active volumes found

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:11.382 'App' 2392 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[attachedVolumeWin32,363] Opening host registry with path="SYSTEM"

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 info] \[attachedVolumeWin32,363] Opening host registry with path="SOFTWARE"

    \[#1] \[2007-06-17 12:15:11.398 'App' 2392 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:1 64:0 Ver:5.0

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,196] 4 volumes found

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,203] 1 potential active volumes found

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[#1] \[2007-06-17 12:15:11.414 'App' 2132 info] \[imageProcessorProxy,148] Successfully connected to UfaSysReconfig

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[ufaClientConnection,69] Successfully connected to local UfaClient.

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 info] \[ufaClientConnection,163] Opening specifed computer ...

    \[#1] \[2007-06-17 12:15:11.414 'App' 1912 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#1] \[2007-06-17 12:15:11.414 'App' 2392 verbose] \[computerProxy,72] Successfully connected to remote Computer.

    \[#1] \[2007-06-17 12:15:11.429 'App' 2392 verbose] \[computerProxy,253] Connecting to the system volume...

    \[#1] \[2007-06-17 12:15:11.429 'App' 2392 verbose] \[computerProxy,144] Retrieving the system volume info ...

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,196] 4 volumes found

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,203] 1 potential active volumes found

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#1] \[2007-06-17 12:15:11.429 'App' 1912 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[#1] \[2007-06-17 12:15:11.429 'App' 2392 verbose] \[volumeProxy,53] Successfully connected to remote volume.

    \[#1] \[2007-06-17 12:15:11.476 'App' 2392 verbose] \[computerProxy,92] Debug

    \[#1] \[2007-06-17 12:15:11.476 'App' 2392 verbose] \[computerProxy,118] Debug

    \[#1] \[2007-06-17 12:15:11.476 'App' 2392 verbose] \[volumeProxy,73] Debug

    \[#1] \[2007-06-17 12:15:11.476 'App' 2392 info] \[ufaClientConnection,136] Info

    \[#1] \[2007-06-17 12:15:11.476 'App' 2392 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:15:11.476 'App' 2392 verbose] \[diskSet,330] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:15:11.585 'App' 2392 verbose] \[attachedComputerWin32,780] Detected 2 NICs

    \[#1] \[2007-06-17 12:15:11.585 'App' 2392 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:B0:D0:68:BF:AA

    \[#1] \[2007-06-17 12:15:11.585 'App' 2392 verbose] \[attachedComputerWin32,815] NIC1 has description "Intel 8255x-based Integrated Fast Ethernet"

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[attachedComputerWin32,788] NIC2 is non-ethernet - discarding

    \[#1] \[2007-06-17 12:15:11.601 'App' 2132 verbose] \[importSource,461] (vim.vm.device.VirtualPCNet32) {

    \[#1] dynamicType =

    \[#1] }

    \[#1] \[2007-06-17 12:15:11.601 'App' 2132 verbose] \[importSource,464] 1 NICs found

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[disposable,121] object explicitly disposed: computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,119] Cleaning up computer's child objects

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,3} \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,4}

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:15:11.601 'App' 2392 verbose] \[diskSet,145] Unregistring disk id=disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:15:11.601 'App' 2132 verbose] \[vmiTask,109] VmiQuerySourceInfoTask::task\{0}: returning results:

    \[#1] (sysimage.QuerySourceInfoResult) {

    \[#1] dynamicType = ,

    \[#1] dynamicProperty = (vmodl.DynamicProperty) [],

    \[#1] id = "attVol=\{computer={2202622998d91b89190c31c8980df6baba3cfd5d},2}",

    \[#1] driveLetter = "
    ?\Volume\{616f88b8-0a77-11d6-b233-806d6172696f}",

    \[#1] capacityInBytes = 32868864,

    \[#1] spaceUsedInBytes = 29424640,

    \[#1] systemVolume = false,

    \[#1] activeVolume = false,

    \[#1] filesystemUnderstood = true,

    \[#1] diskDeviceNumber = 1

    \[#1] },

    \[#1] (sysimage.VolumeInfo) {

    \[#1] dynamicType = ,

    \[#1] dynamicProperty = (vmodl.DynamicProperty) [],

    \[#1] volumeId = "attVol=\{computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}",

    \[#1] bResize = true,

    \[#1] newCapacityInBytes = 3085197209

    \[#1] }

    \[#1] ],

    \[#1] diskLocations = (string) []

    \[#1] }

    \[#1] \[2007-06-17 12:16:18.257 'App' 2132 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:16:18.257 'App' 2132 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:16:18.257 'App' 2132 verbose] \[disposable,68] Registering disposable session object id=VmiGetDiskSizesTask::task\{1}

    \[#1] \[2007-06-17 12:16:18.257 'App' 2132 verbose] \[disposable,79] Associating disposable session object id=VmiGetDiskSizesTask::task\{1} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:18.289 'App' 1380 verbose] \[imageProcessingTaskImpl,504] VmiGetDiskSizesTask::task\{1}: Starting execution of an image processing task

    \[#1] \[2007-06-17 12:16:18.289 'App' 1380 verbose] \[imageProcessingTaskImpl,154] VmiGetDiskSizesTask::task\{1}: SetState to running

    \[#1] \[2007-06-17 12:16:18.289 'App' 1380 info] \[vmiGetDiskSizesTask,53] VmiGetDiskSizesTask initialized

    \[#1] \[2007-06-17 12:16:18.289 'App' 1912 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#1] \[2007-06-17 12:16:18.289 'App' 2132 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

    \[#1] \[2007-06-17 12:16:18.289 'App' 2132 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

    \[#1] \[2007-06-17 12:16:18.289 'App' 2132 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=3894 size=4096 err=0

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[disposable,68] Registering disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[disposable,79] Associating disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:19.257 'App' 1912 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[diskSet,330] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[diskSet,330] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88bb-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b8-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b9-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88ba-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{a4365ee6-d08d-11da-bee9-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:19.257 'App' 2132 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:19.304 'App' 2132 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 1 at sector 63.

    \[#1] \[2007-06-17 12:16:20.710 'App' 2132 verbose] \[attachedComputerWin32,356] Accounted for candidate on disk 1 at sector 63

    \[#1] \[2007-06-17 12:16:22.601 'App' 2132 warning] \[attachedComputerWin32,567] 1

    \[#1] \[2007-06-17 12:16:23.851 'App' 2132 warning] \[attachedComputerWin32,567] 1

    \[??] \[2007-06-17 12:16:24.101 'App' 1724 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ is not supported .... Trying NT method.

    \[??] \[2007-06-17 12:16:24.101 'App' 1724 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ Volume's disk number

    \[#1] \[2007-06-17 12:16:24.179 'App' 1380 verbose] \[vmiTask,109] VmiGetDiskSizesTask::task\{1}: returning results:

    \[#1] (long) [

    \[#1] 3101979136

    \[#1] ]

    \[#1] \[2007-06-17 12:16:24.179 'App' 1380 verbose] \[imageProcessingTaskImpl,154] VmiGetDiskSizesTask::task\{1}: SetState to success

    \[#1] \[2007-06-17 12:16:24.179 'App' 1380 verbose] \[imageProcessingTaskImpl,514] VmiGetDiskSizesTask::task\{1}: Image processing task has succeeded

    \[#1] \[2007-06-17 12:16:24.242 'App' 1376 verbose] \[disposable,121] object explicitly disposed: VmiGetDiskSizesTask::task\{1}

    \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[disposable,121] object explicitly disposed: computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,119] Cleaning up computer's child objects

    \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

    \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,3} \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,4}

    \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:16:24.242 'App' 2132 verbose] \[diskSet,145] Unregistring disk id=disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:16:24.242 'App' 1376 verbose] \[imageProcessingTaskImpl,123] VmiGetDiskSizesTask::task\{1} being destroyed

    \[#1] \[2007-06-17 12:16:32.695 'App' 1444 info] \[vmImporter,150] Entering UFAD at CheckDestAvailable

    \[#1] \[2007-06-17 12:16:32.695 'App' 1444 verbose] \[vmImporter,151] target:

    \[#1] (sysimage.ManagedTargetVMSpec) {

    \[#1] dynamicType = ,

    \[#1] dynamicProperty = (vmodl.DynamicProperty) [],

    \[#1] volumeId = "attVol=\{computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}",

    \[#1] bResize = true,

    \[#1] newCapacityInBytes = 3085197209

    \[#1] }

    \[#1] ],

    \[#1] diskLocations = (string) [

    \[#1] "#datastore-2673"

    \[#1] ]

    \[#1] }

    \[#1] \[2007-06-17 12:16:37.945 'App' 1444 verbose] \[vmImporter,192] diskSizes:

    \[#1] (long) [

    \[#1] 3101979136

    \[#1] ]

    \[#1] \[2007-06-17 12:16:37.960 'App' 1444 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:16:37.960 'App' 1444 verbose] \[ufaConnection,94] Creating local adapter...

    \[#1] \[2007-06-17 12:16:37.960 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=VmiCheckDestParamsTask::task\{3}

    \[#1] \[2007-06-17 12:16:37.960 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=VmiCheckDestParamsTask::task\{3} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:37.976 'App' 2132 verbose] \[imageProcessingTaskImpl,504] VmiCheckDestParamsTask::task\{3}: Starting execution of an image processing task

    \[#1] \[2007-06-17 12:16:37.976 'App' 2132 verbose] \[imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task\{3}: SetState to running

    \[#1] \[2007-06-17 12:16:37.976 'App' 2132 info] \[vmiCheckDestParamsTask,55] VmiCheckDestParamsTask initialized

    \[#1] \[2007-06-17 12:16:37.992 'managedImportDestination' 2132 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[#1]

    \[#1] \[2007-06-17 12:16:38.210 'managedImportDestination' 2132 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:02DBCE48]

    \[2007-06-17 12:16:38.210 'BaseLibs' 1912 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[#1] \[2007-06-17 12:16:38.242 'managedImportDestination' 2132 info] Authenticating user WinAdmin

    \[#1] \[2007-06-17 12:16:38.414 'managedImportDestination' 2132 info] Logged in!

    \[#1] \[2007-06-17 12:16:41.804 'App' 2132 verbose] \[managedImportDestination,322] Checking for 3101979136 bytes free on datastore "datastore-2673"

    \[#1] \[2007-06-17 12:16:42.023 'App' 2132 verbose] \[managedImportDestination,330] Datastore "datastore-2673" has 8513388544 bytes free

    \[#1] \[2007-06-17 12:16:42.023 'App' 2132 verbose] \[managedImportDestination,322] Checking for 10240 bytes free on datastore "datastore-386"

    \[#1] \[2007-06-17 12:16:42.242 'App' 2132 verbose] \[managedImportDestination,330] Datastore "datastore-386" has 13657702400 bytes free

    \[#1] \[2007-06-17 12:16:42.242 'App' 1912 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#1] \[2007-06-17 12:16:42.242 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

    \[#1] \[2007-06-17 12:16:42.242 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

    \[#1] \[2007-06-17 12:16:42.242 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=3894 size=4096 err=0

    \[#1] \[2007-06-17 12:16:43.195 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:16:43.195 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d} with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:43.195 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88bb-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:43.195 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b8-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:43.195 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b9-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:43.195 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88ba-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:43.210 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{a4365ee6-d08d-11da-bee9-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:43.210 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\"

    \[#1] \[2007-06-17 12:16:43.242 'App' 1912 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 1 at sector 63.

    \[#1] \[2007-06-17 12:16:44.648 'App' 1912 verbose] \[attachedComputerWin32,356] Accounted for candidate on disk 1 at sector 63

    \[#1] \[2007-06-17 12:16:46.539 'App' 1912 warning] \[attachedComputerWin32,567] 1

    \[#1] \[2007-06-17 12:16:47.804 'App' 1912 warning] \[attachedComputerWin32,567] 1

    \[#1] \[2007-06-17 12:16:48.054 'App' 1912 verbose] \[diskSet,196] 4 volumes found

    \[??] \[2007-06-17 12:16:48.054 'App' 1724 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ is not supported .... Trying NT method.

    \[#1] \[2007-06-17 12:16:48.054 'App' 1912 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[??] \[2007-06-17 12:16:48.054 'App' 1724 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ Volume's disk number

    \[#1] \[2007-06-17 12:16:48.054 'App' 1912 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#1] \[2007-06-17 12:16:48.054 'App' 1912 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#1] \[2007-06-17 12:16:48.054 'App' 1912 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#1] \[2007-06-17 12:16:48.054 'App' 1912 verbose] \[diskSet,203] 1 potential active volumes found

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:16:48.132 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[attachedVolumeWin32,363] Opening host registry with path="SYSTEM"

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 info] \[attachedVolumeWin32,363] Opening host registry with path="SOFTWARE"

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#1] \[2007-06-17 12:16:48.148 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE with session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[#1] \[2007-06-17 12:16:48.164 'App' 1912 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:1 64:0 Ver:5.0

    \[#1] \[2007-06-17 12:16:48.164 'App' 1912 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#1] \[2007-06-17 12:16:48.164 'App' 1912 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

    \[#1] \[2007-06-17 12:16:48.164 'App' 2132 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

    \[#1] \[2007-06-17 12:16:48.367 'App' 2132 verbose] \[vmiTask,109] VmiCheckDestParamsTask::task\{3}: returning results:

    \[#1] (sysimage.CompatibilityWarning) []

    \[#1] \[2007-06-17 12:16:48.367 'App' 2132 verbose] \[imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task\{3}: SetState to success

    \[#1] \[2007-06-17 12:16:48.367 'App' 2132 verbose] \[imageProcessingTaskImpl,514] VmiCheckDestParamsTask::task\{3}: Image processing task has succeeded

    \[#1] \[2007-06-17 12:16:48.414 'App' 2132 verbose] \[disposable,121] object explicitly disposed: VmiCheckDestParamsTask::task\{3}

    \[#1] \[2007-06-17 12:16:48.414 'managedImportDestination' 2132 verbose] Shutting down VMDB service...

    \[#1] \[2007-06-17 12:16:48.414 'managedImportDestination' 2132 verbose] Unregistering callback...

    \[#1] \[2007-06-17 12:16:48.414 'managedImportDestination' 2132 verbose] ...done

    \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[disposable,121] object explicitly disposed: computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,119] Cleaning up computer's child objects

    \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

    \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,3} \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,4}

    \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#1] \[2007-06-17 12:16:48.429 'App' 1912 verbose] \[diskSet,145] Unregistring disk id=disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#1] \[2007-06-17 12:16:48.429 'App' 2132 verbose] \[imageProcessingTaskImpl,123] VmiCheckDestParamsTask::task\{3} being destroyed

    \[2007-06-17 12:16:57.382 'VmdbSvc' 1444 verbose] IVmdbPipe2Impl operation completed with error: The pipe has been ended.

    \[2007-06-17 12:16:57.382 'App' 1376 verbose] CloseSession called for session id=D5F81DB3-845C-4E26-8983-C0B935EEF7E8

    \[2007-06-17 12:16:57.382 'VmdbAdapter' 1376 verbose] Removed vmdb connection /db/connection/#1/

    \[#2] \[2007-06-17 12:16:57.414 'App' 2392 info] Impersonating user WinAdmin in session 38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 info] \[vmImporter,209] Entering UFAD at DoImport

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 verbose] \[vmImporter,210] source:

    \[#2] (sysimage.LiveSourceComputerSpec) {

    \[#2] dynamicType = ,

    \[#2] dynamicProperty = (vmodl.DynamicProperty) [],

    \[#2] volumeId = "attVol=\{computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}",

    \[#2] bResize = true,

    \[#2] newCapacityInBytes = 3085197209

    \[#2] }

    \[#2] ],

    \[#2] diskLocations = (string) [

    \[#2] "#datastore-2673"

    \[#2] ]

    \[#2] }

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 verbose] \[ufaConnection,94] Creating local adapter...

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 verbose] \[ufaConnection,94] Creating local adapter...

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 info] \[vmiImportTask,110] Successfully connected to UfaClient

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 verbose] \[disposable,68] Registering disposable session object id=VmiImportTask::task\{4}

    \[#2] \[2007-06-17 12:16:57.460 'App' 1376 verbose] \[disposable,79] Associating disposable session object id=VmiImportTask::task\{4} with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:16:57.492 'App' 1376 verbose] \[imageProcessingTaskImpl,504] VmiImportTask::task\{4}: Starting execution of an image processing task

    \[#2] \[2007-06-17 12:16:57.492 'App' 1376 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{4}: SetState to running

    \[#2] \[2007-06-17 12:16:57.492 'App' 1376 info] \[vmiImportTask,180] Starting VM Import...

    \[#2] \[2007-06-17 12:16:57.492 'App' 1376 info] \[vmiImportTask,116] vmiImportTask initialized

    \[#2] \[2007-06-17 12:16:57.492 'App' 1376 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "Clone VM" created

    \[#2] \[2007-06-17 12:16:57.492 'App' 1376 verbose] \[vmiImportTask,201] VmiImportTask::DoRun - Calling GetComputer()

    \[#2] \[2007-06-17 12:16:57.492 'App' 1912 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#2] \[2007-06-17 12:16:57.492 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

    \[#2] \[2007-06-17 12:16:57.492 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

    \[#2] \[2007-06-17 12:16:57.492 'App' 1444 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=3894 size=4096 err=0

    \[#2] \[2007-06-17 12:16:58.460 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#2] \[2007-06-17 12:16:58.460 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=computer=\{2202622998d91b89190c31c8980df6baba3cfd5d} with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:16:58.460 'App' 1376 verbose] \[vmiImportTask,223] VmiImportTask::DoRun - Calling Clone()

    \[#2] \[2007-06-17 12:16:58.460 'App' 1376 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "create and clone to VM" created

    \[#2] \[2007-06-17 12:16:58.460 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88bb-0a77-11d6-b233-806d6172696f}\"

    \[#2] \[2007-06-17 12:16:58.460 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b8-0a77-11d6-b233-806d6172696f}\"

    \[#2] \[2007-06-17 12:16:58.460 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b9-0a77-11d6-b233-806d6172696f}\"

    \[#2] \[2007-06-17 12:16:58.460 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88ba-0a77-11d6-b233-806d6172696f}\"

    \[#2] \[2007-06-17 12:16:58.460 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{a4365ee6-d08d-11da-bee9-806d6172696f}\"

    \[#2] \[2007-06-17 12:16:58.460 'App' 1912 verbose] \[attachedComputerWin32,453] Found volume with name "
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\"

    \[#2] \[2007-06-17 12:16:58.492 'App' 1912 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 1 at sector 63.

    \[#2] \[2007-06-17 12:16:59.898 'App' 1912 verbose] \[attachedComputerWin32,356] Accounted for candidate on disk 1 at sector 63

    \[#2] \[2007-06-17 12:17:01.804 'App' 1912 warning] \[attachedComputerWin32,567] 1

    \[#2] \[2007-06-17 12:17:03.054 'App' 1912 warning] \[attachedComputerWin32,567] 1

    \[#2] \[2007-06-17 12:17:03.304 'App' 1912 verbose] \[diskSet,196] 4 volumes found

    \[??] \[2007-06-17 12:17:03.304 'App' 1724 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ is not supported .... Trying NT method.

    \[#2] \[2007-06-17 12:17:03.304 'App' 1912 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[??] \[2007-06-17 12:17:03.304 'App' 1724 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
    ?\Volume\{616f88b6-0a77-11d6-b233-806d6172696f}\ Volume's disk number

    \[#2] \[2007-06-17 12:17:03.304 'App' 1912 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#2] \[2007-06-17 12:17:03.304 'App' 1912 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#2] \[2007-06-17 12:17:03.304 'App' 1912 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#2] \[2007-06-17 12:17:03.304 'App' 1912 verbose] \[diskSet,203] 1 potential active volumes found

    \[#2] \[2007-06-17 12:17:03.382 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#2] \[2007-06-17 12:17:03.382 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:03.382 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#2] \[2007-06-17 12:17:03.382 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#2] \[2007-06-17 12:17:03.382 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#2] \[2007-06-17 12:17:03.382 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] timeout=30

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[attachedVolumeWin32,363] Opening host registry with path="SYSTEM"

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SYSTEM

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 info] \[attachedVolumeWin32,363] Opening host registry with path="SOFTWARE"

    \[#2] \[2007-06-17 12:17:03.398 'App' 1912 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#2] \[2007-06-17 12:17:03.414 'App' 1912 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:03.414 'App' 1912 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:1 64:0 Ver:5.0

    \[#2] \[2007-06-17 12:17:03.414 'App' 1912 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={2202622998d91b89190c31c8980df6baba3cfd5d},3}},SOFTWARE

    \[#2] \[2007-06-17 12:17:03.414 'App' 1912 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

    \[#2] \[2007-06-17 12:17:03.414 'managedImportDestination' 1376 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[#2]

    \[#2] \[2007-06-17 12:17:03.632 'managedImportDestination' 1376 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:01A54280]

    \[2007-06-17 12:17:03.632 'BaseLibs' 1912 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[#2] \[2007-06-17 12:17:03.664 'managedImportDestination' 1376 info] Authenticating user WinAdmin

    \[#2] \[2007-06-17 12:17:03.898 'managedImportDestination' 1376 info] Logged in!

    \[#2] \[2007-06-17 12:17:05.429 'App' 1376 verbose] \[vmLocUtils,1078] Datacenter name=Ottawa moid=datacenter-2

    \[#2] \[2007-06-17 12:17:05.648 'App' 1376 verbose] \[vmLocUtils,1079] ComputeResource name=Ottawa-Cluster moid=domain-c94

    \[#2] \[2007-06-17 12:17:05.648 'App' 1376 verbose] \[vmLocUtils,1080] Datastore moid=datastore-386

    \[#2] \[2007-06-17 12:17:05.867 'App' 1376 verbose] \[vmLocUtils,1081] VM Folder name=vm moid=group-v3

    \[#2] \[2007-06-17 12:17:06.085 'App' 1376 verbose] \[vmLocUtils,1082] ResourcePool name=Resources moid=resgroup-96

    \[#2] \[2007-06-17 12:17:06.304 'App' 1376 verbose] \[vmLocUtils,1083] HostSystem name=ottvm01.ottawa.zarlink.com moid=host-303

    \[#2] \[2007-06-17 12:17:07.617 'App' 1380 verbose] \[ufaConnection,94] Creating local adapter...

    \[#2] \[2007-06-17 12:17:07.617 'App' 2756 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#2] \[2007-06-17 12:17:07.617 'App' 2756 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#2] \[2007-06-17 12:17:07.617 'App' 2756 verbose] \[diskSet,330] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#2] \[2007-06-17 12:17:07.617 'App' 2756 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#2] \[2007-06-17 12:17:07.617 'App' 2756 verbose] \[diskSet,330] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#2] \[2007-06-17 12:17:07.710 'App' 1376 verbose] \[vmiImportTask,268] Successfully created target storage layout

    \[#2] \[2007-06-17 12:17:07.710 'App' 1376 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "create VM" created

    \[#2] \[2007-06-17 12:17:07.710 'App' 1376 info] \[vmiImportTask,345] Successfully connected to UfaManagedVMCreator

    \[#2] \[2007-06-17 12:17:07.789 'App' 1380 verbose] \[attachedComputerWin32,780] Detected 2 NICs

    \[#2] \[2007-06-17 12:17:07.789 'App' 1380 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:B0:D0:68:BF:AA

    \[#2] \[2007-06-17 12:17:07.789 'App' 1380 verbose] \[attachedComputerWin32,815] NIC1 has description "Intel 8255x-based Integrated Fast Ethernet"

    \[#2] \[2007-06-17 12:17:07.804 'App' 1380 verbose] \[attachedComputerWin32,788] NIC2 is non-ethernet - discarding

    \[#2] \[2007-06-17 12:17:07.804 'App' 1380 verbose] \[managedVMCreator,240] Remapping devices for new managed VM...

    \[#2] \[2007-06-17 12:17:07.804 'managedVMCreator.CreateVM' 1380 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[#2]

    \[#2] \[2007-06-17 12:17:08.023 'managedVMCreator.CreateVM' 1380 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:03A8B008]

    \[2007-06-17 12:17:08.023 'BaseLibs' 2756 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[#2] \[2007-06-17 12:17:08.054 'managedVMCreator.CreateVM' 1380 info] Authenticating user WinAdmin

    \[#2] \[2007-06-17 12:17:08.273 'managedVMCreator.CreateVM' 1380 info] Logged in!

    \[#2] \[2007-06-17 12:17:10.148 'App' 1380 info] \[managedVMCreator,567] Attaching NIC #0 to network "VLAN 15"

    \[#2] \[2007-06-17 12:17:10.164 'managedVMCreator.CreateVM' 1380 verbose] Shutting down VMDB service...

    \[#2] \[2007-06-17 12:17:10.164 'managedVMCreator.CreateVM' 1380 verbose] Unregistering callback...

    \[#2] \[2007-06-17 12:17:10.164 'managedVMCreator.CreateVM' 1380 verbose] ...done

    \[#2] \[2007-06-17 12:17:10.164 'App' 1376 info] \[vmiImportTask,383] DeviceMappingAlert: VM memory not a multiple of 4.Setting VM memory to 1020 MB.

    \[#2] \[2007-06-17 12:17:10.164 'App' 1380 verbose] \[managedVMCreator,655] Creating managed VM...

    \[#2] \[2007-06-17 12:17:10.164 'managedVMCreator.CreateVM' 1380 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[#2]

    \[#2] \[2007-06-17 12:17:10.382 'managedVMCreator.CreateVM' 1380 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:02DD2E88]

    \[2007-06-17 12:17:10.382 'BaseLibs' 2756 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[#2] \[2007-06-17 12:17:10.429 'managedVMCreator.CreateVM' 1380 info] Authenticating user WinAdmin

    \[#2] \[2007-06-17 12:17:10.570 'managedVMCreator.CreateVM' 1380 info] Logged in!

    \[#2] \[2007-06-17 12:17:12.867 'App' 1380 info] \[managedVMCreator,1467] Using specific location WindowsMigration (1) for Disk: 0

    \[#2] \[2007-06-17 12:17:12.867 'App' 1380 info] \[managedVMCreator,1534] Using datastore VMXStore for VMX

    \[#2] \[2007-06-17 12:17:13.085 'App' 1380 info] \[managedVMCreator,680] Waiting for VM creation to complete...

    \[#2] \[2007-06-17 12:17:13.085 'App' 1380 verbose] \[rpcConnectionWrapper,785] Waiting for a task to complete...

    \[#2] \[2007-06-17 12:17:42.242 'App' 1380 info] \[managedVMCreator,733] Target config file: \[VMXStore] swifin01/swifin01.vmx

    \[#2] \[2007-06-17 12:17:42.242 'managedVMCreator.CreateVM' 1380 verbose] Shutting down VMDB service...

    \[#2] \[2007-06-17 12:17:42.242 'managedVMCreator.CreateVM' 1380 verbose] Unregistering callback...

    \[#2] \[2007-06-17 12:17:42.242 'managedVMCreator.CreateVM' 1380 verbose] ...done

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create VM" 100% completed

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 5% completed

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 5% completed

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "create VM" destroyed

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 verbose] \[vmiImportTask,283] Successfully created target VM Spec

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "clone to VM" created

    \[#2] \[2007-06-17 12:17:42.257 'App' 1376 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

    \[#2] \[2007-06-17 12:17:42.257 'App' 1444 info] \[sysMigration,192] Starting cloning...

    \[#2] \[2007-06-17 12:17:42.257 'App' 1444 verbose] \[sysMigration,193] targetVMSpec:

    \[#2] (sysimage.ManagedVmSpec) {

    \[#2] dynamicType =

    \[#2] \[2007-06-17 12:17:42.273 'App' 1444 verbose] \[sysMigration,199] clonePagefile: false

    \[#2] \[2007-06-17 12:17:42.273 'App' 1444 verbose] \[sysMigration,200] updateBootInit: true

    \[#2] \[2007-06-17 12:17:42.273 'App' 1444 verbose] \[sysMigration,201] updateDriveLetters: true

    \[#2] \[2007-06-17 12:17:42.273 'App' 1444 verbose] \[ufaConnection,94] Creating local adapter...

    \[#2] \[2007-06-17 12:17:42.273 'App' 2392 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[#2] \[2007-06-17 12:17:42.273 'App' 1444 info] \[cloneTask,236] Parsing the VM spec to retrieve disk specs...

    \[#2] \[2007-06-17 12:17:42.273 'App' 1444 info] \[vmomiParser,86] VmomiParser::init() - entering

    \[#2] \[2007-06-17 12:17:42.273 'vmomiParser.Init' 1444 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[#2]

    \[#2] \[2007-06-17 12:17:42.492 'vmomiParser.Init' 1444 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:039ACE88]

    \[2007-06-17 12:17:42.492 'BaseLibs' 2392 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[#2] \[2007-06-17 12:17:42.523 'vmomiParser.Init' 1444 info] Authenticating user WinAdmin

    \[#2] \[2007-06-17 12:17:42.726 'vmomiParser.Init' 1444 info] Logged in!

    \[#2] \[2007-06-17 12:17:42.742 'App' 1444 info] \[vmomiParser,97] Connecting to VM id=vm-3337

    \[#2] \[2007-06-17 12:17:42.742 'App' 1444 info] \[vmomiParser,100] VmomiParser::init() - exiting

    \[#2] \[2007-06-17 12:17:42.742 'App' 1444 verbose] \[vmomiParser,210] Getting config info...

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 verbose] \[vmomiParser,160] File Name: \[WindowsMigration (1)] swifin01/swifin01.vmdk

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 info] \[vmomiParser,137] ManagedDiskSpec: Name=\[WindowsMigration (1)] swifin01/swifin01.vmdk

    \[#2] \[2007-06-17 12:17:43.164 'vmomiParser.Init' 1444 verbose] Shutting down VMDB service...

    \[#2] \[2007-06-17 12:17:43.164 'vmomiParser.Init' 1444 verbose] Unregistering callback...

    \[#2] \[2007-06-17 12:17:43.164 'vmomiParser.Init' 1444 verbose] ...done

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 verbose] \[cloneTask,258] Disk Name: \[WindowsMigration (1)] swifin01/swifin01.vmdk

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 verbose] \[disposable,68] Registering disposable session object id=CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 verbose] \[disposable,79] Associating disposable session object id=CloneTask::task\{5} with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:43.164 'App' 1376 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.164 'App' 1376 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.164 'App' 1376 info] \[imageProcessingTaskStep,409] VmiImportTask::task\{4} step "clone to VM" in progress...

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 verbose] \[imageProcessingTaskImpl,504] CloneTask::task\{5}: Starting execution of an image processing task

    \[#2] \[2007-06-17 12:17:43.164 'App' 1376 verbose] \[imageProcessingTaskWrapper,474] Waiting for completion of task CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 verbose] \[imageProcessingTaskImpl,154] CloneTask::task\{5}: SetState to running

    \[#2] \[2007-06-17 12:17:43.164 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 info] \[cloneTask,148] Starting the cloning process...

    \[#2] \[2007-06-17 12:17:43.164 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 info] \[cloneTask,157] Preparing for cloning...

    \[#2] \[2007-06-17 12:17:43.164 'App' 1444 info] \[volumeMappingInfoBuilder,90] Initializing VolumeMappingInfoBuilder...

    \[#2] \[2007-06-17 12:17:43.164 'App' 2132 verbose] \[diskSet,196] 4 volumes found

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=N

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[diskSet,364] Volume: idx=1 sysId=-34 active=N

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[diskSet,364] Volume: idx=2 sysId=7 active=Y

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[diskSet,364] Volume: idx=3 sysId=7 active=N

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[diskSet,203] 1 potential active volumes found

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[disposable,68] Registering disposable session object id=localFile=

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[extendedOsInfo,389] \[boot loader]

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[extendedOsInfo,389] timeout=30

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINNT

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[extendedOsInfo,389] \[operating systems]

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINNT="Microsoft Windows 2000 Server" /fastdetect

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[disposable,121] object explicitly disposed: localFile=

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 verbose] \[diskSet,434] Found a candidate system volume at idx=2

    \[#2] \[2007-06-17 12:17:43.179 'App' 2132 info] \[diskSet,297] OS resides on partition 2 , disk path
    .\PhysicalDrive1

    \[2007-06-17 12:17:43.179 'App' 736 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[2007-06-17 12:17:43.179 'App' 736 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 0% completed

    \[#2] \[2007-06-17 12:17:43.195 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.195 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.195 'App' 1380 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#2] \[2007-06-17 12:17:43.195 'App' 1380 verbose] \[diskSet,330] GetDisks: disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#2] \[2007-06-17 12:17:43.539 'App' 1444 info] \[cloneTask,161] Prepared...

    \[#2] \[2007-06-17 12:17:43.539 'App' 1444 info] \[volumeBasedCloneTask,128] Performing volume-based cloning...

    \[#2] \[2007-06-17 12:17:43.539 'App' 1444 info] \[cloneTask,272] Opening the target computer...

    \[#2] \[2007-06-17 12:17:43.539 'App' 736 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

    \[??] \[2007-06-17 12:17:43.539 'App' 1724 info] \[stub,42] NBD_Clientopen: attempting to create connection to ha-nfc://\[WindowsMigration (1)] swifin01/swifin01.vmdk@ottvm01.ottawa.zarlink.com:902!52 bf 09 d7 41 ef 5e 86-fa ce 89 48 76 b4 b4 0f

    \[??] \[2007-06-17 12:17:43.554 'App' 1724 info] \[stub,42] Started up WSA

    \[??] \[2007-06-17 12:17:43.804 'App' 1724 info] \[stub,42] Opening file \[WindowsMigration (1)] swifin01/swifin01.vmdk (ha-nfc://\[WindowsMigration (1)] swifin01/swifin01.vmdk@ottvm01.ottawa.zarlink.com:902!52 bf 09 d7 41 ef 5e 86-fa ce 89 48 76 b4 b4 0f)

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] DISKLIB-LINK : Opened 'ha-nfc://\[WindowsMigration (1)] swifin01/swifin01.vmdk@ottvm01.ottawa.zarlink.com:902!52 bf 09 d7 41 ef 5e 86-fa ce 89 48 76 b4 b4 0f' (0xa): custom, 6058554 sectors / 2959 Mb.

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] DISKLIB-LIB : Opened "ha-nfc://\[WindowsMigration (1)] swifin01/swifin01.vmdk@ottvm01.ottawa.zarlink.com:902!52 bf 09 d7 41 ef 5e 86-fa ce 89 48 76 b4 b4 0f" (flags 0xa). 02E1DF58

    \[#2] \[2007-06-17 12:17:43.867 'App' 1380 verbose] \[disposable,68] Registering disposable session object id=computer=\{3eb3ea56110128f34182cc7fa352699c9484b0b4}

    \[#2] \[2007-06-17 12:17:43.867 'App' 1380 verbose] \[disposable,79] Associating disposable session object id=computer=\{3eb3ea56110128f34182cc7fa352699c9484b0b4} with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:43.867 'App' 1444 info] \[cloneTask,314] Getting disks...

    \[#2] \[2007-06-17 12:17:43.867 'App' 736 verbose] \[diskSet,330] GetDisks: disk=\{c08ff75557acac676803b31cd764bddd513a8f2}

    \[#2] \[2007-06-17 12:17:43.867 'App' 1444 verbose] \[cloneTask,326] Disk 0 id=disk=\{c08ff75557acac676803b31cd764bddd513a8f2}

    \[#2] \[2007-06-17 12:17:43.867 'App' 1444 info] \[volumeBasedCloneTask,346] Partitioning disk disk=\{c08ff75557acac676803b31cd764bddd513a8f2}

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] No geometry.biosSectors defined,

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] No geometry.biosHeads defined,

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] No geometry.biosCylinders defined,

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] No geometry.biosSectors defined,

    \[??] \[2007-06-17 12:17:43.867 'App' 1724 info] \[stub,42] No geometry.biosHeads defined,

    \[#2] \[2007-06-17 12:17:43.929 'App' 1444 info] \[cloneTask,344] Getting volumes for computer id=computer=\{3eb3ea56110128f34182cc7fa352699c9484b0b4}

    \[??] \[2007-06-17 12:17:43.945 'App' 1724 info] \[stub,42] No geometry.biosSectors defined,

    \[??] \[2007-06-17 12:17:43.945 'App' 1724 info] \[stub,42] No geometry.biosHeads defined,

    \[??] \[2007-06-17 12:17:43.945 'App' 1724 info] \[stub,42] No geometry.biosSectors defined,

    \[#2] \[2007-06-17 12:17:43.945 'App' 1444 verbose] \[cloneTask,356] Volume 0 id=virtVol=\{computer=,0} \[??] \[2007-06-17 12:17:43.960 'App' 1724 info] \[stub,42] No geometry.biosHeads defined, \[#2] \[2007-06-17 12:17:43.960 'App' 1444 info] \[imageProcessingTaskStep,155] CloneTask::task\{5} step "clone volumes" created \[??] \[2007-06-17 12:17:43.960 'App' 1724 info] \[stub,42] No geometry.biosCylinders defined, \[#2] \[2007-06-17 12:17:43.960 'App' 1444 info] \[cloneTask,375] Getting volumes for the source computer... \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[cloneTask,385] Volume 0 id=attVol=\{computer=,1} \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[cloneTask,385] Volume 1 id=attVol=\{computer=,2} \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[cloneTask,385] Volume 2 id=attVol=\{computer=,3} \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[cloneTask,385] Volume 3 id=attVol=\{computer=,4} \[#2] \[2007-06-17 12:17:43.960 'App' 1444 info] \[cloneTask,583] Formatting volume id=virtVol=\{computer=,0}

    \[#2] \[2007-06-17 12:17:43.960 'App' 1444 info] \[imageProcessingTaskStep,155] CloneTask::task\{5} step "Formatting volume virtVol=\{computer=,0}" created \[#2] \[2007-06-17 12:17:43.960 'App' 736 info] \[volumeWin32,750] Formatting volume virtVol=\{computer=,0}

    \[#2] \[2007-06-17 12:17:43.960 'App' 736 verbose] \[disposable,68] Registering disposable session object id=volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.960 'App' 736 verbose] \[disposable,79] Associating disposable session object id=volumeFormattingWin32Task::task\{6} with session id=38BD7477-004B-4FA8-ADFE-94834F74451D

    \[#2] \[2007-06-17 12:17:43.960 'App' 736 info] \[volumeWin32,762] Scheduling volume formatting for execution...

    \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.960 'App' 1444 info] \[imageProcessingTaskStep,409] CloneTask::task\{5} step "Formatting volume virtVol=\{computer=,0}" in progress... \[#2] \[2007-06-17 12:17:43.960 'App' 736 verbose] \[imageProcessingTaskImpl,504] volumeFormattingWin32Task::task\{6}: Starting execution of an image processing task \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[imageProcessingTaskWrapper,474] Waiting for completion of task volumeFormattingWin32Task::task\{6} \[2007-06-17 12:17:43.960 'App' 1380 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5} \[#2] \[2007-06-17 12:17:43.960 'App' 736 verbose] \[imageProcessingTaskImpl,154] volumeFormattingWin32Task::task\{6}: SetState to running \[#2] \[2007-06-17 12:17:43.960 'App' 1444 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{6} \[#2] \[2007-06-17 12:17:43.960 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5} \[#2] \[2007-06-17 12:17:43.976 'App' 1444 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from volumeFormattingWin32Task::task\{6} \[#2] \[2007-06-17 12:17:43.976 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5} \[2007-06-17 12:17:43.976 'App' 2132 verbose] \[imageProcessingTaskWrapper,749] Got an update from volumeFormattingWin32Task::task\{6} \[2007-06-17 12:17:43.976 'App' 2132 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Formatting volume virtVol=\{computer=,0}" 0% completed

    \[2007-06-17 12:17:43.976 'App' 2132 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "clone volumes" 0% completed

    \[2007-06-17 12:17:43.976 'App' 2132 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.976 'App' 1444 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.976 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.976 'App' 1444 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.976 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[2007-06-17 12:17:43.976 'App' 2132 verbose] \[imageProcessingTaskWrapper,749] Got an update from volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.976 'App' 1444 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:17:43.976 'App' 1444 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from volumeFormattingWin32Task::task\{6}

    \[2007-06-17 12:17:43.976 'App' 2392 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.992 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:17:43.992 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.070 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpRead: bRead: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.070 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrRecv: failed with code = 8

    \[#2] \[2007-06-17 12:18:10.070 'App' 736 info] \[volumeFormattingTaskImplWin32,270] Format completed...

    \[??] \[2007-06-17 12:18:10.070 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to receive io reply

    \[#2] \[2007-06-17 12:18:10.070 'App' 736 error] \[imageProcessingTaskImpl,518] volumeFormattingWin32Task::task\{6}: Image processing task has failed

    \[??] \[2007-06-17 12:18:10.070 'App' 1724 info] \[stub,42] DISKLIB-LIB : RWv failed ioId: #113 (5666) (34) .

    \[#2] \[2007-06-17 12:18:10.070 'App' 736 verbose] \[imageProcessingTaskImpl,154] volumeFormattingWin32Task::task\{6}: SetState to error

    \[??] \[2007-06-17 12:18:10.070 'App' 1724 info] \[stub,42] Vmount library: Error 5666 while while writing to device

    \[#2] \[2007-06-17 12:18:10.070 'App' 736 verbose] \[imageProcessingTaskWrapper,886] Remote task volumeFormattingWin32Task::task\{6} completed

    \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{6}

    \[2007-06-17 12:18:10.070 'App' 2392 verbose] \[imageProcessingTaskWrapper,749] Got an update from volumeFormattingWin32Task::task\{6}

    \[2007-06-17 12:18:10.070 'App' 2392 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:18:10.070 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.070 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:18:10.070 'App' 1380 verbose] \[disposable,121] object explicitly disposed: volumeFormattingWin32Task::task\{6}

    \[#2] \[2007-06-17 12:18:10.070 'App' 1380 verbose] \[imageProcessingTaskImpl,123] volumeFormattingWin32Task::task\{6} being destroyed

    \[#2] \[2007-06-17 12:18:10.070 'App' 1444 error] \[imageProcessingTaskStep,425] CloneTask::task\{5} step "Formatting volume virtVol=\{computer=,0}" failed \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskWrapper,1117] Reporting image processing task failure for task volumeFormattingWin32Task::task\{6} \[#2] \[2007-06-17 12:18:10.070 'App' 1444 error] \[imageProcessingTaskWrapper,1124] ImageProcessingTask FAILED. Fault name: sysimage.fault.ImageProcessingTaskFault \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Formatting volume virtVol=\{computer=,0}" 100% completed

    \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "clone volumes" 10% completed

    \[#2] \[2007-06-17 12:18:10.070 'App' 1444 info] \[imageProcessingTaskStep,196] CloneTask::task\{5} step "Formatting volume virtVol=\{computer=,0}" destroyed \[#2] \[2007-06-17 12:18:10.070 'App' 1444 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "clone volumes" 100% completed \[2007-06-17 12:18:10.070 'App' 1380 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5} \[#2] \[2007-06-17 12:18:10.070 'App' 1444 info] \[imageProcessingTaskStep,196] CloneTask::task\{5} step "clone volumes" destroyed \[2007-06-17 12:18:10.085 'App' 1380 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 10% completed \[#2] \[2007-06-17 12:18:10.085 'App' 1912 verbose] \[disposable,121] object explicitly disposed: computer=\

    \[2007-06-17 12:18:10.085 'App' 1380 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 14% completed

    \[#2] \[2007-06-17 12:18:10.085 'App' 1912 verbose] \[diskSet,119] Cleaning up computer's child objects

    \[2007-06-17 12:18:10.085 'App' 1380 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 14% completed

    \[#2] \[2007-06-17 12:18:10.085 'App' 1912 verbose] \[diskSet,135] Unregistring volume id=virtVol=\{computer=,0}

    \[#2] \[2007-06-17 12:18:10.085 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.085 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to send io message

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] DISKLIB-LIB : RWv failed ioId: #114 (38434) (34) .

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] Vmount library: Error 38434 while while reading from device

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to send io message

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to send io message

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] DISKLIB-LIB : RWv failed ioId: #115 (38434) (34) .

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] Vmount library: Error 38434 while while reading from device

    \[2007-06-17 12:18:10.085 'App' 1380 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.085 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[2007-06-17 12:18:10.085 'App' 1380 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 100% completed

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[2007-06-17 12:18:10.101 'App' 1380 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 100% completed

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to send io message

    \[2007-06-17 12:18:10.101 'App' 1380 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 100% completed

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] DISKLIB-LIB : RWv failed ioId: #116 (38434) (34) .

    \[#2] \[2007-06-17 12:18:10.101 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] Vmount library: Error 38434 while while reading from device

    \[#2] \[2007-06-17 12:18:10.101 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to send io message

    \[2007-06-17 12:18:10.101 'App' 1380 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] DISKLIB-LIB : RWv failed ioId: #117 (38434) (34) .

    \[#2] \[2007-06-17 12:18:10.101 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] Vmount library: Error 38434 while while reading from device

    \[#2] \[2007-06-17 12:18:10.101 'App' 1376 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_DiskIo: failed to send io message

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] DISKLIB-LIB : RWv failed ioId: #118 (38434) (34) .

    \[??] \[2007-06-17 12:18:10.101 'App' 1724 info] \[stub,42] Vmount library: Error 38434 while while reading from device

    \[#2] \[2007-06-17 12:18:10.195 'App' 1912 verbose] \[diskSet,145] Unregistring disk id=disk=\{c08ff75557acac676803b31cd764bddd513a8f2}

    \[??] \[2007-06-17 12:18:10.195 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.195 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvrSend: failed

    \[??] \[2007-06-17 12:18:10.195 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcFssrvr_Close: failed to send close message

    \[??] \[2007-06-17 12:18:10.195 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNetTcpWrite: bWritten: -1 err: 10054

    \[??] \[2007-06-17 12:18:10.195 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcNet_Send: requested 264, sent only 8 bytes

    \[#2] \[2007-06-17 12:18:10.195 'App' 1444 error] \[cloneTask,210] Cloning failed!

    \[??] \[2007-06-17 12:18:10.195 'App' 1724 info] \[stub,42] \[NFC ERROR] NfcSendMessage: send failed:

    \[#2] \[2007-06-17 12:18:10.195 'App' 1444 error] \[imageProcessingTaskImpl,552] CloneTask::task\{5}: Image processing task has failed with MethodFault::Exception: sysimage.fault.ImageProcessingTaskFault

    \[#2] \[2007-06-17 12:18:10.195 'App' 1444 verbose] \[imageProcessingTaskImpl,154] CloneTask::task\{5}: SetState to error

    \[#2] \[2007-06-17 12:18:10.195 'App' 1444 verbose] \[imageProcessingTaskWrapper,886] Remote task CloneTask::task\{5} completed

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

    \[2007-06-17 12:18:10.195 'App' 1912 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.195 'App' 736 verbose] \[disposable,121] object explicitly disposed: CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.195 'App' 736 verbose] \[imageProcessingTaskImpl,123] CloneTask::task\{5} being destroyed

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 error] \[imageProcessingTaskStep,425] VmiImportTask::task\{4} step "clone to VM" failed

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 verbose] \[imageProcessingTaskWrapper,1117] Reporting image processing task failure for task CloneTask::task\{5}

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 error] \[imageProcessingTaskWrapper,1124] ImageProcessingTask FAILED. Fault name: sysimage.fault.ImageProcessingTaskFault

    \[#2] \[2007-06-17 12:18:10.195 'App' 1376 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "clone to VM" destroyed

    \[#2] \[2007-06-17 12:18:10.210 'App' 1376 error] \[vmiImportTask,328] Cloning failed: sysimage.fault.ImageProcessingTaskFault

    \[#2] \[2007-06-17 12:18:10.210 'App' 1376 info] \[vmiImportTask,494] Removing destination VM

    \[#2] \[2007-06-17 12:18:10.210 'App' 1376 info] \[vmiImportTask,502] Successfully connected to UfaManagedVMCreator

    \[#2] \[2007-06-17 12:18:10.210 'ManagedVMCreatorImpl::DestroyVM' 736 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[#2]

    \[#2] \[2007-06-17 12:18:10.429 'ManagedVMCreatorImpl::DestroyVM' 736 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:039ACE88]

    \[2007-06-17 12:18:10.429 'BaseLibs' 1380 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[#2] \[2007-06-17 12:18:10.476 'ManagedVMCreatorImpl::DestroyVM' 736 info] Authenticating user WinAdmin

    \[#2] \[2007-06-17 12:18:10.617 'ManagedVMCreatorImpl::DestroyVM' 736 info] Logged in!

    \[#2] \[2007-06-17 12:18:10.835 'App' 736 info] \[managedVMCreator,1866] Waiting for VM destruction to complete...

    \[#2] \[2007-06-17 12:18:10.835 'App' 736 verbose] \[rpcConnectionWrapper,785] Waiting for a task to complete...

    \[#2] \[2007-06-17 12:18:12.148 'App' 736 warning] ============BEGIN FAILED METHOD CALL DUMP============

    \[#2] \[2007-06-17 12:18:12.148 'App' 736 warning] Invoking \[GetInfo] on \[vim.Task:task-5849]

    \[#2] \[2007-06-17 12:18:12.148 'App' 736 warning] Fault has an empty message

    \[#2] \[2007-06-17 12:18:12.148 'App' 736 warning] ============END FAILED METHOD CALL DUMP============

    \[#2] \[2007-06-17 12:18:12.367 'ManagedVMCreatorImpl::DestroyVM' 736 verbose] Shutting down VMDB service...

    \[#2] \[2007-06-17 12:18:12.367 'ManagedVMCreatorImpl::DestroyVM' 736 verbose] Unregistering callback...

    \[#2] \[2007-06-17 12:18:12.367 'ManagedVMCreatorImpl::DestroyVM' 736 verbose] ...done

    \[#2] \[2007-06-17 12:18:12.367 'App' 1376 warning] \[vmiImportTask,507] Failed to delete VM: vmodl.fault.ManagedObjectNotFound

    \[#2] \[2007-06-17 12:18:12.367 'App' 1376 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "create and clone to VM" destroyed

    \[#2] \[2007-06-17 12:18:12.367 'App' 1376 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "Clone VM" destroyed

    \[#2] \[2007-06-17 12:18:12.367 'App' 1376 error] \[imageProcessingTaskImpl,552] VmiImportTask::task\{4}: Image processing task has failed with MethodFault::Exception: sysimage.fault.CloneFault

    \[#2] \[2007-06-17 12:18:12.367 'App' 1376 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{4}: SetState to error

    \[#2] \[2007-06-17 12:18:12.429 'App' 1376 verbose] \[disposable,121] object explicitly disposed: VmiImportTask::task\{4}

    \[#2] \[2007-06-17 12:18:12.429 'managedImportDestination' 1376 verbose] Shutting down VMDB service...

    \[#2] \[2007-06-17 12:18:12.429 'managedImportDestination' 1376 verbose] Unregistering callback...

    \[#2] \[2007-06-17 12:18:12.429 'managedImportDestination' 1376 verbose] ...done

    \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[disposable,121] object explicitly disposed: computer=\{2202622998d91b89190c31c8980df6baba3cfd5d}

    \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,119] Cleaning up computer's child objects

    \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

    \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,3} \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,4}

    \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

    \[#2] \[2007-06-17 12:18:12.429 'App' 1380 verbose] \[diskSet,145] Unregistring disk id=disk=\{5c822bdfe4346fded26a0a0854198ded817c6666}

    \[#2] \[2007-06-17 12:18:12.429 'App' 1376 verbose] \[imageProcessingTaskImpl,123] VmiImportTask::task\{4} being destroyed

    Converter server log:

    Log for VMware Converter, pid=2092, version=3.0.0, build=build-39557, option=Release, section=2

    \[2007-06-17 12:12:13.515 'App' 1200 info] Current working directory: C:\Documents and Settings\WinAdmin\Desktop

    \[2007-06-17 12:12:13.515 'P2V' 1200 info] \[p2vSupport,153] P2V logger created

    \[2007-06-17 12:12:13.515 'BaseLibs' 1200 info] Using system libcrypto, version 90709F

    \[2007-06-17 12:12:13.515 'P2V' 1200 info] \[p2vSupport,160] SSL context created

    \[2007-06-17 12:12:13.515 'P2V' 1200 info] \[p2vSupport,163] AppInit completed

    \[2007-06-17 12:12:13.515 'P2V' 1200 verbose] \[p2vLicenseManager,72] This product Hard Expires: false

    \[2007-06-17 12:12:13.546 'P2V' 1200 verbose] \[jobManager,300] Deserializing the job manager's state

    \[2007-06-17 12:12:13.562 'P2V' 1200 verbose] \[jobManager,378] Deserializing

    \[2007-06-17 12:12:13.562 'P2V' 1200 verbose] \[p2vLicenseManager,235] License job init with license file: C:\Documents and Settings\WinAdmin\Application Data\VMware\p2vlcns

    \[2007-06-17 12:12:13.562 'P2V' 1200 verbose] \[p2vLicenseManager,250] License job Success !

    \[2007-06-17 12:12:13.578 'P2V' 2460 verbose] \[task,339] Transition from None to InProgress requested

    \[2007-06-17 12:12:13.578 'P2V' 2460 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:12:13.578 'P2V' 2460 info] \[task,275] Starting execution of a Task

    \[2007-06-17 12:12:13.578 'P2V' 2460 verbose] \[jobDeserializationTask,112] Deserializing the individual jobs from C:\Documents and Settings\WinAdmin\Application Data\VMware\p2v30\jobs

    \[2007-06-17 12:12:13.578 'P2V' 2460 verbose] \[jobDeserializationTask,121] Archive file: 00000013.xml

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[job,231] Job 13 changing state from -1 to 2

    \[2007-06-17 12:12:13.593 'P2V' 2460 info] \[jobManager,444] Adding job 13

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[serialization,213] Serializing job 13

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[serialization,220] Archiving job to C:\Documents and Settings\WinAdmin\Application Data\VMware\p2v30\jobs\00000013.xml

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[jobManager,269] Raising event 0 for job 13

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[jobDeserializationTask,121] Archive file: jobManager.xml

    \[2007-06-17 12:12:13.593 'P2V' 2460 error] \[jobDeserializationTask,129] Could not deserialize job from file jobManager.xml: Bad job archive file name: jobManager.xml

    \[2007-06-17 12:12:13.593 'P2V' 2460 info] \[task,277] Task execution completed

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[task,339] Transition from InProgress to Success requested

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:12:13.593 'P2V' 2460 verbose] \[jobManager,261] Raising deserialization done event (7)

    \[2007-06-17 12:12:13.593 'P2V' 1200 verbose] \[p2vLicenseManager,235] License job init with license file: C:\Documents and Settings\WinAdmin\Application Data\VMware\p2vlcns

    \[2007-06-17 12:12:13.593 'P2V' 1200 verbose] \[p2vLicenseManager,250] License job Success !

    \[2007-06-17 12:13:12.796 'P2V' 1200 info] \[jobManager,540] Editing job 13

    \[2007-06-17 12:13:12.796 'P2V' 1200 verbose] \[job,231] Job 13 changing state from 2 to 6

    \[2007-06-17 12:13:12.796 'P2V' 1200 verbose] \[serialization,213] Serializing job 13

    \[2007-06-17 12:13:12.796 'P2V' 1200 verbose] \[serialization,220] Archiving job to C:\Documents and Settings\WinAdmin\Application Data\VMware\p2v30\jobs\00000013.xml

    \[2007-06-17 12:13:12.796 'P2V' 1200 verbose] \[jobManager,269] Raising event 2 for job 13

    \[2007-06-17 12:13:16.593 'P2V' 2320 verbose] \[task,339] Transition from None to InProgress requested

    \[2007-06-17 12:13:16.593 'P2V' 2320 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:13:16.593 'P2V' 2320 info] \[task,275] Starting execution of a Task

    \[2007-06-17 12:13:16.593 'P2V' 2320 info] \[clientConnection,100] Connecting to IPC$ on swifin01.ottawa.zarlink.com as ZARLINK\WinAdmin

    \[2007-06-17 12:13:16.609 'P2V' 2320 error] \[task,295] Task failed: P2VError REMOTE_UFA_BAD_CREDENTIALS(swifin01.ottawa.zarlink.com)

    \[2007-06-17 12:13:16.609 'P2V' 2320 verbose] \[task,339] Transition from InProgress to Failure requested

    \[2007-06-17 12:13:16.609 'P2V' 2320 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:13:21.578 'P2V' 2320 verbose] \[task,339] Transition from None to InProgress requested

    \[2007-06-17 12:13:21.578 'P2V' 2320 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:13:21.578 'P2V' 2320 info] \[task,275] Starting execution of a Task

    \[2007-06-17 12:13:21.578 'P2V' 2320 info] \[clientConnection,100] Connecting to IPC$ on swifin01.ottawa.zarlink.com as ZARLINK\WinAdmin

    \[2007-06-17 12:13:21.640 'P2V' 2320 info] \[clientConnection,102] Successfully connected to IPC$ on swifin01.ottawa.zarlink.com

    \[2007-06-17 12:13:21.656 'ClientConnection' 2320 info] Connecting to
    swifin01.ottawa.zarlink.com\pipe\vmware-ufad-p2v30-vmdb, use SSL: true

    \[2007-06-17 12:13:21.656 'ClientConnection' 2320 info] Successfully connected to
    swifin01.ottawa.zarlink.com\pipe\vmware-ufad-p2v30-vmdb

    \[2007-06-17 12:13:21.703 'VmdbSvc' 2320 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:01DDEAC0]

    \[2007-06-17 12:13:21.703 'BaseLibs' 3028 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[2007-06-17 12:13:21.718 'ClientConnection' 2320 info] Making sure that UFAD interface has version vmware-ufad-p2v30-build-39557

    \[2007-06-17 12:13:21.734 'ClientConnection' 2320 info] UFAD interface version is vmware-ufad-p2v30-build-39557

    \[2007-06-17 12:13:22.046 'P2V' 2320 info] \[task,277] Task execution completed

    \[2007-06-17 12:13:22.046 'P2V' 2320 verbose] \[task,339] Transition from InProgress to Success requested

    \[2007-06-17 12:13:22.046 'P2V' 2320 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:13:22.046 'P2V' 1200 info] \[ufaSession,49] QuerySourceInfo called

    \[2007-06-17 12:13:22.046 'P2V' 3028 verbose] \[task,339] Transition from None to InProgress requested

    \[2007-06-17 12:13:22.046 'P2V' 3028 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:13:22.046 'P2V' 3028 info] \[task,275] Starting execution of a Task

    \[2007-06-17 12:13:22.046 'P2V' 3028 info] \[ufaTask,173] Successfully connected to VMImporter

    \[2007-06-17 12:13:22.062 'App' 3028 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:22.078 'App' 3028 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:22.078 'App' 3028 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:22.078 'App' 3028 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:22.093 'App' 2460 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:22.125 'App' 3028 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:22.125 'App' 3028 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:34.390 'App' 2460 verbose] \[imageProcessingTaskWrapper,886] Remote task VmiQuerySourceInfoTask::task\{0} completed

    \[2007-06-17 12:13:34.406 'App' 2320 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:34.421 'App' 3028 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:34.468 'App' 3028 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task VmiQuerySourceInfoTask::task\{0}

    \[2007-06-17 12:13:34.500 'P2V' 3028 info] \[task,277] Task execution completed

    \[2007-06-17 12:13:34.500 'P2V' 3028 verbose] \[task,339] Transition from InProgress to Success requested

    \[2007-06-17 12:13:34.500 'P2V' 3028 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:13:45.656 'P2V' 1200 verbose] \[p2vLicenseManager,235] License job init with license file: C:\Documents and Settings\WinAdmin\Application Data\VMware\p2vlcns

    \[2007-06-17 12:13:45.656 'P2V' 1200 verbose] \[p2vLicenseManager,250] License job Success !

    \[2007-06-17 12:13:45.671 'P2V' 1200 verbose] \[p2vLicenseManager,235] License job init with license file: C:\Documents and Settings\WinAdmin\Application Data\VMware\p2vlcns

    \[2007-06-17 12:13:45.671 'P2V' 1200 verbose] \[p2vLicenseManager,250] License job Success !

    \[2007-06-17 12:14:31.203 'P2V' 3028 verbose] \[task,339] Transition from None to InProgress requested

    \[2007-06-17 12:14:31.203 'P2V' 3028 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:14:31.203 'P2V' 3028 info] \[task,275] Starting execution of a Task

    \[2007-06-17 12:14:31.203 'GetInventory' 3028 info] Connecting to host ottvc01.ottawa.zarlink.com on port 902

    \[2007-06-17 12:14:31.218 'GetInventory' 3028 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:01DE9918]

    \[2007-06-17 12:14:31.218 'BaseLibs' 2460 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

    \[2007-06-17 12:14:31.250 'GetInventory' 3028 info] Authenticating user WinAdmin

    \[2007-06-17 12:14:31.281 'GetInventory' 3028 info] Logged in!

    \[2007-06-17 12:14:31.734 'GetInventory' 3028 verbose] Shutting down VMDB service...

    \[2007-06-17 12:14:31.734 'GetInventory' 3028 verbose] Unregistering callback...

    \[2007-06-17 12:14:31.734 'GetInventory' 3028 verbose] ...done

    \[2007-06-17 12:14:31.734 'P2V' 3028 info] \[task,277] Task execution completed

    \[2007-06-17 12:14:31.734 'P2V' 3028 verbose] \[task,339] Transition from InProgress to Success requested

    \[2007-06-17 12:14:31.734 'P2V' 3028 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:14:41.015 'P2V' 1200 info] \[ufaSession,64] GetTargetDiskSize called

    \[2007-06-17 12:14:41.031 'P2V' 2132 verbose] \[task,339] Transition from None to InProgress requested

    \[2007-06-17 12:14:41.031 'P2V' 2132 verbose] \[task,388] Transition succeeded

    \[2007-06-17 12:14:41.031 'P2V' 2132 info] \[task,275] Starting execution of a Task

    \[2007-06-17 12:14:41.031 'P2V' 2132 info] \[ufaTask,173] Successfully connected to VMImporter

    \[2007-06-17 12:14:41.062 'App' 2132 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:41.078 'App' 2132 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:41.078 'App' 2132 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:41.078 'App' 2132 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:41.109 'App' 3028 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:41.125 'App' 2132 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:41.125 'App' 2132 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:46.968 'App' 360 verbose] \[imageProcessingTaskWrapper,886] Remote task VmiGetDiskSizesTask::task\{1} completed

    \[2007-06-17 12:14:46.984 'App' 2320 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:47.000 'App' 2132 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:47.015 'App' 2132 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task VmiGetDiskSizesTask::task\{1}

    \[2007-06-17 12:14:47.046 'P2V' 2132 info] \[task,277] Task execution completed

    \[2007-06-17 12:14:47.046 'P2V' 2132 verbose] \[task,339] Transition from InProgress to Success requested

    \[2007-06-17 12:14:47.046 'P2V' 2132 verbose] \[task,388] Transition