Chandan Purushothama created CLOUDSTACK-1944:
------------------------------------------------
Summary: VMSnapshot: Destruction of a VM with VMSnapshots throws a
NullPointerException during VM Expunge
Key: CLOUDSTACK-1944
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-1944
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
Reporter: Chandan Purushothama
Fix For: 4.2.0
================
Steps to Reproduce:
================
1. Create multiple VM snapshots for the same VM - say 3 snapshots - S1,S2,S3.
2. Destroy the VM.
===========
Observations:
===========
*Management Server Log*
[root@asfmgmt ~]# zgrep -i "job-81"
/var/log/cloudstack/management/management-server.log.2013-04-03.gz
2013-04-03 17:54:07,786 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-9:null) submit async job-81, details: AsyncJobVO {id:81, userId:
4, accountId: 4, sessionKey: null, instanceType: null, instanceId: 12, cmd:
org.apache.cloudstack.api.command.user.vm.DestroyVMCmd, cmdOriginator: null,
cmdInfo:
{"id":"913e49e6-308b-4d5c-8d05-20942926ef4c","response":"json","sessionkey":"9gP56fKnip2ipT8G+MLx1V14H3M\u003d","ctxUserId":"4","_":"1365036849438","ctxAccountId":"4","ctxStartEventId":"334"},
cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
processStatus: 0, resultCode: 0, result: null, initMsid: 7200344900649,
completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-04-03 17:54:07,790 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-23:job-81) Executing
org.apache.cloudstack.api.command.user.vm.DestroyVMCmd for job-81
2013-04-03 17:54:07,806 DEBUG [cloud.user.AccountManagerImpl]
(Job-Executor-23:job-81) Access to VM[User|atoms-VM-2] granted to Acct[4-atoms]
by DomainChecker_EnhancerByCloudStack_64286415
2013-04-03 17:54:07,813 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-23:job-81) Destroying vm VM[User|atoms-VM-2]
2013-04-03 17:54:07,819 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) VM state transitted from :Running to Stopping with
event: StopRequestedvm's original host id: 1 new host id: 1 host id before
state transition: 1
2013-04-03 17:54:07,820 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534049: Sending { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100111,
[{"StopCommand":{"isProxy":false,"vmName":"i-4-12-VMSNAP","wait":0}}] }
2013-04-03 17:54:07,821 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534049: Executing: { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100111,
[{"StopCommand":{"isProxy":false,"vmName":"i-4-12-VMSNAP","wait":0}}] }
2013-04-03 17:54:13,005 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534049: Received: { Ans: , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
2013-04-03 17:54:13,007 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-23:job-81) VM[User|atoms-VM-2] is stopped on the host.
Proceeding to release resource held.
2013-04-03 17:54:13,011 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-23:job-81) Service SecurityGroup is not supported in the network
id=205
2013-04-03 17:54:13,014 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Changing active number of nics for network id=205 on -1
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking JuniperSRX to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking Netscaler to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking F5BigIP to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking CiscoNexus1000vVSM to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking BigSwitchVnsElement to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [network.element.BigSwitchVnsElement]
(Job-Executor-23:job-81) Checking if BigSwitchVnsElement can handle service
Connectivity on network TestNetwork-atoms-1
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking VirtualRouter to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking Ovs to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking SecurityGroupProvider to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-23:job-81) Asking VpcVirtualRouter to release
Nic[23-12-9816ddea-0ec7-42a1-b772-9b61c2721e77-10.1.1.217]
2013-04-03 17:54:13,018 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-23:job-81) Successfully released network resources for the vm
VM[User|atoms-VM-2]
2013-04-03 17:54:13,018 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-23:job-81) Successfully released storage resources for the vm
VM[User|atoms-VM-2]
2013-04-03 17:54:13,031 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-23:job-81) Service SecurityGroup is not supported in the network
id=205
2013-04-03 17:54:13,035 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-23:job-81) Service SecurityGroup is not supported in the network
id=205
2013-04-03 17:54:13,046 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) VM state transitted from :Stopping to Stopped with
event: OperationSucceededvm's original host id: 1 new host id: null host id
before state transition: 1
2013-04-03 17:54:13,051 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) Hosts's actual total CPU: 4520 and CPU after applying
overprovisioning: 4520
2013-04-03 17:54:13,051 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) Hosts's actual total RAM: 17169530880 and RAM after
applying overprovisioning: 17169530880
2013-04-03 17:54:13,051 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) release cpu from host: 1, old used: 1800,reserved: 0,
actual total: 4520, total with overprovisioning: 4520; new used:
1700,reserved:100; movedfromreserved: false,moveToReserveredtrue
2013-04-03 17:54:13,051 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) release mem from host: 1, old used:
1879048192,reserved: 0, total: 17169530880; new used:
1744830464,reserved:134217728; movedfromreserved: false,moveToReserveredtrue
2013-04-03 17:54:13,072 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534050: Sending { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100011,
[{"DeleteVMSnapshotCommand":{"volumeTOs":[{"id":12,"name":"ROOT-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"ROOT-12-12-000003","size":2147483648,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":0},{"id":13,"name":"DATA-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"cee5b5a1f7844506825ce0075f17eb46-000003","size":5368709120,"type":"DATADISK","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":1}],"target":{"id":2,"snapshotName":"i-4-12-VMSNAP_VS_20130403011101","type":"Disk","createTime":1364951461000,"current":false,"description":"vmsnap-atm-vm-2-1"},"vmName":"i-4-12-VMSNAP","guestOSType":"CentOS
5.3 (64-bit)","wait":0}}] }
2013-04-03 17:54:13,072 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534050: Executing: { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100011,
[{"DeleteVMSnapshotCommand":{"volumeTOs":[{"id":12,"name":"ROOT-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"ROOT-12-12-000003","size":2147483648,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":0},{"id":13,"name":"DATA-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"cee5b5a1f7844506825ce0075f17eb46-000003","size":5368709120,"type":"DATADISK","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":1}],"target":{"id":2,"snapshotName":"i-4-12-VMSNAP_VS_20130403011101","type":"Disk","createTime":1364951461000,"current":false,"description":"vmsnap-atm-vm-2-1"},"vmName":"i-4-12-VMSNAP","guestOSType":"CentOS
5.3 (64-bit)","wait":0}}] }
2013-04-03 17:54:44,101 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534050: Received: { Ans: , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 10, { DeleteVMSnapshotAnswer } }
2013-04-03 17:54:44,109 DEBUG [vm.snapshot.VMSnapshotManagerImpl]
(Job-Executor-23:job-81) Delete VM snapshot i-4-12-VMSNAP_VS_20130403011101
succeeded for vm: i-4-12-VMSNAP
2013-04-03 17:54:44,124 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534052: Sending { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100011,
[{"DeleteVMSnapshotCommand":{"volumeTOs":[{"id":12,"name":"ROOT-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"ROOT-12-12-000003","size":2147483648,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":0},{"id":13,"name":"DATA-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"cee5b5a1f7844506825ce0075f17eb46-000003","size":5368709120,"type":"DATADISK","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":1}],"target":{"id":6,"snapshotName":"i-4-12-VMSNAP_VS_20130403212447","type":"DiskAndMemory","createTime":1365024287000,"current":false,"description":"vmsnap-run-atm-vm-2-2"},"vmName":"i-4-12-VMSNAP","guestOSType":"CentOS
5.3 (64-bit)","wait":0}}] }
2013-04-03 17:54:44,125 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534052: Executing: { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100011,
[{"DeleteVMSnapshotCommand":{"volumeTOs":[{"id":12,"name":"ROOT-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"ROOT-12-12-000003","size":2147483648,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":0},{"id":13,"name":"DATA-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"cee5b5a1f7844506825ce0075f17eb46-000003","size":5368709120,"type":"DATADISK","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":1}],"target":{"id":6,"snapshotName":"i-4-12-VMSNAP_VS_20130403212447","type":"DiskAndMemory","createTime":1365024287000,"current":false,"description":"vmsnap-run-atm-vm-2-2"},"vmName":"i-4-12-VMSNAP","guestOSType":"CentOS
5.3 (64-bit)","wait":0}}] }
2013-04-03 17:54:47,217 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534052: Received: { Ans: , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 10, { DeleteVMSnapshotAnswer } }
2013-04-03 17:54:47,225 DEBUG [vm.snapshot.VMSnapshotManagerImpl]
(Job-Executor-23:job-81) Delete VM snapshot i-4-12-VMSNAP_VS_20130403212447
succeeded for vm: i-4-12-VMSNAP
2013-04-03 17:54:47,255 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534053: Sending { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100011,
[{"DeleteVMSnapshotCommand":{"volumeTOs":[{"id":12,"name":"ROOT-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"ROOT-12-12-000003","size":2147483648,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":0},{"id":13,"name":"DATA-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"cee5b5a1f7844506825ce0075f17eb46-000003","size":5368709120,"type":"DATADISK","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":1}],"target":{"id":8,"snapshotName":"i-4-12-VMSNAP_VS_20130403232140","type":"Disk","createTime":1365031300000,"current":true,"description":"vmsnap-run-atm-vm-2-3"},"vmName":"i-4-12-VMSNAP","guestOSType":"CentOS
5.3 (64-bit)","wait":0}}] }
2013-04-03 17:54:47,255 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534053: Executing: { Cmd , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 100011,
[{"DeleteVMSnapshotCommand":{"volumeTOs":[{"id":12,"name":"ROOT-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"ROOT-12-12-000003","size":2147483648,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":0},{"id":13,"name":"DATA-12","mountPoint":"/export/home/chandan/asf4-131-170/primary","path":"cee5b5a1f7844506825ce0075f17eb46-000003","size":5368709120,"type":"DATADISK","storagePoolType":"NetworkFilesystem","storagePoolUuid":"f42a60c2-0940-305b-bb14-23d8b40f42bc","deviceId":1}],"target":{"id":8,"snapshotName":"i-4-12-VMSNAP_VS_20130403232140","type":"Disk","createTime":1365031300000,"current":true,"description":"vmsnap-run-atm-vm-2-3"},"vmName":"i-4-12-VMSNAP","guestOSType":"CentOS
5.3 (64-bit)","wait":0}}] }
2013-04-03 17:57:48,342 DEBUG [agent.transport.Request]
(Job-Executor-23:job-81) Seq 1-695534053: Received: { Ans: , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 10, { DeleteVMSnapshotAnswer } }
2013-04-03 17:57:48,350 DEBUG [vm.snapshot.VMSnapshotManagerImpl]
(Job-Executor-23:job-81) Delete VM snapshot i-4-12-VMSNAP_VS_20130403232140
succeeded for vm: i-4-12-VMSNAP
2013-04-03 17:57:48,360 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) VM state transitted from :Stopped to Destroyed with
event: DestroyRequestedvm's original host id: 1 new host id: null host id
before state transition: null
2013-04-03 17:57:48,366 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) Hosts's actual total CPU: 4520 and CPU after applying
overprovisioning: 4520
2013-04-03 17:57:48,366 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) Hosts's actual total RAM: 17169530880 and RAM after
applying overprovisioning: 17169530880
2013-04-03 17:57:48,366 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) release cpu from host: 1, old used: 1700,reserved:
100, actual total: 4520, total with overprovisioning: 4520; new used:
1700,reserved:0; movedfromreserved: true,moveToReserveredfalse
2013-04-03 17:57:48,366 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-23:job-81) release mem from host: 1, old used:
1744830464,reserved: 134217728, total: 17169530880; new used:
1744830464,reserved:0; movedfromreserved: true,moveToReserveredfalse
2013-04-03 17:57:48,439 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-23:job-81) Complete async job-81, jobStatus: 1, resultCode: 0,
result: org.apache.cloudstack.api.response.UserVmResponse@5417c02e
2013-04-03 17:57:48,445 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-23:job-81) Done executing
org.apache.cloudstack.api.command.user.vm.DestroyVMCmd for job-81
2013-04-03 17:57:49,883 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-23:null) Async job-81 completed
[root@asfmgmt ~]#
.
.
.
.
.
.
.
2013-04-03 17:59:10,452 INFO [vmware.resource.VmwareResource]
(DirectAgent-288:10.223.58.2) Destroy root volume directly from datastore
2013-04-03 17:59:10,452 INFO [vmware.resource.VmwareResource]
(DirectAgent-288:10.223.58.2) Destroy volume by original name: ROOT-12-12.vmdk
2013-04-03 17:59:10,473 INFO [vmware.mo.DatastoreMO]
(DirectAgent-288:10.223.58.2) Search file ROOT-12-12.vmdk on
[f42a60c20940305bbb1423d8b40f42bc]
2013-04-03 17:59:10,543 INFO [vmware.mo.DatastoreMO]
(DirectAgent-288:10.223.58.2) File [f42a60c20940305bbb1423d8b40f42bc]
ROOT-12-12.vmdk exists on datastore
2013-04-03 17:59:10,648 INFO [vmware.resource.VmwareResource]
(DirectAgent-288:10.223.58.2) Destroy volume by derived name:
ROOT-12-12-flat.vmdk
2013-04-03 17:59:10,658 INFO [vmware.mo.DatastoreMO]
(DirectAgent-288:10.223.58.2) Search file ROOT-12-12-flat.vmdk on
[f42a60c20940305bbb1423d8b40f42bc]
2013-04-03 17:59:10,716 INFO [vmware.mo.DatastoreMO]
(DirectAgent-288:10.223.58.2) File [f42a60c20940305bbb1423d8b40f42bc]
ROOT-12-12-flat.vmdk exists on datastore
2013-04-03 17:59:15,042 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-4:null) Ping from 3
2013-04-03 17:59:15,371 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-5:null) Ping from 4
2013-04-03 17:59:16,861 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-288:null) Seq 1-695534065: Response Received:
2013-04-03 17:59:16,861 DEBUG [agent.transport.Request] (DirectAgent-288:null)
Seq 1-695534065: Processing: { Ans: , MgmtId: 7200344900649, via: 1, Ver: v1,
Flags: 110, [{"Answer":{"result":true,"details":"Success","wait":0}}] }
2013-04-03 17:59:16,861 DEBUG [agent.transport.Request]
(UserVm-Scavenger-1:null) Seq 1-695534065: Received: { Ans: , MgmtId:
7200344900649, via: 1, Ver: v1, Flags: 110, { Answer } }
2013-04-03 17:59:16,863 DEBUG [agent.manager.AgentAttache]
(DirectAgent-288:null) Seq 1-695534065: No more commands found
2013-04-03 17:59:16,872 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(UserVm-Scavenger-1:null) Expunged VM[User|atoms-VM-2]
2013-04-03 17:59:16,872 DEBUG [cloud.vm.UserVmManagerImpl]
(UserVm-Scavenger-1:null) Starting cleaning up vm VM[User|atoms-VM-2]
resources...
2013-04-03 17:59:16,912 DEBUG [network.firewall.FirewallManagerImpl]
(UserVm-Scavenger-1:null) No firewall rules are found for vm id=12
2013-04-03 17:59:16,912 DEBUG [cloud.vm.UserVmManagerImpl]
(UserVm-Scavenger-1:null) Firewall rules are removed successfully as a part of
vm id=12 expunge
2013-04-03 17:59:16,914 DEBUG [network.rules.RulesManagerImpl]
(UserVm-Scavenger-1:null) No port forwarding rules are found for vm id=12
2013-04-03 17:59:16,914 DEBUG [cloud.vm.UserVmManagerImpl]
(UserVm-Scavenger-1:null) Port forwarding rules are removed successfully as a
part of vm id=12 expunge
2013-04-03 17:59:16,921 DEBUG [cloud.vm.UserVmManagerImpl]
(UserVm-Scavenger-1:null) Removed vm id=12 from all load balancers as a part of
expunge process
2013-04-03 17:59:16,924 DEBUG [network.rules.RulesManagerImpl]
(UserVm-Scavenger-1:null) Revoking all Firewallrules as a part of disabling
static nat for public IP id=9
2013-04-03 17:59:16,926 DEBUG [network.firewall.FirewallManagerImpl]
(UserVm-Scavenger-1:null) Releasing 1 firewall rules for ip id=9
2013-04-03 17:59:16,927 WARN [cloud.vm.UserVmManagerImpl]
(UserVm-Scavenger-1:null) Unable to expunge VM[User|atoms-VM-2]
java.lang.NullPointerException
at com.cloud.event.EventUtils.getDomainId(EventUtils.java:116)
at com.cloud.event.EventUtils.saveStartedEvent(EventUtils.java:79)
at
com.cloud.event.ActionEventInterceptor.interceptStart(ActionEventInterceptor.java:47)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:119)
at
com.cloud.network.firewall.FirewallManagerImpl.revokeFirewallRulesForIp(FirewallManagerImpl.java:734)
at
com.cloud.network.rules.RulesManagerImpl.disableStaticNat(RulesManagerImpl.java:1242)
at
com.cloud.vm.UserVmManagerImpl.cleanupVmResources(UserVmManagerImpl.java:1442)
at com.cloud.vm.UserVmManagerImpl.expunge(UserVmManagerImpl.java:1373)
at
com.cloud.vm.UserVmManagerImpl$ExpungeTask.run(UserVmManagerImpl.java:1530)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at
java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
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)
2013-04-03 17:59:25,827 DEBUG [cloud.server.StatsCollector]
(StatsCollector-3:null) VmStatsCollector is running...
2013-04-03 17:59:25,837 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-289:null) Seq 1-695534066: Executing request
--
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
