2013-07-05T16:02:11.950+02:00 [02940 info 'Default'] Connecting to host esxserver1 on port 443 2013-07-05T16:02:11.950+02:00 [02940 info 'Default'] localVersion is: vim.version.version8 2013-07-05T16:02:12.057+02:00 [02940 info 'Default'] Remote version is: vim.version.version5 2013-07-05T16:02:12.090+02:00 [02940 info 'Default'] Authenticating user root 2013-07-05T16:02:12.097+02:00 [02940 error 'Default'] [VimResultFilter] Property vim.UserSession,ipAddress not found 2013-07-05T16:02:12.097+02:00 [02940 error 'Default'] [VimResultFilter] Property vim.UserSession,userAgent not found 2013-07-05T16:02:12.097+02:00 [02940 error 'Default'] [VimResultFilter] Property vim.UserSession,callCount not found 2013-07-05T16:02:12.117+02:00 [02940 info 'Default'] Connecting to host esxvcenter on port 443 2013-07-05T16:02:12.117+02:00 [02956 warning 'Default'] Closing Response processing in unexpected state: 3 2013-07-05T16:02:12.117+02:00 [02940 info 'Default'] localVersion is: vim.version.version8 2013-07-05T16:02:12.142+02:00 [02940 info 'Default'] Remote version is: vim.version.version8 2013-07-05T16:02:12.148+02:00 [02940 info 'Default'] Authenticating user Administrator 2013-07-05T16:02:12.252+02:00 [02940 info 'Default'] ComputeResource for vim.HostSystem:host-9 is vim.ClusterComputeResource:domain-c23 2013-07-05T16:02:12.255+02:00 [02940 info 'Default'] ResourcePool for vim.ComputeResource:domain-c23 is vim.ResourcePool:resgroup-24 2013-07-05T16:02:12.258+02:00 [02940 info 'Default'] Reusing existing VIM connection to esxvcenter 2013-07-05T16:02:12.266+02:00 [02940 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds 2013-07-05T16:02:12.272+02:00 [02940 info 'vmomi.soapStub[2]'] Resetting stub adapter for server <cs p:032592f8, TCP:esxvcenter:443> : Closed 2013-07-05T16:02:12.276+02:00 [02940 info 'vmomi.soapStub[1]'] Resetting stub adapter for server <cs p:03259078, TCP:esxserver1:443> : Closed 2013-07-05T16:02:12.277+02:00 [02940 info 'Default'] Connecting to host esxvcenter on port 443 2013-07-05T16:02:12.277+02:00 [02940 info 'Default'] localVersion is: vim.version.version8 2013-07-05T16:02:12.298+02:00 [02940 info 'Default'] Remote version is: vim.version.version8 2013-07-05T16:02:12.304+02:00 [02940 info 'Default'] Authenticating user Administrator 2013-07-05T16:02:12.401+02:00 [02940 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds 2013-07-05T16:02:12.437+02:00 [02940 info 'Default'] ComputeResource for vim.ResourcePool:resgroup-24 is vim.ClusterComputeResource:domain-c23 2013-07-05T16:02:12.520+02:00 [02940 info 'vmomi.soapStub[3]'] Resetting stub adapter for server <cs p:03259078, TCP:esxvcenter:443> : Closed 2013-07-05T16:02:12.520+02:00 [02940 info 'Default'] Created new scheduler item with id = "1", firstTimeToRun = "2013-07-05 16:02:12.277", 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-07-05T16:02:12.520+02:00 [02940 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-07-05T16:02:12.546+02:00 [03756 info 'Default'] Scheduler scheduling item 1 to run at time = 2013-07-05 16:02:12.546. -- void __thiscall Converter::Server::Scheduler::SimpleScheduler::Run(void) ("d:/build/ob/bora-1087880/bora/sysimage/ufad/server/scheduler/simpleScheduler.cpp:109") 2013-07-05T16:02:12.640+02:00 [03764 info 'Default'] Connecting to host esxserver1 on port 443 2013-07-05T16:02:12.641+02:00 [03764 info 'Default'] localVersion is: vim.version.version8 2013-07-05T16:02:12.660+02:00 [03764 info 'Default'] Remote version is: vim.version.version5 2013-07-05T16:02:12.665+02:00 [03764 info 'Default'] Authenticating user root 2013-07-05T16:02:12.673+02:00 [03764 error 'Default'] [VimResultFilter] Property vim.UserSession,ipAddress not found 2013-07-05T16:02:12.673+02:00 [03764 error 'Default'] [VimResultFilter] Property vim.UserSession,userAgent not found 2013-07-05T16:02:12.673+02:00 [03764 error 'Default'] [VimResultFilter] Property vim.UserSession,callCount not found 2013-07-05T16:02:12.683+02:00 [02956 warning 'Default'] Closing Response processing in unexpected state: 3 2013-07-05T16:02:12.693+02:00 [03764 info 'Default'] Connecting to host esxvcenter on port 443 2013-07-05T16:02:12.693+02:00 [03764 info 'Default'] localVersion is: vim.version.version8 2013-07-05T16:02:12.717+02:00 [03764 info 'Default'] Remote version is: vim.version.version8 2013-07-05T16:02:12.722+02:00 [03764 info 'Default'] Authenticating user Administrator 2013-07-05T16:02:12.818+02:00 [03764 info 'Default'] ComputeResource for vim.HostSystem:host-26 is vim.ClusterComputeResource:domain-c23 2013-07-05T16:02:12.822+02:00 [03764 info 'Default'] ResourcePool for vim.ComputeResource:domain-c23 is vim.ResourcePool:resgroup-24 2013-07-05T16:02:12.826+02:00 [03764 info 'Default'] Reusing existing VIM connection to esxvcenter 2013-07-05T16:02:12.844+02:00 [03764 info 'Default'] Scheduled timer canceled, StopKeepAlive succeeds 2013-07-05T16:02:12.850+02:00 [03764 info 'vmomi.soapStub[5]'] Resetting stub adapter for server <cs p:00691858, TCP:esxvcenter:443> : Closed 2013-07-05T16:02:12.855+02:00 [03764 info 'vmomi.soapStub[4]'] Resetting stub adapter for server <cs p:00691758, TCP:esxserver1:443> : Closed 2013-07-05T16:02:12.884+02:00 [01240 info 'ThreadPool'] Thread enlisted 2013-07-05T16:02:12.977+02:00 [02956 error 'Default'] Failed to send response to the client: class Vmacore::SystemException(Die Pipe wird gerade geschlossen) 2013-07-05T16:02:13.019+02:00 [03764 info 'Default'] Started task "task-4" for job="job-5", 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-07-05T16:02:13.051+02:00 [01240 info 'Default'] [task,338] [task-4] -- BEGIN -- Convert 2013-07-05T16:02:13.091+02:00 [01240 info 'Default'] [taskSpec,467] [task-4] [TaskMap] task-4:task-1 2013-07-05T16:02:13.111+02:00 [02940 info 'Default'] ConverterConnection: KeepAlive timer canceled, StopKeepAlive succeeded 2013-07-05T16:02:13.132+02:00 [02940 info 'vmomi.soapStub[0]'] Resetting stub adapter for server <cs p:03272018, PIPE:\\.\pipe\vmware-converter-worker-soap> : Closed 2013-07-05T16:21:49.811+02:00 [02960 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x03168404, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out) 2013-07-05T16:22:14.812+02:00 [02956 error 'Ufa.HTTPService'] Failed to read request; stream: <io_obj p:0x031683a4, h:-1, <pipe '\\.\pipe\vmware-converter-server-soap'>, <pipe '\\.\pipe\vmware-converter-server-soap'>>, error: class Vmacore::TimeoutException(Operation timed out) 2013-07-05T18:02:04.810+02:00 [01240 error 'Default'] [task,350] [LRO] Unexpected Exception: converter.fault.CloneFault 2013-07-05T18:02:04.850+02:00 [01240 info 'Default'] [task,379] [task-4] -- ERROR -- Convert: converter.fault.CloneFault --> (converter.fault.CloneFault) { --> dynamicType = <unset>, --> faultCause = (vmodl.MethodFault) null, --> description = "The operation experienced a network error", --> msg = "", --> } 2013-07-05T18:02:04.850+02:00 [01240 info 'Default'] [diagnosticManager,269] Retrieved taskInfo for "converter.task.Task:task-4" mapping it to "converter.task.Task:task-4". 2013-07-05T18:02:04.850+02:00 [01240 info 'Default'] [diagnosticManager,309] The task with id = "task-4" was found to be a "recent" task. 2013-07-05T18:02:04.850+02:00 [01240 info 'Default'] [diagnosticManager,323] No existing log bundle found for task with id = "task-4". The task is still "recent" so a log bundle will now be generated for it. 2013-07-05T18:02:04.882+02:00 [01240 info 'Default'] [diagnosticManager,792] Retrieving task related diagnostics for server task with id = "task-4". 2013-07-05T18:02:04.931+02:00 [03760 info 'Default'] Run 0 of job "job-5" 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-07-05T18:02:05.053+02:00 [03760 info 'Default'] 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-1087880/bora/sysimage/lib/converter/server/job/jobProcessorImpl.cpp:830") |