[ https://issues.apache.org/jira/browse/CLOUDSTACK-5317?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13838777#comment-13838777 ]
manasaveloori commented on CLOUDSTACK-5317: ------------------------------------------- Observed the same for "disassociateIpAddress" command 2013-12-04 20:31:00,967 DEBUG [c.c.a.ApiServlet] (catalina-exec-4:ctx-6202ba9a) ===START=== 10.252.192.19 -- GET command=disassociateIpAddress&response=json&sessionkey=nncAp7MmSEoCXkbGG8kV51t91Js%3D&id=88d00a57-8ceb-42bd-ad38-a430db6c963b&_=1386149993732 2013-12-04 20:31:01,077 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina-exec-4:ctx-6202ba9a ctx-1f967611) submit async job-89, details: AsyncJobVO {id:89, userId: 2, accountId: 2, instanceType: IpAddress, instanceId: 11, cmd: org.apache.cloudstack.api.command.user.address.DisassociateIPAddrCmd, cmdInfo: {"response":"json","id":"88d00a57-8ceb-42bd-ad38-a430db6c963b","sessionkey":"nncAp7MmSEoCXkbGG8kV51t91Js\u003d","cmdEventType":"NET.IPRELEASE","ctxUserId":"2","httpmethod":"GET","_":"1386149993732","ctxAccountId":"2","ctxStartEventId":"364"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 6851580133501, completeMsid: null, lastUpdated: null, lastPolled: null, created: null} 2013-12-04 20:31:01,079 DEBUG [c.c.a.ApiServlet] (catalina-exec-4:ctx-6202ba9a ctx-1f967611) ===END=== 10.252.192.19 -- GET command=disassociateIpAddress&response=json&sessionkey=nncAp7MmSEoCXkbGG8kV51t91Js%3D&id=88d00a57-8ceb-42bd-ad38-a430db6c963b&_=1386149993732 2013-12-04 20:31:01,084 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor-10:ctx-0d24b239) Add job-89 into job monitoring 2013-12-04 20:31:01,084 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-10:ctx-0d24b239) Executing AsyncJobVO {id:89, userId: 2, accountId: 2, instanceType: IpAddress, instanceId: 11, cmd: org.apache.cloudstack.api.command.user.address.DisassociateIPAddrCmd, cmdInfo: {"response":"json","id":"88d00a57-8ceb-42bd-ad38-a430db6c963b","sessionkey":"nncAp7MmSEoCXkbGG8kV51t91Js\u003d","cmdEventType":"NET.IPRELEASE","ctxUserId":"2","httpmethod":"GET","_":"1386149993732","ctxAccountId":"2","ctxStartEventId":"364"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 6851580133501, completeMsid: null, lastUpdated: null, lastPolled: null, created: null} 2013-12-04 20:31:01,108 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-2:null) SeqA 5-224: Processing Seq 5-224: { Cmd , MgmtId: -1, via: 5, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":3,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] } 2013-12-04 20:31:01,122 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-2:null) SeqA 5-224: Sending Seq 5-224: { Ans: , MgmtId: 6851580133501, via: 5, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] } 2013-12-04 20:31:01,152 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Revoking all Firewallrules as a part of public IP id=11 release... 2013-12-04 20:31:01,190 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing 0 firewall rules for ip id=11 2013-12-04 20:31:01,195 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) There are no firewall rules to apply 2013-12-04 20:31:01,201 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Successfully released firewall rules for ip id=11 and # of rules now = 0 2013-12-04 20:31:01,217 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Revoking all PortForwarding/StaticNat rules as a part of public IP id=11 release... 2013-12-04 20:31:01,225 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing 0 port forwarding rules for ip id=11 2013-12-04 20:31:01,230 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing 0 static nat rules for ip id=11 2013-12-04 20:31:01,232 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) There are no port forwarding rules to apply for ip id=11 2013-12-04 20:31:01,238 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) There are no static nat rules to apply for ip id=11 2013-12-04 20:31:01,240 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Source ip id=Ip[10.147.47.13-1] is not one to one nat 2013-12-04 20:31:01,248 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Successfully released rules for ip id=11 and # of rules now = 0 2013-12-04 20:31:01,248 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Revoking all LoadBalancing rules as a part of public IP id=11 release... 2013-12-04 20:31:01,256 DEBUG [c.c.n.l.LoadBalancingRulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Found 0 lb rules to cleanup 2013-12-04 20:31:01,256 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Cleaning up remote access vpns as a part of public IP id=11 release... 2013-12-04 20:31:01,260 DEBUG [c.c.n.v.RemoteAccessVpnManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) there are no Remote access vpns for public ip address id=11 2013-12-04 20:31:01,311 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing ip id=11; sourceNat = false 2013-12-04 20:31:01,316 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Revoking all Firewallrules as a part of public IP id=11 release... 2013-12-04 20:31:01,329 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing 0 firewall rules for ip id=11 2013-12-04 20:31:01,331 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) There are no firewall rules to apply 2013-12-04 20:31:01,333 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Successfully released firewall rules for ip id=11 and # of rules now = 0 2013-12-04 20:31:01,343 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Revoking all PortForwarding/StaticNat rules as a part of public IP id=11 release... 2013-12-04 20:31:01,346 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing 0 port forwarding rules for ip id=11 2013-12-04 20:31:01,348 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Releasing 0 static nat rules for ip id=11 2013-12-04 20:31:01,350 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) There are no port forwarding rules to apply for ip id=11 2013-12-04 20:31:01,352 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) There are no static nat rules to apply for ip id=11 2013-12-04 20:31:01,354 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Source ip id=Ip[10.147.47.13-1] is not one to one nat 2013-12-04 20:31:01,358 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Successfully released rules for ip id=11 and # of rules now = 0 2013-12-04 20:31:01,358 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Revoking all LoadBalancing rules as a part of public IP id=11 release... 2013-12-04 20:31:01,360 DEBUG [c.c.n.l.LoadBalancingRulesManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Found 0 lb rules to cleanup 2013-12-04 20:31:01,361 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Cleaning up remote access vpns as a part of public IP id=11 release... 2013-12-04 20:31:01,362 DEBUG [c.c.n.v.RemoteAccessVpnManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) there are no Remote access vpns for public ip address id=11 2013-12-04 20:31:01,372 DEBUG [c.c.n.IpAddressManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Released a public ip id=11 2013-12-04 20:31:01,388 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-10:ctx-0d24b239 ctx-1f967611) Complete async job-89, jobStatus: SUCCEEDED, resultCode: 0, result: org.apache.cloudstack.api.response.SuccessResponse/null/{"success":true} 2013-12-04 20:31:01,398 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-10:ctx-0d24b239) Done executing org.apache.cloudstack.api.command.user.address.DisassociateIPAddrCmd for job-89 2013-12-04 20:31:01,405 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor-10:ctx-0d24b239) Remove job-89 from job monitoring > associateIpAddress command doesnt fails on non upgraded router > -------------------------------------------------------------- > > Key: CLOUDSTACK-5317 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5317 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: Upgrade, Virtual Router > Affects Versions: 4.3.0 > Environment: upgrade from 3.0.7 to 4.3.0 > Reporter: shweta agarwal > Assignee: Kishan Kavala > Priority: Critical > Fix For: 4.3.0 > > > Repro steps: > 1. On 3.0.7 setup create a network > 2. upgrade to 4.3 > 3.Aquire Ip on network when router is not upgraded > Bug: > IP Associate command will succeed > Exception : > IP Associate command should fail sating router needs upgrade: > MS log shows : > 2013-11-29 16:33:56,045 DEBUG [c.c.a.ApiServlet] > (catalina-exec-8:ctx-619dddf1) ===START=== 10.146.0.132 -- GET > command=associateIpAddress&response=json&sessionkey=CNU%2FUxmL5sTxIFWo8PSATnLucYk%3D&networkid=597e7f42-8f78-4c51-954b-c75867d2a17d&_=1385723056001 > 2013-11-29 16:33:56,061 DEBUG [c.c.n.IpAddressManagerImpl] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) Associate IP address called by > the user 2 account 2 > 2013-11-29 16:33:56,062 DEBUG [c.c.n.IpAddressManagerImpl] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) Associate IP address lock acquired > 2013-11-29 16:33:56,067 DEBUG [c.c.n.IpAddressManagerImpl] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) Got 10.147.51.31 to assign for > account 2 in zone 1 > 2013-11-29 16:33:56,113 DEBUG [c.c.n.IpAddressManagerImpl] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) Releasing lock account > Acct[2-admin] > 2013-11-29 16:33:56,114 DEBUG [c.c.n.IpAddressManagerImpl] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) Associate IP address lock released > 2013-11-29 16:33:56,324 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) submit async job-131, details: > AsyncJobVO {id:131, userId: 2, accountId: 2, instanceType: IpAddress, > instanceId: 22, cmd: > org.apache.cloudstack.api.command.user.address.AssociateIPAddrCmd, cmdInfo: > {"id":"22","response":"json","sessionkey":"CNU/UxmL5sTxIFWo8PSATnLucYk\u003d","cmdEventType":"NET.IPASSIGN","ctxUserId":"2","httpmethod":"GET","_":"1385723056001","ctxAccountId":"2","networkid":"597e7f42-8f78-4c51-954b-c75867d2a17d","ctxStartEventId":"457"}, > cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: > null, initMsid: 233845177509765, completeMsid: null, lastUpdated: null, > lastPolled: null, created: null} > 2013-11-29 16:33:56,324 INFO [o.a.c.f.j.i.AsyncJobMonitor] > (Job-Executor-29:ctx-9f8c8b7f) Add job-131 into job monitoring > 2013-11-29 16:33:56,324 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > (Job-Executor-29:ctx-9f8c8b7f) Executing AsyncJobVO {id:131, userId: 2, > accountId: 2, instanceType: IpAddress, instanceId: 22, cmd: > org.apache.cloudstack.api.command.user.address.AssociateIPAddrCmd, cmdInfo: > {"id":"22","response":"json","sessionkey":"CNU/UxmL5sTxIFWo8PSATnLucYk\u003d","cmdEventType":"NET.IPASSIGN","ctxUserId":"2","httpmethod":"GET","_":"1385723056001","ctxAccountId":"2","networkid":"597e7f42-8f78-4c51-954b-c75867d2a17d","ctxStartEventId":"457"}, > cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: > null, initMsid: 233845177509765, completeMsid: null, lastUpdated: null, > lastPolled: null, created: null} > 2013-11-29 16:33:56,325 DEBUG [c.c.a.ApiServlet] > (catalina-exec-8:ctx-619dddf1 ctx-173f6e25) ===END=== 10.146.0.132 -- GET > command=associateIpAddress&response=json&sessionkey=CNU%2FUxmL5sTxIFWo8PSATnLucYk%3D&networkid=597e7f42-8f78-4c51-954b-c75867d2a17d&_=1385723056001 > 2013-11-29 16:33:56,400 DEBUG [c.c.n.IpAddressManagerImpl] > (Job-Executor-29:ctx-9f8c8b7f ctx-173f6e25) Associating ip Ip[10.147.51.31-1] > to network Ntwk[204|Guest|8] > 2013-11-29 16:33:56,506 DEBUG [c.c.n.IpAddressManagerImpl] > (Job-Executor-29:ctx-9f8c8b7f ctx-173f6e25) Successfully associated ip > address 10.147.51.31 to network Ntwk[204|Guest|8] > 2013-11-29 16:33:56,564 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > (Job-Executor-29:ctx-9f8c8b7f ctx-173f6e25) Complete async job-131, > jobStatus: SUCCEEDED, resultCode: 0, result: > org.apache.cloudstack.api.response.IPAddressResponse/ipaddress/{"id":"6b59e05e-243a-4f56-a563-b19d562257ab","ipaddress":"10.147.51.31","allocated":"2013-11-29T16:33:56+0530","zoneid":"71c3d210-e0f7-4ea0-80b3-1a3769cdaeb2","zonename":"xen","issourcenat":false,"account":"admin","domainid":"1","domain":"ROOT","forvirtualnetwork":true,"vlanid":"92e13e64-3f00-4edd-9f35-a203721aee4b","vlanname":"51","isstaticnat":false,"issystem":false,"associatednetworkid":"597e7f42-8f78-4c51-954b-c75867d2a17d","associatednetworkname":"vr1","networkid":"c1e501e4-41a3-47a0-8cf3-472895bc68ae","state":"Allocating","physicalnetworkid":"8b22e636-7774-44cb-b670-63f75c2fd157","tags":[],"isportable":false} > 2013-11-29 16:33:56,615 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > (Job-Executor-29:ctx-9f8c8b7f) Done executing > org.apache.cloudstack.api.command.user.address.AssociateIPAddrCmd for job-131 -- This message was sent by Atlassian JIRA (v6.1#6144)