venkata swamybabu budumuru created CLOUDSTACK-2580:
------------------------------------------------------
Summary: [RVR] Failed to deploy Redundant Router VMs
Key: CLOUDSTACK-2580
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-2580
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Network Controller
Affects Versions: 4.2.0
Environment: commit # 85d54cd1c088997dd08f0328984bee1a55703636
Reporter: venkata swamybabu budumuru
Priority: Blocker
Fix For: 4.2.0
Steps to reproduce :
1. Have CloudStack setup with advanced zone having a VMware cluster with 1 host
- Adv zone
- 1 VMware cluster
- 1 ESXi 5.1 host
2. Create a network offering with RVR enabled
mysql> select * from network_offerings where id=14\G
*************************** 1. row ***************************
id: 14
name: RVROffering
uuid: 50fb0832-08b0-417b-ab2f-612a3cef9911
unique_name: RVROffering
display_text: RVROffering
nw_rate: NULL
mc_rate: 10
traffic_type: Guest
tags: NULL
system_only: 0
specify_vlan: 0
service_offering_id: NULL
conserve_mode: 0
created: 2013-05-20 13:04:02
removed: NULL
default: 0
availability: Optional
dedicated_lb_service: 1
shared_source_nat_service: 0
sort_key: 0
redundant_router_service: 1
state: Enabled
guest_type: Isolated
elastic_ip_service: 0
eip_associate_public_ip: 0
elastic_lb_service: 0
specify_ip_ranges: 0
inline: 0
is_persistent: 0
internal_lb: 0
public_lb: 1
3. select * from networks where id=209\G
mysql> select * from networks where id=209\G
*************************** 1. row ***************************
id: 209
name: RVRNet1
uuid: 2b031a8f-ec65-495c-9251-b9aa973334eb
display_text: RVRNet1
traffic_type: Guest
broadcast_domain_type: Vlan
broadcast_uri: vlan://904
gateway: 10.1.1.1
cidr: 10.1.1.0/24
mode: Dhcp
network_offering_id: 14
physical_network_id: 201
data_center_id: 2
guru_name: ExternalGuestNetworkGuru
state: Implementing
related: 209
domain_id: 2
account_id: 3
dns1: NULL
dns2: NULL
guru_data: NULL
set_fields: 0
acl_type: Account
network_domain: cs3cloud.internal
reservation_id: 377f4a17-24c6-4bb3-9d08-f60e3d3782f1
guest_type: Isolated
restart_required: 0
created: 2013-05-20 13:05:09
removed: NULL
specify_ip_ranges: 0
vpc_id: NULL
ip6_gateway: NULL
ip6_cidr: NULL
network_cidr: NULL
display_network: 1
network_acl_id: NULL
4. Create at least one non-ROOT domain user. Login as this user and try to
create a VM using the above network.
Observations:
(i) First router of the RVR setup (in this case r-13-VM) has come up fine.
(ii) But, the second router failed saying the host is in avoid set and then it
kept on trying and it filled the log file with those entries for thousands of
times.
Here is the log snippet from mgmt server
39355 2013-05-20 09:05:11,087 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-21:null) submit async job-31, details: AsyncJobVO {id:31,
userId: 3, accountId: 3, sessionKey: null, instanceType: VirtualMachine,
instanceId: 12, cmd: org.apache.cloudstack.api.comma
nd.user.vm.DeployVMCmd, cmdOriginator: null, cmdInfo:
{"sessionkey":"Mo6WUsz5nA2NAgP1DR+kefP4PM0\u003d","ctxUserId":"3","serviceOfferingId":"e26d4e7e-ceda-4f2d-bbf9-bbdb1f47cf5c","httpmethod":"GET","zoneId":"e078d6bf-8c54-4a20-a592-c56f7730e69e","templateId":"5c
c4feee-c12d-11e2-8a66-069f2c0000aa","response":"json","id":"12","networkIds":"2b031a8f-ec65-495c-9251-b9aa973334eb","hypervisor":"VMware","name":"VM1RVRZone2","_":"1369035372563","ctxAccountId":"3","ctxStartEventId":"127","displayname":"VM1RVRZone2"},
cmdVersion : 0, callbackType: 0, callbackAddress: null, status: 0,
processStatus: 0, resultCode: 0, result: null, initMsid: 7280707764394,
completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
39411 2013-05-20 09:05:11,459 DEBUG [cloud.storage.StorageManagerImpl]
(Job-Executor-31:job-31) Checking pool: 6 for volume allocation
[Vol[12|vm=12|ROOT]], maxSize : 11804569600000, totalAllocatedSize : 0,
askingSize : 2147483648, allocated disable threshold: 0.85
39412 2013-05-20 09:05:11,459 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Trying to
find a potenial host and associated storage pools from the suitable host/pool
lists for this VM
39413 2013-05-20 09:05:11,459 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Checking
if host: 5 can access any suitable storage pool for volume: ROOT
39414 2013-05-20 09:05:11,461 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Host: 5
can access pool: 6
39415 2013-05-20 09:05:11,463 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Found a
potential host id: 5 name: 10.147.40.11 and associated storage pools for this VM
39416 2013-05-20 09:05:11,465 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Returning
Deployment Destination:
Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(2)-Pod(2)-Cluster(2)-Host(5)-Storage(
Volume(12|ROOT-->Pool(6))]
39417 2013-05-20 09:05:11,465 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Deployment found - P0=VM[User|VM1RVRZone2],
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(2)-Pod(2)-Cluster(2)-Host(5)-St
orage(Volume(12|ROOT-->Pool(6))]
39418 2013-05-20 09:05:11,493 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) VM state transitted from :Starting to Starting with
event: OperationRetryvm's original host id: null new host id: 5 host id before
state transition: null
39419 2013-05-20 09:05:11,510 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) Hosts's actual total CPU: 9572 and CPU after applying
overprovisioning: 9572
39420 2013-05-20 09:05:11,510 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) We are allocating VM, increasing the used capacity of
this host:5
39421 2013-05-20 09:05:11,511 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) Current Used CPU: 1000 , Free CPU:8572 ,Requested CPU:
500
39422 2013-05-20 09:05:11,511 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) Current Used RAM: 1342177280 , Free RAM:15827361792
,Requested RAM: 536870912
39423 2013-05-20 09:05:11,511 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) CPU STATS after allocation: for host: 5, old used:
1000, old reserved: 0, actual total: 9572, total with overprovisioning: 9572;
new used:1500, reserved:0; requested cpu: 500,alloc_from_last:false
39424 2013-05-20 09:05:11,511 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) RAM STATS after allocation: for host: 5, old used:
1342177280, old reserved: 0, total: 17169539072; new used: 1879048192,
reserved: 0; requested mem: 536870912,alloc_from _last:false
39425 2013-05-20 09:05:11,517 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) VM is being created in podId: 2
39426 2013-05-20 09:05:11,523 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Lock is acquired for network id 209 as a part of
network implement
39427 2013-05-20 09:05:11,523 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Asking ExternalGuestNetworkGuru to implement
Ntwk[209|Guest|14]
39428 2013-05-20 09:05:11,690 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Creating a source nat ip for network Ntwk[209|Guest|14]
39429 2013-05-20 09:05:11,697 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) lock account 3 is acquired
39430 2013-05-20 09:05:11,748 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Releasing lock account 3
39431 2013-05-20 09:05:11,756 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Asking VirtualRouter to implemenet Ntwk[209|Guest|14]
39432 2013-05-20 09:05:11,767 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Lock is acquired for network id 209 as a part of router startup in
Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zo
ne(2)-Pod(2)-Cluster(2)-Host(5)-Storage(Volume(12|ROOT-->Pool(6))]
39433 2013-05-20 09:05:11,837 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Adding nic for Virtual Router in Guest network Ntwk[209|Guest|14]
39434 2013-05-20 09:05:11,843 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Adding nic for Virtual Router in Control network
39435 2013-05-20 09:05:11,849 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Found existing network configuration for offering
[Network Offering [3-Control-System-Control-Network]: Ntwk[207|Control|3]
39436 2013-05-20 09:05:11,849 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Releasing lock for Acct[1-system]
39437 2013-05-20 09:05:11,850 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Adding nic for Virtual Router in Public network
39438 2013-05-20 09:05:11,856 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Found existing network configuration for offering
[Network Offering [1-Public-System-Public-Network]: Ntwk[205|Public|1]
39439 2013-05-20 09:05:11,856 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Releasing lock for Acct[1-system]
39440 2013-05-20 09:05:11,866 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Creating the router 13 in datacenter
com.cloud.dc.DataCenterVO$$EnhancerByCGLIB$$4b80e605@2
39441 2013-05-20 09:05:11,866 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Allocating the domR with the hypervisor type VMware
39442 2013-05-20 09:05:11,872 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocating entries for VM: VM[DomainRouter|r-13-VM]
39443 2013-05-20 09:05:11,880 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocating nics for VM[DomainRouter|r-13-VM]
39444 2013-05-20 09:05:11,882 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Allocating nic for vm VM[DomainRouter|r-13-VM] in
network Ntwk[209|Guest|14] with requested profile
NicProfile[0-0-null-10.1.1.116-vlan://904
39445 2013-05-20 09:05:11,904 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-31:job-31) Service SecurityGroup is not supported in the network
id=209
39446 2013-05-20 09:05:11,908 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Allocating nic for vm VM[DomainRouter|r-13-VM] in
network Ntwk[207|Control|3] with requested profile null
39447 2013-05-20 09:05:11,923 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Allocating nic for vm VM[DomainRouter|r-13-VM] in
network Ntwk[205|Public|1] with requested profile
NicProfile[0-0-null-10.147.44.62-vlan://44
39448 2013-05-20 09:05:11,938 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocaing disks for VM[DomainRouter|r-13-VM]
39449 2013-05-20 09:05:11,948 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocation completed for VM: VM[DomainRouter|r-13-VM]
39450 2013-05-20 09:05:11,983 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Adding nic for Virtual Router in Guest network Ntwk[209|Guest|14]
39451 2013-05-20 09:05:11,995 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Adding nic for Virtual Router in Control network
39452 2013-05-20 09:05:12,003 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Found existing network configuration for offering
[Network Offering [3-Control-System-Control-Network]: Ntwk[207|Control|3]
39453 2013-05-20 09:05:12,003 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Releasing lock for Acct[1-system]
39454 2013-05-20 09:05:12,005 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Adding nic for Virtual Router in Public network
39455 2013-05-20 09:05:12,013 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Found existing network configuration for offering
[Network Offering [1-Public-System-Public-Network]: Ntwk[205|Public|1]
39456 2013-05-20 09:05:12,014 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Releasing lock for Acct[1-system]
39457 2013-05-20 09:05:12,020 INFO
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Use same MAC as previous RvR, the MAC is 06:6c:2a:00:00:0d
39458 2013-05-20 09:05:12,027 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Creating the router 14 in datacenter
com.cloud.dc.DataCenterVO$$EnhancerByCGLIB$$4b80e605@2
39459 2013-05-20 09:05:12,028 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Allocating the domR with the hypervisor type VMware
39460 2013-05-20 09:05:12,034 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocating entries for VM: VM[DomainRouter|r-14-VM]
39461 2013-05-20 09:05:12,041 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocating nics for VM[DomainRouter|r-14-VM]
39462 2013-05-20 09:05:12,042 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Allocating nic for vm VM[DomainRouter|r-14-VM] in
network Ntwk[209|Guest|14] with requested profile
NicProfile[0-0-null-10.1.1.98-vlan://904
39463 2013-05-20 09:05:12,071 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-31:job-31) Service SecurityGroup is not supported in the network
id=209
39464 2013-05-20 09:05:12,075 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Allocating nic for vm VM[DomainRouter|r-14-VM] in
network Ntwk[207|Control|3] with requested profile null
39465 2013-05-20 09:05:12,091 DEBUG [cloud.network.NetworkManagerImpl]
(Job-Executor-31:job-31) Allocating nic for vm VM[DomainRouter|r-14-VM] in
network Ntwk[205|Public|1] with requested profile
NicProfile[0-0-null-10.147.44.62-vlan://44
39466 2013-05-20 09:05:12,106 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocaing disks for VM[DomainRouter|r-14-VM]
39467 2013-05-20 09:05:12,117 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Allocation completed for VM: VM[DomainRouter|r-14-VM]
39611 2013-05-20 09:05:19,056 INFO [vmware.resource.VmwareResource]
(DirectAgent-249:10.147.40.11) Executing resource StartCommand:
{"vm":{"id":13,"name":"r-13-VM","bootloader":"HVM","type":"DomainRouter","cpus":1,"minSpeed":500,"maxSpeed":500,"minRam":134217728,"max
Ram":134217728,"arch":"i686","os":"Debian GNU/Linux 5.0
(32-bit)","bootArgs":" template=domP name=r-13-VM eth2ip=10.147.44.62
eth2mask=255.255.255.0 gateway=10.147.44.1 eth0ip=10.1.1.116
eth0mask=255.255.255.0 redundant_router=1 guestgw=10.1.1.1 guestbrd=10.1.1.
255 guestcidrsize=24 router_pr=100 domain=cs3cloud.internal
dhcprange=10.1.1.1 eth1ip=10.147.40.88 eth1mask=255.255.254.0
mgmtcidr=10.147.59.0/24 localgw=10.147.40.1 type=router disable_rp_filter=true
extra_pubnics=2 dns1=10.103.128.16 nic_macs=02:00:03:d6:00:02
|02:00:2a:8a:00:05|06:6c:2a:00:00:0d|02:00:56:54:00:01|02:00:10:d1:00:02","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"vncPassword":"28b1b1b36e34462a","params":{"nicAdapter":"E1000"},"uuid":"2403c36a-3e1f-4a44-948a-049644e8f873","disks":[{"id":13,
"name":"ROOT-13","mountPoint":"/export/home/swamy/primary.campo.vmw.1","path":"ROOT-13-13","size":2097152000,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"b5671f5d-c64c-36e7-9249-6d87010c7bac","deviceId":0}],"nics":[{"deviceId":2,"networ
kRateMbps":200,"defaultNic":true,"uuid":"4addb6ed-d5bc-4b29-9b0d-4ee3fa24a719","ip":"10.147.44.62","netmask":"255.255.255.0","gateway":"10.147.44.1","mac":"06:6c:2a:00:00:0d","dns1":"10.103.128.16","broadcastType":"Vlan","type":"Public","broadcastUri":"vlan://44
","isolationUri":"vlan://44","isSecurityGroupEnabled":false},{"deviceId":0,"networkRateMbps":200,"defaultNic":false,"uuid":"43ec010d-93d2-4625-8f64-76e4da797f72","ip":"10.1.1.116","netmask":"255.255.255.0","mac":"02:00:03:d6:00:02","dns1":"10.103.128.16","broadc
astType":"Vlan","type":"Guest","broadcastUri":"vlan://904","isolationUri":"vlan://904","isSecurityGroupEnabled":false},{"deviceId":1,"networkRateMbps":-1,"defaultNic":false,"uuid":"212b8db8-bb53-4dde-81d0-751be079dc1e","ip":"10.147.40.88","netmask":"255.255.254.
0","gateway":"10.147.40.1","mac":"02:00:2a:8a:00:05","broadcastType":"Native","type":"Control","isSecurityGroupEnabled":false},{"deviceId":3,"networkRateMbps":-1,"defaultNic":false,"ip":"0.0.0.0","netmask":"255.255.255.255","gateway":"10.147.44.1","mac":"02:00:5
6:54:00:01","dns1":"10.103.128.16","broadcastType":"Vlan","type":"Public","broadcastUri":"vlan://44","isolationUri":"vlan://44","isSecurityGroupEnabled":false},{"deviceId":4,"networkRateMbps":-1,"defaultNic":false,"ip":"0.0.0.0","netmask":"255.255.255.255","gate
way":"10.147.44.1","mac":"02:00:10:d1:00:02","dns1":"10.103.128.16","broadcastType":"Vlan","type":"Public","broadcastUri":"vlan://44","isolationUri":"vlan://44","isSecurityGroupEnabled":false}]},"hostIp":"10.147.40.11","wait":0}
39612 2013-05-20 09:05:19,057 DEBUG [vmware.resource.VmwareResource]
(DirectAgent-249:10.147.40.11) VM r-13-VM will be started with NIC device type:
E1000
39807 2013-05-20 09:06:48,252 DEBUG [agent.transport.Request]
(DirectAgent-249:null) Seq 5-2133262447: Processing: { Ans: , MgmtId:
7280707764394, via: 5, Ver: v1, Flags: 110,
[{"StartAnswer":{"vm":{"id":13,"name":"r-13-VM","bootloader":"HVM","type":"DomainRouter","c
pus":1,"minSpeed":500,"maxSpeed":500,"minRam":134217728,"maxRam":134217728,"arch":"i686","os":"Debian
GNU/Linux 5.0 (32-bit)","bootArgs":" template=domP name=r-13-VM
eth2ip=10.147.44.62 eth2mask=255.255.255.0 gateway=10.147.44.1
eth0ip=10.1.1.116 eth0mask=255.25 5.255.0 redundant_router=1
guestgw=10.1.1.1 guestbrd=10.1.1.255 guestcidrsize=24 router_pr=100
domain=cs3cloud.internal dhcprange=10.1.1.1 eth1ip=10.147.40.88
eth1mask=255.255.254.0 mgmtcidr=10.147.59.0/24 localgw=10.147.40.1 type=router
disable_rp_filter=true e xtra_pubnics=2 dns1=10.103.128.16
nic_macs=02:00:03:d6:00:02|02:00:2a:8a:00:05|06:6c:2a:00:00:0d|02:00:56:54:00:01|02:00:10:d1:00:02","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"vncPassword":"28b1b1b36e34462a","params":{"nicAdapter":"E1000"},"uui
d":"2403c36a-3e1f-4a44-948a-049644e8f873","disks":[{"id":13,"name":"ROOT-13","mountPoint":"/export/home/swamy/primary.campo.vmw.1","path":"ROOT-13-13","size":2097152000,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"b5671f5d-c64c-36e7-924
9-6d87010c7bac","deviceId":0}],"nics":[{"deviceId":2,"networkRateMbps":200,"defaultNic":true,"uuid":"4addb6ed-d5bc-4b29-9b0d-4ee3fa24a719","ip":"10.147.44.62","netmask":"255.255.255.0","gateway":"10.147.44.1","mac":"06:6c:2a:00:00:0d","dns1":"10.103.128.16","bro
adcastType":"Vlan","type":"Public","broadcastUri":"vlan://44","isolationUri":"vlan://44","isSecurityGroupEnabled":false},{"deviceId":0,"networkRateMbps":200,"defaultNic":false,"uuid":"43ec010d-93d2-4625-8f64-76e4da797f72","ip":"10.1.1.116","netmask":"255.255.255
.0","mac":"02:00:03:d6:00:02","dns1":"10.103.128.16","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://904","isolationUri":"vlan://904","isSecurityGroupEnabled":false},{"deviceId":1,"networkRateMbps":-1,"defaultNic":false,"uuid":"212b8db8-bb53-4dde-81
d0-751be079dc1e","ip":"10.147.40.88","netmask":"255.255.254.0","gateway":"10.147.40.1","mac":"02:00:2a:8a:00:05","broadcastType":"Native","type":"Control","isSecurityGroupEnabled":false},{"deviceId":3,"networkRateMbps":-1,"defaultNic":false,"ip":"0.0.0.0","netma
sk":"255.255.255.255","gateway":"10.147.44.1","mac":"02:00:56:54:00:01","dns1":"10.103.128.16","broadcastType":"Vlan","type":"Public","broadcastUri":"vlan://44","isolationUri":"vlan://44","isSecurityGroupEnabled":false},{"deviceId":4,"networkRateMbps":-1,"defaul
tNic":false,"ip":"0.0.0.0","netmask":"255.255.255.255","gateway":"10.147.44.1","mac":"02:00:10:d1:00:02","dns1":"10.103.128.16","broadcastType":"Vlan","type":"Public","broadcastUri":"vlan://44","isolationUri":"vlan://44","isSecurityGroupEnabled":false}]},"result
":true,"wait":0}},{"check.CheckSshAnswer":{"result":true,"wait":0}},{"GetDomRVersionAnswer":{"templateVersion":"Cloudstack
Release 4.2.0 Tue Apr 16 04:09:58 UTC
2013","scriptsVersion":"12ed1584c0a9d218d203bdb1f85433f7\n","result":true,"details":"Cloudstack
Relea se 4.2.0 Tue Apr 16 04:09:58 UTC
2013&12ed1584c0a9d218d203bdb1f85433f7\n","wait":0}},{"NetworkUsageAnswer":{"routerName":"r-13-VM","bytesSent":0,"bytesReceived":0,"result":true,"details":"","wait":0}},{"routing.IpAssocAnswer":{"results":["10.147.44.62
- success"
],"result":true,"wait":0}},{"Answer":{"result":false,"details":"deleteipAlias
failed due to bash: /root/deleteipAlias.sh: No such file or
directory\n","wait":0}}] }
39808 2013-05-20 09:06:48,253 DEBUG [agent.transport.Request]
(Job-Executor-31:job-31) Seq 5-2133262447: Received: { Ans: , MgmtId:
7280707764394, via: 5, Ver: v1, Flags: 110, { StartAnswer, CheckSshAnswer,
GetDomRVersionAnswer, NetworkUsageAnswer, IpAssocAnswer, Ans wer } }
39812 2013-05-20 09:06:48,316 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-31:job-31)
Starting router VM[DomainRouter|r-14-VM]
39813 2013-05-20 09:06:48,323 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-31:job-31) VM state transitted from :Stopped to Starting with
event: StartRequestedvm's original host id: null new host id: null host id
before state transition: null
39814 2013-05-20 09:06:48,324 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Successfully transitioned to start state for
VM[DomainRouter|r-14-VM] reservation id = 65b3144f-83ff-4ce7-9131-9e7b68273916
39815 2013-05-20 09:06:48,330 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Trying to deploy VM, vm has dcId: 2 and podId: null
39816 2013-05-20 09:06:48,330 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-31:job-31) Deploy avoids pods: [2], clusters: null, hosts: null
39817 2013-05-20 09:06:48,334 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Deploy
avoids pods: [2], clusters: null, hosts: null
39818 2013-05-20 09:06:48,336 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31)
DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_4c69dc2f@7f9dcbc7
39819 2013-05-20 09:06:48,336 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Trying to
allocate a host and storage pools from dc:2, pod:null,cluster:null, requested
cpu: 500, requested ram: 134217728
39820 2013-05-20 09:06:48,336 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-31) Is ROOT
volume READY (pool already allocated)?: No
39821 2013-05-20 09:06:48,336 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-31:job-31) Searching all possible resources under this Zone: 2
39822 2013-05-20 09:06:48,338 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-31:job-31) Listing clusters in order of aggregate capacity, that
have (atleast one host with) enough CPU and RAM capacity under this Zone: 2
Attaching all the required logs along with db dump.
--
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
