[ https://issues.apache.org/jira/browse/CLOUDSTACK-2580?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Prachi Damle resolved CLOUDSTACK-2580. -------------------------------------- Resolution: Fixed > [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 > Assignee: Prachi Damle > Priority: Blocker > Fix For: 4.2.0 > > Attachments: additional_mgmt_server.log, logs.tgz > > > 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