VMware Cloud Community
beetlejelly
Contributor
Contributor

98% FAILED: Unable to reconfigure the destination virtual machine. Win srvr 2003

We are trying to convert a Windows Server 2003 x64 SP2 machine and get this error at 98 percent,

"FAILED: Unable to reconfigure the destination virtual machine."

The only reference I see in the log file is,

"2013-07-01T16:56:55.491-04:00 [03224 error 'Default'] [task,350] [LRO] Unexpected Exception: converter.fault.ReconfigurationFault

2013-07-01T16:56:55.538-04:00 [03224 info 'Default'] [task,379] [task-1] -- ERROR -- Convert: converter.fault.ReconfigurationFault

--> (converter.fault.ReconfigurationFault) {

-->    dynamicType = <unset>,

-->    faultCause = (vmodl.MethodFault) null,

-->    description = "converter.fault.FileNotFound",

-->    msg = "",

--> }"

I have attached the full log. Any help would be greatly appreciated.

0 Kudos
14 Replies
admin
Immortal
Immortal

can you goto the concerned destination on the datastore and check if the vm directory is present. If yes,Try creating a new VM and point it to the vmdk file.

0 Kudos
TomHowarth
Leadership
Leadership

when a failure occurs at 98% usually the machine has copied over,  the vmdk's have been created at the target sight.

you should be able to navigate to the target location and manually create your machine by building a new Win2K3 64bit Guest and mounting the already existing VMDKs as its disks.

Tom Howarth VCP / VCAP / vExpert
VMware Communities User Moderator
Blog: http://www.planetvm.net
Contributing author on VMware vSphere and Virtual Infrastructure Security: Securing ESX and the Virtual Environment
Contributing author on VCP VMware Certified Professional on VSphere 4 Study Guide: Exam VCP-410
0 Kudos
patanassov
VMware Employee
VMware Employee

Conversion has 2 phases - cloning the data and reconfiguration. Cloning has finished successfully and your VM has all the data (the VM is created at the destination in the beginning of the conversion, there is no need to recreate it). First just try to power it on. If it succeeds , you are lucky 🙂 It it does not (e.g. BSOD), the errors in the log are:

2013-07-01T16:56:00.460-04:00 [03188 error 'task-3'] Error 2 (opening key) saving registry key mntApi163093493130307997\ControlSet001\Services\rhelfltr into \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\$Reconfig$\mntApi163093493130307997-ControlSet001-Services-rhelfltr-reg

2013-07-01T16:56:00.507-04:00 [03188 error 'task-3'] Error 2 (opening key) saving registry key mntApi163093493130307997\ControlSet001\Services\rhelnet into \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\$Reconfig$\mntApi163093493130307997-ControlSet001-Services-rhelnet-reg

2013-07-01T16:56:00.507-04:00 [03188 error 'task-3'] Error 2 (opening key) saving registry key mntApi163093493130307997\ControlSet001\Services\rhelscsi into \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\$Reconfig$\mntApi163093493130307997-ControlSet001-Services-rhelscsi-reg

2013-07-01T16:56:00.538-04:00 [03188 error 'task-3'] Error 2 (opening key) saving registry key mntApi163093493130307997\ControlSet001\Services\coherence into \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\$Reconfig$\mntApi163093493130307997-ControlSet001-Services-coherence-reg

2013-07-01T16:56:00.554-04:00 [03188 error 'task-3'] Error 2 (opening key) saving registry key mntApi163093493130307997\ControlSet001\Services\toolsrv into \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\$Reconfig$\mntApi163093493130307997-ControlSet001-Services-toolsrv-reg

which do not seem critical (I think these were error during making backups of the registry keys

and:

2013-07-01T16:56:00.679-04:00 [03188 error 'task-3'] Could not find symmpi.sys in \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\Driver Cache\amd64\sp2.cab

2013-07-01T16:56:00.788-04:00 [03188 error 'task-3'] Could not find symmpi.sys in \\.\vstor2-mntapi20-shared-6E126F12000010000000000005000000\WINDOWS\Driver Cache\amd64\driver.cab

2013-07-01T16:56:00.788-04:00 [03188 error 'task-3'] Unable to find symmpi.sys in the specified CAB files

I don't know why the Windows doesn't have symmpi.sys in its driver cache. What you may do as a workaround is to mount the vmdk of the converted Windows in another Windows VM and copy this file (e.g. from another Windows machine) to \Windows\System32\drivers folder in the mounted disk.

This is a bit hackerish but easy to do:

  - edit settings of the VM you will mount the vmdk; add the vmdk file as an existing disk.

  - go to OS console. Disk manager in Windows will automatically recognize the new disk. Just mount it to a drive letter and copy the file.

  - remove the vmdk from the VM without deleting it from disk!

HTH

Plamen

0 Kudos
POCEH
VMware Employee
VMware Employee

Have you try to run the destination VM? What the result is?

0 Kudos
beetlejelly
Contributor
Contributor

OK, so I setup the ESXi server, copied the vmdk file to the datastore and converted it using the "vmkfstools -i" command.

I am able to turn the machine on, but It blue screens during boot up.

0 Kudos
a_p_
Leadership
Leadership

After confirming the target virtual machine has an "LSILogic" disk controller with the virtual disk connected as SCSI0:0, try to run the Converter again for this VM - using the "Configure Machine" option - to see whether the final reconfiguration works this way!?

André

0 Kudos
beetlejelly
Contributor
Contributor

I no longer have access to the original machine. All I have is the vmdm. I assume what you are referring to would take place on the machine to be imaged?

0 Kudos
a_p_
Leadership
Leadership

The "Configure Machine" runs on the virtual machine itself and basically tries to re-configure the existing VM by e.g. injecting the appropriate drivers.

André

0 Kudos
beetlejelly
Contributor
Contributor

I dont have access to the virtual machine itself because it blue screens every time we try to boot it up. We only have access to the esxi server.

0 Kudos
a_p_
Leadership
Leadership

No need to have the VM powered on. Run the Converter from another system and select the powered off VM in the Converter wizard.

André

0 Kudos
beetlejelly
Contributor
Contributor

Thanks, the error I get now is, "Unable to reconfigure the destination virtual machine."

0 Kudos
POCEH
VMware Employee
VMware Employee

Could you upload logs for failed reconfig?

0 Kudos
canoncola
Contributor
Contributor

I have the same issue! and I have log files pasted here... MANY THANKS IN ADVANCE

DETAILS: Converting a Windows 7 os Acronis True Image 2010 .TIB to a New Virtual Machine on the ESXi 5.1 server from laptop with converter standalone running on my laptop and the .TIB on an external USB drive.

At first I used version 9 which failed at 1% now I'm using VMWare version 8 and the conversion fails at 98% and says FAILED: Unable to create '\\.\vstor2-mntapi20-shared-26A63FEE0000E001000000001A000000\$Reconfig$'.

There are many more logs, just figured I would post this one because it seemed most general. If you need more info please let me know.

VMWARE CONVERTER SERVER 8 LOG

Section for VMware vCenter Converter Standalone, pid=492, version=5.1.0, build=1087880, option=Release

2013-12-05T10:42:37.733-08:00 [00524 info 'Default'] Logging uses fast path: true

2013-12-05T10:42:37.733-08:00 [00524 info 'Libs'] VThreadBase detected multiple threads.

2013-12-05T10:42:37.733-08:00 [00524 info 'Default'] Handling bora/lib logs with VmaCore facilities

2013-12-05T10:42:37.734-08:00 [00524 info 'Default'] Initialized channel manager

2013-12-05T10:42:37.734-08:00 [00524 info 'Default'] Current working directory: C:\Program Files\VMware\VMware vCenter Converter Standalone

2013-12-05T10:42:37.735-08:00 [00524 info 'Default'] ThreadPool windowsStackImmediateCommit = true

2013-12-05T10:42:37.735-08:00 [00524 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:37.735-08:00 [00524 info 'Default'] Trying converter_server

2013-12-05T10:42:37.780-08:00 [00520 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:37.780-08:00 [00544 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:37.780-08:00 [00524 info 'Default'] Trying ufa_agent

2013-12-05T10:42:37.783-08:00 [00524 info 'Default'] [shutdownMgr,25] Ufa shutdown manager registered.

2013-12-05T10:42:37.783-08:00 [00524 info 'Default'] [helper,454] Initializing SSL Contexts

2013-12-05T10:42:37.814-08:00 [00552 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] Vmacore::InitSSL: handshakeTimeoutUs = 20000000

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] [helper,507] Initializing certificates

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] [task,2069] [LRO] 32 max LROs

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] [task,2071] [LRO] 6 reserved internal LROs

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] [task,2073] [LRO] 6 reserved short LROs

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] [task,2075] [LRO] 12 reserved long LROs

2013-12-05T10:42:38.933-08:00 [00524 info 'Default'] [task,2087] [LRO] 86400-second task lifetime

2013-12-05T10:42:38.980-08:00 [00524 warning 'Locale'] Duplicate key 'hi_IN' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\iso2win.vlcl', only first one is used

2013-12-05T10:42:38.980-08:00 [00524 warning 'Locale'] Duplicate key 'az_AZ' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\iso2win.vlcl', only first one is used

2013-12-05T10:42:38.980-08:00 [00524 warning 'Locale'] Duplicate key 'sr_SP' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\iso2win.vlcl', only first one is used

2013-12-05T10:42:38.980-08:00 [00524 warning 'Locale'] Duplicate key 'az_AZ' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\isodata.vlcl', only first one is used

2013-12-05T10:42:38.980-08:00 [00524 warning 'Locale'] Duplicate key 'sr_SP' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\isodata.vlcl', only first one is used

2013-12-05T10:42:38.980-08:00 [00524 warning 'Locale'] Duplicate key 'uz_UZ' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\isodata.vlcl', only first one is used

2013-12-05T10:42:38.980-08:00 [00524 info 'Locale'] Added locale path C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\ to locale map

2013-12-05T10:42:38.995-08:00 [00524 info 'Locale'] Added locale path C:\Program Files\VMware\VMware vCenter Converter Standalone\coreLocale\ to locale map

2013-12-05T10:42:39.026-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\de/default.vmsg', only first one is used

2013-12-05T10:42:39.026-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\de/default.vmsg', only first one is used

2013-12-05T10:42:39.026-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\de/enum.vmsg', only first one is used

2013-12-05T10:42:39.026-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\de/enum.vmsg', only first one is used

2013-12-05T10:42:39.292-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\en/default.vmsg', only first one is used

2013-12-05T10:42:39.292-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\en/default.vmsg', only first one is used

2013-12-05T10:42:39.307-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\en/enum.vmsg', only first one is used

2013-12-05T10:42:39.307-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\en/enum.vmsg', only first one is used

2013-12-05T10:42:39.557-08:00 [00524 warning 'Locale'] Key 'Internal.Tasks.SingleSpacedList' in module 'task' under 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\en/' was previously defined; ignored

2013-12-05T10:42:39.619-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\fr/default.vmsg', only first one is used

2013-12-05T10:42:39.619-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\fr/default.vmsg', only first one is used

2013-12-05T10:42:39.635-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\fr/enum.vmsg', only first one is used

2013-12-05T10:42:39.635-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\fr/enum.vmsg', only first one is used

2013-12-05T10:42:39.697-08:00 [00524 warning 'Locale'] 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\fr/eventaux.vmsg', line 490: Invalid message '          en cours d'exécution sur l'hôte au moment où HA est activé sur celui-ci.        </description>    </EventLongDescription>    "'

2013-12-05T10:42:39.950-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\ja/default.vmsg', only first one is used

2013-12-05T10:42:39.950-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\ja/default.vmsg', only first one is used

2013-12-05T10:42:40.044-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\ja/enum.vmsg', only first one is used

2013-12-05T10:42:40.044-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\ja/enum.vmsg', only first one is used

2013-12-05T10:42:40.153-08:00 [00524 warning 'Locale'] 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\ja/eventaux.vmsg', line 695: Invalid message '           デフォルトでは、vSphere HA は仮想マシンのフェイルオーバーを 5 回試みます。         </description>        <cause>           <description>フェイルオーバーは、仮想マシンの構成ファイルが破損している、                すべてのパスがダウンしている状態であるためクラスタ内のどのホストからも 1 つまたは複数の                仮想マシンのデータストアにアクセスできないなど、                さまざまな理由で失敗します。さらに、vSphere HA master agent が管理ネットワークまたはハートビート                データストアを使用して監視できなくなったホスト上で仮想マシンがパワーオンされたか、                またはクラスタ外のホスト上のユーザーによって仮想マシンがパワーオンされたなどの理由も考えられます。           </description>           <action>                 過去のフェイルオーバーの試みが失敗した理由を確認するには、仮想マシンのログに                記録されたイベントを検索して、フェイルオーバーの失敗時に vSphere HA が報告した発生イベントを見つけます。 これらのイベントでは、                フェイルオーバーが失敗した理由が報告されます。 vSphere HA イベントは、「vSphere HA」という                語句を検索することで特定できます。 まだ問題が残っているかどうかを確認するには、                仮想マシンを手動でパワーオンします。 パワーオンに失敗する場合は、                返されたエラーを調べます。 一方、パワーオンの保留が長時間続く場合は、すべての                パスがダウンしている状態になっていないか確認します。 また、いずれかのホストが非活動と判断され                た場合、ネットワークまたはストレージの問題が原因となっていないかどうか確認します。             </action>        </cause>     </EventLongDescription>     "'

2013-12-05T10:42:40.372-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\zh_CN/default.vmsg', only first one is used

2013-12-05T10:42:40.372-08:00 [00524 warning 'Locale'] Duplicate key 'LicenseManager.LicenseInfo.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\zh_CN/default.vmsg', only first one is used

2013-12-05T10:42:40.403-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.label' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\zh_CN/enum.vmsg', only first one is used

2013-12-05T10:42:40.403-08:00 [00524 warning 'Locale'] Duplicate key 'com.vmware.vim.license.Feature.vaai.summary' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\zh_CN/enum.vmsg', only first one is used

2013-12-05T10:42:40.590-08:00 [00524 info 'Locale'] Added locale path C:\Program Files\VMware\VMware vCenter Converter Standalone\vimLocale\ to locale map

2013-12-05T10:42:40.590-08:00 [00524 info 'Locale'] Locale subsystem initialized from C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\ with default locale en.

2013-12-05T10:42:40.621-08:00 [00524 info 'Libs'] [ADS] Local Domain : WORKGROUP

2013-12-05T10:42:40.621-08:00 [00524 info 'Libs'] [ADS] IsWorkgroup: Yes

2013-12-05T10:42:40.621-08:00 [00524 info 'Libs'] [ADS] Local Machine: F700

2013-12-05T10:42:40.715-08:00 [00524 info 'Default'] BaseScheduler initialization complete -- void __thiscall Converter::Server::Scheduler::BaseScheduler::Initialize(const class boost::shared_ptr<class Converter::Server::Scheduler::SchedulerEnv>,const class boost::optional<class Converter::Server::Scheduler::SchedulerConstraints const &> &,const class boost::optional<class Converter::VdbConnection &> &) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/baseScheduler.cpp:112")

2013-12-05T10:42:40.715-08:00 [00524 info 'Default'] Loaded all items from the scheduler's database tables. -- void __thiscall Converter::Server::Scheduler::BaseScheduler::LoadSchedulerItemIdMap(void) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/baseScheduler.cpp:70")

2013-12-05T10:42:40.715-08:00 [00524 info 'Default'] Loaded all items from scheduler's database tables.Scheduler's item map contains 0 items. -- void __thiscall Converter::Server::Scheduler::PriorityQScheduler::LoadPriorityQ(void) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:185")

2013-12-05T10:42:40.715-08:00 [00524 info 'Default'] [plugin,158] ServerPlugin Init

2013-12-05T10:42:40.715-08:00 [00524 info 'Ufa'] Initializing SSL context

2013-12-05T10:42:40.715-08:00 [00524 info 'Ufa'] Plugin initialized

2013-12-05T10:42:40.793-08:00 [02376 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:40.793-08:00 [02376 info 'Default'] [task,338] [task-internal-1] -- BEGIN -- TaskManager

2013-12-05T10:42:40.793-08:00 [02380 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:40.793-08:00 [02384 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:40.793-08:00 [00524 info 'Default'] [plugin,188] ServerPlugin Start

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa'] SOAP pipe name: \\.\pipe\vmware-converter-server-soap

2013-12-05T10:42:40.793-08:00 [02388 info 'ThreadPool'] Thread enlisted

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa.HTTPService'] Setting buffer size to 32768

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa.HTTPService'] Using default for nonChunkingAgents: 'VMware VI Client|VMware-client|VMware-client/3.*'

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa.HTTPService'] Using default for agentsNeedingContentLength: 'VMware-client'

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa.HTTPService'] Max buffered response size is 104857600 bytes

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa'] enableChunkedResponses: true

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa.HTTPService'] Http Service started: <server p:0x030367c8, '\\.\pipe\vmware-converter-server-soap'>

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa'] Soap (over Named Pipe) started on pipe \\.\pipe\vmware-converter-server-soap. enableRemoteAccess = false, nonRootAccess = true, useSSL = false

2013-12-05T10:42:40.793-08:00 [00524 info 'Ufa'] Plugin started

2013-12-05T10:42:40.793-08:00 [00524 info 'Default'] [serviceWin32,416] vmware-converter-server service started

2013-12-05T10:50:07.109-08:00 [00520 info 'Libs'] [ADS] Get user name failed: 1332

2013-12-05T10:50:07.109-08:00 [00520 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T10:50:07.172-08:00 [00520 info 'Default'] [user,874] Successfully authenticated user 0000 from this local machine (using pipe)

2013-12-05T10:50:23.177-08:00 [00552 info 'Libs'] [ADS] Get user name failed: 1332

2013-12-05T10:50:23.177-08:00 [00552 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T10:50:23.224-08:00 [00552 info 'Default'] [user,874] Successfully authenticated user 0000 from this local machine (using pipe)

2013-12-05T10:50:24.987-08:00 [03880 info 'ThreadPool'] Thread enlisted

2013-12-05T10:51:12.191-08:00 [00552 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x030d37cc, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T11:57:24.537-08:00 [00524 info 'Default'] === [Converter::Server::Conversion::ConversionManagerImpl::CreateJob] ConversionJobSpec:

--> (converter.server.conversion.ConversionJobSpec) {

-->    dynamicType = <unset>,

-->    name = "ConversionJob_5299d8cd-cf08-7a49-3ba6-1995068ecb05",

-->    description = <unset>,

-->    firstRun = <unset>,

-->    startSuspended = <unset>,

-->    source = (converter.ComputerSpec) {

-->       dynamicType = <unset>,

-->       location = (converter.ComputerSpec.ThirdPartyHostedLocation) {

-->          dynamicType = <unset>,

-->          configFilePath = "H:\win7.tib",

-->          password = <unset>,

-->          networkUsername = <unset>,

-->          networkPassword = <unset>,

-->          type = "acronisBackup",

-->       },

-->    },

-->    conversionParams = (converter.params.ConversionParams) {

-->       dynamicType = <unset>,

-->       osInstanceBootLoaderEntry = (converter.info.BootConfigInfo.BootLoaderEntrySpec) null,

-->       doClone = true,

-->       cloningParams = (converter.params.CloningParams) {

-->          dynamicType = <unset>,

-->          target = (converter.params.TargetVmSpec) {

-->             dynamicType = <unset>,

-->             name = "Win7",

-->             location = (converter.params.TargetVmSpec.ManagedVmLocation) {

-->                dynamicType = <unset>,

-->                vimConnect = (converter.VimConnectionSpec) {

-->                   dynamicType = <unset>,

-->                   hostname = "192.168.2.250",

-->                   port = <unset>,

-->                   credentials = (converter.VimConnectionSpec.LoginVimCredentials) {

-->                      dynamicType = <unset>,

-->                      username = "root",

-->                      password = (not shown),

-->                   },

-->                   verifyPeer = <unset>,

-->                   sslThumbprint = "B3:B9:DB:AF:1F:A4:93:A6:A0:60:75:19:43:28:98:DD:B0:F9:E8:FA",

-->                },

-->                vmFolder = <unset>,

-->                host = 'vim.HostSystem:ha-host',

-->                resourcePool = <unset>,

-->                computeResource = <unset>,

-->                vmFolderName = <unset>,

-->                hostName = "ESXi.SAN",

-->                resourcePoolName = <unset>,

-->                computeResourceName = <unset>,

-->             },

-->             productVersion = <unset>,

-->             hardwareVersion = "vmx-09",

-->          },

-->          storageParams = (converter.params.StorageParams) {

-->             dynamicType = <unset>,

-->             cloningMode = "volumeBasedCloning",

-->             diskControllerType = "scsiLsiLogicSAS",

-->             targetDiskParams = (converter.params.StorageParams.TargetDiskParams) [

-->                (converter.params.StorageParams.ManagedTargetDiskParams) {

-->                   dynamicType = <unset>,

-->                   sourceDiskId = "disk-2",

-->                   volumesToClone = (converter.params.StorageParams.VolumeCloningParams) [

-->                      (converter.params.StorageParams.VolumeCloningParams) {

-->                         dynamicType = <unset>,

-->                         sourceVolumeId = "626*?3>.0000010000000000",

-->                         resize = true,

-->                         newCapacityInBytes = 29753344,

-->                         newClusterSizeInBytes = <unset>,

-->                      },

-->                      (converter.params.StorageParams.VolumeCloningParams) {

-->                         dynamicType = <unset>,

-->                         sourceVolumeId = "626*?3>.0000056000000000",

-->                         resize = true,

-->                         newCapacityInBytes = 62813896704,

-->                         newClusterSizeInBytes = <unset>,

-->                      }

-->                   ],

-->                   diskType = "vmfsMonolithicFlat",

-->                   lvg = false,

-->                   gpt = false,

-->                   datastoreName = "SSD",

-->                }

-->             ],

-->             skipUnwantedFiles = true,

-->             useBitmapDriver = <unset>,

-->             intermediateImage = <unset>,

-->             modifiedClustersOnly = <unset>,

-->             vmxDatastoreName = "SSD",

-->             resizeRatio = <unset>,

-->             minVolumeSize = <unset>,

-->             optimizedPartitionAlignment = true,

-->          },

-->          networkParams = (converter.params.NetworkParams) {

-->             dynamicType = <unset>,

-->             preserveNicCount = false,

-->             preserveNicMapping = false,

-->             nic = (converter.params.NetworkParams.NicParams) [

-->                (converter.params.NetworkParams.ManagedNicParams) {

-->                   dynamicType = <unset>,

-->                   connected = true,

-->                   networkName = "VM Network",

-->                }

-->             ],

-->          },

-->          basicHardwareParams = (converter.params.BasicHardwareParams) {

-->             dynamicType = <unset>,

-->             numCPUs = 1,

-->             numCoresPerSocket = 1,

-->             memoryMB = 3000,

-->          },

-->          incrementalP2VCloningParams = (converter.params.IncrementalP2VCloningParams) null,

-->          nextBitmapId = <unset>,

-->          snapshotSource = <unset>,

-->          preserveDeviceBacking = <unset>,

-->          preserveIdentity = <unset>,

-->          doSynchronize = <unset>,

-->          doFinalize = <unset>,

-->          ovfParams = (converter.params.OvfParams) null,

-->          retainLimit = <unset>,

-->       },

-->       doReconfig = true,

-->       reconfigParams = (converter.params.ReconfigParams) null,

-->       doSynchronize = <unset>,

-->       doInstallTools = true,

-->       doCustomize = false,

-->       customizationParams = (converter.params.CustomizationParams) null,

-->       doUninstallAgent = false,

-->       powerOnTargetVM = true,

-->       removeRestoreCheckpoints = true,

-->       helperVmNetworkParams = (converter.params.HelperVmNetworkParams) null,

-->       throttlingParams = (converter.params.ThrottlingParams) {

-->          dynamicType = <unset>,

-->          cpuThrottling = "none",

-->          networkBandwidthThrottlingInMB = <unset>,

-->       },

-->    },

-->    p2vSourceModificationSpec = (converter.server.conversion.P2VSourceModificationSpec) null,

-->    parentFolder = 'converter.server.conversion.ConversionFolder:ConversionFolder',

-->    synchronizeImmediately = <unset>,

-->    synchronizationStartTime = <unset>,

--> }

--> =====================

2013-12-05T11:57:28.546-08:00 [00524 info 'Default'] Connecting to host 192.168.2.250 on port 443

2013-12-05T11:57:28.547-08:00 [00524 info 'Default'] localVersion is: vim.version.version8

2013-12-05T11:57:28.630-08:00 [00524 info 'Default'] Remote version is: vim.version.version8

2013-12-05T11:57:28.638-08:00 [00524 info 'Default'] Authenticating user root

2013-12-05T11:57:28.711-08:00 [00524 info 'Default'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res

2013-12-05T11:57:28.714-08:00 [00524 info 'Default'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool

2013-12-05T11:57:28.717-08:00 [00524 info 'Default'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res

2013-12-05T11:57:28.722-08:00 [00524 info 'Default'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter

2013-12-05T11:57:28.725-08:00 [00524 info 'Default'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm

2013-12-05T11:57:28.725-08:00 [00524 info 'Default'] Reusing existing VIM connection to 192.168.2.250

2013-12-05T11:57:28.733-08:00 [00524 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds

2013-12-05T11:57:28.737-08:00 [00524 info 'vmomi.soapStub[1]'] Resetting stub adapter for server <cs p:006c4698, TCP:192.168.2.250:443> : Closed

2013-12-05T11:57:28.742-08:00 [00524 info 'Default'] Connecting to host 192.168.2.250 on port 443

2013-12-05T11:57:28.742-08:00 [00524 info 'Default'] localVersion is: vim.version.version8

2013-12-05T11:57:28.760-08:00 [00524 info 'Default'] Remote version is: vim.version.version8

2013-12-05T11:57:28.763-08:00 [00524 info 'Default'] Authenticating user root

2013-12-05T11:57:28.825-08:00 [00524 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds

2013-12-05T11:57:28.832-08:00 [00524 info 'Default'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res

2013-12-05T11:57:28.984-08:00 [00524 info 'vmomi.soapStub[2]'] Resetting stub adapter for server <cs p:006c4698, TCP:192.168.2.250:443> : Closed

2013-12-05T11:57:28.985-08:00 [00524 info 'Default'] Created new scheduler item with id = "1", firstTimeToRun = "2013-12-05 11:57:28.740", source = "", targetRPOInMinutes = "0". -- __thiscall Converter::Server::Scheduler::SchedulerItemImpl::SchedulerItemImpl(const class Converter::Server::Scheduler::SchedulerItemSpec &,const int &,const class boost::shared_ptr<class Converter::Server::Scheduler::SchedulerEnv> &,const class Vmacore::Ref<class Vmacore::Service::Logger> &,const class boost::optional<class Converter::VdbConnection &> &) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/schedulerItemImpl.cpp:87")

2013-12-05T11:57:28.987-08:00 [00524 info 'Default'] scheduler item with id="1" created -- int __thiscall Converter::Server::Scheduler::PriorityQScheduler::AddSchedulerItem(const class boost::shared_ptr<class Converter::Server::Scheduler::SchedulerItemSpec> &,const class boost::optional<class Converter::VdbConnection &> &) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:49")

2013-12-05T11:57:29.000-08:00 [02380 info 'Default'] Scheduler scheduling item 1 to run at time = 2013-12-05 11:57:28.993. -- void __thiscall Converter::Server::Scheduler::SimpleScheduler::Run(void) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/simpleScheduler.cpp:109")

2013-12-05T11:57:29.092-08:00 [02388 info 'Default'] Connecting to host 192.168.2.250 on port 443

2013-12-05T11:57:29.093-08:00 [02388 info 'Default'] localVersion is: vim.version.version8

2013-12-05T11:57:29.125-08:00 [02388 info 'Default'] Remote version is: vim.version.version8

2013-12-05T11:57:29.130-08:00 [02388 info 'Default'] Authenticating user root

2013-12-05T11:57:29.202-08:00 [02388 info 'Default'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res

2013-12-05T11:57:29.206-08:00 [02388 info 'Default'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool

2013-12-05T11:57:29.209-08:00 [02388 info 'Default'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res

2013-12-05T11:57:29.217-08:00 [02388 info 'Default'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter

2013-12-05T11:57:29.221-08:00 [02388 info 'Default'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm

2013-12-05T11:57:29.222-08:00 [02388 info 'Default'] Reusing existing VIM connection to 192.168.2.250

2013-12-05T11:57:29.257-08:00 [02388 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds

2013-12-05T11:57:29.262-08:00 [02388 info 'vmomi.soapStub[3]'] Resetting stub adapter for server <cs p:032c7ee8, TCP:192.168.2.250:443> : Closed

2013-12-05T11:57:29.365-08:00 [03828 info 'ThreadPool'] Thread enlisted

2013-12-05T11:57:29.375-08:00 [00544 error 'Default'] Failed to send response to the client: class Vmacore::SystemException(The pipe is being closed)

2013-12-05T11:57:29.440-08:00 [02388 info 'Default'] Started task "task-1" for job="job-1", item ="1" -- void __thiscall Converter::Server::Job::JobProcessorImpl::StartProcessingJobs(void) ("d:/build/ob/bora-1087880/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:385")

2013-12-05T11:57:29.462-08:00 [03828 info 'Default'] [task,338] [task-1] -- BEGIN -- Convert

2013-12-05T11:57:29.502-08:00 [03828 info 'Default'] [taskSpec,467] [task-1] [TaskMap] task-1:task-1

2013-12-05T11:57:29.589-08:00 [00520 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded

2013-12-05T11:57:29.593-08:00 [00520 info 'vmomi.soapStub[0]'] Resetting stub adapter for server <cs p:0326f7e8, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed

2013-12-05T11:58:29.260-08:00 [03828 error 'Default'] [task,350] [LRO] Unexpected Exception: converter.fault.CloneFault

2013-12-05T11:58:29.293-08:00 [03828 info 'Default'] [task,379] [task-1] -- ERROR -- Convert: converter.fault.CloneFault

--> (converter.fault.CloneFault) {

-->    dynamicType = <unset>,

-->    faultCause = (vmodl.MethodFault) null,

-->    description = "Cannot format volume.",

-->    msg = "",

--> }

2013-12-05T11:58:29.295-08:00 [03828 info 'Default'] [diagnosticManager,269] Retrieved taskInfo for "converter.task.Task:task-1" mapping it to "converter.task.Task:task-1".

2013-12-05T11:58:29.295-08:00 [03828 info 'Default'] [diagnosticManager,309] The task with id = "task-1" was found to be a "recent" task.

2013-12-05T11:58:29.295-08:00 [03828 info 'Default'] [diagnosticManager,323] No existing log bundle found for task with id = "task-1". The task is still "recent" so a log bundle will now be generated for it.

2013-12-05T11:58:29.298-08:00 [03828 info 'Default'] [diagnosticManager,792] Retrieving task related diagnostics for server task with id = "task-1".

2013-12-05T11:58:29.563-08:00 [02384 info 'Default'] Run 0 of job "job-1" finished with an error. -- struct Converter::Server::Job::JobExecutor::SchedulerUpdateSpec __thiscall Converter::Server::Conversion::ConversionJobExecutorImpl::UpdateJob(class Converter::Server::Job::InternalJob &,bool,const class Converter::Server::Scheduler::SchedulerItemStatistics &,const class Converter::Task::TaskInfo &) ("d:/build/ob/bora-1087880/bora/sysimage/lib/converter/server/conversion/conversionJobExecutorImpl.cpp:405")

2013-12-05T11:58:29.862-08:00 [02384 info 'Default'] Suspended 1 scheduler items for job (job-1) -- void __thiscall Converter::Server::Job::JobProcessorImpl::SuspendJobAux(const class Converter::Server::Job::InternalJob &,class Converter::VdbConnection &) ("d:/build/ob/bora-1087880/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:830")

2013-12-05T11:58:52.950-08:00 [03828 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded

2013-12-05T11:58:52.953-08:00 [03828 info 'vmomi.soapStub[4]'] Resetting stub adapter for server <cs p:032c9368, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed

2013-12-05T11:58:52.980-08:00 [03828 info 'ThreadPool'] Thread delisted

2013-12-05T12:01:34.076-08:00 [00544 info 'Libs'] [ADS] Get user name failed: 1332

2013-12-05T12:01:34.079-08:00 [00544 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T12:01:34.130-08:00 [00544 info 'Default'] [user,874] Successfully authenticated user 0000 from this local machine (using pipe)

2013-12-05T12:01:38.219-08:00 [00552 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded

2013-12-05T12:01:38.280-08:00 [00552 info 'vmomi.soapStub[5]'] Resetting stub adapter for server <cs p:032b6e48, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed

2013-12-05T12:02:19.290-08:00 [00520 info 'Libs'] [ADS] Get user name failed: 1332

2013-12-05T12:02:19.290-08:00 [00520 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T12:02:19.345-08:00 [00520 info 'Default'] [user,874] Successfully authenticated user 0000 from this local machine (using pipe)

2013-12-05T12:02:23.837-08:00 [00552 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x0326fb54, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:02:49.714-08:00 [00520 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded

2013-12-05T12:02:49.717-08:00 [00520 info 'vmomi.soapStub[6]'] Resetting stub adapter for server <cs p:03270048, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed

2013-12-05T12:02:54.007-08:00 [03880 info 'Default'] [conversionJobUtils,254] Job 1 deleted from database

2013-12-05T12:02:54.010-08:00 [03880 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T12:02:54.010-08:00 [03880 info 'Default'] [diagnosticManager,743] Deleting log bundle with key = "task-1-diag-20131205195829-higvyd.zip".

2013-12-05T12:02:54.037-08:00 [03880 info 'Default'] [taskManager,1267] Task 1 deleted from database

2013-12-05T12:02:55.117-08:00 [00520 info 'Libs'] [ADS] Get user name failed: 1332

2013-12-05T12:02:55.117-08:00 [00520 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T12:02:55.349-08:00 [00520 info 'Default'] [user,874] Successfully authenticated user 0000 from this local machine (using pipe)

2013-12-05T12:03:08.837-08:00 [00520 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x031e9cc4, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:03:24.408-08:00 [00520 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded

2013-12-05T12:03:24.411-08:00 [00520 info 'vmomi.soapStub[7]'] Resetting stub adapter for server <cs p:032c9488, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed

2013-12-05T12:03:33.675-08:00 [00552 info 'Libs'] [ADS] Get user name failed: 1332

2013-12-05T12:03:33.675-08:00 [00552 info 'Libs'] [ADS] GetTokenInformation failed: 1312

2013-12-05T12:03:33.729-08:00 [00552 info 'Default'] [user,874] Successfully authenticated user 0000 from this local machine (using pipe)

2013-12-05T12:03:43.849-08:00 [00524 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x032b6f54, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:05:12.232-08:00 [00520 info 'Default'] === [Converter::Server::Conversion::ConversionManagerImpl::CreateJob] ConversionJobSpec:

--> (converter.server.conversion.ConversionJobSpec) {

-->    dynamicType = <unset>,

-->    name = "ConversionJob_52804d9c-00b7-134e-09d2-cf4099181f3e",

-->    description = <unset>,

-->    firstRun = <unset>,

-->    startSuspended = <unset>,

-->    source = (converter.ComputerSpec) {

-->       dynamicType = <unset>,

-->       location = (converter.ComputerSpec.ThirdPartyHostedLocation) {

-->          dynamicType = <unset>,

-->          configFilePath = "H:\win7.tib",

-->          password = <unset>,

-->          networkUsername = <unset>,

-->          networkPassword = <unset>,

-->          type = "acronisBackup",

-->       },

-->    },

-->    conversionParams = (converter.params.ConversionParams) {

-->       dynamicType = <unset>,

-->       osInstanceBootLoaderEntry = (converter.info.BootConfigInfo.BootLoaderEntrySpec) null,

-->       doClone = true,

-->       cloningParams = (converter.params.CloningParams) {

-->          dynamicType = <unset>,

-->          target = (converter.params.TargetVmSpec) {

-->             dynamicType = <unset>,

-->             name = "win7",

-->             location = (converter.params.TargetVmSpec.ManagedVmLocation) {

-->                dynamicType = <unset>,

-->                vimConnect = (converter.VimConnectionSpec) {

-->                   dynamicType = <unset>,

-->                   hostname = "192.168.2.250",

-->                   port = <unset>,

-->                   credentials = (converter.VimConnectionSpec.LoginVimCredentials) {

-->                      dynamicType = <unset>,

-->                      username = "root",

-->                      password = (not shown),

-->                   },

-->                   verifyPeer = <unset>,

-->                   sslThumbprint = "B3:B9:DB:AF:1F:A4:93:A6:A0:60:75:19:43:28:98:DD:B0:F9:E8:FA",

-->                },

-->                vmFolder = <unset>,

-->                host = 'vim.HostSystem:ha-host',

-->                resourcePool = <unset>,

-->                computeResource = <unset>,

-->                vmFolderName = <unset>,

-->                hostName = "ESXi.SAN",

-->                resourcePoolName = <unset>,

-->                computeResourceName = <unset>,

-->             },

-->             productVersion = <unset>,

-->             hardwareVersion = "vmx-08",

-->          },

-->          storageParams = (converter.params.StorageParams) {

-->             dynamicType = <unset>,

-->             cloningMode = "volumeBasedCloning",

-->             diskControllerType = "scsiLsiLogicSAS",

-->             targetDiskParams = (converter.params.StorageParams.TargetDiskParams) [

-->                (converter.params.StorageParams.ManagedTargetDiskParams) {

-->                   dynamicType = <unset>,

-->                   sourceDiskId = "disk-2",

-->                   volumesToClone = (converter.params.StorageParams.VolumeCloningParams) [

-->                      (converter.params.StorageParams.VolumeCloningParams) {

-->                         dynamicType = <unset>,

-->                         sourceVolumeId = "626*?3>.0000010000000000",

-->                         resize = true,

-->                         newCapacityInBytes = 29753344,

-->                         newClusterSizeInBytes = <unset>,

-->                      },

-->                      (converter.params.StorageParams.VolumeCloningParams) {

-->                         dynamicType = <unset>,

-->                         sourceVolumeId = "626*?3>.0000056000000000",

-->                         resize = true,

-->                         newCapacityInBytes = 58543988736,

-->                         newClusterSizeInBytes = <unset>,

-->                      }

-->                   ],

-->                   diskType = "vmfsMonolithicFlat",

-->                   lvg = false,

-->                   gpt = false,

-->                   datastoreName = "SSD",

-->                }

-->             ],

-->             skipUnwantedFiles = true,

-->             useBitmapDriver = <unset>,

-->             intermediateImage = <unset>,

-->             modifiedClustersOnly = <unset>,

-->             vmxDatastoreName = "SSD",

-->             resizeRatio = <unset>,

-->             minVolumeSize = <unset>,

-->             optimizedPartitionAlignment = true,

-->          },

-->          networkParams = (converter.params.NetworkParams) {

-->             dynamicType = <unset>,

-->             preserveNicCount = true,

-->             preserveNicMapping = false,

-->          },

-->          basicHardwareParams = (converter.params.BasicHardwareParams) {

-->             dynamicType = <unset>,

-->             numCPUs = 1,

-->             numCoresPerSocket = 1,

-->             memoryMB = 2000,

-->          },

-->          incrementalP2VCloningParams = (converter.params.IncrementalP2VCloningParams) null,

-->          nextBitmapId = <unset>,

-->          snapshotSource = <unset>,

-->          preserveDeviceBacking = <unset>,

-->          preserveIdentity = <unset>,

-->          doSynchronize = <unset>,

-->          doFinalize = <unset>,

-->          ovfParams = (converter.params.OvfParams) null,

-->          retainLimit = <unset>,

-->       },

-->       doReconfig = true,

-->       reconfigParams = (converter.params.ReconfigParams) null,

-->       doSynchronize = <unset>,

-->       doInstallTools = false,

-->       doCustomize = false,

-->       customizationParams = (converter.params.CustomizationParams) null,

-->       doUninstallAgent = false,

-->       powerOnTargetVM = false,

-->       removeRestoreCheckpoints = true,

-->       helperVmNetworkParams = (converter.params.HelperVmNetworkParams) null,

-->       throttlingParams = (converter.params.ThrottlingParams) {

-->          dynamicType = <unset>,

-->          cpuThrottling = "none",

-->          networkBandwidthThrottlingInMB = <unset>,

-->       },

-->    },

-->    p2vSourceModificationSpec = (converter.server.conversion.P2VSourceModificationSpec) null,

-->    parentFolder = 'converter.server.conversion.ConversionFolder:ConversionFolder',

-->    synchronizeImmediately = <unset>,

-->    synchronizationStartTime = <unset>,

--> }

--> =====================

2013-12-05T12:05:16.104-08:00 [00520 info 'Default'] Connecting to host 192.168.2.250 on port 443

2013-12-05T12:05:16.104-08:00 [00520 info 'Default'] localVersion is: vim.version.version8

2013-12-05T12:05:16.127-08:00 [00520 info 'Default'] Remote version is: vim.version.version8

2013-12-05T12:05:16.133-08:00 [00520 info 'Default'] Authenticating user root

2013-12-05T12:05:16.200-08:00 [00520 info 'Default'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res

2013-12-05T12:05:16.202-08:00 [00520 info 'Default'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool

2013-12-05T12:05:16.205-08:00 [00520 info 'Default'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res

2013-12-05T12:05:16.212-08:00 [00520 info 'Default'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter

2013-12-05T12:05:16.218-08:00 [00520 info 'Default'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm

2013-12-05T12:05:16.219-08:00 [00520 info 'Default'] Reusing existing VIM connection to 192.168.2.250

2013-12-05T12:05:16.230-08:00 [00520 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds

2013-12-05T12:05:16.236-08:00 [00520 info 'vmomi.soapStub[9]'] Resetting stub adapter for server <cs p:032c8268, TCP:192.168.2.250:443> : Closed

2013-12-05T12:05:16.239-08:00 [00520 info 'Default'] Connecting to host 192.168.2.250 on port 443

2013-12-05T12:05:16.239-08:00 [00520 info 'Default'] localVersion is: vim.version.version8

2013-12-05T12:05:16.263-08:00 [00520 info 'Default'] Remote version is: vim.version.version8

2013-12-05T12:05:16.269-08:00 [00520 info 'Default'] Authenticating user root

2013-12-05T12:05:16.341-08:00 [00520 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds

2013-12-05T12:05:16.383-08:00 [00520 info 'vmomi.soapStub[10]'] Resetting stub adapter for server <cs p:032c82e8, TCP:192.168.2.250:443> : Closed

2013-12-05T12:05:16.384-08:00 [00520 warning 'Default'] Failed to generate ESX list for scheduler item 2. Reason is SSL Exception: error:0906D06C:PEM routines:PEM_read_bio:no start line. Load balancing will not be performed correctly  when this item is running. -- __thiscall Converter::Server::Scheduler::SchedulerItemImpl::SchedulerItemImpl(const class Converter::Server::Scheduler::SchedulerItemSpec &,const int &,const class boost::shared_ptr<class Converter::Server::Scheduler::SchedulerEnv> &,const class Vmacore::Ref<class Vmacore::Service::Logger> &,const class boost::optional<class Converter::VdbConnection &> &) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/schedulerItemImpl.cpp:74")

2013-12-05T12:05:16.384-08:00 [00520 info 'Default'] Created new scheduler item with id = "2", firstTimeToRun = "2013-12-05 12:05:16.237", source = "", targetRPOInMinutes = "0". -- __thiscall Converter::Server::Scheduler::SchedulerItemImpl::SchedulerItemImpl(const class Converter::Server::Scheduler::SchedulerItemSpec &,const int &,const class boost::shared_ptr<class Converter::Server::Scheduler::SchedulerEnv> &,const class Vmacore::Ref<class Vmacore::Service::Logger> &,const class boost::optional<class Converter::VdbConnection &> &) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/schedulerItemImpl.cpp:87")

2013-12-05T12:05:16.384-08:00 [00520 info 'Default'] scheduler item with id="2" created -- int __thiscall Converter::Server::Scheduler::PriorityQScheduler::AddSchedulerItem(const class boost::shared_ptr<class Converter::Server::Scheduler::SchedulerItemSpec> &,const class boost::optional<class Converter::VdbConnection &> &) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:49")

2013-12-05T12:05:16.385-08:00 [02380 info 'Default'] Scheduler scheduling item 2 to run at time = 2013-12-05 12:05:16.385. -- void __thiscall Converter::Server::Scheduler::SimpleScheduler::Run(void) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/simpleScheduler.cpp:109")

2013-12-05T12:05:16.440-08:00 [02388 info 'Default'] Connecting to host 192.168.2.250 on port 443

2013-12-05T12:05:16.440-08:00 [02388 info 'Default'] localVersion is: vim.version.version8

2013-12-05T12:05:16.458-08:00 [02388 info 'Default'] Remote version is: vim.version.version8

2013-12-05T12:05:16.462-08:00 [02388 info 'Default'] Authenticating user root

2013-12-05T12:05:16.530-08:00 [02388 info 'Default'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res

2013-12-05T12:05:16.532-08:00 [02388 info 'Default'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool

2013-12-05T12:05:16.535-08:00 [02388 info 'Default'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res

2013-12-05T12:05:16.540-08:00 [02388 info 'Default'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter

2013-12-05T12:05:16.543-08:00 [02388 info 'Default'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm

2013-12-05T12:05:16.543-08:00 [02388 info 'Default'] Reusing existing VIM connection to 192.168.2.250

2013-12-05T12:05:16.567-08:00 [02388 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds

2013-12-05T12:05:16.571-08:00 [02388 info 'vmomi.soapStub[11]'] Resetting stub adapter for server <cs p:032c82e8, TCP:192.168.2.250:443> : Closed

2013-12-05T12:05:16.669-08:00 [03656 info 'ThreadPool'] Thread enlisted

2013-12-05T12:05:16.719-08:00 [02388 info 'Default'] Started task "task-2" for job="job-2", item ="2" -- void __thiscall Converter::Server::Job::JobProcessorImpl::StartProcessingJobs(void) ("d:/build/ob/bora-1087880/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:385")

2013-12-05T12:05:16.754-08:00 [03656 info 'Default'] [task,338] [task-2] -- BEGIN -- Convert

2013-12-05T12:05:16.796-08:00 [03656 info 'Default'] [taskSpec,467] [task-2] [TaskMap] task-2:task-3

2013-12-05T12:05:16.883-08:00 [00524 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded

2013-12-05T12:05:16.887-08:00 [00524 info 'vmomi.soapStub[8]'] Resetting stub adapter for server <cs p:032c90c8, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed

2013-12-05T12:17:29.078-08:00 [03880 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x0144c934, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:17:34.078-08:00 [00544 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x0326fc74, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:21:39.079-08:00 [00552 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x032701b4, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:22:54.080-08:00 [00520 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x0326f714, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:23:29.080-08:00 [00544 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x032b6894, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:25:14.081-08:00 [00520 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x0326fd34, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T12:25:19.081-08:00 [00552 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x03270154, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out)

2013-12-05T14:50:51.974-08:00 [03656 error 'Default'] [task,350] [LRO] Unexpected Exception: converter.fault.FileCreationFault

2013-12-05T14:50:51.997-08:00 [03656 info 'Default'] [task,379] [task-2] -- ERROR -- Convert: converter.fault.FileCreationFault

--> (converter.fault.FileCreationFault) {

-->    dynamicType = <unset>,

-->    faultCause = (vmodl.MethodFault) null,

-->    file = "\\.\vstor2-mntapi20-shared-26A63FEE0000E001000000001A000000\$Reconfig$",

-->    msg = "",

--> }

2013-12-05T14:50:52.011-08:00 [03656 info 'Default'] [diagnosticManager,269] Retrieved taskInfo for "converter.task.Task:task-2" mapping it to "converter.task.Task:task-2".

2013-12-05T14:50:52.011-08:00 [03656 info 'Default'] [diagnosticManager,309] The task with id = "task-2" was found to be a "recent" task.

2013-12-05T14:50:52.011-08:00 [03656 info 'Default'] [diagnosticManager,323] No existing log bundle found for task with id = "task-2". The task is still "recent" so a log bundle will now be generated for it.

2013-12-05T14:50:52.018-08:00 [03656 info 'Default'] [diagnosticManager,792] Retrieving task related diagnostics for server task with id = "task-2".

2013-12-05T14:50:52.144-08:00 [02384 info 'Default'] Run 0 of job "job-2" finished with an error. -- struct Converter::Server::Job::JobExecutor::SchedulerUpdateSpec __thiscall Converter::Server::Conversion::ConversionJobExecutorImpl::UpdateJob(class Converter::Server::Job::InternalJob &,bool,const class Converter::Server::Scheduler::SchedulerItemStatistics &,const class Converter::Task::TaskInfo &) ("d:/build/ob/bora-1087880/bora/sysimage/lib/converter/server/conversion/conversionJobExecutorImpl.cpp:405")

2013-12-05T14:50:52.222-08:00 [02384 info 'Default'] Suspended 1 scheduler items for job (job-2) -- void __thiscall Converter::Server::Job::JobProcessorImpl::SuspendJobAux(const class Converter::Server::Job::InternalJob &,class Converter::VdbConnection &) ("d:/build/ob/bora-1087880/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:830")

0 Kudos
POCEH
VMware Employee
VMware Employee

Try only to reconfig new VM and upload log bundle if reconfig failed, as attachment please.

0 Kudos