[
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)
