[jira] [Updated] (CLOUDSTACK-7997) [Automation] Deployment of VM is failing on Basic Zone in Few Cases - Unable to apply dhcp entry on router
[ 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
[ 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
[ 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
[ 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
[ 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
[ 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