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.
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.
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.
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
Have you try to run the destination VM? What the result is?
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.
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é
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?
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é
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.
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é
Thanks, the error I get now is, "Unable to reconfigure the destination virtual machine."
Could you upload logs for failed reconfig?
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")
Try only to reconfig new VM and upload log bundle if reconfig failed, as attachment please.