Same old problem...1%-5%-97% Failed on Win2k3sp1 P2V

[jpa9058]

Converting this server consistently fails in a matter of seconds. The server itself is a Dell PE2850 with 2 basic partitions and a utility partition. I have tried converting with and without the utility partition. I have tried converting wiht and without NICs. I've tried the different settings for the destination disk type. I've tried converting locally as well as remotely. I don't have an ESX host to convert to, so I haven't tried that. I'm also only using the starter edition, so I can't perform a cold conversion.

I've tried a lot of things, including:

Install converter from console

convert locally

Defragment all drives

chkdsk /f on all drives

Reduce number of installed NICs

Verify that the following services are running:

Workstation

Server

TCP/IP NetBios Helper

Stop the antivirus service

Change partition sizes (reduce slightly)

Verify that following services are already running during convert (may be 2k only):

net start vstor2-p2v30

net start ufad-p2v

net start vstor2-p2v30

net start stcp2v30

remove com port devices from registry (unfortunately there was only one com port device)

1) On the source host, open regedit

2) Navigate to HKEY_LOCAL_MACHINE\HARDWARE\DEVICEMAP\SERIALCOMM

3) Export the key for backup-archive-restore purposes

4) Delete CcmPort3 thru CcmPort9

5) Retry the migration

I've also tried all kinds of different settings in the converter, as well as different logons (domain/local admin)

The result is always the same.

Following is one of the logs from vmware-converter-6.log:

\[#6] \[2007-04-11 13:42:10.796 'App' 496 info] \[vmImporter,150] Entering UFAD at CheckDestAvailable

\[#6] \[2007-04-11 13:42:10.796 'App' 496 verbose] \[vmImporter,151] target:

\[#6] (sysimage.HostedTargetVMSpec) {

\[#6] dynamicType = ,

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

\[#6] volumeId = "attVol=\{computer={8d56326b536472341b75b4f61b479869dbeb1967},2}",

\[#6] bResize = true,

\[#6] newCapacityInBytes = 343597383680

\[#6] }

\[#6] ],

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

\[#6] }

\[#6] \[2007-04-11 13:42:12.234 'App' 2476 verbose] \[ufaConnection,94] Creating local adapter...

\[#6] \[2007-04-11 13:42:12.234 'App' 2476 verbose] \[ufaConnection,94] Creating local adapter...

\[#6] \[2007-04-11 13:42:12.234 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=VmiGetDiskSizesTask::task\{15}

\[#6] \[2007-04-11 13:42:12.234 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=VmiGetDiskSizesTask::task\{15} with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:12.265 'App' 360 verbose] \[imageProcessingTaskImpl,504] VmiGetDiskSizesTask::task\{15}: Starting execution of an image processing task

\[#6] \[2007-04-11 13:42:12.265 'App' 360 verbose] \[imageProcessingTaskImpl,154] VmiGetDiskSizesTask::task\{15}: SetState to running

\[#6] \[2007-04-11 13:42:12.265 'App' 360 info] \[vmiGetDiskSizesTask,53] VmiGetDiskSizesTask initialized

\[#6] \[2007-04-11 13:42:12.265 'App' 3512 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=4371 size=8192 err=0

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:12.265 'App' 3512 verbose] \[ufaConnection,94] Creating local adapter...

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{8a906ff3-6343-11da-bb84-806e6f6e6963}\"

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{027ffb7d-636f-11da-b315-505054503030}\"

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{027ffb74-636f-11da-b315-806e6f6e6963}\"

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\"

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 0 at sector 63.

\[#6] \[2007-04-11 13:42:12.265 'App' 2476 warning] \[attachedComputerWin32,567] 1

\[#6] \[2007-04-11 13:42:13.328 'App' 2476 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-04-11 13:42:13.390 'App' 2228 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\ is not supported .... Trying NT method.

\[??] \[2007-04-11 13:42:13.390 'App' 2228 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\ Volume's disk number

\[#6] \[2007-04-11 13:42:13.390 'App' 360 verbose] \[vmiTask,109] VmiGetDiskSizesTask::task\{15}: returning results:

\[#6] (long) [

\[#6] 40441151488

\[#6] ]

\[#6] \[2007-04-11 13:42:13.390 'App' 360 verbose] \[imageProcessingTaskImpl,154] VmiGetDiskSizesTask::task\{15}: SetState to success

\[#6] \[2007-04-11 13:42:13.390 'App' 360 verbose] \[imageProcessingTaskImpl,514] VmiGetDiskSizesTask::task\{15}: Image processing task has succeeded

\[#6] \[2007-04-11 13:42:13.437 'App' 364 verbose] \[disposable,121] object explicitly disposed: VmiGetDiskSizesTask::task\{15}

\[#6] \[2007-04-11 13:42:13.437 'App' 3512 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#6] \[2007-04-11 13:42:13.437 'App' 3512 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#6] \[2007-04-11 13:42:13.437 'App' 3512 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#6] \[2007-04-11 13:42:13.437 'App' 3512 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#6] \[2007-04-11 13:42:13.437 'App' 3512 verbose] \[diskSet,135] Unregistring volume id=detVol=\{computer=,3} \[#6] \[2007-04-11 13:42:13.437 'App' 3512 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea} \[#6] \[2007-04-11 13:42:13.437 'App' 364 verbose] \[imageProcessingTaskImpl,123] VmiGetDiskSizesTask::task\{15} being destroyed \[#6] \[2007-04-11 13:42:13.453 'App' 364 info] \[vmImporter,188] Entering UFAD at CheckDestParams \[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[vmImporter,189] source: \[#6] (sysimage.LiveSourceComputerSpec) { \[#6] dynamicType = <unset>, \[#6] dynamicProperty = (vmodl.DynamicProperty) [], \[#6] host = "", \[#6] port = 0 \[#6] } \[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[vmImporter,190] target: \[#6] (sysimage.HostedTargetVMSpec) { \[#6] dynamicType = <unset>, \[#6] dynamicProperty = (vmodl.DynamicProperty) [], \[#6] vmName = "protosql", \[#6] vmPath = "d:\", \[#6] username = <unset>, \[#6] password = <unset> \[#6] } \[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[vmImporter,191] params: \[#6] (sysimage.ImportParams) { \[#6] dynamicType = <unset>, \[#6] dynamicProperty = (vmodl.DynamicProperty) [], \[#6] targetProductVersion = "PRODUCT_WS_5X", \[#6] diskType = "MONOLITHIC_SPARSE", \[#6] preallocateDisks = false, \[#6] clonePagefile = false, \[#6] nicMapping = (string) [ \[#6] "Bridged" \[#6] ], \[#6] volumesToClone = (sysimage.VolumeCloneInfo) [ \[#6] (sysimage.VolumeCloneInfo) { \[#6] dynamicType = <unset>, \[#6] dynamicProperty = (vmodl.DynamicProperty) [], \[#6] volumeId = "attVol=\{computer=,1}",

\[#6] bResize = true,

\[#6] newCapacityInBytes = 21367462297

\[#6] },

\[#6] (sysimage.VolumeCloneInfo) {

\[#6] dynamicType = ,

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

\[#6] volumeId = "attVol=\{computer={8d56326b536472341b75b4f61b479869dbeb1967},2}",

\[#6] bResize = true,

\[#6] newCapacityInBytes = 343597383680

\[#6] }

\[#6] ],

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

\[#6] }

\[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[vmImporter,192] diskSizes:

\[#6] (long) [

\[#6] 40441151488

\[#6] ]

\[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[ufaConnection,94] Creating local adapter...

\[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[ufaConnection,94] Creating local adapter...

\[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[disposable,68] Registering disposable session object id=VmiCheckDestParamsTask::task\{16}

\[#6] \[2007-04-11 13:42:13.453 'App' 364 verbose] \[disposable,79] Associating disposable session object id=VmiCheckDestParamsTask::task\{16} with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.484 'App' 3512 verbose] \[imageProcessingTaskImpl,504] VmiCheckDestParamsTask::task\{16}: Starting execution of an image processing task

\[#6] \[2007-04-11 13:42:13.484 'App' 3512 verbose] \[imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task\{16}: SetState to running

\[#6] \[2007-04-11 13:42:13.484 'App' 3512 info] \[vmiCheckDestParamsTask,55] VmiCheckDestParamsTask initialized

\[#6] \[2007-04-11 13:42:13.484 'App' 3512 warning] \[importSource,319] : no metadata for sources of this type

\[#6] \[2007-04-11 13:42:13.484 'App' 364 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#6] \[2007-04-11 13:42:13.484 'App' 496 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#6] \[2007-04-11 13:42:13.484 'App' 496 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#6] \[2007-04-11 13:42:13.484 'App' 496 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122

\[#6] \[2007-04-11 13:42:13.484 'App' 496 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=4371 size=8192 err=0

\[#6] \[2007-04-11 13:42:13.484 'App' 496 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#6] \[2007-04-11 13:42:13.484 'App' 496 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.484 'App' 364 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{8a906ff3-6343-11da-bb84-806e6f6e6963}\"

\[#6] \[2007-04-11 13:42:13.484 'App' 364 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{027ffb7d-636f-11da-b315-505054503030}\"

\[#6] \[2007-04-11 13:42:13.484 'App' 364 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{027ffb74-636f-11da-b315-806e6f6e6963}\"

\[#6] \[2007-04-11 13:42:13.484 'App' 364 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\"

\[#6] \[2007-04-11 13:42:13.484 'App' 364 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 0 at sector 63.

\[#6] \[2007-04-11 13:42:13.500 'App' 364 warning] \[attachedComputerWin32,567] 1

\[#6] \[2007-04-11 13:42:13.562 'App' 364 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-04-11 13:42:13.625 'App' 2228 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\ is not supported .... Trying NT method.

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[diskSet,196] 3 volumes found

\[??] \[2007-04-11 13:42:13.625 'App' 2228 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\ Volume's disk number

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[diskSet,364] Volume: idx=2 sysId=-34 active=N

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[diskSet,203] 1 potential active volumes found

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] \[boot loader]

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] timeout=30

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] \[operating systems]

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[extendedOsInfo,453] SwagNTInfoFromString has Windows Server 2003, Standard

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[extendedOsInfo,176] Returning OS as display Windows Server 2003, Standard

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] \[boot loader]

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] timeout=30

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] \[operating systems]

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[extendedOsInfo,453] SwagNTInfoFromString has Windows Server 2003, Standard

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[extendedOsInfo,176] Returning OS as display Windows Server 2003, Standard

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[diskSet,297] OS resides on partition 2 , disk path
.\PhysicalDrive0

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] \[boot loader]

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] timeout=30

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] \[operating systems]

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[attachedVolumeWin32,363] Opening host registry with path="SYSTEM"

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SYSTEM

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SYSTEM with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SYSTEM

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[attachedVolumeWin32,363] Opening host registry with path="SOFTWARE"

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SOFTWARE

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SOFTWARE with session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows Server 2003 root:WINDOWS\ SrvPack:1 SMP:1 64:0 Ver:5.2

\[#6] \[2007-04-11 13:42:13.625 'App' 364 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SOFTWARE

\[#6] \[2007-04-11 13:42:13.625 'App' 364 info] \[extendedOsInfo,178] Returning OS as winNetEnterpriseGuest

\[#6] \[2007-04-11 13:42:13.625 'App' 3512 verbose] \[vmiTask,109] VmiCheckDestParamsTask::task\{16}: returning results:

\[#6] (sysimage.CompatibilityWarning) [

\[#6] "IMPORT_OK_NO_INSTALL_TOOLS"

\[#6] ]

\[#6] \[2007-04-11 13:42:13.625 'App' 3512 verbose] \[imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task\{16}: SetState to success

\[#6] \[2007-04-11 13:42:13.625 'App' 3512 verbose] \[imageProcessingTaskImpl,514] VmiCheckDestParamsTask::task\{16}: Image processing task has succeeded

\[#6] \[2007-04-11 13:42:13.671 'App' 3512 verbose] \[disposable,121] object explicitly disposed: VmiCheckDestParamsTask::task\{16}

\[#6] \[2007-04-11 13:42:13.671 'App' 2476 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#6] \[2007-04-11 13:42:13.671 'App' 2476 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#6] \[2007-04-11 13:42:13.671 'App' 2476 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#6] \[2007-04-11 13:42:13.671 'App' 2476 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#6] \[2007-04-11 13:42:13.671 'App' 2476 verbose] \[diskSet,135] Unregistring volume id=detVol=\{computer=,3} \[#6] \[2007-04-11 13:42:13.671 'App' 2476 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea} \[#6] \[2007-04-11 13:42:13.671 'App' 3512 verbose] \[imageProcessingTaskImpl,123] VmiCheckDestParamsTask::task\{16} being destroyed \[2007-04-11 13:42:17.375 'VmdbSvc' 500 verbose] IVmdbPipe2Impl operation completed with . \[2007-04-11 13:42:17.375 'App' 2084 verbose] CloseSession called for session id=B9DF8556-3C14-4A40-B08E-6ECD6C28110C \[2007-04-11 13:42:17.375 'VmdbAdapter' 2084 verbose] Removed vmdb connection /db/connection/#6/ \[#7] \[2007-04-11 13:42:17.406 'App' 2084 info] Impersonating user Administrator in session 5584FDF4-B1D3-4C20-83BB-7D546727EF00 \[#7] \[2007-04-11 13:42:17.437 'App' 2084 info] \[vmImporter,209] Entering UFAD at DoImport \[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[vmImporter,210] source: \[#7] (sysimage.LiveSourceComputerSpec) { \[#7] dynamicType = <unset>, \[#7] dynamicProperty = (vmodl.DynamicProperty) [], \[#7] host = "", \[#7] port = 0 \[#7] } \[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[vmImporter,211] target: \[#7] (sysimage.HostedTargetVMSpec) { \[#7] dynamicType = <unset>, \[#7] dynamicProperty = (vmodl.DynamicProperty) [], \[#7] vmName = "protosql", \[#7] vmPath = "d:\", \[#7] username = <unset>, \[#7] password = <unset> \[#7] } \[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[vmImporter,212] params: \[#7] (sysimage.ImportParams) { \[#7] dynamicType = <unset>, \[#7] dynamicProperty = (vmodl.DynamicProperty) [], \[#7] targetProductVersion = "PRODUCT_WS_5X", \[#7] diskType = "MONOLITHIC_SPARSE", \[#7] preallocateDisks = false, \[#7] clonePagefile = false, \[#7] nicMapping = (string) [ \[#7] "Bridged" \[#7] ], \[#7] volumesToClone = (sysimage.VolumeCloneInfo) [ \[#7] (sysimage.VolumeCloneInfo) { \[#7] dynamicType = <unset>, \[#7] dynamicProperty = (vmodl.DynamicProperty) [], \[#7] volumeId = "attVol=\{computer=,1}",

\[#7] bResize = true,

\[#7] newCapacityInBytes = 21367462297

\[#7] },

\[#7] (sysimage.VolumeCloneInfo) {

\[#7] dynamicType = ,

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

\[#7] volumeId = "attVol=\{computer={8d56326b536472341b75b4f61b479869dbeb1967},2}",

\[#7] bResize = true,

\[#7] newCapacityInBytes = 343597383680

\[#7] }

\[#7] ],

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

\[#7] }

\[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[ufaConnection,94] Creating local adapter...

\[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[ufaConnection,94] Creating local adapter...

\[#7] \[2007-04-11 13:42:17.437 'App' 2084 info] \[vmiImportTask,110] Successfully connected to UfaClient

\[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[disposable,68] Registering disposable session object id=VmiImportTask::task\{17}

\[#7] \[2007-04-11 13:42:17.437 'App' 2084 verbose] \[disposable,79] Associating disposable session object id=VmiImportTask::task\{17} with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 verbose] \[imageProcessingTaskImpl,504] VmiImportTask::task\{17}: Starting execution of an image processing task

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{17}: SetState to running

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 info] \[vmiImportTask,180] Starting VM Import...

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 info] \[vmiImportTask,116] vmiImportTask initialized

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{17} step "Clone VM" created

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 verbose] \[vmiImportTask,201] VmiImportTask::DoRun - Calling GetComputer()

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#7] \[2007-04-11 13:42:17.468 'App' 364 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#7] \[2007-04-11 13:42:17.468 'App' 364 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#7] \[2007-04-11 13:42:17.468 'App' 364 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=4096 err=122

\[#7] \[2007-04-11 13:42:17.468 'App' 364 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=4371 size=8192 err=0

\[#7] \[2007-04-11 13:42:17.468 'App' 364 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#7] \[2007-04-11 13:42:17.468 'App' 364 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 verbose] \[vmiImportTask,223] VmiImportTask::DoRun - Calling Clone()

\[#7] \[2007-04-11 13:42:17.468 'App' 3512 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{17} step "create and clone to VM" created

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{8a906ff3-6343-11da-bb84-806e6f6e6963}\"

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{027ffb7d-636f-11da-b315-505054503030}\"

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{027ffb74-636f-11da-b315-806e6f6e6963}\"

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\"

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 verbose] \[attachedComputerWin32,314] Found candidate of type 222 on disk 0 at sector 63.

\[#7] \[2007-04-11 13:42:17.468 'App' 2476 warning] \[attachedComputerWin32,567] 1

\[#7] \[2007-04-11 13:42:18.546 'App' 2476 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-04-11 13:42:18.609 'App' 2228 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\ is not supported .... Trying NT method.

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[diskSet,196] 3 volumes found

\[??] \[2007-04-11 13:42:18.609 'App' 2228 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{8a906ff2-6343-11da-bb84-806e6f6e6963}\ Volume's disk number

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[diskSet,364] Volume: idx=2 sysId=-34 active=N

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[diskSet,203] 1 potential active volumes found

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] \[boot loader]

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] timeout=30

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] \[operating systems]

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[extendedOsInfo,453] SwagNTInfoFromString has Windows Server 2003, Standard

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[extendedOsInfo,176] Returning OS as display Windows Server 2003, Standard

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] \[boot loader]

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] timeout=30

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] \[operating systems]

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[extendedOsInfo,453] SwagNTInfoFromString has Windows Server 2003, Standard

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[extendedOsInfo,176] Returning OS as display Windows Server 2003, Standard

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[diskSet,297] OS resides on partition 2 , disk path
.\PhysicalDrive0

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] \[boot loader]

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] timeout=30

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] \[operating systems]

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[attachedVolumeWin32,363] Opening host registry with path="SYSTEM"

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SYSTEM

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SYSTEM with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SYSTEM

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[attachedVolumeWin32,363] Opening host registry with path="SOFTWARE"

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,68] Registering disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SOFTWARE

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,79] Associating disposable session object id=hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SOFTWARE with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows Server 2003 root:WINDOWS\ SrvPack:1 SMP:1 64:0 Ver:5.2

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[disposable,121] object explicitly disposed: hostRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},SOFTWARE

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 info] \[extendedOsInfo,178] Returning OS as winNetEnterpriseGuest

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[ufaConnection,94] Creating local adapter...

\[#7] \[2007-04-11 13:42:18.609 'App' 2084 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#7] \[2007-04-11 13:42:18.609 'App' 2084 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#7] \[2007-04-11 13:42:18.609 'App' 2084 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#7] \[2007-04-11 13:42:18.609 'App' 3512 verbose] \[vmiImportTask,268] Successfully created target storage layout

\[#7] \[2007-04-11 13:42:18.609 'App' 3512 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{17} step "create VM" created

\[#7] \[2007-04-11 13:42:18.609 'App' 3512 info] \[vmiImportTask,345] Successfully connected to UfaLocalVMCreator

\[??] \[2007-04-11 13:42:18.609 'App' 2228 info] \[stub,42] HostDeviceInfo: Failed to enumerate host parallel ports via the registry. Could not open device map parallel port registry key.

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[attachedComputerWin32,780] Detected 2 NICs

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:11:43:E9:63:37

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[attachedComputerWin32,815] NIC1 has description "TEAM : On-Board Team"

\[#7] \[2007-04-11 13:42:18.609 'App' 2476 verbose] \[attachedComputerWin32,788] NIC2 is non-ethernet - discarding

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] scsi controller 0 has key=1004 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] ide controller 0 has key=200 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] ide controller 1 has key=201 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 verbose] \[localVMCreator,1794] Adding a NIC with legacy backing type "bridged"

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for USBcontroller

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] USBcontroller controller 0 has key=1005 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for cdrom

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1920] Device cdrom has key=1000 and ID=0 CtrlKey=200

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1943] Devicebacking E:

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,2021] Setting cdrom to Autodetect

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for disk

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1920] Device disk has key=1003 and ID=0 CtrlKey=1004

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1943] Devicebacking PhysicalDrive0

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for floppy

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1920] Device floppy has key=1001 and ID=None CtrlKey=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1943] Devicebacking A:

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for ide

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] ide controller 0 has key=200 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] ide controller 1 has key=201 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for scsi

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1915] scsi controller 0 has key=1004 and ID=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1987] no backing

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1828] FixupBacking for serial

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1920] Device serial has key=1002 and ID=None CtrlKey=None

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1943] Devicebacking COM1

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,2073] CPU info: physHost 4 VM 4

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,334] Alerts:

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,337] There are more virtual CPUs in the VM than there are on the physical Host. Setting number of virtual CPUs to match host.

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,337] VM memory exceeds allowable physical memory on the physical Host. Setting allowed memory to match host.

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,451] Creating Local VM (protosql) TargetProduct PRODUCT_WS_550

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,1059] Target config file: d:\protosql\protosql.vmx

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[,0] UUID: canonical path is d:\protosql\protosql.vmx

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[,0] UUID: location-UUID is 56 4d d6 3d 94 a7 b4 49-41 7f df a6 ca b6 4f b8

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 verbose] \[localVMCreator,1198] Got a NIC: (vim.vm.device.VirtualEthernetCard) {

\[#7] dynamicType = ,

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

\[#7] deviceName = "auto-detect"

\[#7] },

\[#7] connectable = (vim.vm.device.VirtualDevice.ConnectInfo) null,

\[#7] controllerKey = 200,

\[#7] unitNumber = 0

\[#7] }

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 info] \[localVMCreator,773] Creating Local Disks

\[#7] \[2007-04-11 13:42:18.625 'App' 2476 verbose] \[localVMCreator,879] Creating Local Disk; Disk name= d:\protosql\protosql.vmdk; Mode = persistent; Is2GBSplit = No; preallocate space = No; is disk Flat = No; size = 356435179

\[#7] \[2007-04-11 13:42:18.921 'App' 2476 info] \[,0] DISKLIB-LINK : "d:\protosql\protosql.vmdk" : creation successful.

\[#7] \[2007-04-11 13:42:19.578 'App' 2476 info] \[,0] DISKLIB-DSCPTR: Opened : "protosql.vmdk" (0x2)

\[#7] \[2007-04-11 13:42:19.593 'App' 2476 info] \[,0] DISKLIB-LINK : Opened 'd:\protosql\protosql.vmdk' (0x2): monolithicSparse, 712870358 sectors / 348082 Mb.

\[#7] \[2007-04-11 13:42:19.593 'App' 2476 info] \[,0] DISKLIB-LIB : Opened "d:\protosql\protosql.vmdk" (flags 0x2). 030D6F88

\[#7] \[2007-04-11 13:42:19.593 'App' 2476 verbose] \[diskHandleWrapper,246] Setting DDB property virtualHWVersion to 4.

\[#7] \[2007-04-11 13:42:19.593 'App' 2476 verbose] \[diskHandleWrapper,246] Setting DDB property toolsVersion to 0.

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "create VM" 100% completed

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "create and clone to VM" 5% completed

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "Clone VM" 5% completed

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{17} step "create VM" destroyed

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 verbose] \[vmiImportTask,283] Successfully created target VM Spec

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{17} step "clone to VM" created

\[#7] \[2007-04-11 13:42:19.593 'App' 3512 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

\[#7] \[2007-04-11 13:42:19.593 'App' 2084 info] \[sysMigration,192] Starting cloning...

\[#7] \[2007-04-11 13:42:19.593 'App' 2084 verbose] \[sysMigration,193] targetVMSpec:

\[#7] (sysimage.LocalVmSpec) {

\[#7] dynamicType =

\[#7] \[2007-04-11 13:42:19.609 'App' 2084 verbose] \[sysMigration,199] clonePagefile: false

\[#7] \[2007-04-11 13:42:19.609 'App' 2084 verbose] \[sysMigration,200] updateBootInit: true

\[#7] \[2007-04-11 13:42:19.609 'App' 2084 verbose] \[sysMigration,201] updateDriveLetters: true

\[#7] \[2007-04-11 13:42:19.609 'App' 2084 verbose] \[ufaConnection,94] Creating local adapter...

\[#7] \[2007-04-11 13:42:19.609 'App' 500 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#7] \[2007-04-11 13:42:19.609 'App' 2084 info] \[cloneTask,236] Parsing the VM spec to retrieve disk specs...

\[#7] \[2007-04-11 13:42:19.609 'App' 2084 verbose] \[foundryParser,838] Using temporary VMX file: d:\protosql\protosql__0.vmx

\[??] \[2007-04-11 13:42:19.609 'App' 2416 warning] \[,0] CreateFile on pipe
.\pipe\d:\protosql\protosql__0.vmx failed 2.

\[#7] \[2007-04-11 13:42:19.718 'Ufa-foundry' 2084 verbose] Completed opening virtual machine.

\[#7] \[2007-04-11 13:42:19.734 'Ufa-foundry' 2084 verbose] Time to gather config: 15 (msecs)

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 verbose] \[foundryParser,377] Adding controller for IDE bus 0 with key 200.

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[foundryParser,628] LocalVirtualDiskSpec: Name=d:\protosql\protosql.vmdk

\[#7] \[2007-04-11 13:42:19.734 'Ufa-foundry' 2084 verbose] Close vmHandle called

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 verbose] \[cloneTask,258] Disk Name: d:\protosql\protosql.vmdk

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 verbose] \[disposable,68] Registering disposable session object id=CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 verbose] \[disposable,79] Associating disposable session object id=CloneTask::task\{18} with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 info] \[imageProcessingTaskStep,409] VmiImportTask::task\{17} step "clone to VM" in progress...

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 verbose] \[imageProcessingTaskImpl,504] CloneTask::task\{18}: Starting execution of an image processing task

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,474] Waiting for completion of task CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 verbose] \[imageProcessingTaskImpl,154] CloneTask::task\{18}: SetState to running

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[cloneTask,148] Starting the cloning process...

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[cloneTask,157] Preparing for cloning...

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[volumeMappingInfoBuilder,90] Initializing VolumeMappingInfoBuilder...

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,196] 3 volumes found

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[2007-04-11 13:42:19.734 'App' 364 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[2007-04-11 13:42:19.734 'App' 364 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "clone to VM" 0% completed

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,364] Volume: idx=2 sysId=-34 active=N

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,203] 1 potential active volumes found

\[#7] \[2007-04-11 13:42:19.734 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[extendedOsInfo,389] \[boot loader]

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[extendedOsInfo,389] timeout=30

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(2)\WINDOWS

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[extendedOsInfo,389] \[operating systems]

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(2)\WINDOWS="Windows Server 2003, Standard" /fastdetect /NoExecute=OptOut

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#7] \[2007-04-11 13:42:19.734 'App' 500 info] \[extendedOsInfo,453] SwagNTInfoFromString has Windows Server 2003, Standard

\[#7] \[2007-04-11 13:42:19.734 'App' 500 info] \[extendedOsInfo,176] Returning OS as display Windows Server 2003, Standard

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#7] \[2007-04-11 13:42:19.734 'App' 500 info] \[diskSet,297] OS resides on partition 2 , disk path
.\PhysicalDrive0

\[#7] \[2007-04-11 13:42:19.734 'App' 500 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[volumeMappingInfoBuilder,178] Failed to get Windows unique ID

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[cloneTask,161] Prepared...

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[volumeBasedCloneTask,128] Performing volume-based cloning...

\[#7] \[2007-04-11 13:42:19.734 'App' 2084 info] \[cloneTask,272] Opening the target computer...

\[#7] \[2007-04-11 13:42:19.734 'App' 360 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[??] \[2007-04-11 13:42:20.343 'App' 2228 info] \[stub,42] DISKLIB-DSCPTR: Opened : "protosql.vmdk" (0xa)

\[??] \[2007-04-11 13:42:20.343 'App' 2228 info] \[stub,42] DISKLIB-LINK : Opened 'd:\protosql\protosql.vmdk' (0xa): monolithicSparse, 712870358 sectors / 348082 Mb.

\[??] \[2007-04-11 13:42:20.343 'App' 2228 info] \[stub,42] DISKLIB-LIB : Opened "d:\protosql\protosql.vmdk" (flags 0xa). 0311F360

\[#7] \[2007-04-11 13:42:20.453 'App' 500 verbose] \[disposable,68] Registering disposable session object id=computer=\{faa41bae8f60504ecc62e470d6cea7cb4519158a}

\[#7] \[2007-04-11 13:42:20.453 'App' 500 verbose] \[disposable,79] Associating disposable session object id=computer=\{faa41bae8f60504ecc62e470d6cea7cb4519158a} with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[cloneTask,314] Getting disks...

\[#7] \[2007-04-11 13:42:20.453 'App' 360 verbose] \[diskSet,330] GetDisks: disk=\{1fd6c27833ee2aa8f3443d9168de081213a17f7d}

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 verbose] \[cloneTask,326] Disk 0 id=disk=\{1fd6c27833ee2aa8f3443d9168de081213a17f7d}

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[volumeBasedCloneTask,346] Partitioning disk disk=\{1fd6c27833ee2aa8f3443d9168de081213a17f7d}

\[??] \[2007-04-11 13:42:20.453 'App' 2228 info] \[stub,42] No geometry.biosSectors defined,

\[??] \[2007-04-11 13:42:20.453 'App' 2228 info] \[stub,42] No geometry.biosHeads defined,

\[??] \[2007-04-11 13:42:20.453 'App' 2228 info] \[stub,42] No geometry.biosCylinders defined,

\[??] \[2007-04-11 13:42:20.453 'App' 2228 info] \[stub,42] No geometry.biosSectors defined,

\[??] \[2007-04-11 13:42:20.453 'App' 2228 info] \[stub,42] No geometry.biosHeads defined,

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[cloneTask,344] Getting volumes for computer id=computer=\{faa41bae8f60504ecc62e470d6cea7cb4519158a}

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 verbose] \[cloneTask,356] Volume 0 id=virtVol=\{computer=,0} \[#7] \[2007-04-11 13:42:20.453 'App' 2084 verbose] \[cloneTask,356] Volume 1 id=virtVol=\{computer=,1}

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[imageProcessingTaskStep,155] CloneTask::task\{18} step "clone volumes" created

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[cloneTask,375] Getting volumes for the source computer...

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 verbose] \[cloneTask,385] Volume 0 id=attVol=\{computer=,1} \[#7] \[2007-04-11 13:42:20.453 'App' 2084 verbose] \[cloneTask,385] Volume 1 id=attVol=\{computer=,2}

\[#7] \[2007-04-11 13:42:20.453 'App' 2084 verbose] \[cloneTask,385] Volume 2 id=detVol=\{computer=,3} \[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[cloneTask,583] Formatting volume id=virtVol=\{computer=,0} \[#7] \[2007-04-11 13:42:20.453 'App' 2084 info] \[imageProcessingTaskStep,155] CloneTask::task\{18} step "Formatting volume virtVol=\{computer=,0}" created \[#7] \[2007-04-11 13:42:20.453 'App' 360 info] \[volumeWin32,750] Formatting volume virtVol=\{computer=,0} \[#7] \[2007-04-11 13:42:20.453 'App' 360 verbose] \[disposable,68] Registering disposable session object id=volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.453 'App' 360 verbose] \[disposable,79] Associating disposable session object id=volumeFormattingWin32Task::task\{19} with session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00 \[#7] \[2007-04-11 13:42:20.453 'App' 360 info] \[volumeWin32,762] Scheduling volume formatting for execution... \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 info] \[imageProcessingTaskStep,409] CloneTask::task\{18} step "Formatting volume virtVol=\{computer=,0}" in progress... \[#7] \[2007-04-11 13:42:20.468 'App' 360 verbose] \[imageProcessingTaskImpl,504] volumeFormattingWin32Task::task\{19}: Starting execution of an image processing task \[2007-04-11 13:42:20.468 'App' 500 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,474] Waiting for completion of task volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 360 verbose] \[imageProcessingTaskImpl,154] volumeFormattingWin32Task::task\{19}: SetState to running \[#7] \[2007-04-11 13:42:20.468 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from volumeFormattingWin32Task::task\{19} \[2007-04-11 13:42:20.468 'App' 364 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[2007-04-11 13:42:20.468 'App' 496 verbose] \[imageProcessingTaskWrapper,749] Got an update from volumeFormattingWin32Task::task\{19} \[2007-04-11 13:42:20.468 'App' 496 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{18} step "Formatting volume virtVol=\{computer=,0}" 0% completed \[2007-04-11 13:42:20.468 'App' 496 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{18} step "clone volumes" 0% completed \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{19} \[2007-04-11 13:42:20.468 'App' 3516 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:20.468 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[2007-04-11 13:42:20.468 'App' 3516 verbose] \[imageProcessingTaskWrapper,749] Got an update from volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:20.468 'App' 2084 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:23.640 'App' 360 info] \[volumeFormattingTaskImplWin32,270] Format completed... \[#7] \[2007-04-11 13:42:23.640 'App' 360 verbose] \[imageProcessingTaskImpl,154] volumeFormattingWin32Task::task\{19}: SetState to success \[#7] \[2007-04-11 13:42:23.640 'App' 360 verbose] \[imageProcessingTaskImpl,514] volumeFormattingWin32Task::task\{19}: Image processing task has succeeded \[#7] \[2007-04-11 13:42:23.640 'App' 360 verbose] \[imageProcessingTaskWrapper,886] Remote task volumeFormattingWin32Task::task\{19} completed \[#7] \[2007-04-11 13:42:23.640 'App' 2084 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from volumeFormattingWin32Task::task\{19} \[2007-04-11 13:42:23.640 'App' 500 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18} \[2007-04-11 13:42:23.640 'App' 500 verbose] \[imageProcessingTaskWrapper,749] Got an update from volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:23.640 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[2007-04-11 13:42:23.640 'App' 500 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{18} step "Formatting volume virtVol=\{computer=,0}" 100% completed \[#7] \[2007-04-11 13:42:23.640 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:23.640 'App' 2084 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:23.640 'App' 2084 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:23.640 'App' 496 verbose] \[disposable,121] object explicitly disposed: volumeFormattingWin32Task::task\{19} \[#7] \[2007-04-11 13:42:23.640 'App' 496 verbose] \[imageProcessingTaskImpl,123] volumeFormattingWin32Task::task\{19} being destroyed \[#7] \[2007-04-11 13:42:23.640 'App' 2084 info] \[imageProcessingTaskStep,431] CloneTask::task\{18} step "Formatting volume virtVol=\{computer=,0}" succeeded \[#7] \[2007-04-11 13:42:23.656 'App' 2084 info] \[imageProcessingTaskStep,196] CloneTask::task\{18} step "Formatting volume virtVol=\{computer=,0}" destroyed \[#7] \[2007-04-11 13:42:23.656 'App' 496 info] \[attachedVolumeWin32,225] Creating snapshot for volume attVol=\{computer=,1}

\[#7] \[2007-04-11 13:42:23.656 'App' 496 verbose] \[snapshotVolumeFactory,149] Creating snapshot using vss

\[#7] \[2007-04-11 13:42:23.671 'App' 496 error] \[pluginWin32,34] Error 2147549183 creating snapshot.

\[#7] \[2007-04-11 13:42:23.671 'App' 496 warning] \[snapshotVolumeFactory,120] Shapshot creation failed. driver=vss

\[#7] \[2007-04-11 13:42:23.671 'App' 496 verbose] \[snapshotVolumeFactory,149] Creating snapshot using vsnap

\[#7] \[2007-04-11 13:42:23.671 'App' 496 verbose] \[snapshotVolumeVSNAPWin32,124] Vsnap does not have admin rights

\[#7] \[2007-04-11 13:42:23.671 'App' 496 verbose] \[snapshotVolumeVSNAPWin32,127] Snapshotting
?\Volume\{8a906ff3-6343-11da-bb84-806e6f6e6963} using stcbasic.sys

\[#7] \[2007-04-11 13:42:23.671 'App' 496 error] \[snapshotVolumeVSNAPWin32,137] stcbasic.sys not installed or snapshot creation failed. err=2

\[#7] \[2007-04-11 13:42:23.671 'App' 2084 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{18} step "clone volumes" 5% completed

\[#7] \[2007-04-11 13:42:23.671 'App' 2084 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{18} step "clone volumes" 100% completed

\[#7] \[2007-04-11 13:42:23.671 'App' 2084 info] \[imageProcessingTaskStep,196] CloneTask::task\{18} step "clone volumes" destroyed

\[2007-04-11 13:42:23.671 'App' 496 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:23.671 'App' 3516 verbose] \[disposable,121] object explicitly disposed: computer=\{faa41bae8f60504ecc62e470d6cea7cb4519158a}

\[2007-04-11 13:42:23.671 'App' 496 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "clone to VM" 5% completed

\[#7] \[2007-04-11 13:42:23.671 'App' 3516 verbose] \[diskSet,119] Cleaning up computer's child objects

\[2007-04-11 13:42:23.671 'App' 496 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "create and clone to VM" 9% completed

\[#7] \[2007-04-11 13:42:23.671 'App' 3516 verbose] \[diskSet,135] Unregistring volume id=virtVol=\{computer=,0} \[2007-04-11 13:42:23.671 'App' 496 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "Clone VM" 9% completed \[#7] \[2007-04-11 13:42:23.671 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:23.671 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[2007-04-11 13:42:23.671 'App' 500 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18} \[2007-04-11 13:42:23.671 'App' 500 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "clone to VM" 100% completed \[2007-04-11 13:42:23.671 'App' 500 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "create and clone to VM" 100% completed \[2007-04-11 13:42:23.671 'App' 500 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{17} step "Clone VM" 100% completed \[#7] \[2007-04-11 13:42:23.671 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:23.671 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[2007-04-11 13:42:23.671 'App' 360 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:23.671 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:23.671 'App' 3512 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{18} \[#7] \[2007-04-11 13:42:23.796 'App' 3516 verbose] \[diskSet,135] Unregistring volume id=virtVol=\{computer=,1}

\[#7] \[2007-04-11 13:42:23.796 'App' 3516 verbose] \[diskSet,145] Unregistring disk id=disk=\{1fd6c27833ee2aa8f3443d9168de081213a17f7d}

\[#7] \[2007-04-11 13:42:23.796 'App' 2084 error] \[cloneTask,210] Cloning failed!

\[#7] \[2007-04-11 13:42:23.796 'App' 2084 error] \[imageProcessingTaskImpl,552] CloneTask::task\{18}: Image processing task has failed with MethodFault::Exception: vim.fault.NotFound

\[#7] \[2007-04-11 13:42:23.796 'App' 2084 verbose] \[imageProcessingTaskImpl,154] CloneTask::task\{18}: SetState to error

\[#7] \[2007-04-11 13:42:23.796 'App' 2084 verbose] \[imageProcessingTaskWrapper,886] Remote task CloneTask::task\{18} completed

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{18}

\[2007-04-11 13:42:23.796 'App' 3516 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:23.796 'App' 2084 verbose] \[disposable,121] object explicitly disposed: CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:23.796 'App' 2084 verbose] \[imageProcessingTaskImpl,123] CloneTask::task\{18} being destroyed

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 error] \[imageProcessingTaskStep,425] VmiImportTask::task\{17} step "clone to VM" failed

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 verbose] \[imageProcessingTaskWrapper,1117] Reporting image processing task failure for task CloneTask::task\{18}

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 error] \[imageProcessingTaskWrapper,1124] ImageProcessingTask FAILED. Fault name: vim.fault.NotFound

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{17} step "clone to VM" destroyed

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 error] \[vmiImportTask,328] Cloning failed: vim.fault.NotFound

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 info] \[vmiImportTask,494] Removing destination VM

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 info] \[vmiImportTask,502] Successfully connected to UfaLocalVMCreator

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{17} step "create and clone to VM" destroyed

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{17} step "Clone VM" destroyed

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 error] \[imageProcessingTaskImpl,552] VmiImportTask::task\{17}: Image processing task has failed with MethodFault::Exception: sysimage.fault.CloneFault

\[#7] \[2007-04-11 13:42:23.796 'App' 3512 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{17}: SetState to error

\[#7] \[2007-04-11 13:42:23.859 'App' 3512 verbose] \[disposable,121] object explicitly disposed: VmiImportTask::task\{17}

\[#7] \[2007-04-11 13:42:23.859 'App' 364 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#7] \[2007-04-11 13:42:23.859 'App' 364 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#7] \[2007-04-11 13:42:23.859 'App' 364 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#7] \[2007-04-11 13:42:23.859 'App' 364 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#7] \[2007-04-11 13:42:23.859 'App' 364 verbose] \[diskSet,135] Unregistring volume id=detVol=\{computer=,3}

\[#7] \[2007-04-11 13:42:23.859 'App' 364 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#7] \[2007-04-11 13:42:23.859 'App' 3512 verbose] \[imageProcessingTaskImpl,123] VmiImportTask::task\{17} being destroyed

\[2007-04-11 13:42:36.921 'VmdbSvc' 3512 verbose] IVmdbPipe2Impl operation completed with error: The pipe has been ended.

\[2007-04-11 13:42:36.921 'App' 2084 verbose] CloseSession called for session id=5584FDF4-B1D3-4C20-83BB-7D546727EF00

\[2007-04-11 13:42:36.921 'VmdbAdapter' 2084 verbose] Removed vmdb connection /db/connection/#7/

\[#8] \[2007-04-11 13:42:36.953 'App' 364 info] Impersonating user Administrator in session 770D3447-D9C6-4E31-95A0-BAE6F3535D3D

Message was edited by:

jpa9058

Message was edited by:

jpa9058

[asatoran]

On all the 2k3 servers I've converted, they only converted if I installed converter locally (I'm using starter so I can't cold boot) and NOT change the partition size. That's sometime difficult, but it's the only way it's worked for my so far.

[jpa9058]

I have tried installing converter locally. In fact, I've seen the same behavior that you have. I've never had a successful remote conversion.

[jpa9058]

I don't know if this helps, but I seem to fairly consistently receive the following errors, althoguht the shadow copy service is set to manual and can run...

Volume Shadow Copy Service error: Unexpected error OpenService (shSCManager, 'VSS', SERVICE_QUERY_STATUS). hr = 0x80070424.

Volume Shadow Copy Service information: The COM Server with CLSID \{e579ab5f-1cc4-44b4-bed9-de0991ff0623} and name Coordinator cannot be started. \[0x80070424]

Volume Shadow Copy Service error: Unexpected error calling routine CoCreateInstance. hr = 0x80070424.

[admin]

Is your MS Software Shadow Copy Provider service (SWPRV) running? Make sure the service is set to at least manual and not disabled. Windows 2003 requires VSS to work.

[jpa9058]

It looks like there's a problem with the Volume Shadow Copy Service on this computer. The service appears to be missing, and clicking on the shadow copies tab for a drive's properties results in an "Initialization failed" error.

How do I get the Volume Shadow Copy Service to install/show up?

[jpa9058]

Yes, the MS Software Shadow Copy Provider service is running. I've tried converting with this service both started and set to manual. Is there supposed to be a separate Volume Shadow Copy service?

[admin]

VSS is built into W2K3 and should work by default. If you access the properties on your volume, you should be able to schedule a VSS backup. If you cannot, something is misconfigured in Windows.

http://www.windowsitpro.com/Files/16/39370/shadowcopyproperties.gif

[jpa9058]

I get an "Initialization failed" error when I go to that tab, and there is no Volume Shadow Copy service in the services mmc...Any idea on a fix? I can't seem to find anything on this problem on the web.

[admin]

Perhaps this might help.

http://forums.msrportal.com/showthread.php?t=8658

[jpa9058]

That helped, but still no luck. I tried re-Installing SP1 and then installing SP2. I also tried installing the Volume Shadow Copy(VSS) SDK. The program that's normally bound to the service throws an event when it's manually run:

Volume Shadow Copy Service initialization error: the control dispatcher cannot be started \[0x80070427].

Any ideas?

[admin]

Have you tried "net start vss"?

[belerofon]

hello,

i have a similar problem with conversion of a win2k3 server, too.

I want to create a stand alone machine, neither the creation of the vmware files from the server itself nor from a workstation works.

I checked the volume shadow service and it's running, i checked the NETBIOS Help Service and it's running, too.

I don't know what I should do, we need to create this machine in a virtual environment but it doesn't work.

A week ago I created a vm of an Windows 2000 SB Server and it has worked without any problems so I thought this is a really good and easy tool but now nothing works...

I hope somebody will be able to help me, here are the last lines of the log file:

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 verbose] \[sysMigration,198] maxBandwidth:

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 verbose] \[sysMigration,199] clonePagefile: false

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 verbose] \[sysMigration,200] updateBootInit: true

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 verbose] \[sysMigration,201] updateDriveLetters: true

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 verbose] \[ufaConnection,94] Creating local adapter...

\[#4] \[2007-05-10 15:43:37.435 'App' 596 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 info] \[cloneTask,236] Parsing the VM spec to retrieve disk specs...

\[#4] \[2007-05-10 15:43:37.435 'App' 7276 verbose] \[foundryParser,838] Using temporary VMX file:
obelix\vm\merkur\merkur__0.vmx

\[??] \[2007-05-10 15:43:37.545 'App' 7668 warning] \[,0] CreateFile on pipe
.\pipe
\obelix\vm\merkur\merkur__0.vmx failed 2.

\[??] \[2007-05-10 15:43:37.576 'App' 7712 warning] \[,0] VMHSVMLoadConfig failed: File "
obelix\vm\merkur\merkur__0.vmx" line 14: Variable `floppy0.present' is already defined.

\[??] File "
obelix\vm\merkur\merkur__0.vmx" line 15: Variable `floppy0.fileName' is already defined.

\[??] \[2007-05-10 15:43:37.576 'App' 7712 info] \[,0] VMHS_ManageVM failed: ret = VMX file is corrupt

\[#4] \[2007-05-10 15:43:37.576 'Ufa-foundry' 7276 error] Callback_GetResultStatus - Async Callback Wait failed: Cannot read the virtual machine configuration file

\[#4] \[2007-05-10 15:43:37.576 'Ufa-foundry' 7276 error] VM_OpenEx failed: Cannot read the virtual machine configuration file

\[#4] \[2007-05-10 15:43:37.576 'Ufa-foundry' 7276 warning] Failed to open virtual machine.

\[#4] \[2007-05-10 15:43:37.576 'App' 7276 error] \[vmSpecParser,64] Creation of VM spec parser failed

\[#4] \[2007-05-10 15:43:37.576 'App' 7276 verbose] \[imageProcessingTaskImpl,123] CloneTask::task\{11} being destroyed

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{10} step "clone to VM" 100% completed

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{10} step "create and clone to VM" 100% completed

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{10} step "Clone VM" 100% completed

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{10} step "clone to VM" destroyed

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 error] \[vmiImportTask,328] Cloning failed: sysimage.fault.CannotCreateVmSpecParser

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 info] \[vmiImportTask,494] Removing destination VM

\[#4] \[2007-05-10 15:43:37.576 'App' 7488 info] \[vmiImportTask,502] Successfully connected to UfaLocalVMCreator

\[#4] \[2007-05-10 15:43:37.607 'App' 7488 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{10} step "create and clone to VM" destroyed

\[#4] \[2007-05-10 15:43:37.607 'App' 7488 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{10} step "Clone VM" destroyed

\[#4] \[2007-05-10 15:43:37.607 'App' 7488 error] \[imageProcessingTaskImpl,552] VmiImportTask::task\{10}: Image processing task has failed with MethodFault::Exception: sysimage.fault.CloneFault

\[#4] \[2007-05-10 15:43:37.623 'App' 7488 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{10}: SetState to error

\[#4] \[2007-05-10 15:43:37.670 'App' 1116 verbose] \[disposable,121] object explicitly disposed: VmiImportTask::task\{10}

\[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,3} \[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[diskSet,135] Unregistring volume id=detVol=\{computer=,4}

\[#4] \[2007-05-10 15:43:37.670 'App' 596 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#4] \[2007-05-10 15:43:37.670 'App' 1116 verbose] \[imageProcessingTaskImpl,123] VmiImportTask::task\{10} being destroyed

Thanks in advance

Message was edited by:

belerofon