Skip to content

Instantly share code, notes, and snippets.

@mbailey

mbailey/VMware vCenter Convertor Standalone SLOWNESS

Created March 15, 2011 06:03
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save mbailey/870379 to your computer and use it in GitHub Desktop.
Save mbailey/870379 to your computer and use it in GitHub Desktop.
Download ZIP
I'm attempting to use VMware vCenter Converter Standalone to migrate a 10GB Ubuntu guest from an ESXi 4.0.0 host to an ESXi 4.1.0 host. After running for two hours and reporting only 3% progress the conversion failed. The converter logs are below.
Raw
VMware vCenter Convertor Standalone SLOWNESS
Section for VMware vCenter Converter Standalone, pid=2596, version=4.3.0, build=build-292238, option=Release
[2011-02-24 14:41:11.109 03884 warning 'App'] Failed to create console writer
[2011-02-24 14:41:11.249 02808 info 'App'] Current working directory: C:\Program Files\VMware\VMware vCenter Converter Standalone
[2011-02-24 14:41:11.249 02808 info 'App'] Trying converter_server
[2011-02-24 14:41:12.374 02808 info 'App'] [shutdownMgr,25] Ufa shutdown manager registered.
[2011-02-24 14:41:12.374 02808 info 'App'] [helper,453] Initializing SSL Contexts
[2011-02-24 14:41:12.406 02808 info 'Libs'] Using system libcrypto, version 9080CF
[2011-02-24 14:41:13.671 02808 info 'App'] Vmacore::InitSSL: doVersionCheck = true, handshakeTimeoutUs = 120000000
[2011-02-24 14:41:13.671 02808 info 'App'] [helper,499] Initializing certificates
[2011-02-24 14:41:13.671 02808 info 'App'] [task,2061] [LRO] 32 max LROs
[2011-02-24 14:41:13.671 02808 info 'App'] [task,2063] [LRO] 6 reserved internal LROs
[2011-02-24 14:41:13.671 02808 info 'App'] [task,2065] [LRO] 6 reserved short LROs
[2011-02-24 14:41:13.671 02808 info 'App'] [task,2067] [LRO] 12 reserved long LROs
[2011-02-24 14:41:13.671 02808 info 'App'] [task,2079] [LRO] 86400-second task lifetime
[2011-02-24 14:41:14.421 02808 warning 'Locale'] Duplicate key 'hi_IN' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\iso2win.vlcl'
[2011-02-24 14:41:14.421 02808 warning 'Locale'] Duplicate key 'az_AZ' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\iso2win.vlcl'
[2011-02-24 14:41:14.421 02808 warning 'Locale'] Duplicate key 'sr_SP' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\iso2win.vlcl'
[2011-02-24 14:41:14.452 02808 warning 'Locale'] Duplicate key 'az_AZ' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\isodata.vlcl'
[2011-02-24 14:41:14.452 02808 warning 'Locale'] Duplicate key 'sr_SP' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\isodata.vlcl'
[2011-02-24 14:41:14.452 02808 warning 'Locale'] Duplicate key 'uz_UZ' in file 'C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\isodata.vlcl'
[2011-02-24 14:41:14.796 02808 info 'Locale'] Added locale path C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\ to locale map
[2011-02-24 14:41:14.874 02808 info 'Locale'] Locale subsystem initialized from C:\Program Files\VMware\VMware vCenter Converter Standalone\locale\ with default locale en.
[2011-02-24 14:41:15.437 02808 info 'Libs'] [ADS] Local Domain : WORKGROUP
[2011-02-24 14:41:15.437 02808 info 'Libs'] [ADS] IsWorkgroup: Yes
[2011-02-24 14:41:15.437 02808 info 'Libs'] [ADS] Local Machine: MGMNT-CONSOLE
[2011-02-24 14:41:15.765 02808 info 'App'] 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-292238/bora/sysimage/ufad/server/scheduler/baseScheduler.cpp:112")
[2011-02-24 14:41:15.827 02808 info 'App'] [plugin,160] ServerPlugin Init
[2011-02-24 14:41:15.827 02808 info 'App'] Trying ufa_agent
[2011-02-24 14:41:16.031 02808 info 'Ufa'] Initializing SSL context
[2011-02-24 14:41:16.031 02808 info 'Ufa'] Plugin initialized
[2011-02-24 14:41:16.265 01560 info 'App'] [task,332] [task-internal-1] -- BEGIN -- TaskManager
[2011-02-24 14:41:16.265 02808 info 'App'] Loaded all items from the scheduler's database tables. -- void __thiscall Converter::Server::Scheduler::BaseScheduler::LoadSchedulerItemIdMap(void) ("d:/build/ob/bora-292238/bora/sysimage/ufad/server/scheduler/baseScheduler.cpp:70")
[2011-02-24 14:41:16.265 02808 info 'App'] 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-292238/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:183")
[2011-02-24 14:41:16.265 02808 info 'App'] [plugin,191] ServerPlugin Start
[2011-02-24 14:41:16.265 02808 info 'Ufa'] SOAP pipe name: \\.\pipe\vmware-converter-server-soap
[2011-02-24 14:41:16.265 02808 info 'Ufa.HTTPService'] Using default for nonChunkingAgents: 'VMware VI Client|VMware-client|VMware-client/3.*'
[2011-02-24 14:41:16.265 02808 info 'Ufa.HTTPService'] Using default for agentsNeedingContentLength: 'VMware-client'
[2011-02-24 14:41:16.265 02808 info 'Ufa.HTTPService'] Max buffered response size is 104857600 bytes
[2011-02-24 14:41:16.327 02808 info 'Ufa'] doPendedPostBodyReads: false, enableChunkedResponses: true
[#1] [2011-02-24 14:41:16.468 02996 info 'Libs'] HOSTINFO 2929544805779 @ 3579545Hz -> 0 @ 1000000Hz
[#1] [2011-02-24 14:41:16.468 02996 info 'Libs'] HOSTINFO ((x * 2399728063) >> 33) + -818412620835
[2011-02-24 14:41:17.999 02808 info 'Ufa.HTTPService'] Http Service started: [class Vmacore::System::NamedPipeServerWin32:02EEB3C8]
[2011-02-24 14:41:17.999 02808 info 'Ufa'] Soap (over Named Pipe) started on pipe \\.\pipe\vmware-converter-server-soap. enableRemoteAccess = false, nonRootAccess = true, useSSL = false
[2011-02-24 14:41:17.999 02808 info 'Ufa'] Plugin started
[2011-02-24 14:41:18.046 02808 info 'App'] [serviceWin32,413] vmware-converter-server service started
[#2] [2011-02-24 14:42:56.843 03704 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[#2] [2011-02-24 14:45:10.265 02620 info 'App'] Connecting to host 10.1.1.10 on port 443
[#2] [2011-02-24 14:45:10.296 02620 info 'App'] Authenticating user root
[2011-02-24 14:45:10.312 03172 warning 'App'] Closing Response processing in unexpected state: 3
[#2] [2011-02-24 14:45:10.312 02620 info 'App'] Connecting to host 10.1.1.11 on port 443
[#2] [2011-02-24 14:45:10.343 02620 info 'App'] Authenticating user root
[#2] [2011-02-24 14:45:10.359 02620 info 'App'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res
[#2] [2011-02-24 14:45:10.359 02620 info 'App'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool
[#2] [2011-02-24 14:45:10.374 02620 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[#2] [2011-02-24 14:45:10.374 02620 info 'App'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter
[#2] [2011-02-24 14:45:10.374 02620 info 'App'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm
[#2] [2011-02-24 14:45:10.374 02620 info 'App'] Reusing existing VIM connection to 10.1.1.11
[#2] [2011-02-24 14:45:10.390 02620 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[#2] [2011-02-24 14:45:10.390 02620 info 'vmomi.soapStub[2]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[#2] [2011-02-24 14:45:10.390 02620 warning 'vmomi.soapStub[2]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#2] [2011-02-24 14:45:10.390 02620 info 'vmomi.soapStub[1]'] Resetting stub adapter for server TCP:10.1.1.10:443 : Closed
[#2] [2011-02-24 14:45:10.390 02620 warning 'vmomi.soapStub[1]'] Canceling invocation: server=TCP:10.1.1.10:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#2] [2011-02-24 14:45:10.390 02620 info 'App'] Connecting to host 10.1.1.11 on port 443
[#2] [2011-02-24 14:45:10.421 02620 info 'App'] Authenticating user root
[#2] [2011-02-24 14:45:10.437 02620 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[#2] [2011-02-24 14:45:10.452 02620 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[#2] [2011-02-24 14:45:10.546 02620 info 'vmomi.soapStub[3]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[#2] [2011-02-24 14:45:10.546 02620 warning 'vmomi.soapStub[3]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#2] [2011-02-24 14:45:10.546 02620 info 'App'] Created new scheduler item with id = "1", firstTimeToRun = "2011-02-24 14:45:10.390", 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-292238/bora/sysimage/ufad/server/scheduler/schedulerItemImpl.cpp:87")
[#2] [2011-02-24 14:45:10.546 02620 info 'App'] 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-292238/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:46")
[2011-02-24 14:45:10.593 01372 info 'App'] Scheduler scheduling item 1 to run at time = 2011-02-24 14:45:10.593. -- void __thiscall Converter::Server::Scheduler::SimpleScheduler::Run(void) ("d:/build/ob/bora-292238/bora/sysimage/ufad/server/scheduler/simpleScheduler.cpp:88")
[#2] [2011-02-24 14:45:10.671 03580 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[#2] [2011-02-24 14:45:10.671 03580 info 'vmomi.soapStub[0]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[2011-02-24 14:45:10.702 02848 info 'App'] Connecting to host 10.1.1.10 on port 443
[#2] [2011-02-24 14:45:10.702 03580 warning 'vmomi.soapStub[0]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[2011-02-24 14:45:10.734 02848 info 'App'] Authenticating user root
[2011-02-24 14:45:10.749 02756 warning 'App'] Closing Response processing in unexpected state: 3
[2011-02-24 14:45:10.749 02848 info 'App'] Connecting to host 10.1.1.11 on port 443
[2011-02-24 14:45:10.781 02848 info 'App'] Authenticating user root
[2011-02-24 14:45:10.796 02848 info 'App'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res
[2011-02-24 14:45:10.796 02848 info 'App'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool
[2011-02-24 14:45:10.796 02848 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[2011-02-24 14:45:10.812 02848 info 'App'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter
[2011-02-24 14:45:10.812 02848 info 'App'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm
[2011-02-24 14:45:10.812 02848 info 'App'] Reusing existing VIM connection to 10.1.1.11
[2011-02-24 14:45:10.843 02848 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[2011-02-24 14:45:10.843 02848 info 'vmomi.soapStub[5]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[2011-02-24 14:45:10.843 02848 warning 'vmomi.soapStub[5]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[2011-02-24 14:45:10.843 02848 info 'vmomi.soapStub[4]'] Resetting stub adapter for server TCP:10.1.1.10:443 : Closed
[2011-02-24 14:45:10.843 02848 warning 'vmomi.soapStub[4]'] Canceling invocation: server=TCP:10.1.1.10:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[2011-02-24 14:45:11.015 02848 info 'App'] Started task "task-1" for job="job-4", item ="1" -- void __thiscall Converter::Server::Job::JobProcessorImpl::StartProcessingJobs(void) ("d:/build/ob/bora-292238/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:351")
[2011-02-24 14:45:11.281 03576 info 'App'] [task,332] [task-1] -- BEGIN -- Convert
[2011-02-24 14:45:11.296 03576 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[2011-02-24 14:45:11.312 03576 info 'App'] [taskSpec,464] [task-1] [TaskMap] task-1:task-1
[#3] [2011-02-24 14:46:35.359 03704 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[2011-02-24 14:47:07.874 03576 error 'App'] [task,344] [LRO] Unexpected Exception: vmodl.fault.RequestCanceled
[2011-02-24 14:47:07.984 03576 info 'App'] [task,373] [task-1] -- ERROR -- Convert: vmodl.fault.RequestCanceled
(vmodl.fault.RequestCanceled) {
dynamicType = <unset>,
faultCause = (vmodl.MethodFault) null,
msg = "",
}
[2011-02-24 14:47:07.984 03576 info 'App'] [diagnosticManager,260] Retrieved taskInfo for "converter.task.Task:task-1" mapping it to "converter.task.Task:task-1".
[2011-02-24 14:47:07.984 03576 info 'App'] [diagnosticManager,300] The task with id = "task-1" was found to be a "recent" task.
[2011-02-24 14:47:07.984 03576 info 'App'] [diagnosticManager,314] 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.
[2011-02-24 14:47:07.984 03576 info 'App'] [diagnosticManager,756] Retrieving task related diagnostics for server task with id = "task-1".
[2011-02-24 14:47:08.406 03576 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[2011-02-24 14:47:08.406 03576 info 'vmomi.soapStub[6]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[2011-02-24 14:47:08.468 03576 warning 'vmomi.soapStub[6]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[2011-02-24 14:47:08.468 02816 info 'App'] Suspended 1 scheduler items for job (job-4) -- void __thiscall Converter::Server::Job::JobProcessorImpl::SuspendJobAux(const class Converter::Server::Job::InternalJob &,class Converter::VdbConnection &) ("d:/build/ob/bora-292238/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:823")
[#3] [2011-02-24 15:00:11.484 02620 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[#3] [2011-02-24 15:00:11.484 02620 info 'vmomi.soapStub[7]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[#3] [2011-02-24 15:00:11.515 02620 warning 'vmomi.soapStub[7]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[#4] [2011-02-24 15:03:09.859 02996 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[#4] [2011-02-24 15:08:05.202 03212 info 'App'] Connecting to host 10.1.1.10 on port 443
[#4] [2011-02-24 15:08:05.218 03212 info 'App'] Authenticating user root
[2011-02-24 15:08:05.234 03580 warning 'App'] Closing Response processing in unexpected state: 3
[#4] [2011-02-24 15:08:05.249 03212 info 'App'] Connecting to host 10.1.1.11 on port 443
[#4] [2011-02-24 15:08:05.265 03212 info 'App'] Authenticating user root
[#4] [2011-02-24 15:08:05.281 03212 info 'App'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res
[#4] [2011-02-24 15:08:05.296 03212 info 'App'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool
[#4] [2011-02-24 15:08:05.296 03212 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[#4] [2011-02-24 15:08:05.296 03212 info 'App'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter
[#4] [2011-02-24 15:08:05.296 03212 info 'App'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm
[#4] [2011-02-24 15:08:05.296 03212 info 'App'] Reusing existing VIM connection to 10.1.1.11
[#4] [2011-02-24 15:08:05.312 03212 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[#4] [2011-02-24 15:08:05.312 03212 info 'vmomi.soapStub[10]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[#4] [2011-02-24 15:08:05.312 03212 warning 'vmomi.soapStub[10]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#4] [2011-02-24 15:08:05.312 03212 info 'vmomi.soapStub[9]'] Resetting stub adapter for server TCP:10.1.1.10:443 : Closed
[#4] [2011-02-24 15:08:05.312 03212 warning 'vmomi.soapStub[9]'] Canceling invocation: server=TCP:10.1.1.10:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#4] [2011-02-24 15:08:05.327 03212 info 'App'] Connecting to host 10.1.1.11 on port 443
[#4] [2011-02-24 15:08:05.343 03212 info 'App'] Authenticating user root
[#4] [2011-02-24 15:08:05.359 03212 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[#4] [2011-02-24 15:08:05.374 03212 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[#4] [2011-02-24 15:08:05.452 03212 info 'vmomi.soapStub[11]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[#4] [2011-02-24 15:08:05.452 03212 info 'App'] Created new scheduler item with id = "2", firstTimeToRun = "2011-02-24 15:08:05.312", 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-292238/bora/sysimage/ufad/server/scheduler/schedulerItemImpl.cpp:87")
[#4] [2011-02-24 15:08:05.452 03212 info 'App'] 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-292238/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:46")
[2011-02-24 15:08:05.499 01372 info 'App'] Scheduler scheduling item 2 to run at time = 2011-02-24 15:08:05.499. -- void __thiscall Converter::Server::Scheduler::SimpleScheduler::Run(void) ("d:/build/ob/bora-292238/bora/sysimage/ufad/server/scheduler/simpleScheduler.cpp:88")
[#4] [2011-02-24 15:08:05.562 02996 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[#4] [2011-02-24 15:08:05.562 02996 info 'vmomi.soapStub[8]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[2011-02-24 15:08:05.577 02848 info 'App'] Connecting to host 10.1.1.10 on port 443
[#4] [2011-02-24 15:08:05.593 02996 warning 'vmomi.soapStub[8]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[2011-02-24 15:08:05.609 02848 info 'App'] Authenticating user root
[2011-02-24 15:08:05.624 03212 warning 'App'] Closing Response processing in unexpected state: 3
[2011-02-24 15:08:05.640 02848 info 'App'] Connecting to host 10.1.1.11 on port 443
[2011-02-24 15:08:05.671 02848 info 'App'] Authenticating user root
[2011-02-24 15:08:05.687 02848 info 'App'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res
[2011-02-24 15:08:05.702 02848 info 'App'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool
[2011-02-24 15:08:05.702 02848 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[2011-02-24 15:08:05.702 02848 info 'App'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter
[2011-02-24 15:08:05.702 02848 info 'App'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm
[2011-02-24 15:08:05.702 02848 info 'App'] Reusing existing VIM connection to 10.1.1.11
[2011-02-24 15:08:05.734 02848 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[2011-02-24 15:08:05.734 02848 info 'vmomi.soapStub[13]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[2011-02-24 15:08:05.734 02848 warning 'vmomi.soapStub[13]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[2011-02-24 15:08:05.734 02848 info 'vmomi.soapStub[12]'] Resetting stub adapter for server TCP:10.1.1.10:443 : Closed
[2011-02-24 15:08:05.734 02848 warning 'vmomi.soapStub[12]'] Canceling invocation: server=TCP:10.1.1.10:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[2011-02-24 15:08:05.890 02848 info 'App'] Started task "task-2" for job="job-5", item ="2" -- void __thiscall Converter::Server::Job::JobProcessorImpl::StartProcessingJobs(void) ("d:/build/ob/bora-292238/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:351")
[2011-02-24 15:08:05.921 03424 info 'App'] [task,332] [task-2] -- BEGIN -- Convert
[2011-02-24 15:08:05.937 03424 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[2011-02-24 15:08:05.952 03424 info 'App'] [taskSpec,464] [task-2] [TaskMap] task-2:task-3
[2011-02-24 15:22:30.062 03424 error 'App'] [task,344] [LRO] Unexpected Exception: converter.fault.CloneFault
[2011-02-24 15:22:30.062 03424 info 'App'] [task,373] [task-2] -- ERROR -- Convert: converter.fault.CloneFault
(converter.fault.CloneFault) {
dynamicType = <unset>,
faultCause = (vmodl.MethodFault) null,
description = "Sysimgbase_Nfc_GetPutFile failed",
msg = "",
}
[2011-02-24 15:22:30.062 03424 info 'App'] [diagnosticManager,260] Retrieved taskInfo for "converter.task.Task:task-2" mapping it to "converter.task.Task:task-2".
[2011-02-24 15:22:30.062 03424 info 'App'] [diagnosticManager,300] The task with id = "task-2" was found to be a "recent" task.
[2011-02-24 15:22:30.062 03424 info 'App'] [diagnosticManager,314] 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.
[2011-02-24 15:22:30.062 03424 info 'App'] [diagnosticManager,756] Retrieving task related diagnostics for server task with id = "task-2".
[2011-02-24 15:22:30.249 03424 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[2011-02-24 15:22:30.265 03424 info 'vmomi.soapStub[14]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[2011-02-24 15:22:30.312 03424 warning 'vmomi.soapStub[14]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[2011-02-24 15:22:30.343 02816 info 'App'] Suspended 1 scheduler items for job (job-5) -- void __thiscall Converter::Server::Job::JobProcessorImpl::SuspendJobAux(const class Converter::Server::Job::InternalJob &,class Converter::VdbConnection &) ("d:/build/ob/bora-292238/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:823")
[#5] [2011-02-24 15:30:56.343 02996 info 'App'] [diagnosticManager,260] Retrieved taskInfo for "converter.task.Task:task-2" mapping it to "converter.task.Task:task-2".
[#5] [2011-02-24 15:30:56.343 02996 info 'App'] [diagnosticManager,285] The log bundle for task with id = "task-2" already exists Returning the serialized bundle info for it.
[2011-02-25 14:47:10.187 03172 error 'App'] Failed to send response to the client: The pipe is being closed.
[2011-02-25 15:22:31.624 03172 error 'App'] Failed to send response to the client: The pipe is being closed.
[#6] [2011-03-15 13:35:44.671 02756 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[#6] [2011-03-15 14:58:20.781 02620 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[#6] [2011-03-15 14:58:20.827 02620 info 'vmomi.soapStub[15]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[#6] [2011-03-15 14:58:20.859 02620 warning 'vmomi.soapStub[15]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[#7] [2011-03-15 14:58:23.249 02996 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[#7] [2011-03-15 15:00:35.609 02620 info 'App'] Connecting to host 10.1.1.10 on port 443
[#7] [2011-03-15 15:00:35.890 02620 info 'App'] Authenticating user root
[2011-03-15 15:00:35.906 03172 warning 'App'] Closing Response processing in unexpected state: 3
[#7] [2011-03-15 15:00:35.968 02620 info 'App'] Connecting to host 10.1.1.11 on port 443
[#7] [2011-03-15 15:00:35.999 02620 info 'App'] Authenticating user root
[#7] [2011-03-15 15:00:37.077 02620 info 'App'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res
[#7] [2011-03-15 15:00:37.109 02620 info 'App'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool
[#7] [2011-03-15 15:00:37.124 02620 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[#7] [2011-03-15 15:00:37.140 02620 info 'App'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter
[#7] [2011-03-15 15:00:37.140 02620 info 'App'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm
[#7] [2011-03-15 15:00:37.140 02620 info 'App'] Reusing existing VIM connection to 10.1.1.11
[#7] [2011-03-15 15:00:37.156 02620 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[#7] [2011-03-15 15:00:37.171 02620 info 'vmomi.soapStub[18]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[#7] [2011-03-15 15:00:37.171 02620 warning 'vmomi.soapStub[18]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#7] [2011-03-15 15:00:37.171 02620 info 'vmomi.soapStub[17]'] Resetting stub adapter for server TCP:10.1.1.10:443 : Closed
[#7] [2011-03-15 15:00:37.171 02620 warning 'vmomi.soapStub[17]'] Canceling invocation: server=TCP:10.1.1.10:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#7] [2011-03-15 15:00:37.202 02620 info 'App'] Connecting to host 10.1.1.11 on port 443
[#7] [2011-03-15 15:00:37.249 02620 info 'App'] Authenticating user root
[#7] [2011-03-15 15:00:37.249 02620 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[#7] [2011-03-15 15:00:37.265 02620 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[#7] [2011-03-15 15:00:38.624 02620 info 'vmomi.soapStub[19]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[#7] [2011-03-15 15:00:38.624 02620 warning 'vmomi.soapStub[19]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[#7] [2011-03-15 15:00:38.640 02620 info 'App'] Created new scheduler item with id = "3", firstTimeToRun = "2011-03-15 15:00:37.202", 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-292238/bora/sysimage/ufad/server/scheduler/schedulerItemImpl.cpp:87")
[#7] [2011-03-15 15:00:38.640 02620 info 'App'] scheduler item with id="3" 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-292238/bora/sysimage/ufad/server/scheduler/priorityQScheduler.cpp:46")
[2011-03-15 15:00:38.781 01372 info 'App'] Scheduler scheduling item 3 to run at time = 2011-03-15 15:00:38.781. -- void __thiscall Converter::Server::Scheduler::SimpleScheduler::Run(void) ("d:/build/ob/bora-292238/bora/sysimage/ufad/server/scheduler/simpleScheduler.cpp:88")
[2011-03-15 15:00:38.890 02848 info 'App'] Connecting to host 10.1.1.10 on port 443
[2011-03-15 15:00:38.952 02848 info 'App'] Authenticating user root
[2011-03-15 15:00:38.968 03580 warning 'App'] Closing Response processing in unexpected state: 3
[2011-03-15 15:00:38.984 02848 info 'App'] Connecting to host 10.1.1.11 on port 443
[2011-03-15 15:00:39.015 02848 info 'App'] Authenticating user root
[2011-03-15 15:00:39.046 02848 info 'App'] ComputeResource for vim.HostSystem:ha-host is vim.ComputeResource:ha-compute-res
[2011-03-15 15:00:39.046 02848 info 'App'] ResourcePool for vim.ComputeResource:ha-compute-res is vim.ResourcePool:ha-root-pool
[2011-03-15 15:00:39.046 02848 info 'App'] ComputeResource for vim.ResourcePool:ha-root-pool is vim.ComputeResource:ha-compute-res
[2011-03-15 15:00:39.046 02848 info 'App'] Datacenter for vim.ComputeResource:ha-compute-res is vim.Datacenter:ha-datacenter
[2011-03-15 15:00:39.046 02848 info 'App'] Root VM folder for vim.Datacenter:ha-datacenter is vim.Folder:ha-folder-vm
[2011-03-15 15:00:39.046 02848 info 'App'] Reusing existing VIM connection to 10.1.1.11
[2011-03-15 15:00:39.077 02848 info 'App'] Scheduled timer canceled, StopKeepAlive succeeds
[2011-03-15 15:00:39.077 02848 info 'vmomi.soapStub[21]'] Resetting stub adapter for server TCP:10.1.1.11:443 : Closed
[2011-03-15 15:00:39.077 02848 warning 'vmomi.soapStub[21]'] Canceling invocation: server=TCP:10.1.1.11:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[2011-03-15 15:00:39.093 02848 info 'vmomi.soapStub[20]'] Resetting stub adapter for server TCP:10.1.1.10:443 : Closed
[2011-03-15 15:00:39.093 02848 warning 'vmomi.soapStub[20]'] Canceling invocation: server=TCP:10.1.1.10:443, moref=vim.SessionManager:ha-sessionmgr, method=logout
[2011-03-15 15:00:39.781 03004 info 'App'] [task,332] [task-3] -- BEGIN -- Convert
[2011-03-15 15:00:39.827 03580 error 'App'] Failed to send response to the client: The pipe is being closed.
[2011-03-15 15:00:39.827 02996 error 'App'] Failed to send response to the client: The pipe is being closed.
[2011-03-15 15:00:39.827 03004 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[#7] [2011-03-15 15:00:39.937 03212 info 'App'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded
[#7] [2011-03-15 15:00:39.999 03212 info 'vmomi.soapStub[16]'] Resetting stub adapter for server PIPE:\\.\pipe\vmware-converter-worker-soap : Closed
[#7] [2011-03-15 15:00:40.031 03212 warning 'vmomi.soapStub[16]'] Canceling invocation: server=PIPE:\\.\pipe\vmware-converter-worker-soap, moref=converter.agent.AgentSessionManager:ConverterAgentSessionManager, method=logout
[2011-03-15 15:00:40.265 02848 info 'App'] Started task "task-3" for job="job-6", item ="3" -- void __thiscall Converter::Server::Job::JobProcessorImpl::StartProcessingJobs(void) ("d:/build/ob/bora-292238/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:351")
[2011-03-15 15:00:40.796 03004 info 'App'] [taskSpec,464] [task-3] [TaskMap] task-3:task-5
[#8] [2011-03-15 16:08:49.452 03704 info 'App'] [diagnosticManager,336] Generating Converter.Server log bundle with no task logs.
[#8] [2011-03-15 16:08:49.452 03704 info 'App'] [diagnosticManager,357] Generating Converter.Server log bundle.
[#8] [2011-03-15 16:08:49.984 03704 info 'App'] [diagnosticManager,372] Connecting to Converter.Worker to retrieve source related logs.
[#8] [2011-03-15 16:08:49.999 03704 error 'App'] Found dangling SSL error: [0] error:00000001:lib(0):func(0):reason(1)
[#8] [2011-03-15 16:08:50.015 03704 info 'App'] [diagnosticManager,405] Generating worker log bundle for source = NULL.
[#8] [2011-03-15 16:08:50.499 03704 info 'App'] [diagnosticManager,441] Retrieving worker log bundle for source. Storing at "C:\WINDOWS\Temp\vmware-temp\vmware-SYSTEM\worker-source-diagnostics-uvz.zip"
[#8] [2011-03-15 16:08:50.546 03704 info 'App'] [diagnosticManager,482] Adding worker log bundle from temp location "C:\WINDOWS\Temp\vmware-temp\vmware-SYSTEM\worker-source-diagnostics-uvz.zip" to server log bundle at "C:\Documents and Settings\All Users\Application Data\VMware\VMware vCenter Converter Standalone\logs\bundles\server-diag-20110315050849-pkqwop.zip".
[#8] [2011-03-15 16:08:51.484 02620 info 'App'] [diagnosticManager,707] Deleting log bundle with key = "server-diag-20110315050849-pkqwop.zip".
[2011-03-15 18:14:55.906 03004 error 'App'] [task,344] [LRO] Unexpected Exception: converter.fault.CloneFault
[2011-03-15 18:14:55.999 03004 info 'App'] [task,373] [task-3] -- ERROR -- Convert: converter.fault.CloneFault
(converter.fault.CloneFault) {
dynamicType = <unset>,
faultCause = (vmodl.MethodFault) null,
description = "Sysimgbase_Nfc_GetPutFile failed",
msg = "",
}
[2011-03-15 18:14:56.031 03004 info 'App'] [diagnosticManager,260] Retrieved taskInfo for "converter.task.Task:task-3" mapping it to "converter.task.Task:task-3".
[2011-03-15 18:14:56.031 03004 info 'App'] [diagnosticManager,300] The task with id = "task-3" was found to be a "recent" task.
[2011-03-15 18:14:56.031 03004 info 'App'] [diagnosticManager,314] No existing log bundle found for task with id = "task-3". The task is still "recent" so a log bundle will now be generated for it.
[2011-03-15 18:14:56.046 03004 info 'App'] [diagnosticManager,756] Retrieving task related diagnostics for server task with id = "task-3".
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment