[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router

2015-03-16 Thread Jayapal Reddy (JIRA)

 [ 
https://issues.apache.org/jira/browse/CLOUDSTACK-7997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Jayapal Reddy updated CLOUDSTACK-7997:
--
Assignee: Jayapal Reddy  (was: Chandan Purushothama)

> [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable 
> to apply dhcp entry on router
> --
>
> Key: CLOUDSTACK-7997
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7997
> Project: CloudStack
>  Issue Type: Bug
>  Security Level: Public(Anyone can view this level - this is the 
> default.) 
>  Components: Automation
>Affects Versions: 4.5.0
>Reporter: Chandan Purushothama
>Assignee: Jayapal Reddy
>Priority: Critical
> Fix For: 4.5.1
>
> Attachments: management-server.zip
>
>
> VM Deployment failure occurred multiple times. Posting the details from one 
> such occurrence below:
> =
> Unable to apply dhcp entry on router:
> =
> {noformat}
> 2014-12-01 18:57:12,396 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Asking VirtualRouter to prepare for 
> Nic[132-125-5e3877b8-7029-4029-be70-429a6e47d568-10.219.197.222]
> 2014-12-01 18:57:12,405 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is acquired for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,408 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is released for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,431 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Applying dhcp entry in network Ntwk[204|Guest|6]
> 2014-12-01 18:57:12,446 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Sending  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Executing:  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:312cfd5b) Seq 2-1882786119217578814: 
> Executing request
> 2014-12-01 18:57:12,447 DEBUG [c.c.h.x.r.CitrixResourceBase] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Executing command in VR: 
> /opt/cloud/bin/router_proxy.sh edithosts.sh 169.254.3.164  -m 
> 06:26:ea:00:00:37 -4 10.219.197.222 -h 
> VM-2656bcb0-f793-4248-8256-1754ebe2c2ef -d 10.219.192.1 -n 10.219.197.221
> 2014-12-01 18:57:12,448 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Response Received: 
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Processing:  { Ans: , MgmtId: 195740251462904, via: 2, Ver: v1, Flags: 10, 
> [{"com.cloud.agent.api.Answer":{"result":false,"details":"There was a problem 
> while connecting to 10.219.195.58:22","wait":0}}] }
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573

[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router

2015-03-15 Thread Rajani Karuturi (JIRA)

 [ 
https://issues.apache.org/jira/browse/CLOUDSTACK-7997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Rajani Karuturi updated CLOUDSTACK-7997:

Fix Version/s: (was: 4.5.0)
   4.5.1

> [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable 
> to apply dhcp entry on router
> --
>
> Key: CLOUDSTACK-7997
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7997
> Project: CloudStack
>  Issue Type: Bug
>  Security Level: Public(Anyone can view this level - this is the 
> default.) 
>  Components: Automation
>Affects Versions: 4.5.0
>Reporter: Chandan Purushothama
>Assignee: Chandan Purushothama
>Priority: Critical
> Fix For: 4.5.1
>
> Attachments: management-server.zip
>
>
> VM Deployment failure occurred multiple times. Posting the details from one 
> such occurrence below:
> =
> Unable to apply dhcp entry on router:
> =
> {noformat}
> 2014-12-01 18:57:12,396 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Asking VirtualRouter to prepare for 
> Nic[132-125-5e3877b8-7029-4029-be70-429a6e47d568-10.219.197.222]
> 2014-12-01 18:57:12,405 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is acquired for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,408 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is released for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,431 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Applying dhcp entry in network Ntwk[204|Guest|6]
> 2014-12-01 18:57:12,446 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Sending  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Executing:  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:312cfd5b) Seq 2-1882786119217578814: 
> Executing request
> 2014-12-01 18:57:12,447 DEBUG [c.c.h.x.r.CitrixResourceBase] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Executing command in VR: 
> /opt/cloud/bin/router_proxy.sh edithosts.sh 169.254.3.164  -m 
> 06:26:ea:00:00:37 -4 10.219.197.222 -h 
> VM-2656bcb0-f793-4248-8256-1754ebe2c2ef -d 10.219.192.1 -n 10.219.197.221
> 2014-12-01 18:57:12,448 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Response Received: 
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Processing:  { Ans: , MgmtId: 195740251462904, via: 2, Ver: v1, Flags: 10, 
> [{"com.cloud.agent.api.Answer":{"result":false,"details":"There was a problem 
> while connecting to 10.219.195.58:22","wait":0}}] }
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (Work-Job-Execut

[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router

2014-12-02 Thread Jayapal Reddy (JIRA)

 [ 
https://issues.apache.org/jira/browse/CLOUDSTACK-7997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Jayapal Reddy updated CLOUDSTACK-7997:
--
Assignee: Chandan Purushothama  (was: Jayapal Reddy)

> [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable 
> to apply dhcp entry on router
> --
>
> Key: CLOUDSTACK-7997
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7997
> Project: CloudStack
>  Issue Type: Bug
>  Security Level: Public(Anyone can view this level - this is the 
> default.) 
>  Components: Automation
>Affects Versions: 4.5.0
>Reporter: Chandan Purushothama
>Assignee: Chandan Purushothama
>Priority: Critical
> Fix For: 4.5.0
>
> Attachments: management-server.zip
>
>
> VM Deployment failure occurred multiple times. Posting the details from one 
> such occurrence below:
> =
> Unable to apply dhcp entry on router:
> =
> {noformat}
> 2014-12-01 18:57:12,396 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Asking VirtualRouter to prepare for 
> Nic[132-125-5e3877b8-7029-4029-be70-429a6e47d568-10.219.197.222]
> 2014-12-01 18:57:12,405 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is acquired for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,408 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is released for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,431 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Applying dhcp entry in network Ntwk[204|Guest|6]
> 2014-12-01 18:57:12,446 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Sending  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Executing:  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:312cfd5b) Seq 2-1882786119217578814: 
> Executing request
> 2014-12-01 18:57:12,447 DEBUG [c.c.h.x.r.CitrixResourceBase] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Executing command in VR: 
> /opt/cloud/bin/router_proxy.sh edithosts.sh 169.254.3.164  -m 
> 06:26:ea:00:00:37 -4 10.219.197.222 -h 
> VM-2656bcb0-f793-4248-8256-1754ebe2c2ef -d 10.219.192.1 -n 10.219.197.221
> 2014-12-01 18:57:12,448 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Response Received: 
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Processing:  { Ans: , MgmtId: 195740251462904, via: 2, Ver: v1, Flags: 10, 
> [{"com.cloud.agent.api.Answer":{"result":false,"details":"There was a problem 
> while connecting to 10.219.195.58:22","wait":0}}] }
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ct

[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router

2014-12-02 Thread Animesh Chaturvedi (JIRA)

 [ 
https://issues.apache.org/jira/browse/CLOUDSTACK-7997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Animesh Chaturvedi updated CLOUDSTACK-7997:
---
Assignee: Jayapal Reddy

> [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable 
> to apply dhcp entry on router
> --
>
> Key: CLOUDSTACK-7997
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7997
> Project: CloudStack
>  Issue Type: Bug
>  Security Level: Public(Anyone can view this level - this is the 
> default.) 
>  Components: Automation
>Affects Versions: 4.5.0
>Reporter: Chandan Purushothama
>Assignee: Jayapal Reddy
>Priority: Critical
> Fix For: 4.5.0
>
> Attachments: management-server.zip
>
>
> VM Deployment failure occurred multiple times. Posting the details from one 
> such occurrence below:
> =
> Unable to apply dhcp entry on router:
> =
> {noformat}
> 2014-12-01 18:57:12,396 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Asking VirtualRouter to prepare for 
> Nic[132-125-5e3877b8-7029-4029-be70-429a6e47d568-10.219.197.222]
> 2014-12-01 18:57:12,405 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is acquired for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,408 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is released for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,431 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Applying dhcp entry in network Ntwk[204|Guest|6]
> 2014-12-01 18:57:12,446 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Sending  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Executing:  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:312cfd5b) Seq 2-1882786119217578814: 
> Executing request
> 2014-12-01 18:57:12,447 DEBUG [c.c.h.x.r.CitrixResourceBase] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Executing command in VR: 
> /opt/cloud/bin/router_proxy.sh edithosts.sh 169.254.3.164  -m 
> 06:26:ea:00:00:37 -4 10.219.197.222 -h 
> VM-2656bcb0-f793-4248-8256-1754ebe2c2ef -d 10.219.192.1 -n 10.219.197.221
> 2014-12-01 18:57:12,448 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Response Received: 
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Processing:  { Ans: , MgmtId: 195740251462904, via: 2, Ver: v1, Flags: 10, 
> [{"com.cloud.agent.api.Answer":{"result":false,"details":"There was a problem 
> while connecting to 10.219.195.58:22","wait":0}}] }
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-11

[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router

2014-12-01 Thread Chandan Purushothama (JIRA)

 [ 
https://issues.apache.org/jira/browse/CLOUDSTACK-7997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Chandan Purushothama updated CLOUDSTACK-7997:
-
Description: 
VM Deployment failure occurred multiple times. Posting the details from one 
such occurrence below:

=
Unable to apply dhcp entry on router:
=

{noformat}
2014-12-01 18:57:12,396 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Asking VirtualRouter to prepare for 
Nic[132-125-5e3877b8-7029-4029-be70-429a6e47d568-10.219.197.222]
2014-12-01 18:57:12,405 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Lock is acquired for network id 204 as a part of router 
startup in 
Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))] 
: Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
2014-12-01 18:57:12,408 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Lock is released for network id 204 as a part of router 
startup in 
Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))] 
: Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
2014-12-01 18:57:12,431 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Applying dhcp entry in network Ntwk[204|Guest|6]
2014-12-01 18:57:12,446 DEBUG [c.c.a.t.Request] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Seq 2-1882786119217578814: Sending  { Cmd , MgmtId: 
195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
[{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
 }
2014-12-01 18:57:12,447 DEBUG [c.c.a.t.Request] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Seq 2-1882786119217578814: Executing:  { Cmd , MgmtId: 
195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
[{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
 }
2014-12-01 18:57:12,447 DEBUG [c.c.a.m.DirectAgentAttache] 
(DirectAgent-239:ctx-e27e7d7a) (logid:312cfd5b) Seq 2-1882786119217578814: 
Executing request
2014-12-01 18:57:12,447 DEBUG [c.c.h.x.r.CitrixResourceBase] 
(DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Executing command in VR: 
/opt/cloud/bin/router_proxy.sh edithosts.sh 169.254.3.164  -m 06:26:ea:00:00:37 
-4 10.219.197.222 -h VM-2656bcb0-f793-4248-8256-1754ebe2c2ef -d 10.219.192.1 -n 
10.219.197.221
2014-12-01 18:57:12,448 DEBUG [c.c.a.m.DirectAgentAttache] 
(DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
Response Received: 
2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] (DirectAgent-239:ctx-e27e7d7a) 
(logid:a7cfe505) Seq 2-1882786119217578814: Processing:  { Ans: , MgmtId: 
195740251462904, via: 2, Ver: v1, Flags: 10, 
[{"com.cloud.agent.api.Answer":{"result":false,"details":"There was a problem 
while connecting to 10.219.195.58:22","wait":0}}] }
2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Seq 2-1882786119217578814: Received:  { Ans: , MgmtId: 
195740251462904, via: 2, Ver: v1, Flags: 10, { Answer } }
2014-12-01 18:57:12,449 INFO  [c.c.v.VirtualMachineManagerImpl] 
(Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
(logid:a7cfe505) Unable to contact resource.
com.cloud.exception.ResourceUnavailableException: Resource [Pod:1] is 
unreachable: Unable to apply dhcp entry on router 
at 
com.cloud.network.router.VirtualNetworkApplianceManagerImpl.applyRules(VirtualNetworkApplianceManagerImpl.java:4086)
at 
com.cloud.network.router.VirtualNetworkApplianceManagerImpl.applyDhcpEntry(VirtualNetworkApplianceManagerImpl.java:3205)
at sun.reflect.GeneratedMethodAccessor399.invoke(Unknown Source)
at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe

[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router

2014-12-01 Thread Chandan Purushothama (JIRA)

 [ 
https://issues.apache.org/jira/browse/CLOUDSTACK-7997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Chandan Purushothama updated CLOUDSTACK-7997:
-
Attachment: management-server.zip

> [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable 
> to apply dhcp entry on router
> --
>
> Key: CLOUDSTACK-7997
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7997
> Project: CloudStack
>  Issue Type: Bug
>  Security Level: Public(Anyone can view this level - this is the 
> default.) 
>  Components: Automation
>Affects Versions: 4.5.0
>Reporter: Chandan Purushothama
>Priority: Critical
> Fix For: 4.5.0
>
> Attachments: management-server.zip
>
>
> =
> Unable to apply dhcp entry on router:
> =
> {noformat}
> 2014-12-01 18:57:12,396 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Asking VirtualRouter to prepare for 
> Nic[132-125-5e3877b8-7029-4029-be70-429a6e47d568-10.219.197.222]
> 2014-12-01 18:57:12,405 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is acquired for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,408 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Lock is released for network id 204 as a part of router 
> startup in 
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
>  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(152|ROOT-->Pool(1))]
> 2014-12-01 18:57:12,431 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Applying dhcp entry in network Ntwk[204|Guest|6]
> 2014-12-01 18:57:12,446 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Sending  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Executing:  { Cmd , MgmtId: 
> 195740251462904, via: 2(technetium), Ver: v1, Flags: 100011, 
> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:26:ea:00:00:37","vmIpAddress":"10.219.197.222","vmName":"VM-2656bcb0-f793-4248-8256-1754ebe2c2ef","defaultRouter":"10.219.192.1","defaultDns":"10.219.197.221","duid":"00:03:00:01:06:26:ea:00:00:37","isDefault":true,"executeInSequence":false,"accessDetails":{"router.guest.ip":"10.219.197.221","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.3.164"},"wait":0}}]
>  }
> 2014-12-01 18:57:12,447 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:312cfd5b) Seq 2-1882786119217578814: 
> Executing request
> 2014-12-01 18:57:12,447 DEBUG [c.c.h.x.r.CitrixResourceBase] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Executing command in VR: 
> /opt/cloud/bin/router_proxy.sh edithosts.sh 169.254.3.164  -m 
> 06:26:ea:00:00:37 -4 10.219.197.222 -h 
> VM-2656bcb0-f793-4248-8256-1754ebe2c2ef -d 10.219.192.1 -n 10.219.197.221
> 2014-12-01 18:57:12,448 DEBUG [c.c.a.m.DirectAgentAttache] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Response Received: 
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (DirectAgent-239:ctx-e27e7d7a) (logid:a7cfe505) Seq 2-1882786119217578814: 
> Processing:  { Ans: , MgmtId: 195740251462904, via: 2, Ver: v1, Flags: 10, 
> [{"com.cloud.agent.api.Answer":{"result":false,"details":"There was a problem 
> while connecting to 10.219.195.58:22","wait":0}}] }
> 2014-12-01 18:57:12,449 DEBUG [c.c.a.t.Request] 
> (Work-Job-Executor-98:ctx-eb573b61 job-1170/job-1171 ctx-4477fe15) 
> (logid:a7cfe505) Seq 2-1882786119217578814: Received:  { Ans: , MgmtId: 
> 195740251462904, via: 2, Ver: v1