[
https://issues.apache.org/jira/browse/CLOUDSTACK-2793?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Sheng Yang reassigned CLOUDSTACK-2793:
--------------------------------------
Assignee: (was: Sheng Yang)
Error in the log: "migrate volume failed:java.lang.NullPointerException"
Don't know why assign to me, it's not related to either network or pvlan.
2013-06-03 17:16:28,706 DEBUG [agent.manager.AgentAttache]
(AgentManager-Handler-6:null) Seq 4-1184837305: No more commands found
2013-06-03 17:16:28,706 DEBUG [agent.transport.Request]
(Job-Executor-82:job-80) Seq 4-1184837305: Received: { Ans: , MgmtId:
6655051826959, via: 4, Ver: v1, Flags: 110, { Answer, Answer } }
2013-06-03 17:16:28,706 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-82:job-80) Asking Ovs to prepare for
Nic[26-15-null-10.223.161.153]
2013-06-03 17:16:28,706 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-82:job-80) Asking SecurityGroupProvider to prepare for
Nic[26-15-null-10.223.161.153]
2013-06-03 17:16:28,710 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-82:job-80) Asking VpcVirtualRouter to prepare for
Nic[26-15-null-10.223.161.153]
2013-06-03 17:16:28,714 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-82:job-80) Asking InternalLbVm to prepare for
Nic[26-15-null-10.223.161.153]
2013-06-03 17:16:28,719 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-82:job-80) Service SecurityGroup is not supported in the network
id=205
2013-06-03 17:16:28,721 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-82:job-80) Checking if we need to prepare 1 volumes for
VM[User|z1admin1612-153V91]
2013-06-03 17:16:28,722 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-82:job-80) Mismatch in storage pool Pool[1|NetworkFilesystem]
assigned by deploymentPlanner and the one associated with volume
Vol[18|vm=15|ROOT]
2013-06-03 17:16:28,722 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-82:job-80) Shared volume Vol[18|vm=15|ROOT] will be migrated on
storage pool Pool[1|NetworkFilesystem] assigned by deploymentPlanner
2013-06-03 17:16:28,726 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-82:job-80) migrate volume failed:java.lang.NullPointerException
2013-06-03 17:16:28,735 DEBUG [agent.transport.Request]
(Job-Executor-82:job-80) Seq 3-832713513: Sending { Cmd , MgmtId:
6655051826959, via: 3, Ver: v1, Flags: 100111,
[{"StartCommand":{"vm":{"id":15,"name":"i-2-15-VM","type":"User","cpus":1
,"minSpeed":100,"maxSpeed":100,"minRam":536870912,"maxRam":536870912,"arch":"x86_64","os":"CentOS
5.5
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":true,"limitCpuUse":false,"vncPassword":"10313b492ad2a278","params":{"Message.Reserve
dCapacityFreed.Flag":"false"},"uuid":"658cd671-12ee-4589-bb1c-62f625511cb2","disks":[{"id":18,"name":"ROOT-15","mountPoint":"/export/home/angie/primary/kvmpvlan2","path":"32d630f7-6e92-4d45-9d4c-42b655e8a2f0","size":8589934592,"type":"ROOT","s
toragePoolType":"NetworkFilesystem","storagePoolUuid":"a8e01df5-f763-3bd9-8f7c-5f40751e8397","deviceId":0},{"id":15,"name":"CentOS
5.5(64-bit) no GUI
(KVM)","size":0,"type":"ISO","storagePoolType":"ISO","deviceId":3}],"nics":[{"deviceId":0,"ne
tworkRateMbps":200,"defaultNic":true,"uuid":"9da7e8a3-ebe8-49fa-b078-3e609c308b12","ip":"10.223.161.153","netmask":"255.255.255.192","gateway":"10.223.161.129","mac":"06:d4:30:00:00:14","dns1":"10.223.110.254","broadcastType":"Pvlan","type":"G
uest","broadcastUri":"pvlan://1612-i997","isolationUri":"vlan://1612","isSecurityGroupEnabled":false}]},"hostIp":"10.223.81.61","wait":0}}]
}
2013-06-03 17:16:28,845 DEBUG [agent.transport.Request]
(AgentManager-Handler-15:null) Seq 3-832713513: Processing: { Ans: , MgmtId:
6655051826959, via: 3, Ver: v1, Flags: 110,
[{"Answer":{"result":false,"details":"com.cloud.utils.exception.C
loudRuntimeException: org.libvirt.LibvirtException: Storage volume not found:
no storage vol with matching name '32d630f7-6e92-4d45-9d4c-42b655e8a2f0'\n\tat
com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getVolume(LibvirtStorageAdaptor
.java:106)\n\tat
com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getPhysicalDisk(LibvirtStorageAdaptor.java:401)\n\tat
com.cloud.hypervisor.kvm.storage.LibvirtStoragePool.getPhysicalDisk(LibvirtStoragePool.java:123)\n\tat
com.cloud.hype
rvisor.kvm.resource.LibvirtComputingResource.createVbd(LibvirtComputingResource.java:3409)\n\tat
com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:3300)\n\tat
com.cloud.hypervisor.kvm.resource.Lib
virtComputingResource.executeRequest(LibvirtComputingResource.java:1187)\n\tat
com.cloud.agent.Agent.processRequest(Agent.java:525)\n\tat
com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:852)\n\tat
com.cloud.utils.nio.Task.run(Task.
java:83)\n\tat
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)\n\tat
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)\n\tat
java.lang.Thread.run(Thread.java:679)\n","wait":0}}] }
2013-06-03 17:16:28,845 DEBUG [agent.manager.AgentAttache]
(AgentManager-Handler-15:null) Seq 3-832713513: No more commands found
2013-06-03 17:16:28,845 DEBUG [agent.transport.Request]
(Job-Executor-82:job-80) Seq 3-832713513: Received: { Ans: , MgmtId:
6655051826959, via: 3, Ver: v1, Flags: 110, { Answer } }
2013-06-03 17:16:28,848 ERROR [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-82:job-80) Failed to start instance VM[User|z1admin1612-153V91]
com.cloud.utils.exception.CloudRuntimeException: Unable to get answer that is
of class com.cloud.agent.api.StartAnswer
at com.cloud.agent.manager.Commands.getAnswer(Commands.java:80)
at
com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:873)
at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:550)
at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:243)
at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3243)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:1786)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(StartVMCmd.java:120)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
at
com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:437)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
> PVLAN - Ubuntu 13.04 - Unable to start Stopped VM
> -------------------------------------------------
>
> Key: CLOUDSTACK-2793
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-2793
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Management Server
> Affects Versions: 4.2.0
> Environment: MS ubuntu 13.04 KVM ACS 4.2 PVLAN
> host ubuntu 13.04 KVM ACS 4.2 PVLAN
> Reporter: angeline shen
> Priority: Critical
> Fix For: 4.2.0
>
> Attachments: management-server.log.gz, management-server.log.gz,
> Screenshot-CloudStack - Mozilla Firefox-5.png
>
>
> 1. advance zone. Add 2 hosts.
> create PVLAN shared network with VLAN ID, private VLAN ID, IPV4 gateway,
> netmask, start IP, end IP
> gateway primary vlan secondary vlan IP
> network1: 10.223.161.65 1611 998
> 10.223.161.110- 10.223.161.113
> network2: 10.223.161.129 1612 997
> 10.223.161.150-10.223.161.153
> 2. Deploy VMs in both PVLAN shared network
> 3. stop VMs in both PVLAN shared networks
> 4. Start stopped VMs in network2 failed . Start stopped VMs in network1
> succeeded.
> 2013-05-31 20:01:36,070 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-57:job-55) Successfully released network resources for the vm
> VM[User|z1admin1612-153V86]
> 2013-05-31 20:01:36,070 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-57:job-55) Successfully cleanued up resources for the vm
> VM[User|z1admin1612-153V86] in Starting state
> 2013-05-31 20:01:36,077 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-57:job-55) VM state transitted from :Starting to Stopped with
> event: OperationFailedvm's original host id: 4 new host id: null host id
> before s
> tate transition: 4
> 2013-05-31 20:01:36,084 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-57:job-55) Hosts's actual total CPU: 9040 and CPU after
> applying overprovisioning: 9040
> 2013-05-31 20:01:36,084 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-57:job-55) Hosts's actual total RAM: 16816881664 and RAM after
> applying overprovisioning: 16816881664
> 2013-05-31 20:01:36,084 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-57:job-55) release cpu from host: 4, old used: 1200,reserved:
> 0, actual total: 9040, total with overprovisioning: 9040; new used:
> 1100,reserved
> :0; movedfromreserved: false,moveToReserveredfalse
> 2013-05-31 20:01:36,084 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-57:job-55) release mem from host: 4, old used:
> 2281701376,reserved: 0, total: 16816881664; new used: 1744830464,reserved:0;
> movedfromreserved: false,moveToReserveredfalse
> 2013-05-31 20:01:36,101 ERROR [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-57:job-55) Unexpected exception while executing
> org.apache.cloudstack.api.command.user.vm.StartVMCmd
> com.cloud.exception.AgentUnavailableException: Resource [Host:4] is
> unreachable: Host 4: Unable to start instance due to Unable to get answer
> that is of class com.cloud.agent.api.StartAnswer
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:937)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:550)
> at
> org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:243)
> at
> org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3243)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:1786)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
> at
> org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(StartVMCmd.java:120)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
> at
> com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:437)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
> at java.util.concurrent.FutureTask.run(FutureTask.java:166)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> Caused by: com.cloud.utils.exception.CloudRuntimeException: Unable to get
> answer that is of class com.cloud.agent.api.StartAnswer
> at com.cloud.agent.manager.Commands.getAnswer(Commands.java:80)
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:873)
> ... 19 more
> 2013-05-31 20:01:36,102 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-57:job-55) Complete async job-55, jobStatus: 2, resultCode:
> 530, result: Error Code: 530 Error text: Resource [Host:4] is unreachable:
> Host 4: Unable to start instance due to Unable to get answer that is of class
> com.cloud.agent.api.StartAnswer
> 2013-05-31 20:01:36,735 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) HostStatsCollector is running...
> 2013-05-31 20:01:36,953 DEBUG [agent.transport.Request]
> (AgentManager-Handler-10:null) Seq 3-832705172: Processing: { Ans: , MgmtId:
> 6655051826959, via: 3, Ver: v1, Flags: 10,
> [{"Answer":{"result":false,"details":"empty String","wait":0}}] }
> 2013-05-31 20:01:36,953 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-832705172: Received: { Ans: , MgmtId:
> 6655051826959, via: 3, Ver: v1, Flags: 10, { Answer } }
> .............
> 2013-05-31 20:00:51,587 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-56:job-54) Successfully released network resources for the vm
> VM[User|z1admin1612-153V91]
> 2013-05-31 20:00:51,587 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-56:job-54) Successfully cleanued up resources for the vm
> VM[User|z1admin1612-153V91] in Starting state
> 2013-05-31 20:00:51,998 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null)
> ===START=== 10.216.133.70 -- GET
> command=queryAsyncJobResult&jobId=c17ba6ba-5f84-4514-8d1c-0f0a9f6c840a&response=json&sessionkey=dt1MF5Ukqwpq4Hb%
> 2B3O%2F1spFKU5g%3D&_=1370055896240
> 2013-05-31 20:00:52,935 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-56:job-54) VM state transitted from :Starting to Stopped with
> event: OperationFailedvm's original host id: 3 new host id: null host id
> before s
> tate transition: 3
> 2013-05-31 20:00:52,943 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null)
> ===END=== 10.216.133.70 -- GET
> command=queryAsyncJobResult&jobId=c17ba6ba-5f84-4514-8d1c-0f0a9f6c840a&response=json&sessionkey=dt1MF5Ukqwpq4Hb%2B
> 3O%2F1spFKU5g%3D&_=1370055896240
> 2013-05-31 20:00:52,944 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-56:job-54) Hosts's actual total CPU: 9044 and CPU after
> applying overprovisioning: 9044
> 2013-05-31 20:00:52,944 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-56:job-54) Hosts's actual total RAM: 4066250752 and RAM after
> applying overprovisioning: 4066250752
> 2013-05-31 20:00:52,944 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-56:job-54) release cpu from host: 3, old used: 1200,reserved:
> 0, actual total: 9044, total with overprovisioning: 9044; new used:
> 1100,reserved
> :0; movedfromreserved: false,moveToReserveredfalse
> 2013-05-31 20:00:52,944 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-56:job-54) release mem from host: 3, old used:
> 1476395008,reserved: 0, total: 4066250752; new used: 939524096,reserved:0;
> movedfromreserved: fa
> lse,moveToReserveredfalse
> 2013-05-31 20:00:52,959 ERROR [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-56:job-54) Unexpected exception while executing
> org.apache.cloudstack.api.command.user.vm.StartVMCmd
> com.cloud.exception.AgentUnavailableException: Resource [Host:3] is
> unreachable: Host 3: Unable to start instance due to Unable to get answer
> that is of class com.cloud.agent.api.StartAnswer
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:937)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:550)
> at
> org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:243)
> at
> org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3243)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:1786)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
> at
> org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(StartVMCmd.java:120)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
> at
> com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:437)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
> at java.util.concurrent.FutureTask.run(FutureTask.java:166)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> Caused by: com.cloud.utils.exception.CloudRuntimeException: Unable to get
> answer that is of class com.cloud.agent.api.StartAnswer
> at com.cloud.agent.manager.Commands.getAnswer(Commands.java:80)
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:873)
> ... 19 more
> 2013-05-31 20:00:52,960 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-56:job-54) Complete async job-54, jobStatus: 2, resultCode:
> 530, result: Error Code: 530 Error text: Resource [Host:3] is unreachable:
> Host 3: Una
> ble to start instance due to Unable to get answer that is of class
> com.cloud.agent.api.StartAnswer
> 2013-05-31 20:00:53,554 DEBUG [storage.secondary.SecondaryStorageManagerImpl]
> (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
