Uploaded image for project: 'CloudStack'
  1. CloudStack
  2. CLOUDSTACK-7931

Setting Null for global network throttling params doesn't trigger suitable error, fails silently

    XMLWordPrintableJSON

Details

    • Bug
    • Status: Resolved
    • Critical
    • Resolution: Fixed
    • None
    • 4.5.0
    • None
    • Security Level: Public (Anyone can view this level - this is the default.)
    • None

    Description

      Set global configs network.throttling.rate and vm.network.throttling.rate to NULL value.
      Then launch VM in a new network

      Result
      =====
      VM fails to launch but it fails without any ERROR logs or suitable exceptions.
      A corresponding INFO log seems to have nothing but "null"

      Generally, for few global configs NULL is an acceptable value in some cases. If this is not the case, then we should not allow to set such a value for the config. The API should error out suitably. This is one issue.

      Further, it should throw an appropriate error when the deploy VM fails to design network. The error in this case is not handled suitably and there's nothing in ERROR logs as well.

      Looking at the below logs, it's impossible to figure out the reason for the failure of deploy VM. So at some point, if a user inadvertently sets it to NULL, neither does the updateConfiguration API result in error nor does the deployVirtualMachine throw a suitable error.

      Here's the log:

      2014-11-13 13:29:15,584 DEBUG [c.c.a.ApiServlet] (catalina-exec-18:ctx-285ce7d9) ===START=== 10.144.7.5 – GET command=createNetwork&response=json&sessionkey=6ZKk3l0f4pdKU1yfDZxwF31YgCM%3D&networkOfferingId=e8746c6b-e945-4084-9290-37cea253e262&name=newtest1&displayText=newtest1&zoneId=b642a92a-3480-4818-99bf-6546a28df624&_=1415866216789
      2014-11-13 13:29:15,617 DEBUG [o.a.c.n.c.m.ContrailGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network
      2014-11-13 13:29:15,617 DEBUG [c.c.n.g.MidoNetGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) design called
      2014-11-13 13:29:15,618 DEBUG [c.c.n.g.MidoNetGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network, the physical isolation type is not MIDO
      2014-11-13 13:29:15,619 DEBUG [c.c.n.g.NiciraNvpGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network
      2014-11-13 13:29:15,620 DEBUG [o.a.c.n.o.OpendaylightGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network
      2014-11-13 13:29:15,621 DEBUG [c.c.n.g.OvsGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network
      2014-11-13 13:29:15,644 DEBUG [o.a.c.n.g.SspGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) SSP not configured to be active
      2014-11-13 13:29:15,645 DEBUG [c.c.n.g.BrocadeVcsGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network
      2014-11-13 13:29:15,646 DEBUG [c.c.n.g.NuageVspGuestNetworkGuru] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Refusing to design this network
      2014-11-13 13:29:15,648 DEBUG [o.a.c.e.o.NetworkOrchestrator] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) Releasing lock for Acct[467a4f66-698f-11e4-be18-42407779c24b-admin]
      2014-11-13 13:29:15,688 DEBUG [c.c.a.ApiServlet] (catalina-exec-18:ctx-285ce7d9 ctx-5245ccb7) ===END=== 10.144.7.5 – GET command=createNetwork&response=json&sessionkey=6ZKk3l0f4pdKU1yfDZxwF31YgCM%3D&networkOfferingId=e8746c6b-e945-4084-9290-37cea253e262&name=newtest1&displayText=newtest1&zoneId=b642a92a-3480-4818-99bf-6546a28df624&_=1415866216789
      2014-11-13 13:29:15,727 DEBUG [c.c.a.ApiServlet] (catalina-exec-9:ctx-54781545) ===START=== 10.144.7.5 – GET command=deployVirtualMachine&response=json&sessionkey=6ZKk3l0f4pdKU1yfDZxwF31YgCM%3D&zoneid=b642a92a-3480-4818-99bf-6546a28df624&templateid=f7df5ef0-698e-11e4-be18-42407779c24b&hypervisor=XenServer&serviceofferingid=04840780-04d0-4b41-847a-dda08ad460f4&iptonetworklist%5B0%5D.networkid=c0e24f7a-fe03-4a3b-a11e-ab29150b803b&displayname=throttlingvm1&name=throttlingvm1&_=1415866216945
      2014-11-13 13:29:15,753 DEBUG [c.c.n.NetworkModelImpl] (catalina-exec-9:ctx-54781545 ctx-e87f4810) Service SecurityGroup is not supported in the network id=209
      2014-11-13 13:29:15,777 DEBUG [c.c.v.UserVmManagerImpl] (catalina-exec-9:ctx-54781545 ctx-e87f4810) Allocating in the DB for vm
      2014-11-13 13:29:15,793 DEBUG [c.c.v.VirtualMachineManagerImpl] (catalina-exec-9:ctx-54781545 ctx-e87f4810) Allocating entries for VM: VM[User|i-2-22-VM]
      2014-11-13 13:29:15,794 DEBUG [c.c.v.VirtualMachineManagerImpl] (catalina-exec-9:ctx-54781545 ctx-e87f4810) Allocating nics for VM[User|i-2-22-VM]
      2014-11-13 13:29:15,794 DEBUG [o.a.c.e.o.NetworkOrchestrator] (catalina-exec-9:ctx-54781545 ctx-e87f4810) Allocating nic for vm VM[User|i-2-22-VM] in network Ntwk[209|Guest|8] with requested profile NicProfile[0-0-null-null-null
      2014-11-13 13:29:15,808 DEBUG [c.c.u.d.T.Transaction] (catalina-exec-9:ctx-54781545 ctx-e87f4810) Rolling back the transaction: Time = 41 Name = catalina-exec-9; called by -TransactionLegacy.rollback:902-TransactionLegacy.removeUpTo:845-TransactionLegacy.close:669-Transaction.execute:49-NetworkOrchestrator.allocate:711-VirtualMachineManagerImpl$1.doInTransactionWithoutResult:396-TransactionCallbackWithExceptionNoReturn.doInTransaction:25-TransactionCallbackWithExceptionNoReturn.doInTransaction:21-Transaction.execute:45-VirtualMachineManagerImpl.allocate:388-CloudOrchestrator.createVirtualMachine:236-UserVmManagerImpl$3.doInTransaction:3119
      2014-11-13 13:29:15,817 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-3:null) SeqA 2-28534: Processing Seq 2-28534: { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":

      {"_proxyVmId":1,"_loadInfo":" {\n \"connections\": []\n}

      ","wait":0}}] }
      2014-11-13 13:29:15,819 INFO [c.c.a.ApiServer] (catalina-exec-9:ctx-54781545 ctx-e87f4810) null
      2014-11-13 13:29:15,821 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-3:null) SeqA 2-28534: Sending Seq 2-28534: { Ans: , MgmtId: 72844649808459, via: 2, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
      2014-11-13 13:29:15,822 DEBUG [c.c.a.ApiServlet] (catalina-exec-9:ctx-54781545 ctx-e87f4810) ===END=== 10.144.7.5 – GET command=deployVirtualMachine&response=json&sessionkey=6ZKk3l0f4pdKU1yfDZxwF31YgCM%3D&zoneid=b642a92a-3480-4818-99bf-6546a28df624&templateid=f7df5ef0-698e-11e4-be18-42407779c24b&hypervisor=XenServer&serviceofferingid=04840780-04d0-4b41-847a-dda08ad460f4&iptonetworklist%5B0%5D.networkid=c0e24f7a-fe03-4a3b-a11e-ab29150b803b&displayname=throttlingvm1&name=throttlingvm1&_=1415866216945
      2014-11-13 13:29:20,818 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-4:null) SeqA 2-28535: Processing Seq 2-28535: { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":

      {"_proxyVmId":1,"_loadInfo":" {\n \"connections\": []\n}

      ","wait":0}}] }
      2014-11-13 13:29:20,823 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-4:null) SeqA 2-28535: Sending Seq 2-28535: { Ans: , MgmtId: 72844649808459, via: 2, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
      2014-11-13 13:29:25,159 DEBUG [c.c.a.AlertManagerImpl] (CapacityChecker:ctx-632a5f21) Running Capacity Checker ...
      2014-11-13 13:29:25,159 DEBUG [c.c.a.AlertManagerImpl] (CapacityChecker:ctx-632a5f21) recalculating system capacity
      2014-11-13 13:29:25,159 DEBUG [c.c.a.AlertManagerImpl] (CapacityChecker:ctx-632a5f21) Executing cpu/ram capacity update
      2014-11-13 13:29:25,170 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) Found 2 VMs on host 1
      2014-11-13 13:29:25,173 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) Found 0 VM, not running on host 1
      2014-11-13 13:29:25,174 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) No need to calibrate cpu capacity, host:1 usedCpu: 1000 reservedCpu: 0
      2014-11-13 13:29:25,174 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) No need to calibrate memory capacity, host:1 usedMem: 1610612736 reservedMem: 0
      2014-11-13 13:29:25,188 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) Found 2 VMs on host 4
      2014-11-13 13:29:25,194 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) Found 0 VM, not running on host 4
      2014-11-13 13:29:25,196 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) No need to calibrate cpu capacity, host:4 usedCpu: 4500 reservedCpu: 0
      2014-11-13 13:29:25,196 DEBUG [c.c.c.CapacityManagerImpl] (CapacityChecker:ctx-632a5f21) No need to calibrate memory capacity, host:4 usedMem: 1610612736 reservedMem: 0
      2014-11-13 13:29:25,201 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (HostReservationReleaseChecker:ctx-d94e4eee) Checking if any host reservation can be released ...
      2014-11-13 13:29:25,204 INFO [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-ad293aec) Begin cleanup expired async-jobs
      2014-11-13 13:29:25,229 DEBUG [c.c.a.AlertManagerImpl] (CapacityChecker:ctx-632a5f21) Done executing cpu/ram capacity update
      2014-11-13 13:29:25,229 DEBUG [c.c.a.AlertManagerImpl] (CapacityChecker:ctx-632a5f21) Executing storage capacity update

      Attachments

        Activity

          People

            anshulg Anshul Gangwar
            anshulg Anshul Gangwar
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: