You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Mice Xia (JIRA)" <ji...@apache.org> on 2013/04/24 14:13:16 UTC
[jira] [Resolved] (CLOUDSTACK-1944) Destruction of a VM with
StaticNAT/FW throws a NullPointerException during VM Expunge
[ https://issues.apache.org/jira/browse/CLOUDSTACK-1944?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Mice Xia resolved CLOUDSTACK-1944.
----------------------------------
Resolution: Fixed
registerContext with system account in expungeTask
> Destruction of a VM with StaticNAT/FW 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
> Assignee: Mice Xia
> Fix For: 4.2.0
>
> Attachments: management-server.log.2013-04-03.gz
>
>
> ================
> 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