You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "venkata swamybabu budumuru (JIRA)" <ji...@apache.org> on 2013/05/20 12:43:15 UTC
[jira] [Created] (CLOUDSTACK-2580) [RVR] Failed to deploy Redundant
Router VMs
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