建议检查下存储是否可以使用
2015-02-03 14:18 GMT+08:00 朱益萍 <874413...@qq.com>:
> 这是我的日志信息：
> 2015-02-03 14:16:12,524 ERROR [c.c.v.VmWorkJobHandlerProxy]
> (Work-Job-Executor-9
> :ctx-0f926fb1 job-10/job-20 ctx-2f4a0068) Invocation exception, caused by:
> com.c
> loud.exception.AgentUnavailableException: Resource [Host:1] is unreachable:
> Host
> 1: Unable to start instance due to Unable to acquire lock on
> VMTemplateStorageP
> ool: 4
> 2015-02-03 14:16:12,524 INFO [c.c.v.VmWorkJobHandlerProxy]
> (Work-Job-Executor-9
> :ctx-0f926fb1 job-10/job-20 ctx-2f4a0068) Rethrow exception
> com.cloud.exception.
> AgentUnavailableException: Resource [Host:1] is unreachable: Host 1: Unable
> to s
> tart instance due to Unable to acquire lock on VMTemplateStoragePool: 4
> 2015-02-03 14:16:12,524 DEBUG [c.c.v.VmWorkJobDispatcher]
> (Work-Job-Executor-9:c
> tx-0f926fb1 job-10/job-20) Done with run of VM work job:
> com.cloud.vm.VmWorkStar
> t for VM 153, job origin: 10
> 2015-02-03 14:16:12,524 ERROR [c.c.v.VmWorkJobDispatcher]
> (Work-Job-Executor-9:c
> tx-0f926fb1 job-10/job-20) Unable to complete AsyncJobVO {id:20, userId: 1,
> acco
> untId: 1, instanceType: null, instanceId: null, cmd:
> com.cloud.vm.VmWorkStart, c
> mdInfo:
> rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZh
> dm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAlj
> bHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxq
> YXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVw
> b2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNl
> cnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElk
> SgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAABAAAAAAAAAAEAAAAAAAAA
> mXQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAHBwcHBwcHBwcHA, cmdVersion:
> 0,
> status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid:
> 20
> 9900612933211, completeMsid: null, lastUpdated: null, lastPolled: null,
> created:
> Tue Feb 03 14:16:06 CST 2015}, job origin:10
> com.cloud.exception.AgentUnavailableException: Resource [Host:1] is
> unreachable:
> Host 1: Unable to start instance due to Unable to acquire lock on
> VMTemplateSto
> ragePool: 4
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachin
> eManagerImpl.java:1106)
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachin
> eManagerImpl.java:5195)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.
> java:57)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces
> sorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at
> com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerPr
> oxy.java:107)
> at
> com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMachine
> ManagerImpl.java:5340)
> at
> com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runIn
> Context(AsyncJobManagerImpl.java:503)
> at
> org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(Ma
> nagedContextRunnable.java:49)
> at
> org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.ca
> ll(DefaultManagedContext.java:56)
> at
> org.apache.cloudstack.managed.context.impl.DefaultManagedContext.call
> WithContext(DefaultManagedContext.java:103)
> at
> org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runW
> ithContext(DefaultManagedContext.java:53)
> at
> org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Mana
> gedContextRunnable.java:46)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(A
> syncJobManagerImpl.java:460)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47
> 1)
> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.
> java:1145)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor
> .java:615)
> at java.lang.Thread.run(Thread.java:745)
> Caused by: com.cloud.utils.exception.CloudRuntimeException: Unable to acquire
> lo
> ck on VMTemplateStoragePool: 4
> at
> org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImag
> eAsync(VolumeServiceImpl.java:486)
> at
> org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFr
> omTemplateAsync(VolumeServiceImpl.java:745)
> at
> org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.recreat
> eVolume(VolumeOrchestrator.java:1193)
> at
> org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.prepare
> (VolumeOrchestrator.java:1257)
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachin
> eManagerImpl.java:988)
> ... 20 more
> 2015-02-03 14:16:12,527 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (Work-Job-Execut
> or-9:ctx-0f926fb1 job-10/job-20) Complete async job-20, jobStatus: FAILED,
> resul
> tCode: 0, result:
> rO0ABXNyABpqYXZhLmxhbmcuUnVudGltZUV4Y2VwdGlvbp5fBkcKNIPlAgAAeH
> IAE2phdmEubGFuZy5FeGNlcHRpb27Q_R8-GjscxAIAAHhyABNqYXZhLmxhbmcuVGhyb3dhYmxl1cY1Jz
> l3uMsDAARMAAVjYXVzZXQAFUxqYXZhL2xhbmcvVGhyb3dhYmxlO0wADWRldGFpbE1lc3NhZ2V0ABJMam
> F2YS9sYW5nL1N0cmluZztbAApzdGFja1RyYWNldAAeW0xqYXZhL2xhbmcvU3RhY2tUcmFjZUVsZW1lbn
> Q7TAAUc3VwcHJlc3NlZEV4Y2VwdGlvbnN0ABBMamF2YS91dGlsL0xpc3Q7eHBxAH4AB3QAmEpvYiBmYW
> lsZWQgZHVlIHRvIGV4Y2VwdGlvbiBSZXNvdXJjZSBbSG9zdDoxXSBpcyB1bnJlYWNoYWJsZTogSG9zdC
> AxOiBVbmFibGUgdG8gc3RhcnQgaW5zdGFuY2UgZHVlIHRvIFVuYWJsZSB0byBhY3F1aXJlIGxvY2sgb2
> 4gVk1UZW1wbGF0ZVN0b3JhZ2VQb29sOiA0dXIAHltMamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50Ow
> JGKjw8_SI5AgAAeHAAAAANc3IAG2phdmEubGFuZy5TdGFja1RyYWNlRWxlbWVudGEJxZomNt2FAgAESQ
> AKbGluZU51bWJlckwADmRlY2xhcmluZ0NsYXNzcQB-AARMAAhmaWxlTmFtZXEAfgAETAAKbWV0aG9kTm
> FtZXEAfgAEeHAAAABydAAgY29tLmNsb3VkLnZtLlZtV29ya0pvYkRpc3BhdGNoZXJ0ABhWbVdvcmtKb2
> JEaXNwYXRjaGVyLmphdmF0AAZydW5Kb2JzcQB-AAsAAAH3dAA_b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm
> ZyYW1ld29yay5qb2JzLmltcGwuQXN5bmNKb2JNYW5hZ2VySW1wbCQ1dAAYQXN5bmNKb2JNYW5hZ2VySW
> 1wbC5qYXZhdAAMcnVuSW5Db250ZXh0c3EAfgALAAAAMXQAPm9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW
> 5hZ2VkLmNvbnRleHQuTWFuYWdlZENvbnRleHRSdW5uYWJsZSQxdAAbTWFuYWdlZENvbnRleHRSdW5uYW
> JsZS5qYXZhdAADcnVuc3EAfgALAAAAOHQAQm9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW5hZ2VkLmNvbn
> RleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHQkMXQAGkRlZmF1bHRNYW5hZ2VkQ29udGV4dC5qYX
> ZhdAAEY2FsbHNxAH4ACwAAAGd0AEBvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0Lm
> ltcGwuRGVmYXVsdE1hbmFnZWRDb250ZXh0cQB-ABp0AA9jYWxsV2l0aENvbnRleHRzcQB-AAsAAAA1cQ
> B-AB1xAH4AGnQADnJ1bldpdGhDb250ZXh0c3EAfgALAAAALnQAPG9yZy5hcGFjaGUuY2xvdWRzdGFjay
> 5tYW5hZ2VkLmNvbnRleHQuTWFuYWdlZENvbnRleHRSdW5uYWJsZXEAfgAWcQB-ABdzcQB-AAsAAAHMcQ
> B-ABFxAH4AEnEAfgAXc3EAfgALAAAB13QALmphdmEudXRpbC5jb25jdXJyZW50LkV4ZWN1dG9ycyRSdW
> 5uYWJsZUFkYXB0ZXJ0AA5FeGVjdXRvcnMuamF2YXEAfgAbc3EAfgALAAABBnQAH2phdmEudXRpbC5jb2
> 5jdXJyZW50LkZ1dHVyZVRhc2t0AA9GdXR1cmVUYXNrLmphdmFxAH4AF3NxAH4ACwAABHl0ACdqYXZhLn
> V0aWwuY29uY3VycmVudC5UaHJlYWRQb29sRXhlY3V0b3J0ABdUaHJlYWRQb29sRXhlY3V0b3IuamF2YX
> QACXJ1bldvcmtlcnNxAH4ACwAAAmd0AC5qYXZhLnV0aWwuY29uY3VycmVudC5UaHJlYWRQb29sRXhlY3
> V0b3IkV29ya2VycQB-ACxxAH4AF3NxAH4ACwAAAul0ABBqYXZhLmxhbmcuVGhyZWFkdAALVGhyZWFkLm
> phdmFxAH4AF3NyACZqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlTGlzdPwPJTG17I4QAg
> ABTAAEbGlzdHEAfgAGeHIALGphdmEudXRpbC5Db2xsZWN0aW9ucyRVbm1vZGlmaWFibGVDb2xsZWN0aW
> 9uGUIAgMte9x4CAAFMAAFjdAAWTGphdmEvdXRpbC9Db2xsZWN0aW9uO3hwc3IAE2phdmEudXRpbC5Bcn
> JheUxpc3R4gdIdmcdhnQMAAUkABHNpemV4cAAAAAB3BAAAAAB4cQB-ADh4
> 2015-02-03 14:16:12,542 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (Work-Job-Execut
> or-9:ctx-0f926fb1 job-10/job-20) Done executing com.cloud.vm.VmWorkStart for
> job
> -20
> 2015-02-03 14:16:12,550 WARN [o.a.c.s.SecondaryStorageManagerImpl]
> (secstorage-
> 1:ctx-21a3ff7a) Exception while trying to start secondary storage vm
> java.lang.RuntimeException: Job failed due to exception Resource [Host:1] is
> unr
> eachable: Host 1: Unable to start instance due to Unable to acquire lock on
> VMTe
> mplateStoragePool: 4
> at
> com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:114)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runIn
> Context(AsyncJobManagerImpl.java:503)
> at
> org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(Ma
> nagedContextRunnable.java:49)
> at
> org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.ca
> ll(DefaultManagedContext.java:56)
> at
> org.apache.cloudstack.managed.context.impl.DefaultManagedContext.call
> WithContext(DefaultManagedContext.java:103)
> at
> org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runW
> ithContext(DefaultManagedContext.java:53)
> at
> org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Mana
> gedContextRunnable.java:46)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(A
> syncJobManagerImpl.java:460)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47
> 1)
> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.
> java:1145)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor
> .java:615)
> at java.lang.Thread.run(Thread.java:745)
> 2015-02-03 14:16:12,551 INFO [o.a.c.s.SecondaryStorageManagerImpl]
> (secstorage-
> 1:ctx-21a3ff7a) Unable to start secondary storage vm for standby capacity,
> secSt
> orageVm vm Id : 153, will recycle it and start a new one
> 2015-02-03 14:16:12,552 INFO [o.a.c.s.PremiumSecondaryStorageManagerImpl]
> (secs
> torage-1:ctx-21a3ff7a) Primary secondary storage is not even started, wait
> until
> next turn
> 2015-02-03 14:16:12,574 DEBUG [o.a.c.f.j.i.SyncQueueManagerImpl]
> (Work-Job-Execu
> tor-9:ctx-0f926fb1 job-10/job-20) Sync queue (755) is currently empty
> 2015-02-03 14:16:12,575 INFO [o.a.c.f.j.i.AsyncJobMonitor]
> (Work-Job-Executor-9
> :ctx-0f926fb1 job-10/job-20) Remove job-20 from job monitoring
> 2015-02-03 14:16:22,527 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl]
> (Rout
> erStatusMonitor-1:ctx-6164a5ca) Found 0 routers to update status.
> 2015-02-03 14:16:22,528 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl]
> (Rout
> erStatusMonitor-1:ctx-6164a5ca) Found 0 networks to update RvR status.
--
白清杰 (Born Bai)
Mail: linux...@gmail.com
