VMware Cloud Community
Rajaragavan
Contributor
Contributor

Windows 2000 server with SP4 fails to convert at 97%

Hi

I am facing trouble when I try to convert a Windows 2000 server with Service pack 4 using VMWare converter tool.

I tried using Cold convert by using the bootable CD, target is the ESX server 3.0.1, it all goes well, and at 97% it will give me an error saying "Unknown error" and VMWare converted failed. Find below the logs of the process. Any help would be appreciated.

Initially I tried converting by changing the partition size, later when I browse through the forum I found about the known issue of changing partition size. So I have tried leaving all the settings intact including the partition size. Still I got the same error 97%

I also went through the checking of the HKLM\Hardware.... registry keys, I dont find those keys listed on the discussion forum.

I am struck with this. Your help is really appreciated.

Regards

Ragav

0 Kudos
24 Replies
Rajaragavan
Contributor
Contributor

Hi

Forget to post the logs:

\[2007-02-18 18:56:16.671 'P2V' 1104 info] \[jobManager,444] Adding job 1

\[2007-02-18 18:56:16.671 'P2V' 1104 verbose] \[serialization,213] Serializing job 1

\[2007-02-18 18:56:16.671 'P2V' 1104 verbose] \[serialization,220] Archiving job to Z:\Documents and Settings\Default User\Application Data\VMware\p2v30\jobs\00000001.xml

\[2007-02-18 18:56:16.671 'P2V' 1104 verbose] \[jobManager,367] Starting job 1 as 1/1 running job

\[2007-02-18 18:56:16.671 'P2V' 1104 info] \[jobManager,845] Starting job 1

\[2007-02-18 18:56:16.671 'P2V' 1104 verbose] \[job,231] Job 1 changing state from 0 to 1

\[2007-02-18 18:56:16.671 'P2V' 1104 verbose] \[serialization,213] Serializing job 1

\[2007-02-18 18:56:16.671 'P2V' 1416 verbose] \[task,339] Transition from None to InProgress requested

\[2007-02-18 18:56:16.671 'P2V' 1416 verbose] \[task,388] Transition succeeded

\[2007-02-18 18:56:16.671 'P2V' 1104 verbose] \[serialization,220] Archiving job to Z:\Documents and Settings\Default User\Application Data\VMware\p2v30\jobs\00000001.xml

\[2007-02-18 18:56:16.687 'P2V' 1416 info] \[task,275] Starting execution of a Task

\[2007-02-18 18:56:16.687 'P2V' 1104 verbose] \[jobManager,269] Raising event 0 for job 1

\[2007-02-18 18:56:16.687 'P2V' 1104 verbose] \[jobManager,269] Raising event 2 for job 1

\[2007-02-18 18:56:16.687 'ClientConnection' 1416 info] Connecting to
.\pipe\vmware-ufad-p2v30-vmdb, use SSL: true

\[2007-02-18 18:56:16.687 'ClientConnection' 1416 info] Successfully connected to
.\pipe\vmware-ufad-p2v30-vmdb

\[2007-02-18 18:56:16.687 'P2V' 1104 verbose] \[jobManager,269] Raising event 4 for job 1

\[2007-02-18 18:56:16.765 'P2V' 1104 warning] \[job,172] 1 is not a valid index for 1 messages

\[2007-02-18 18:56:16.765 'P2V' 1104 warning] \[job,172] 1 is not a valid index for 1 messages

\[2007-02-18 18:56:16.843 'VmdbSvc' 1416 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:01DBFE80]

\[2007-02-18 18:56:16.843 'BaseLibs' 1436 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[2007-02-18 18:56:16.875 'ClientConnection' 1416 info] Making sure that UFAD interface has version vmware-ufad-p2v30-build-39557

\[2007-02-18 18:56:16.890 'ClientConnection' 1416 info] UFAD interface version is vmware-ufad-p2v30-build-39557

\[2007-02-18 18:56:16.906 'P2V' 1416 info] \[task,277] Task execution completed

\[2007-02-18 18:56:16.906 'P2V' 1416 verbose] \[task,339] Transition from InProgress to Success requested

\[2007-02-18 18:56:16.906 'P2V' 1416 verbose] \[task,388] Transition succeeded

\[2007-02-18 18:56:16.921 'P2V' 1416 verbose] \[jobManager,1216] UFA session created successfully

\[2007-02-18 18:56:16.921 'P2V' 1416 info] \[ufaSession,113] DoImport called

\[2007-02-18 18:56:16.921 'P2V' 1416 verbose] \[serialization,213] Serializing job 1

\[2007-02-18 18:56:16.921 'P2V' 1416 verbose] \[serialization,220] Archiving job to Z:\Documents and Settings\Default User\Application Data\VMware\p2v30\jobs\00000001.xml

\[2007-02-18 18:56:16.921 'P2V' 1436 verbose] \[task,339] Transition from None to InProgress requested

\[2007-02-18 18:56:16.921 'P2V' 1436 verbose] \[task,388] Transition succeeded

\[2007-02-18 18:56:16.921 'P2V' 1416 verbose] \[jobManager,269] Raising event 4 for job 1

\[2007-02-18 18:56:16.937 'P2V' 1436 info] \[task,275] Starting execution of a Task

\[2007-02-18 18:56:16.937 'P2V' 1436 info] \[ufaTask,173] Successfully connected to VMImporter

\[2007-02-18 18:56:16.968 'App' 1436 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to VmiImportTask::task\{6}

\[2007-02-18 18:56:16.984 'App' 1436 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task VmiImportTask::task\{6}

\[2007-02-18 18:56:16.984 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:16.984 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:17.015 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:56:17.031 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:17.031 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:51.156 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:56:51.171 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:51.171 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.218 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.250 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.250 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.281 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.296 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.296 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.921 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.953 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.953 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:52.968 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:56:53.000 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:56:53.000 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:58:18.500 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:58:18.531 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:58:18.531 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 18:59:41.656 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 18:59:41.687 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 18:59:41.687 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:02:27.000 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:02:27.015 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:02:27.015 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:04:48.734 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:04:48.765 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:04:48.765 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:04:48.781 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:04:48.828 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:04:48.828 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:06:46.187 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:06:46.203 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:06:46.203 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:08:31.859 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:08:31.875 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:08:31.875 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:10:00.890 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:10:00.906 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:10:00.906 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:11:54.562 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:11:54.593 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:11:54.593 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:11:54.625 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:11:54.640 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:11:54.640 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:13:30.250 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:13:30.265 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:13:30.265 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:15:01.046 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:15:01.062 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:15:01.062 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:16:44.250 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:16:44.265 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:16:44.265 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:18:07.015 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:18:07.046 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:18:07.046 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:18:07.078 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:18:07.093 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:18:07.093 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:19:32.843 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:19:32.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:19:32.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:21:18.375 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:21:18.390 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:21:18.390 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:23:05.765 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:23:05.781 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:23:05.781 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:24:28.703 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:24:28.734 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:24:28.734 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:24:28.765 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:24:28.781 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:24:28.781 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:25:54.109 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:25:54.140 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:25:54.140 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:27:16.640 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:27:16.656 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:27:16.656 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:28:41.359 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:28:41.375 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:28:41.375 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:30:05.328 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:30:05.343 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:30:05.343 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:30:05.375 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:30:05.390 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:30:05.390 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:32:33.953 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:32:33.984 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:32:33.984 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:34:22.546 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:34:22.578 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:34:22.578 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:35:47.187 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:35:47.203 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:35:47.203 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:37:13.937 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:37:13.953 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:37:13.953 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:37:13.984 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:37:14.000 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:37:14.000 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:38:37.171 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:38:37.187 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:38:37.187 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:40:00.718 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:40:00.750 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:40:00.750 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:41:43.593 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:41:43.609 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:41:43.609 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:43:45.156 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:43:45.171 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:43:45.171 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:43:45.203 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:43:45.218 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:43:45.218 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:45:24.171 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:45:24.187 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:45:24.187 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:47:07.468 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:47:07.484 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:47:07.484 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:48:30.437 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:48:30.453 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:48:30.453 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:49:54.765 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:49:54.796 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:49:54.796 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:49:54.828 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:49:54.843 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:49:54.843 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:51:42.734 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:51:42.765 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:51:42.765 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:53:39.140 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:53:39.156 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:53:39.156 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:55:04.437 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:55:04.453 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:55:04.453 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:56:29.937 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:56:29.968 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:56:29.968 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:56:30.000 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:56:30.015 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:56:30.015 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:57:55.062 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:57:55.093 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:57:55.093 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 19:59:18.015 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 19:59:18.031 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 19:59:18.031 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:01:19.828 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:01:19.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:01:19.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:03:31.718 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:03:31.750 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:03:31.750 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:03:31.781 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:03:31.796 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:03:31.796 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:05:25.750 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:05:25.765 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:05:25.765 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:07:08.109 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:07:08.125 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:07:08.125 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:08:32.859 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:08:32.890 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:08:32.890 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:09:57.546 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:09:57.578 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:09:57.578 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:09:57.609 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:09:57.625 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:09:57.625 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:12:09.156 'App' 1404 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:12:09.187 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:12:09.187 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:13:55.718 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:13:55.734 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:13:55.734 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:15:24.125 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:15:24.140 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:15:24.140 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:17:35.781 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:17:35.812 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:17:35.812 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:17:35.843 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:17:35.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:17:35.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:18:59.828 'App' 1424 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:18:59.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:18:59.859 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:09.765 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:20:09.796 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:09.796 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:09.828 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:20:09.843 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:09.843 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:10.359 'App' 1420 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:20:10.390 'App' 1436 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:10.390 'App' 1436 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from VmiImportTask::task\{6}

\[2007-02-18 20:20:55.046 'App' 1404 verbose] \[imageProcessingTaskWrapper,886] Remote task VmiImportTask::task\{6} completed

\[2007-02-18 20:20:55.046 'App' 1416 verbose] \[imageProcessingTaskWrapper,749] Got an update from VmiImportTask::task\{6}

\[2007-02-18 20:20:55.062 'App' 1436 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task VmiImportTask::task\{6}

\[2007-02-18 20:20:55.093 'App' 1436 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task VmiImportTask::task\{6}

\[2007-02-18 20:20:58.125 'P2V' 1436 error] \[task,295] Task failed: P2VError UNKNOWN_METHOD_FAULT(sysimage.fault.CloneFault)

\[2007-02-18 20:20:58.125 'P2V' 1436 verbose] \[task,339] Transition from InProgress to Failure requested

\[2007-02-18 20:20:58.125 'P2V' 1436 verbose] \[task,388] Transition succeeded

\[2007-02-18 20:20:58.125 'P2V' 1436 verbose] \[job,231] Job 1 changing state from 1 to 2

\[2007-02-18 20:20:58.125 'P2V' 1436 verbose] \[serialization,213] Serializing job 1

\[2007-02-18 20:20:58.125 'P2V' 1436 verbose] \[serialization,220] Archiving job to Z:\Documents and Settings\Default User\Application Data\VMware\p2v30\jobs\00000001.xml

\[2007-02-18 20:20:58.140 'P2V' 1436 verbose] \[jobManager,269] Raising event 2 for job 1

\[2007-02-18 20:20:58.140 'P2V' 1436 verbose] \[jobManager,269] Raising event 4 for job 1

\[2007-02-18 20:20:58.140 'P2V' 1436 verbose] \[jobManager,269] Raising event 6 for job 1

\[2007-02-18 20:20:58.156 'VmdbSvc' 1436 verbose] Shutting down VMDB service...

\[2007-02-18 20:20:58.156 'VmdbSvc' 1436 verbose] Unregistering callback...

\[2007-02-18 20:20:58.156 'VmdbSvc' 1436 verbose] ...done

\[2007-02-18 22:08:48.781 'ClientConnection' 1104 info] Connecting to
.\pipe\vmware-ufad-p2v30-vmdb, use SSL: true

\[2007-02-18 22:08:48.781 'ClientConnection' 1104 info] Successfully connected to
.\pipe\vmware-ufad-p2v30-vmdb

\[2007-02-18 22:08:48.796 'VmdbSvc' 1104 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:01E464A0]

\[2007-02-18 22:08:48.796 'BaseLibs' 1404 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[2007-02-18 22:08:48.828 'ClientConnection' 1104 info] Making sure that UFAD interface has version vmware-ufad-p2v30-build-39557

\[2007-02-18 22:08:48.843 'ClientConnection' 1104 info] UFAD interface version is vmware-ufad-p2v30-build-39557

\[2007-02-18 22:08:48.875 'VmdbSvc' 1104 verbose] Shutting down VMDB service...

\[2007-02-18 22:08:48.875 'VmdbSvc' 1104 verbose] Unregistering callback...

\[2007-02-18 22:08:48.875 'VmdbSvc' 1104 verbose] ...done

Regards

Ragav

0 Kudos
bretti
Expert
Expert

It looks like everything is going along well until the very end and then you get this error.

\[task,295] Task failed: P2VError UNKNOWN_METHOD_FAULT(sysimage.fault.CloneFault)

How much disk space do you have on the source computer? Are you using any dynamic disks?

0 Kudos
Rajaragavan
Contributor
Contributor

Hi

I am trying to convert the physical machine of size 37.7 gb (NTFS Partitioned) to my ESX which is having VMFS partition free about 57GB.

I didnt' get exactly what you mean by the word are you using Dynamic disk ?

Regards

Ragav

0 Kudos
admin
Immortal
Immortal

In your Computer Management -> Storage -> Disk Management your Disk0, Disk1 will say "basic" or "dynamic".

0 Kudos
bretti
Expert
Expert

That's alright. A Dynamic disk will show up differently in disk management on Windows. You probably have a basic disk.

How much free space do you have on the physical machine? This error has me stumped.

0 Kudos
ryan_gallagher
Enthusiast
Enthusiast

You have posted the client logs. Is it possible for you to post the converter logs? These are the actual agent logs and are much more useful for diagnosing exactly what type of cloning error you had. Thanks.

-Ryan

0 Kudos
Rajaragavan
Contributor
Contributor

My Hardisk is BASIC

0 Kudos
Rajaragavan
Contributor
Contributor

Hi

Free space in my harddis:

Disk0 is partitioned two drives:

c: drive 32 % free (2.7Gb)

d: drive 56% free (16.3Gb)

Below are the converter logs:

Log for VMware Converter, pid=884, version=3.0.0, build=build-39557, option=Release, section=1

\[2007-02-20 20:56:48.421 'App' 864 info] Current working directory: X:\VMWARE-CONVERTER

\[??] \[2007-02-20 20:56:48.437 'ThreadPool' 864 verbose] TaskMax=40, IoMin=1, IoMax=81

\[2007-02-20 20:56:48.453 'App' 864 info] Trying ufa-agent

\[2007-02-20 20:56:48.453 'App' 864 info] Trying ufa-client

\[2007-02-20 20:56:48.453 'App' 864 info] Trying ufa-slave

\[2007-02-20 20:56:48.453 'App' 864 info] Trying ufa-sysMigration

\[2007-02-20 20:56:48.453 'App' 864 info] Trying ufa-sysReconfig

\[2007-02-20 20:56:48.453 'App' 864 info] Trying ufa-vmImporter

\[2007-02-20 20:56:48.453 'App' 864 verbose] Plugin 0 path: ufa-agent.dll

\[2007-02-20 20:56:48.453 'App' 864 verbose] Plugin 0 absolute path: X:\VMWARE-CONVERTER\ufa-agent.dll

\[2007-02-20 20:56:50.484 'Ufa' 864 info] Plugin initialized

\[2007-02-20 20:56:50.484 'App' 864 verbose] Plugin 1 path: ufa-client.dll

\[2007-02-20 20:56:50.484 'App' 864 verbose] Plugin 1 absolute path: X:\VMWARE-CONVERTER\ufa-client.dll

\[2007-02-20 20:56:50.609 'App' 864 info] \[plugin,104] UfaClientPlugin Init

\[2007-02-20 20:56:50.609 'App' 864 verbose] Plugin 2 path: ufa-slave.dll

\[2007-02-20 20:56:50.609 'App' 864 verbose] Plugin 2 absolute path: X:\VMWARE-CONVERTER\ufa-slave.dll

\[??] \[2007-02-20 20:56:51.234 'App' 1276 info] \[stub,42] Using system libcrypto, version 90709F

\[2007-02-20 20:56:51.234 'App' 864 info] \[osInfoWin32,101] Microsoft Windows Server 2003,

\[2007-02-20 20:56:51.234 'App' 864 info] \[osInfoWin32,134] Professional

\[2007-02-20 20:56:51.234 'App' 864 info] \[osInfoWin32,269] (Build 3790)

\[??] \[2007-02-20 20:56:51.234 'App' 1276 info] \[stub,42] NFC connection accept timeout: 180000 milliseconds

\[??] \[2007-02-20 20:56:51.234 'App' 1276 info] \[stub,42] NFC request timeout: 180000 milliseconds

\[??] \[2007-02-20 20:56:51.234 'App' 1276 info] \[stub,42] NFC read timeout: 60000 milliseconds

\[??] \[2007-02-20 20:56:51.234 'App' 1276 info] \[stub,42] NFC write timeout: 600000 milliseconds

\[2007-02-20 20:56:51.234 'App' 864 info] \[plugin,117] UfaSlavePlugin Init

\[2007-02-20 20:56:51.234 'App' 864 verbose] Plugin 3 path: ufa-sysMigration.dll

\[2007-02-20 20:56:51.234 'App' 864 verbose] Plugin 3 absolute path: X:\VMWARE-CONVERTER\ufa-sysMigration.dll

\[2007-02-20 20:56:51.937 'App' 864 info] \[,0] NFC connection accept timeout: 180000 milliseconds

\[2007-02-20 20:56:51.937 'App' 864 info] \[,0] NFC request timeout: 180000 milliseconds

\[2007-02-20 20:56:51.937 'App' 864 info] \[,0] NFC read timeout: 60000 milliseconds

\[2007-02-20 20:56:51.937 'App' 864 info] \[,0] NFC write timeout: 600000 milliseconds

\[2007-02-20 20:56:51.937 'App' 864 info] \[,0] Using system libcrypto, version 90709F

\[2007-02-20 20:56:51.937 'App' 864 info] \[plugin,166] SysMigrationPlugin Init

\[2007-02-20 20:56:51.937 'App' 864 verbose] Plugin 4 path: ufa-sysReconfig.dll

\[2007-02-20 20:56:51.937 'App' 864 verbose] Plugin 4 absolute path: X:\VMWARE-CONVERTER\ufa-sysReconfig.dll

\[2007-02-20 20:56:52.375 'App' 864 info] \[plugin,102] SysReconfigPlugin Init

\[2007-02-20 20:56:52.453 'App' 864 info] \[utils,126] Using temp dir Z:\vmware-temp\vmware-SYSTEM\sysReconfig

\[2007-02-20 20:56:52.781 'App' 864 verbose] \[rdsXmlLoader,153] Loading reconfiguration data store XML from Z:\vmware-temp\vmware-SYSTEM\sysReconfig\reconfigurationDataStore.xml ...

\[2007-02-20 20:56:52.781 'App' 864 verbose] \[rdsXmlLoader,207] Populating ReconfigurationDataStore ...

\[2007-02-20 20:56:52.781 'App' 864 verbose] \[rdsXmlLoader,282] Loading action LsiLogic

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_1000&dev_0030 patch: C_SYMMPI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\symmpi patch: S_SYMMPI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\symmpi.sys source: BASEOS sourceFileName: symmpi.sys

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action BuslogicMylex

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: enum\root\LEGACY_BUSLOGIC patch: L_BUSLOGIC

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: services\buslogic patch: S_BUSLOGIC

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\buslogic.sys source: VMWARE_DRIVERS_CAB sourceFileName: buslogic_for_nt.sys

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Buslogic_vmscsi

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_104b&dev_1040 patch: C_VMSCSI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E97B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMSCSI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\vmscsi patch: S_VMSCSI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\vmscsi.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmscsi.sys

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Buslogic_vmscsi_ACPI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\3&61aaa01&0&80 patch: E_VMSCSI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Buslogic_vmscsi_NotACPI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_104B&DEV_1040&SUBSYS_1040104B&REV_01\2&ebb567f&0&80 patch: E_VMSCSI

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Mouse_Keyboard

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0303 patch: C_PS2KBD

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E96B-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2KBD

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\ACPI\PNP0303 patch: E_PS2KBD

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\*pnp0f13 patch: C_PS2MOUSE

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E96F-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_PS2MOUSE

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\ACPI\PNP0F13 patch: E_PS2MOUSE

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\CriticalDeviceDatabase\pci#ven_8086&dev_7110&cc_0601 patch: C_ISAPNP

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action NT_SVGA_Registry_Updates

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: enum\root\LEGACY_VMX_SVGA patch: E_VMX_SVGA

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Common_SVGA_Updates

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: services\vmx_svga patch: S_VMX_SVGA

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Control\Class\{4D36E968-E325-11CE-BFC1-08002BE10318}\0009 patch: CL_VMX_SVGA

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: INF\vmx_svga.inf source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.inf

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\vmx_fb.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_fb.dll

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\vmx_mode.dll source: VMWARE_DRIVERS_CAB sourceFileName: vmx_mode.dll

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,418] File Update filePathInWinDir: system32\drivers\vmx_svga.sys source: VMWARE_DRIVERS_CAB sourceFileName: vmx_svga.sys

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action SVGA_Registry_Updates_APCI

\[2007-02-20 20:56:52.796 'App' 864 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-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action SVGA_Registry_Updates_NotACPI

\[2007-02-20 20:56:52.796 'App' 864 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-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Restore_i8042_NT5

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\i8042prt patch: SKUNKWORKS_S_I8042

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_vpcsrvc

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-driver-vpcsrvc patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-vpcsrvc patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\LEGACY_1-DRIVER-VPCSRVC patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\LEGACY_1-VPCSRVC patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_mrxvpc

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\MrxVPC patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_MRXVPC patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_vpcmap

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\VPCMap patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPCMAP patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_vpc-s3_NT4

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_VPC-S3 patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_vpc-s3_NT5

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\vpc-s3 patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\PCI\VEN_5333&DEV_8811&SUBSYS_00000000&REV_00\3&267a616a&0&40 patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\s3legacy patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_vmsrvc

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-driver-vmsrvc patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-DRIVER-VMSRVC patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\1-vmsrvc patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_1-VMSRVC patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_netfin

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_NETFIN patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\NetFin patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_cpqdmi

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQDMI patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\CPQDMI patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_cpqalert

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Enum\Root\LEGACY_CPQALERT patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\CPQALERT patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.796 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\EventLog\System\CPQALERT patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,282] Loading action Deref_cpqagent

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\EventLog\System\Snmp patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,368] Registry Update currentControlSetKey: Services\Snmp patch: SKUNKWORKS_FILLER

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,282] Loading action PreserveDriveLetters

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,282] Loading action RemoveUnwantedKeys

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,282] Loading action FixSetupLog

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,459] String replace Update filePathInWinDir: repair\setup.log pattern:
system32
ntoskrnl.exe.*\.exe value:
system32
ntoskrnl.exe = "ntoskrnl.exe

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,459] String replace Update filePathInWinDir: repair\setup.log pattern:
system32
hal.dll.*\.dll value:
system32
hal.dll = "hal.dll

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin2k364Bit

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWinXP64Bit

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin64Bit

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition NotWin64Bit

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin2k3

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWinXP

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWin2k

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GenericWinNT4

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition NotWinNT4

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition ACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition NotACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition MultiProc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition InstallSVGA

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition MustUpdateCoreNTFiles

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition GuestOsIncludesLsiLogicDriver

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition BuslogicMylex

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,493] Loading expression definition Buslogic_vmscsi

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration FixSetupLog

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action FixSetupLog

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration BuslogicMylex

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action BuslogicMylex

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration LsiLogic

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action LsiLogic

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Buslogic_vmscsi_ACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi_ACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Buslogic_vmscsi_NotACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Buslogic_vmscsi_NotACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Mouse_Keyboard_notNT_ACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Mouse_Keyboard

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration SVGA_NT

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Common_SVGA_Updates

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action NT_SVGA_Registry_Updates

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration SVGA_notNT_ACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Common_SVGA_Updates

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action SVGA_Registry_Updates_APCI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration SVGA_notNT_NotACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Common_SVGA_Updates

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action SVGA_Registry_Updates_NotACPI

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Restore_i8042_NT5

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Restore_i8042_NT5

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpcsrvc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpcsrvc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_mrxvpc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_mrxvpc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpcmap

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpcmap

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpc-s3_NT4

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpc-s3_NT4

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vpc-s3_NT5

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vpc-s3_NT5

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_vmsrvc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_vmsrvc

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_netfin

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_netfin

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_cpqdmi

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_cpqdmi

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_cpqalert

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_cpqalert

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration Deref_cpqagent

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action Deref_cpqagent

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration PreserveDriveLetters

\[2007-02-20 20:56:52.812 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action PreserveDriveLetters

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1055] Loading reconfiguration RemoveUnwantedKeys

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1123] Loading reconfiguration action RemoveUnwantedKeys

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

\[2007-02-20 20:56:52.828 'App' 864 verbose] \[rdsXmlLoader,1177] Loading hotfixes for WINDOWS4.0

\[2007-02-20 20:56:52.828 'App' 864 info] \[utils,153] Cleaning up temp directory Z:\vmware-temp\vmware-SYSTEM\sysReconfig ...

\[2007-02-20 20:56:52.828 'App' 864 verbose] Plugin 5 path: ufa-vmImporter.dll

\[2007-02-20 20:56:52.828 'App' 864 verbose] Plugin 5 absolute path: X:\VMWARE-CONVERTER\ufa-vmImporter.dll

\[2007-02-20 20:56:53.609 'App' 864 info] \[plugin,111] VMImporterPlugin Init

\[2007-02-20 20:56:53.609 'Ufa' 864 info] Initializing SSL context

\[2007-02-20 20:56:53.703 'BaseLibs' 864 info] Using system libcrypto, version 90709F

\[2007-02-20 20:56:54.250 'Ufa' 864 info] VMDB pipe name:
.\pipe\vmware-ufad-p2v30-vmdb, use SSL: true

\[2007-02-20 20:56:54.250 'VmdbAdapter' 864 info] Using new VMDB VMOMI serialization format

\[2007-02-20 20:56:54.250 'Ufa' 864 info] Plugin started

\[2007-02-20 20:56:54.250 'App' 864 info] \[plugin,119] UfaClientPlugin Start

\[2007-02-20 20:56:54.546 'VmdbSvc' 1348 error] Failed to accept: SSL Exception: error:00000000:lib(0):func(0):reason(0)

\[2007-02-20 20:56:54.859 'App' 864 info] \[plugin,132] UfaSlavePlugin Start

\[2007-02-20 20:56:54.859 'App' 864 info] \[plugin,182] SysMigrationPlugin Start

\[2007-02-20 20:56:54.859 'App' 864 info] \[plugin,119] SysReconfigPlugin Start

\[2007-02-20 20:56:54.859 'App' 864 info] \[plugin,126] VMImporterPlugin Start

\[#1] \[2007-02-20 20:57:22.375 'App' 864 info] Impersonating user SYSTEM in session F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:22.375 'Vmsvc' 864 info] VMServices Plugin initializing

\[#1] \[2007-02-20 20:57:22.656 'App' 864 info] \[,0] PREF Failed to find user preference file name.

\[#1] \[2007-02-20 20:57:22.656 'App' 864 info] \[,0] PREF Failed to load user preferences.

\[#1] \[2007-02-20 20:57:22.656 'App' 864 info] \[,0] Using system libcrypto, version 90709F

\[#1] \[2007-02-20 20:57:22.875 'App' 864 warning] \[,0] VMHS: Failed to load vmnetMgr.dll

\[??] \[2007-02-20 20:57:22.875 'App' 1844 info] \[,0] HOSTINFO 1404348035 @ 3579545Hz -> 0 @ 1000000Hz

\[#1] \[2007-02-20 20:57:22.875 'VmMisc' 864 info] Max supported virtual machines: 1200

\[??] \[2007-02-20 20:57:22.875 'App' 1844 info] \[,0] HOSTINFO ((x * 2399728063) >> 33) + -392325849

\[#1] \[2007-02-20 20:57:22.968 'App' 1352 info] \[vmImporter,133] Entering UFAD at QuerySourceInfo

\[#1] \[2007-02-20 20:57:22.968 'App' 1352 verbose] \[vmImporter,134] source:

\[#1] (sysimage.LiveSourceComputerSpec) {

\[#1] dynamicType = ,

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

\[#1] host = "",

\[#1] port = 0

\[#1] }

\[#1] \[2007-02-20 20:57:22.968 'App' 1352 verbose] \[ufaConnection,94] Creating local adapter...

\[#1] \[2007-02-20 20:57:22.984 'App' 1352 verbose] \[disposable,68] Registering disposable session object id=VmiQuerySourceInfoTask::task\{0}

\[#1] \[2007-02-20 20:57:22.984 'App' 1352 verbose] \[disposable,79] Associating disposable session object id=VmiQuerySourceInfoTask::task\{0} with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:23.015 'App' 1352 verbose] \[imageProcessingTaskImpl,504] VmiQuerySourceInfoTask::task\{0}: Starting execution of an image processing task

\[#1] \[2007-02-20 20:57:23.015 'App' 1352 verbose] \[imageProcessingTaskImpl,154] VmiQuerySourceInfoTask::task\{0}: SetState to running

\[#1] \[2007-02-20 20:57:23.015 'App' 1352 info] \[vmiQuerySourceInfoTask,79] VmiQuerySourceInfoTask initialized

\[#1] \[2007-02-20 20:57:23.015 'App' 1352 verbose] \[ufaConnection,94] Creating local adapter...

\[#1] \[2007-02-20 20:57:23.015 'App' 1352 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

\[#1] \[2007-02-20 20:57:23.015 'App' 1348 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#1] \[2007-02-20 20:57:23.015 'App' 1816 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#1] \[2007-02-20 20:57:23.015 'App' 1816 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#1] \[2007-02-20 20:57:23.015 'App' 1816 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=2405 size=4096 err=0

\[#1] \[2007-02-20 20:57:23.093 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#1] \[2007-02-20 20:57:23.093 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:23.156 'App' 1816 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:23.156 'App' 1816 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663f-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:23.156 'App' 1816 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6642-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:23.156 'App' 1816 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6643-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:24.187 'App' 1816 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-02-20 20:57:24.218 'App' 1276 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ is not supported .... Trying NT method.

\[??] \[2007-02-20 20:57:24.218 'App' 1276 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ Volume's disk number

\[#1] \[2007-02-20 20:57:24.218 'App' 1816 warning] \[attachedComputerWin32,567] 1

\[#1] \[2007-02-20 20:57:24.218 'App' 1816 verbose] \[diskSet,196] 2 volumes found

\[#1] \[2007-02-20 20:57:24.218 'App' 1816 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#1] \[2007-02-20 20:57:24.218 'App' 1816 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#1] \[2007-02-20 20:57:24.218 'App' 1816 verbose] \[diskSet,203] 1 potential active volumes found

\[#1] \[2007-02-20 20:57:24.234 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:24.234 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:24.375 'App' 1816 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:24.390 'App' 1816 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:26.953 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#1] \[2007-02-20 20:57:26.953 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:26.953 'App' 1816 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#1] \[2007-02-20 20:57:32.578 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#1] \[2007-02-20 20:57:32.578 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:32.578 'App' 1816 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:0 64:0 Ver:5.0

\[#1] \[2007-02-20 20:57:32.578 'App' 1816 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#1] \[2007-02-20 20:57:32.593 'App' 1816 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedComputerWin32,780] Detected 3 NICs

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:E0:4C:09:1C:86

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedComputerWin32,815] NIC1 has description "Realtek RTL8139 Family PCI Fast Ethernet NIC"

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedComputerWin32,807] NIC2 has MAC address 00:02:A5:F9:C9:EB

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedComputerWin32,815] NIC2 has description "Intel(R) PRO/100 VM Network Connection"

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedComputerWin32,788] NIC3 is non-ethernet - discarding

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#1] \[2007-02-20 20:57:32.718 'App' 1816 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=2405 size=4096 err=0

\[#1] \[2007-02-20 20:57:32.718 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#1] \[2007-02-20 20:57:32.718 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:32.718 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:32.718 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663f-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:32.718 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6642-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:32.718 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6643-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:57:33.781 'App' 1856 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-02-20 20:57:33.812 'App' 1276 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ is not supported .... Trying NT method.

\[??] \[2007-02-20 20:57:33.812 'App' 1276 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ Volume's disk number

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 warning] \[attachedComputerWin32,567] 1

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[diskSet,196] 2 volumes found

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[diskSet,203] 1 potential active volumes found

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[#1] \[2007-02-20 20:57:33.812 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:33.828 'App' 1856 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:33.906 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#1] \[2007-02-20 20:57:33.906 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:33.906 'App' 1856 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#1] \[2007-02-20 20:57:34.328 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#1] \[2007-02-20 20:57:34.328 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:34.328 'App' 1856 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:0 64:0 Ver:5.0

\[#1] \[2007-02-20 20:57:34.328 'App' 1856 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#1] \[2007-02-20 20:57:34.343 'App' 1856 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[diskSet,196] 2 volumes found

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[diskSet,203] 1 potential active volumes found

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:34.343 'App' 864 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:34.343 'App' 864 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#1] \[2007-02-20 20:57:34.343 'App' 864 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[#1] \[2007-02-20 20:57:34.343 'App' 1352 info] \[imageProcessorProxy,148] Successfully connected to UfaSysReconfig

\[#1] \[2007-02-20 20:57:34.343 'App' 864 info] \[ufaClientConnection,69] Successfully connected to local UfaClient.

\[#1] \[2007-02-20 20:57:34.343 'App' 864 info] \[ufaClientConnection,163] Opening specifed computer ...

\[#1] \[2007-02-20 20:57:34.343 'App' 1856 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[computerProxy,72] Successfully connected to remote Computer.

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[computerProxy,253] Connecting to the system volume...

\[#1] \[2007-02-20 20:57:34.343 'App' 864 verbose] \[computerProxy,144] Retrieving the system volume info ...

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[diskSet,196] 2 volumes found

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[diskSet,203] 1 potential active volumes found

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:57:34.343 'App' 1852 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#1] \[2007-02-20 20:57:34.359 'App' 1852 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[#1] \[2007-02-20 20:57:34.359 'App' 864 verbose] \[volumeProxy,53] Successfully connected to remote volume.

\[#1] \[2007-02-20 20:57:34.437 'App' 864 verbose] \[computerProxy,92] Debug

\[#1] \[2007-02-20 20:57:34.437 'App' 864 verbose] \[computerProxy,118] Debug

\[#1] \[2007-02-20 20:57:34.437 'App' 864 verbose] \[volumeProxy,73] Debug

\[#1] \[2007-02-20 20:57:34.437 'App' 864 info] \[ufaClientConnection,136] Info

\[#1] \[2007-02-20 20:57:34.437 'App' 1852 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#1] \[2007-02-20 20:57:34.453 'App' 864 verbose] \[attachedComputerWin32,780] Detected 3 NICs

\[#1] \[2007-02-20 20:57:34.453 'App' 864 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:E0:4C:09:1C:86

\[#1] \[2007-02-20 20:57:34.453 'App' 864 verbose] \[attachedComputerWin32,815] NIC1 has description "Realtek RTL8139 Family PCI Fast Ethernet NIC"

\[#1] \[2007-02-20 20:57:34.453 'App' 864 verbose] \[attachedComputerWin32,807] NIC2 has MAC address 00:02:A5:F9:C9:EB

\[#1] \[2007-02-20 20:57:34.453 'App' 864 verbose] \[attachedComputerWin32,815] NIC2 has description "Intel(R) PRO/100 VM Network Connection"

\[#1] \[2007-02-20 20:57:34.453 'App' 864 verbose] \[attachedComputerWin32,788] NIC3 is non-ethernet - discarding

\[#1] \[2007-02-20 20:57:34.453 'App' 1352 verbose] \[importSource,461] (vim.vm.device.VirtualPCNet32) {

\[#1] dynamicType =

\[#1] }

\[#1] \[2007-02-20 20:57:34.453 'App' 1352 verbose] \[importSource,464] 2 NICs found

\[#1] \[2007-02-20 20:57:34.453 'App' 1852 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#1] \[2007-02-20 20:57:34.453 'App' 1852 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#1] \[2007-02-20 20:57:34.453 'App' 1852 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-02-20 20:57:34.453 'App' 1852 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#1] \[2007-02-20 20:57:34.453 'App' 1852 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#1] \[2007-02-20 20:57:34.453 'App' 1352 verbose] \[vmiTask,109] VmiQuerySourceInfoTask::task\{0}: returning results:

\[#1] (sysimage.QuerySourceInfoResult) {

\[#1] dynamicType = ,

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

\[#1] targetProductVersion = "PRODUCT_MANAGED",

\[#1] diskType = "VMFS",

\[#1] preallocateDisks = false,

\[#1] clonePagefile = false,

\[#1] nicMapping = (string) [],

\[#1] volumesToClone = (sysimage.VolumeCloneInfo) [],

\[#1] diskLocations = (string) [

\[#1] "#c807e2a3-d9111dfc-3780-d7be3cda07f1"

\[#1] ]

\[#1] }

\[#1] \[2007-02-20 20:58:07.109 'App' 1816 verbose] \[vmImporter,192] diskSizes:

\[#1] (long) [

\[#1] 40020664320

\[#1] ]

\[#1] \[2007-02-20 20:58:07.109 'App' 1816 verbose] \[ufaConnection,94] Creating local adapter...

\[#1] \[2007-02-20 20:58:07.109 'App' 1816 verbose] \[ufaConnection,94] Creating local adapter...

\[#1] \[2007-02-20 20:58:07.109 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=VmiCheckDestParamsTask::task\{3}

\[#1] \[2007-02-20 20:58:07.109 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=VmiCheckDestParamsTask::task\{3} with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:07.140 'App' 1816 verbose] \[imageProcessingTaskImpl,504] VmiCheckDestParamsTask::task\{3}: Starting execution of an image processing task

\[#1] \[2007-02-20 20:58:07.140 'App' 1816 verbose] \[imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task\{3}: SetState to running

\[#1] \[2007-02-20 20:58:07.140 'App' 1816 info] \[vmiCheckDestParamsTask,55] VmiCheckDestParamsTask initialized

\[#1] \[2007-02-20 20:58:07.140 'managedImportDestination' 1816 info] Connecting to host 192.9.200.13 on port 902

\[#1]

\[#1] \[2007-02-20 20:58:07.390 'managedImportDestination' 1816 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:03029F20]

\[2007-02-20 20:58:07.390 'BaseLibs' 1852 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[#1] \[2007-02-20 20:58:07.437 'managedImportDestination' 1816 info] Authenticating user root

\[#1] \[2007-02-20 20:58:07.656 'managedImportDestination' 1816 info] Logged in!

\[#1] \[2007-02-20 20:58:12.468 'App' 1816 verbose] \[managedImportDestination,322] Checking for 40020674560 bytes free on datastore "c807e2a3-d9111dfc-3780-d7be3cda07f1"

\[#1] \[2007-02-20 20:58:12.687 'App' 1816 verbose] \[managedImportDestination,330] Datastore "c807e2a3-d9111dfc-3780-d7be3cda07f1" has 56674484224 bytes free

\[#1] \[2007-02-20 20:58:12.687 'App' 864 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=2405 size=4096 err=0

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663f-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6642-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:58:12.687 'App' 1348 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6643-c124-11db-9a9d-806e6f6e6963}\"

\[#1] \[2007-02-20 20:58:13.718 'App' 1348 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-02-20 20:58:13.750 'App' 1276 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ is not supported .... Trying NT method.

\[??] \[2007-02-20 20:58:13.750 'App' 1276 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ Volume's disk number

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 warning] \[attachedComputerWin32,567] 1

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[diskSet,196] 2 volumes found

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[diskSet,203] 1 potential active volumes found

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#1] \[2007-02-20 20:58:13.750 'App' 1348 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[extendedOsInfo,389] \[boot loader]

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[extendedOsInfo,389] timeout=30

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[extendedOsInfo,389] \[operating systems]

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#1] \[2007-02-20 20:58:13.765 'App' 1348 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#1] \[2007-02-20 20:58:13.843 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#1] \[2007-02-20 20:58:13.843 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:13.843 'App' 1348 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#1] \[2007-02-20 20:58:14.265 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#1] \[2007-02-20 20:58:14.265 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE with session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[#1] \[2007-02-20 20:58:14.265 'App' 1348 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:0 64:0 Ver:5.0

\[#1] \[2007-02-20 20:58:14.265 'App' 1348 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#1] \[2007-02-20 20:58:14.281 'App' 1348 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

\[#1] \[2007-02-20 20:58:14.281 'App' 1816 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

\[#1] \[2007-02-20 20:58:14.546 'App' 1816 verbose] \[vmiTask,109] VmiCheckDestParamsTask::task\{3}: returning results:

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

\[#1] \[2007-02-20 20:58:14.546 'App' 1816 verbose] \[imageProcessingTaskImpl,154] VmiCheckDestParamsTask::task\{3}: SetState to success

\[#1] \[2007-02-20 20:58:14.546 'App' 1816 verbose] \[imageProcessingTaskImpl,514] VmiCheckDestParamsTask::task\{3}: Image processing task has succeeded

\[#1] \[2007-02-20 20:58:14.609 'App' 1348 verbose] \[disposable,121] object explicitly disposed: VmiCheckDestParamsTask::task\{3}

\[#1] \[2007-02-20 20:58:14.609 'managedImportDestination' 1348 verbose] Shutting down VMDB service...

\[#1] \[2007-02-20 20:58:14.609 'managedImportDestination' 1348 verbose] Unregistering callback...

\[#1] \[2007-02-20 20:58:14.609 'managedImportDestination' 1348 verbose] ...done

\[#1] \[2007-02-20 20:58:14.609 'App' 1852 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#1] \[2007-02-20 20:58:14.609 'App' 1852 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#1] \[2007-02-20 20:58:14.609 'App' 1852 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#1] \[2007-02-20 20:58:14.609 'App' 1852 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#1] \[2007-02-20 20:58:14.609 'App' 1852 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#1] \[2007-02-20 20:58:14.609 'App' 1348 verbose] \[imageProcessingTaskImpl,123] VmiCheckDestParamsTask::task\{3} being destroyed

\[2007-02-20 20:58:33.656 'VmdbSvc' 1348 verbose] IVmdbPipe2Impl operation completed with error: The pipe has been ended.

\[2007-02-20 20:58:33.656 'App' 864 verbose] CloseSession called for session id=F61CE83C-B70B-4D8D-A7E9-4A2F67B44144

\[2007-02-20 20:58:33.656 'VmdbAdapter' 864 verbose] Removed vmdb connection /db/connection/#1/

\[#2] \[2007-02-20 20:58:33.703 'App' 864 info] Impersonating user SYSTEM in session FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:33.750 'App' 864 info] \[vmImporter,209] Entering UFAD at DoImport

\[#2] \[2007-02-20 20:58:33.750 'App' 864 verbose] \[vmImporter,210] source:

\[#2] (sysimage.LiveSourceComputerSpec) {

\[#2] dynamicType = ,

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

\[#2] targetProductVersion = "PRODUCT_MANAGED",

\[#2] diskType = "VMFS",

\[#2] preallocateDisks = false,

\[#2] clonePagefile = false,

\[#2] nicMapping = (string) [

\[#2] "VMNetwork"

\[#2] ],

\[#2] volumesToClone = (sysimage.VolumeCloneInfo) [],

\[#2] diskLocations = (string) [

\[#2] "#c807e2a3-d9111dfc-3780-d7be3cda07f1"

\[#2] ]

\[#2] }

\[#2] \[2007-02-20 20:58:33.750 'App' 864 verbose] \[ufaConnection,94] Creating local adapter...

\[#2] \[2007-02-20 20:58:33.750 'App' 864 verbose] \[ufaConnection,94] Creating local adapter...

\[#2] \[2007-02-20 20:58:33.750 'App' 864 info] \[vmiImportTask,110] Successfully connected to UfaClient

\[#2] \[2007-02-20 20:58:33.750 'App' 864 verbose] \[disposable,68] Registering disposable session object id=VmiImportTask::task\{4}

\[#2] \[2007-02-20 20:58:33.750 'App' 864 verbose] \[disposable,79] Associating disposable session object id=VmiImportTask::task\{4} with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:33.796 'App' 864 verbose] \[imageProcessingTaskImpl,504] VmiImportTask::task\{4}: Starting execution of an image processing task

\[#2] \[2007-02-20 20:58:33.796 'App' 864 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{4}: SetState to running

\[#2] \[2007-02-20 20:58:33.796 'App' 864 info] \[vmiImportTask,180] Starting VM Import...

\[#2] \[2007-02-20 20:58:33.796 'App' 864 info] \[vmiImportTask,116] vmiImportTask initialized

\[#2] \[2007-02-20 20:58:33.796 'App' 864 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "Clone VM" created

\[#2] \[2007-02-20 20:58:33.796 'App' 864 verbose] \[vmiImportTask,201] VmiImportTask::DoRun - Calling GetComputer()

\[#2] \[2007-02-20 20:58:33.796 'App' 1352 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#2] \[2007-02-20 20:58:33.796 'App' 1856 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=1024 err=122

\[#2] \[2007-02-20 20:58:33.796 'App' 1856 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=0 size=2048 err=122

\[#2] \[2007-02-20 20:58:33.796 'App' 1856 verbose] \[attachedDiskWin32,150] QueryDosDevice: ret=2405 size=4096 err=0

\[#2] \[2007-02-20 20:58:33.812 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#2] \[2007-02-20 20:58:33.828 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=computer=\{8d56326b536472341b75b4f61b479869dbeb1967} with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:33.828 'App' 864 verbose] \[vmiImportTask,223] VmiImportTask::DoRun - Calling Clone()

\[#2] \[2007-02-20 20:58:33.828 'App' 864 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "create and clone to VM" created

\[#2] \[2007-02-20 20:58:33.828 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\"

\[#2] \[2007-02-20 20:58:33.828 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd663f-c124-11db-9a9d-806e6f6e6963}\"

\[#2] \[2007-02-20 20:58:33.828 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6642-c124-11db-9a9d-806e6f6e6963}\"

\[#2] \[2007-02-20 20:58:33.828 'App' 1856 verbose] \[attachedComputerWin32,453] Found volume with name "
?\Volume\{11cd6643-c124-11db-9a9d-806e6f6e6963}\"

\[#2] \[2007-02-20 20:58:34.859 'App' 1856 warning] \[attachedComputerWin32,567] 1

\[??] \[2007-02-20 20:58:34.890 'App' 1276 info] \[stub,42] Info\[Windisk_GetVolumeDeviceNumber]: IOCTL_GET_STORAGE_DEVICE_NUMBER for volume
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ is not supported .... Trying NT method.

\[??] \[2007-02-20 20:58:34.890 'App' 1276 info] \[stub,42] Error\[Windisk_GetVolumeDeviceNumber]:Failed to get
?\Volume\{11cd663e-c124-11db-9a9d-806e6f6e6963}\ Volume's disk number

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 warning] \[attachedComputerWin32,567] 1

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[diskSet,196] 2 volumes found

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[diskSet,203] 1 potential active volumes found

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[extendedOsInfo,389] \[boot loader]

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[extendedOsInfo,389] timeout=30

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[extendedOsInfo,389] \[operating systems]

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:34.890 'App' 1856 verbose] \[extendedOsInfo,389] \[boot loader]

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] timeout=30

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] \[operating systems]

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] \[boot loader]

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] timeout=30

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] \[operating systems]

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#2] \[2007-02-20 20:58:34.906 'App' 1856 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#2] \[2007-02-20 20:58:35.000 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#2] \[2007-02-20 20:58:35.000 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:35.000 'App' 1856 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SYSTEM

\[#2] \[2007-02-20 20:58:35.421 'App' 1856 verbose] \[disposable,68] Registering disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#2] \[2007-02-20 20:58:35.421 'App' 1856 verbose] \[disposable,79] Associating disposable session object id=fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:58:35.421 'App' 1856 info] \[extendedOsInfo,752] ParseNTRegistry FOUND: Microsoft Windows 2000 root:WINNT\ SrvPack:4 SMP:0 64:0 Ver:5.0

\[#2] \[2007-02-20 20:58:35.421 'App' 1856 verbose] \[disposable,121] object explicitly disposed: fileRegistry=\{attVol={computer={8d56326b536472341b75b4f61b479869dbeb1967},1}},WINNT\system32\config\SOFTWARE

\[#2] \[2007-02-20 20:58:35.437 'App' 1856 info] \[extendedOsInfo,178] Returning OS as win2000AdvServGuest

\[#2] \[2007-02-20 20:58:35.437 'managedImportDestination' 864 info] Connecting to host 192.9.200.13 on port 902

\[#2]

\[#2] \[2007-02-20 20:58:35.703 'managedImportDestination' 864 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:01854850]

\[2007-02-20 20:58:35.703 'BaseLibs' 1816 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[#2] \[2007-02-20 20:58:35.734 'managedImportDestination' 864 info] Authenticating user root

\[#2] \[2007-02-20 20:58:35.875 'managedImportDestination' 864 info] Logged in!

\[#2] \[2007-02-20 20:58:36.968 'App' 864 info] \[rpcConnectionWrapper,286] Locating the VM folder...

\[#2] \[2007-02-20 20:58:37.406 'App' 864 verbose] \[vmLocUtils,1078] Datacenter name=ha-datacenter moid=ha-datacenter

\[#2] \[2007-02-20 20:58:37.625 'App' 864 verbose] \[vmLocUtils,1079] ComputeResource name=ESX-ONE.intersid.com moid=ha-compute-res

\[#2] \[2007-02-20 20:58:37.625 'App' 864 verbose] \[vmLocUtils,1080] Datastore moid=c807e2a3-d9111dfc-3780-d7be3cda07f1

\[#2] \[2007-02-20 20:58:37.843 'App' 864 verbose] \[vmLocUtils,1081] VM Folder name=vm moid=ha-folder-vm

\[#2] \[2007-02-20 20:58:38.062 'App' 864 verbose] \[vmLocUtils,1082] ResourcePool name=Resources moid=ha-root-pool

\[#2] \[2007-02-20 20:58:38.281 'App' 864 verbose] \[vmLocUtils,1083] HostSystem name=ESX-ONE.intersid.com moid=ha-host

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[ufaConnection,94] Creating local adapter...

\[#2] \[2007-02-20 20:58:39.609 'App' 1852 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#2] \[2007-02-20 20:58:39.609 'App' 1816 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#2] \[2007-02-20 20:58:39.609 'App' 1852 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#2] \[2007-02-20 20:58:39.609 'App' 864 verbose] \[vmiImportTask,268] Successfully created target storage layout

\[#2] \[2007-02-20 20:58:39.609 'App' 864 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "create VM" created

\[#2] \[2007-02-20 20:58:39.609 'App' 864 info] \[vmiImportTask,345] Successfully connected to UfaManagedVMCreator

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[attachedComputerWin32,780] Detected 3 NICs

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[attachedComputerWin32,807] NIC1 has MAC address 00:E0:4C:09:1C:86

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[attachedComputerWin32,815] NIC1 has description "Realtek RTL8139 Family PCI Fast Ethernet NIC"

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[attachedComputerWin32,807] NIC2 has MAC address 00:02:A5:F9:C9:EB

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[attachedComputerWin32,815] NIC2 has description "Intel(R) PRO/100 VM Network Connection"

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[attachedComputerWin32,788] NIC3 is non-ethernet - discarding

\[#2] \[2007-02-20 20:58:39.609 'App' 1856 verbose] \[managedVMCreator,240] Remapping devices for new managed VM...

\[#2] \[2007-02-20 20:58:39.609 'managedVMCreator.CreateVM' 1856 info] Connecting to host 192.9.200.13 on port 902

\[#2]

\[#2] \[2007-02-20 20:58:40.359 'managedVMCreator.CreateVM' 1856 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:0303B1E0]

\[2007-02-20 20:58:40.359 'BaseLibs' 1348 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[#2] \[2007-02-20 20:58:40.421 'managedVMCreator.CreateVM' 1856 info] Authenticating user root

\[#2] \[2007-02-20 20:58:40.578 'managedVMCreator.CreateVM' 1856 info] Logged in!

\[#2] \[2007-02-20 20:58:43.265 'App' 1856 info] \[managedVMCreator,567] Attaching NIC #0 to network "VMNetwork"

\[#2] \[2007-02-20 20:58:43.281 'managedVMCreator.CreateVM' 1856 verbose] Shutting down VMDB service...

\[#2] \[2007-02-20 20:58:43.281 'managedVMCreator.CreateVM' 1856 verbose] Unregistering callback...

\[#2] \[2007-02-20 20:58:43.281 'managedVMCreator.CreateVM' 1856 verbose] ...done

\[#2] \[2007-02-20 20:58:43.281 'App' 864 info] \[vmiImportTask,383] DeviceMappingAlert: VM memory not a multiple of 4.Setting VM memory to 1532 MB.

\[#2] \[2007-02-20 20:58:43.281 'App' 1856 verbose] \[managedVMCreator,655] Creating managed VM...

\[#2] \[2007-02-20 20:58:43.281 'managedVMCreator.CreateVM' 1856 info] Connecting to host 192.9.200.13 on port 902

\[#2]

\[#2] \[2007-02-20 20:58:43.593 'managedVMCreator.CreateVM' 1856 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:0304B208]

\[2007-02-20 20:58:43.593 'BaseLibs' 1852 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[#2] \[2007-02-20 20:58:43.656 'managedVMCreator.CreateVM' 1856 info] Authenticating user root

\[#2] \[2007-02-20 20:58:43.859 'managedVMCreator.CreateVM' 1856 info] Logged in!

\[#2] \[2007-02-20 20:58:47.031 'App' 1856 info] \[managedVMCreator,1467] Using specific location VMFS for Disk: 0

\[#2] \[2007-02-20 20:58:47.031 'App' 1856 info] \[managedVMCreator,1534] Using datastore VMFS for VMX

\[#2] \[2007-02-20 20:58:47.468 'App' 1856 info] \[managedVMCreator,680] Waiting for VM creation to complete...

\[#2] \[2007-02-20 20:58:47.468 'App' 1856 verbose] \[rpcConnectionWrapper,785] Waiting for a task to complete...

\[#2] \[2007-02-20 20:59:24.750 'App' 1856 info] \[managedVMCreator,733] Target config file: \[VMFS] susvm/susvm.vmx

\[#2] \[2007-02-20 20:59:24.750 'managedVMCreator.CreateVM' 1856 verbose] Shutting down VMDB service...

\[#2] \[2007-02-20 20:59:24.750 'managedVMCreator.CreateVM' 1856 verbose] Unregistering callback...

\[#2] \[2007-02-20 20:59:24.750 'managedVMCreator.CreateVM' 1856 verbose] ...done

\[#2] \[2007-02-20 20:59:24.750 'App' 864 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create VM" 100% completed

\[#2] \[2007-02-20 20:59:24.750 'App' 864 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 5% completed

\[#2] \[2007-02-20 20:59:24.750 'App' 864 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 5% completed

\[#2] \[2007-02-20 20:59:24.750 'App' 864 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "create VM" destroyed

\[#2] \[2007-02-20 20:59:24.750 'App' 864 verbose] \[vmiImportTask,283] Successfully created target VM Spec

\[#2] \[2007-02-20 20:59:24.750 'App' 864 info] \[imageProcessingTaskStep,155] VmiImportTask::task\{4} step "clone to VM" created

\[#2] \[2007-02-20 20:59:24.750 'App' 864 warning] \[importSource,319] Unable to determine the guest OS from metadata: no metadata for sources of this type

\[#2] \[2007-02-20 20:59:24.765 'App' 1816 info] \[sysMigration,192] Starting cloning...

\[#2] \[2007-02-20 20:59:24.765 'App' 1816 verbose] \[sysMigration,193] targetVMSpec:

\[#2] (sysimage.ManagedVmSpec) {

\[#2] dynamicType = ,

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

\[#2] cylinder = 5169,

\[#2] head = 240,

\[#2] sector = 63

\[#2] }

\[#2] },

\[#2] sourceDiskDeviceNumber = 0,

\[#2] sourceDiskSpec = "
.\PhysicalDrive0",

\[#2] signature =

\[#2] \[2007-02-20 20:59:24.765 'App' 1816 verbose] \[sysMigration,199] clonePagefile: false

\[#2] \[2007-02-20 20:59:24.765 'App' 1816 verbose] \[sysMigration,200] updateBootInit: true

\[#2] \[2007-02-20 20:59:24.765 'App' 1816 verbose] \[sysMigration,201] updateDriveLetters: true

\[#2] \[2007-02-20 20:59:24.765 'App' 1816 verbose] \[ufaConnection,94] Creating local adapter...

\[#2] \[2007-02-20 20:59:24.781 'App' 1352 verbose] \[slaveConnection,189] Creating local connection to the co-located slave

\[#2] \[2007-02-20 20:59:24.781 'App' 1816 info] \[cloneTask,236] Parsing the VM spec to retrieve disk specs...

\[#2] \[2007-02-20 20:59:24.781 'App' 1816 info] \[vmomiParser,86] VmomiParser::init() - entering

\[#2] \[2007-02-20 20:59:24.781 'vmomiParser.Init' 1816 info] Connecting to host 192.9.200.13 on port 902

\[#2]

\[#2] \[2007-02-20 20:59:25.062 'vmomiParser.Init' 1816 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:0304B208]

\[2007-02-20 20:59:25.062 'BaseLibs' 1856 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[#2] \[2007-02-20 20:59:25.109 'vmomiParser.Init' 1816 info] Authenticating user root

\[#2] \[2007-02-20 20:59:25.312 'vmomiParser.Init' 1816 info] Logged in!

\[#2] \[2007-02-20 20:59:25.359 'App' 1816 info] \[vmomiParser,97] Connecting to VM id=96

\[#2] \[2007-02-20 20:59:25.359 'App' 1816 info] \[vmomiParser,100] VmomiParser::init() - exiting

\[#2] \[2007-02-20 20:59:25.359 'App' 1816 verbose] \[vmomiParser,210] Getting config info...

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 verbose] \[vmomiParser,160] File Name: \[VMFS] susvm/susvm.vmdk

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 info] \[vmomiParser,137] ManagedDiskSpec: Name=\[VMFS] susvm/susvm.vmdk

\[#2] \[2007-02-20 20:59:25.968 'vmomiParser.Init' 1816 verbose] Shutting down VMDB service...

\[#2] \[2007-02-20 20:59:25.968 'vmomiParser.Init' 1816 verbose] Unregistering callback...

\[#2] \[2007-02-20 20:59:25.968 'vmomiParser.Init' 1816 verbose] ...done

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 verbose] \[cloneTask,258] Disk Name: \[VMFS] susvm/susvm.vmdk

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 verbose] \[disposable,68] Registering disposable session object id=CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 verbose] \[disposable,79] Associating disposable session object id=CloneTask::task\{5} with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:59:25.968 'App' 864 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:25.968 'App' 864 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:25.968 'App' 864 info] \[imageProcessingTaskStep,409] VmiImportTask::task\{4} step "clone to VM" in progress...

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 verbose] \[imageProcessingTaskImpl,504] CloneTask::task\{5}: Starting execution of an image processing task

\[#2] \[2007-02-20 20:59:25.968 'App' 864 verbose] \[imageProcessingTaskWrapper,474] Waiting for completion of task CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:25.968 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 verbose] \[imageProcessingTaskImpl,154] CloneTask::task\{5}: SetState to running

\[#2] \[2007-02-20 20:59:25.968 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 info] \[cloneTask,148] Starting the cloning process...

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 info] \[cloneTask,157] Preparing for cloning...

\[#2] \[2007-02-20 20:59:25.968 'App' 1816 info] \[volumeMappingInfoBuilder,90] Initializing VolumeMappingInfoBuilder...

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[diskSet,196] 2 volumes found

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[diskSet,364] Volume: idx=0 sysId=7 active=Y

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[diskSet,364] Volume: idx=1 sysId=7 active=N

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[diskSet,203] 1 potential active volumes found

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[disposable,68] Registering disposable session object id=localFile=

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[disposable,79] Associating disposable session object id=localFile= with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[extendedOsInfo,389] \[boot loader]

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[extendedOsInfo,389] timeout=30

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[extendedOsInfo,389] default=multi(0)disk(0)rdisk(0)partition(1)\WINNT

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[extendedOsInfo,389] \[operating systems]

\[#2] \[2007-02-20 20:59:25.984 'App' 1348 verbose] \[extendedOsInfo,389] multi(0)disk(0)rdisk(0)partition(1)\WINNT="Microsoft Windows 2000 Server" /fastdetect

\[#2] \[2007-02-20 20:59:26.000 'App' 1348 verbose] \[disposable,121] object explicitly disposed: localFile=

\[#2] \[2007-02-20 20:59:26.000 'App' 1348 info] \[extendedOsInfo,453] SwagNTInfoFromString has Microsoft Windows 2000 Server

\[#2] \[2007-02-20 20:59:26.000 'App' 1348 info] \[extendedOsInfo,176] Returning OS as display Microsoft Windows 2000 Server

\[#2] \[2007-02-20 20:59:26.000 'App' 1348 verbose] \[diskSet,434] Found a candidate system volume at idx=0

\[#2] \[2007-02-20 20:59:26.000 'App' 1348 info] \[diskSet,297] OS resides on partition 1 , disk path
.\PhysicalDrive0

\[2007-02-20 20:59:26.000 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 20:59:26.000 'App' 932 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 0% completed

\[#2] \[2007-02-20 20:59:26.000 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:26.000 'App' 1856 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#2] \[2007-02-20 20:59:26.000 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[cloneTask,161] Prepared...

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[imageProcessingTaskStep,155] CloneTask::task\{5} step "cloning target disks" created

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[diskBasedCloneTask,109] Performing disk-based block-level cloning...

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[cloneTask,404] Getting disks for the source computer...

\[#2] \[2007-02-20 20:59:26.015 'App' 872 verbose] \[diskSet,330] GetDisks: disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[cloneTask,413] Disk 0 id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[diskBasedCloneTask,125] Cloning 1 disks...

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[diskBasedCloneTask,147] Cloning 0 disk of 1...

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[cloneTask,746] Cloning disk disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea} into disk \[VMFS] susvm/susvm.vmdk

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[imageProcessingTaskStep,155] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" created

\[#2] \[2007-02-20 20:59:26.015 'App' 872 info] \[attachedDiskWin32,405] Disk::CloneTo...

\[#2] \[2007-02-20 20:59:26.015 'App' 872 verbose] \[disposable,68] Registering disposable session object id=BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.015 'App' 872 verbose] \[disposable,79] Associating disposable session object id=BlockLevelCloning::task\{6} with session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[#2] \[2007-02-20 20:59:26.015 'App' 872 info] \[attachedDiskWin32,415] Scheduling BlockLevelCloningTask for execution...

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[imageProcessingTaskWrapper,109] Successfully connected to BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[imageProcessingTaskWrapper,817] Start managed object method for task BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 info] \[imageProcessingTaskStep,409] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" in progress...

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[imageProcessingTaskWrapper,474] Waiting for completion of task BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.015 'App' 1348 verbose] \[imageProcessingTaskImpl,504] BlockLevelCloning::task\{6}: Starting execution of an image processing task

\[#2] \[2007-02-20 20:59:26.015 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.015 'App' 1348 verbose] \[imageProcessingTaskImpl,154] BlockLevelCloning::task\{6}: SetState to running

\[#2] \[2007-02-20 20:59:26.015 'App' 1348 info] \[blockLevelCloningTaskImpl,494] Starting Block level cloning ...

\[#2] \[2007-02-20 20:59:26.015 'App' 1348 info] \[,0] NFC connection accept timeout: 180000 milliseconds

\[2007-02-20 20:59:26.015 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:26.015 'App' 1348 info] \[,0] NFC request timeout: 180000 milliseconds

\[#2] \[2007-02-20 20:59:26.031 'App' 1348 info] \[,0] NFC read timeout: 60000 milliseconds

\[#2] \[2007-02-20 20:59:26.031 'App' 1348 info] \[,0] NFC write timeout: 600000 milliseconds

\[#2] \[2007-02-20 20:59:26.031 'App' 1348 info] \[,0] NBD_Clientopen: attempting to create connection to ha-nfc://\[VMFS] susvm/susvm.vmdk@192.9.200.13:902!52 88 ac ad 6e 44 98 58-a8 70 ca 27 a4 3f a9 36

\[#2] \[2007-02-20 20:59:26.031 'App' 1348 info] \[,0] Started up WSA

\[#2] \[2007-02-20 20:59:26.031 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:26.046 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 20:59:26.046 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 20:59:26.046 'App' 1856 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 0% completed

\[2007-02-20 20:59:26.046 'App' 1856 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 0% completed

\[#2] \[2007-02-20 20:59:26.046 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.046 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 20:59:26.046 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:26.062 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:26.062 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 20:59:26.062 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.062 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.062 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:26.375 'App' 1348 info] \[,0] Opening file \[VMFS] susvm/susvm.vmdk (ha-nfc://\[VMFS] susvm/susvm.vmdk@192.9.200.13:902!52 88 ac ad 6e 44 98 58-a8 70 ca 27 a4 3f a9 36)

\[#2] \[2007-02-20 20:59:26.609 'App' 1348 info] \[,0] DISKLIB-LINK : Opened 'ha-nfc://\[VMFS] susvm/susvm.vmdk@192.9.200.13:902!52 88 ac ad 6e 44 98 58-a8 70 ca 27 a4 3f a9 36' (0x2): custom, 78165360 sectors / 38167 Mb.

\[#2] \[2007-02-20 20:59:26.609 'App' 1348 info] \[,0] DISKLIB-LIB : Opened "ha-nfc://\[VMFS] susvm/susvm.vmdk@192.9.200.13:902!52 88 ac ad 6e 44 98 58-a8 70 ca 27 a4 3f a9 36" (flags 0x2). 0301F5C0

\[#2] \[2007-02-20 20:59:26.625 'App' 1348 verbose] \[blockLevelCloningTaskImpl,1207] Copying 78165360 sectors from sector 0 to sector 0.

\[2007-02-20 20:59:28.593 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:28.593 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 20:59:28.593 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:28.593 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 20:59:28.593 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 20:59:28.593 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:04:46.562 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:04:46.562 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 1% completed

\[2007-02-20 21:04:46.562 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 1% completed

\[#2] \[2007-02-20 21:04:46.562 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:04:46.562 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:04:46.562 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:04:46.562 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:04:46.562 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:04:46.578 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:04:46.578 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 1% completed

\[#2] \[2007-02-20 21:04:46.578 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 21:04:46.578 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:04:46.578 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:04:46.578 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:04:46.578 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:11:21.328 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:11:21.328 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 21:11:21.328 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:11:21.328 'App' 1852 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 2% completed

\[#2] \[2007-02-20 21:11:21.328 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:11:21.328 'App' 1852 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 2% completed

\[#2] \[2007-02-20 21:11:21.328 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:11:21.328 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:11:21.328 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:11:21.343 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 2% completed

\[2007-02-20 21:11:21.343 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 6% completed

\[2007-02-20 21:11:21.343 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 6% completed

\[#2] \[2007-02-20 21:11:21.343 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:11:21.343 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:11:21.343 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:11:21.343 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:11:21.343 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:11:21.343 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:11:21.343 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:11:21.343 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:11:21.343 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:11:21.343 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:11:21.343 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:16:40.812 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:16:40.812 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 21:16:40.812 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:16:40.812 'App' 932 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 3% completed

\[#2] \[2007-02-20 21:16:40.812 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:16:40.812 'App' 932 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 3% completed

\[#2] \[2007-02-20 21:16:40.812 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:16:40.812 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:16:40.828 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:16:40.828 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 3% completed

\[2007-02-20 21:16:40.828 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 7% completed

\[2007-02-20 21:16:40.828 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 7% completed

\[#2] \[2007-02-20 21:16:40.828 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:16:40.828 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:16:40.828 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:16:40.828 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:16:40.828 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:16:40.828 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:16:40.859 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:16:40.859 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:16:40.859 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:16:40.859 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:16:40.859 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:22:39.046 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:22:39.046 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 21:22:39.046 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:22:39.046 'App' 932 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 4% completed

\[#2] \[2007-02-20 21:22:39.046 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:22:39.046 'App' 932 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 4% completed

\[#2] \[2007-02-20 21:22:39.046 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:22:39.062 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:22:39.062 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:22:39.062 'App' 1856 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 4% completed

\[2007-02-20 21:22:39.062 'App' 1856 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 8% completed

\[2007-02-20 21:22:39.062 'App' 1856 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 8% completed

\[#2] \[2007-02-20 21:22:39.062 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:22:39.062 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:22:39.078 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:22:39.078 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:22:39.078 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:22:39.078 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:22:39.078 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:22:39.078 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:22:39.078 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:22:39.078 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:22:39.078 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:38:05.562 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:38:05.562 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:38:05.562 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:38:05.562 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:38:05.562 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 5% completed

\[2007-02-20 21:38:05.562 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 5% completed

\[#2] \[2007-02-20 21:38:05.562 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:38:05.562 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:38:05.562 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:38:05.562 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 5% completed

\[2007-02-20 21:38:05.562 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 9% completed

\[2007-02-20 21:38:05.578 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 9% completed

\[#2] \[2007-02-20 21:38:05.578 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:38:05.578 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:38:05.578 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:38:05.578 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:38:05.578 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:38:05.578 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:38:05.578 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:38:05.578 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:38:05.578 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:38:05.593 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:38:05.609 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:44:36.875 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:44:36.875 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 21:44:36.875 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:44:36.875 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 6% completed

\[#2] \[2007-02-20 21:44:36.875 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:44:36.875 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 6% completed

\[#2] \[2007-02-20 21:44:36.875 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:44:36.890 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:44:36.906 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:44:36.906 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 6% completed

\[2007-02-20 21:44:36.906 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 10% completed

\[2007-02-20 21:44:36.906 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 10% completed

\[#2] \[2007-02-20 21:44:36.906 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:44:36.906 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:44:36.906 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:44:36.906 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:44:36.906 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:44:36.906 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:44:36.906 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:44:36.906 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:44:36.906 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:44:36.906 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:44:36.906 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:52:13.453 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:52:13.453 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:52:13.453 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:52:13.453 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:52:13.453 'App' 872 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 7% completed

\[2007-02-20 21:52:13.453 'App' 872 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 7% completed

\[#2] \[2007-02-20 21:52:13.468 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:52:13.468 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:52:13.468 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:52:13.484 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 7% completed

\[2007-02-20 21:52:13.484 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 11% completed

\[2007-02-20 21:52:13.484 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 11% completed

\[#2] \[2007-02-20 21:52:13.484 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:52:13.484 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:52:13.484 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:52:13.484 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:52:13.484 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:52:13.484 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:52:13.484 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:52:13.484 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:58:03.296 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 21:58:03.296 'App' 872 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 8% completed

\[2007-02-20 21:58:03.296 'App' 872 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 8% completed

\[#2] \[2007-02-20 21:58:03.296 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:58:03.296 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:58:03.296 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:58:03.296 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:58:03.296 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:58:03.312 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:58:03.312 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:58:03.312 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:58:03.312 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 21:58:03.312 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 8% completed

\[2007-02-20 21:58:03.312 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 12% completed

\[2007-02-20 21:58:03.312 'App' 872 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 12% completed

\[#2] \[2007-02-20 21:58:03.312 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:58:03.312 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 21:58:03.312 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:58:03.312 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 21:58:03.312 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 21:58:03.312 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 21:58:03.312 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 21:58:03.312 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:03:56.906 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 22:03:56.906 'App' 932 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 9% completed

\[2007-02-20 22:03:56.906 'App' 932 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 9% completed

\[#2] \[2007-02-20 22:03:56.906 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:03:56.906 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:03:56.906 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:03:56.906 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:03:56.906 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:03:56.921 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 22:03:56.921 'App' 932 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 9% completed

\[2007-02-20 22:03:56.921 'App' 932 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 13% completed

\[2007-02-20 22:03:56.921 'App' 932 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 13% completed

\[#2] \[2007-02-20 22:03:56.921 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:03:56.921 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:03:56.921 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:03:56.921 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:03:56.921 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:03:56.937 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:03:56.953 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 22:03:56.953 'App' 932 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:03:56.953 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:03:56.953 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:03:56.953 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:09:39.671 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:09:39.671 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 22:09:39.671 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 22:09:39.671 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 10% completed

\[#2] \[2007-02-20 22:09:39.671 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:09:39.671 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 10% completed

\[#2] \[2007-02-20 22:09:39.671 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:09:39.671 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:09:39.687 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 22:09:39.687 'App' 1852 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 10% completed

\[2007-02-20 22:09:39.687 'App' 1852 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 14% completed

\[2007-02-20 22:09:39.687 'App' 1852 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 14% completed

\[#2] \[2007-02-20 22:09:39.687 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:09:39.687 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:09:39.687 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:09:39.687 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:09:39.703 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:09:39.718 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:09:39.718 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:09:39.718 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:14:45.625 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 22:14:45.625 'App' 872 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 11% completed

\[2007-02-20 22:14:45.625 'App' 872 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 11% completed

\[#2] \[2007-02-20 22:14:45.625 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:14:45.625 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:14:45.625 'App' 872 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:14:45.625 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:14:45.640 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:14:45.640 'App' 1856 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:14:45.640 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:14:45.640 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:14:45.656 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 22:14:45.656 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 11% completed

\[2007-02-20 22:14:45.656 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 15% completed

\[2007-02-20 22:14:45.656 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 15% completed

\[#2] \[2007-02-20 22:14:45.656 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:14:45.656 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:14:45.656 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:14:45.656 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:14:45.656 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:14:45.656 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:14:45.656 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:14:45.656 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:15:16.468 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[2007-02-20 22:15:16.468 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" 100% completed

\[2007-02-20 22:15:16.468 'App' 1352 verbose] \[imageProcessingTaskStep,307] CloneTask::task\{5} step "cloning target disks" 100% completed

\[#2] \[2007-02-20 22:15:16.468 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:15:16.468 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:16.468 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:16.468 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:16.484 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:15:16.484 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:16.484 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:16.484 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:15:16.500 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[2007-02-20 22:15:16.500 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "clone to VM" 100% completed

\[2007-02-20 22:15:16.500 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "create and clone to VM" 100% completed

\[2007-02-20 22:15:16.500 'App' 1352 verbose] \[imageProcessingTaskStep,307] VmiImportTask::task\{4} step "Clone VM" 100% completed

\[#2] \[2007-02-20 22:15:16.500 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:16.500 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[2007-02-20 22:15:16.500 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:16.500 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:16.500 'App' 1816 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:15:16.500 'App' 1352 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:16.500 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:16.500 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.359 'App' 1348 error] \[imageProcessingTaskImpl,536] BlockLevelCloning::task\{6}: Image processing task has failed with PlatformError fault: 23

\[#2] \[2007-02-20 22:15:17.359 'App' 1348 verbose] \[imageProcessingTaskImpl,154] BlockLevelCloning::task\{6}: SetState to error

\[#2] \[2007-02-20 22:15:17.359 'App' 1348 verbose] \[imageProcessingTaskWrapper,886] Remote task BlockLevelCloning::task\{6} completed

\[#2] \[2007-02-20 22:15:17.359 'App' 1816 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from BlockLevelCloning::task\{6}

\[2007-02-20 22:15:17.359 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:17.359 'App' 1816 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task BlockLevelCloning::task\{6}

\[2007-02-20 22:15:17.359 'App' 880 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.359 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.359 'App' 1816 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:17.359 'App' 864 verbose] \[imageProcessingTaskWrapper,668] (Re)Start waiting for property updates from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.375 'App' 1348 verbose] \[disposable,121] object explicitly disposed: BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:17.375 'App' 1348 verbose] \[imageProcessingTaskImpl,123] BlockLevelCloning::task\{6} being destroyed

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 error] \[imageProcessingTaskStep,425] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" failed

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 verbose] \[imageProcessingTaskWrapper,1117] Reporting image processing task failure for task BlockLevelCloning::task\{6}

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 error] \[imageProcessingTaskWrapper,1124] ImageProcessingTask FAILED. Fault name: sysimage.fault.PlatformError

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 info] \[imageProcessingTaskStep,196] CloneTask::task\{5} step "Cloning into disk \[VMFS] susvm/susvm.vmdk" destroyed

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 info] \[imageProcessingTaskStep,196] CloneTask::task\{5} step "cloning target disks" destroyed

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 error] \[cloneTask,210] Cloning failed!

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 error] \[imageProcessingTaskImpl,536] CloneTask::task\{5}: Image processing task has failed with PlatformError fault: 23

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 verbose] \[imageProcessingTaskImpl,154] CloneTask::task\{5}: SetState to error

\[#2] \[2007-02-20 22:15:17.375 'App' 1816 verbose] \[imageProcessingTaskWrapper,886] Remote task CloneTask::task\{5} completed

\[#2] \[2007-02-20 22:15:17.375 'App' 864 verbose] \[imageProcessingTaskWrapper,437] Waiting for updates from CloneTask::task\{5}

\[2007-02-20 22:15:17.375 'App' 1852 verbose] \[imageProcessingTaskWrapper,749] Got an update from CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.375 'App' 864 verbose] \[imageProcessingTaskWrapper,515] Cleaning up remote task CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.390 'App' 864 verbose] \[imageProcessingTaskWrapper,1201] Disposing task wrapper for task CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.390 'App' 880 verbose] \[disposable,121] object explicitly disposed: CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.390 'App' 880 verbose] \[imageProcessingTaskImpl,123] CloneTask::task\{5} being destroyed

\[#2] \[2007-02-20 22:15:17.390 'App' 864 error] \[imageProcessingTaskStep,425] VmiImportTask::task\{4} step "clone to VM" failed

\[#2] \[2007-02-20 22:15:17.390 'App' 864 verbose] \[imageProcessingTaskWrapper,1117] Reporting image processing task failure for task CloneTask::task\{5}

\[#2] \[2007-02-20 22:15:17.390 'App' 864 error] \[imageProcessingTaskWrapper,1124] ImageProcessingTask FAILED. Fault name: sysimage.fault.PlatformError

\[#2] \[2007-02-20 22:15:17.390 'App' 864 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "clone to VM" destroyed

\[#2] \[2007-02-20 22:15:17.390 'App' 864 error] \[vmiImportTask,328] Cloning failed: sysimage.fault.PlatformError

\[#2] \[2007-02-20 22:15:17.390 'App' 864 info] \[vmiImportTask,494] Removing destination VM

\[#2] \[2007-02-20 22:15:17.390 'App' 864 info] \[vmiImportTask,502] Successfully connected to UfaManagedVMCreator

\[#2] \[2007-02-20 22:15:17.390 'ManagedVMCreatorImpl::DestroyVM' 880 info] Connecting to host 192.9.200.13 on port 902

\[#2]

\[#2] \[2007-02-20 22:15:17.953 'ManagedVMCreatorImpl::DestroyVM' 880 verbose] Connected to stream: \[class Vmacore::Ssl::SSLStreamImpl:0304B208]

\[2007-02-20 22:15:17.953 'BaseLibs' 1852 warning] \[Vmdb_Unset] Unsetting unknown path: /vmomi/

\[#2] \[2007-02-20 22:15:18.031 'ManagedVMCreatorImpl::DestroyVM' 880 info] Authenticating user root

\[#2] \[2007-02-20 22:15:19.859 'ManagedVMCreatorImpl::DestroyVM' 880 info] Logged in!

\[#2] \[2007-02-20 22:15:20.546 'App' 880 info] \[managedVMCreator,1866] Waiting for VM destruction to complete...

\[#2] \[2007-02-20 22:15:20.546 'App' 880 verbose] \[rpcConnectionWrapper,785] Waiting for a task to complete...

\[#2] \[2007-02-20 22:16:02.593 'ManagedVMCreatorImpl::DestroyVM' 880 verbose] Shutting down VMDB service...

\[#2] \[2007-02-20 22:16:02.593 'ManagedVMCreatorImpl::DestroyVM' 880 verbose] Unregistering callback...

\[#2] \[2007-02-20 22:16:02.593 'ManagedVMCreatorImpl::DestroyVM' 880 verbose] ...done

\[#2] \[2007-02-20 22:16:02.593 'App' 864 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "create and clone to VM" destroyed

\[#2] \[2007-02-20 22:16:02.593 'App' 864 info] \[imageProcessingTaskStep,196] VmiImportTask::task\{4} step "Clone VM" destroyed

\[#2] \[2007-02-20 22:16:02.593 'App' 864 error] \[imageProcessingTaskImpl,552] VmiImportTask::task\{4}: Image processing task has failed with MethodFault::Exception: sysimage.fault.CloneFault

\[#2] \[2007-02-20 22:16:02.593 'App' 864 verbose] \[imageProcessingTaskImpl,154] VmiImportTask::task\{4}: SetState to error

\[#2] \[2007-02-20 22:16:02.671 'App' 880 verbose] \[disposable,121] object explicitly disposed: VmiImportTask::task\{4}

\[#2] \[2007-02-20 22:16:02.671 'managedImportDestination' 880 verbose] Shutting down VMDB service...

\[#2] \[2007-02-20 22:16:02.671 'managedImportDestination' 880 verbose] Unregistering callback...

\[#2] \[2007-02-20 22:16:02.671 'managedImportDestination' 880 verbose] ...done

\[#2] \[2007-02-20 22:16:02.671 'App' 864 verbose] \[disposable,121] object explicitly disposed: computer=\{8d56326b536472341b75b4f61b479869dbeb1967}

\[#2] \[2007-02-20 22:16:02.671 'App' 864 verbose] \[diskSet,119] Cleaning up computer's child objects

\[#2] \[2007-02-20 22:16:02.671 'App' 864 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,1} \[#2] \[2007-02-20 22:16:02.671 'App' 864 verbose] \[diskSet,135] Unregistring volume id=attVol=\{computer=,2}

\[#2] \[2007-02-20 22:16:02.671 'App' 864 verbose] \[diskSet,145] Unregistring disk id=disk=\{9ba2498e79b590fd75d62d8b65fa945c367c0fea}

\[#2] \[2007-02-20 22:16:02.671 'App' 880 verbose] \[imageProcessingTaskImpl,123] VmiImportTask::task\{4} being destroyed

\[2007-02-20 22:16:35.875 'VmdbSvc' 880 verbose] IVmdbPipe2Impl operation completed with error: The pipe has been ended.

\[2007-02-20 22:16:35.875 'App' 864 verbose] CloseSession called for session id=FE87189C-479D-4701-93C6-99B5F3845C97

\[2007-02-20 22:16:35.875 'VmdbAdapter' 864 verbose] Removed vmdb connection /db/connection/#2/

\[#3] \[2007-02-20 22:16:35.921 'App' 864 info] Impersonating user SYSTEM in session EDF7CD91-2DB5-4C87-AABD-F6D8D5198A54

Regards

Ragav

0 Kudos
ryan_gallagher
Enthusiast
Enthusiast

PlatformError: 23 is the key here. It appears that there is a bad sector on your source disk.

To work around this issue, you might try switching to file-based cloning instead. You can do this by slightly changing the size of the target volume. If this bad sector is actually not in any file data then the cloning should succeed.

HTH,

-Ryan

Rajaragavan
Contributor
Contributor

Hi

When I first tried the conversion, I tried resizing the partition size and did the conversion, that too fails at 97% (I increased the size of C: drive and decreased the size of 😧 drive)

Only then I tried running the conversion with the same size. Let me give a try again.

Also one quick qn, when you say it may be due to bad sector, If i run a defrag and clear that out, will it help ?

Regards

ragav

0 Kudos
Datto
Expert
Expert

>> If i run a defrag and clear that out, will it help

You're going to want to run CHKDSK /F instead of defrag.

Datto

0 Kudos
AHUGLA
Contributor
Contributor

I had the same problem and I have found a workaround which work for me:

P2V:

Physical server : Windows 2000 SP4

The message I was in log was :

\[task,295] Task failed: P2VError UNKNOWN_METHOD_FAULT(sysimage.fault.CloneFault)

Resolution ???

When VMware ask if you want it desinstall automaticaly the Converter Agent, just select "I will manually uninstall the files later".

It seems that sometimes VMware cannot desinstall the drivers on the physical server.... and as a consequence stop the migration procedure.

Hope it will work for you....

0 Kudos
Rajaragavan
Contributor
Contributor

Hi Ahugla

I am doing a cold-conversion, booting using the vmware converter CD. The option you specify will work only when I do a conversion by running the application from the OS itself

Regards

Ragav

0 Kudos
aleph0
Hot Shot
Hot Shot

Finally solved for me, without passing in Virtual Center but pointing directly to ESXHOST on which the vm Clone will be created.

cheers

mf

\aleph0 ____________________________ http://virtualaleph.blogspot.com/ ############### If you found this information useful, please consider awarding points for "Correct" or "Helpful". Thanks!!!
0 Kudos
Rajaragavan
Contributor
Contributor

Hi All

I got the issue solved,

I tried fixing the Bad sector all... but always it fails at 97%.... Not sure why ?

So I installed the VMWare converter agent on the OS. And from that agent I tried virtualizing to ESX and it successfully completed.

Ragav

0 Kudos
PB1856
Contributor
Contributor

Thank you very much, this also did the trick for me!! I must say, starting to see converter3 being a tad flaky!! But in the end, it is working for us so can't complain too much I guess.

0 Kudos
jack_chen
Contributor
Contributor

Hi,

It is very glad to hear that the case was resolved.

Would you please instruct me to how to install VM converter agent directly into the local system which you want to clone to ESX3.0 server,thank you.

Is it just install the vm converter application itself and address to clone the local physical machine?

0 Kudos
jack_chen
Contributor
Contributor

Continued...

It looks to me we should use hot-cloning instead.

Because Converter supports disk-based cloning for cold cloning and for importation of existing virtual machines.

Disk-based cloning transfers all sectors from all disks, preserving all volume metadata. The destination virtual

machine receives exactly the same volumes, of the same type, as those of the source virtual machine.

Disk-based cloning supports all types of basic and dynamic disks.

So if I try and install VM Converter 3.0 on the local system which is going to clone to ESX3.0 server, and lauch VM Converter 3.0, then specify clone this physical machine to target VM in the ESX3.0 server, and with check uninstall agent manually later than the cloning, it shoild work.

Any comment?

0 Kudos
Rajaragavan
Contributor
Contributor

Hi

under the product downloads for VMWare converter you have to download the exe file and run the same in the system which you want to convert.

Once the installation is done, open the converter application and then you can start with "Import machine" you will get the wizard which will guide you on converting the local physical machine to ESX (Hot cloning). It also allows you to do the re-sizing of partitions.

Hope this helps.

Regards

Ragav

0 Kudos