Skip to content

Instantly share code, notes, and snippets.

@sm97

sm97/management-server.log

Created August 5, 2020 17:09
Show Gist options
  • Save sm97/eab813ec255ee4dc07c99b46e4357737 to your computer and use it in GitHub Desktop.
Save sm97/eab813ec255ee4dc07c99b46e4357737 to your computer and use it in GitHub Desktop.
Download ZIP
some description
Raw
management-server.log
2020-08-05 19:40:57,215 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32214:ctx-650b7760) (logid:4ebb8691) ===START=== 146.253.34.63 -- GET name=kubetest&description=kubetest&zoneid=1be9f1f9-e6d2-4133-942d-8582d645ca8f&kubernetesversionid=4841cf7a-9033-462f-9bfe-f7425303daf2&serviceofferingid=3e833db6-175c-42b6-be5e-632168d453db&size=2&noderootdisksize=30&networkid=02e37ff6-48ab-4c87-888e-09f248845fd0&keypair=kirill.pushkin@example.com&command=createKubernetesCluster&response=json
2020-08-05 19:40:57,222 DEBUG [c.c.a.ApiServer] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) CIDRs from which account 'Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com]' is allowed to perform API calls: 0.0.0.0/0,::/0
2020-08-05 19:40:57,243 DEBUG [c.c.u.AccountManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,246 DEBUG [c.c.u.AccountManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,249 DEBUG [c.c.u.AccountManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Access to Ntwk[211|Guest|7] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,281 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 1
2020-08-05 19:40:57,282 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking if host: 2 has enough capacity for requested CPU: 4000 and requested RAM: 4294967296 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,283 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,283 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free CPU: 123708 , Requested CPU: 4000
2020-08-05 19:40:57,283 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free RAM: 358822576128 , Requested RAM: 4294967296
2020-08-05 19:40:57,283 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Host has enough CPU and RAM available
2020-08-05 19:40:57,283 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:4000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,283 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 4294967296,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,283 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=4000 RAM=4294967296
2020-08-05 19:40:57,284 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 2
2020-08-05 19:40:57,284 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking if host: 2 has enough capacity for requested CPU: 8000 and requested RAM: 8589934592 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,285 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,285 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free CPU: 123708 , Requested CPU: 8000
2020-08-05 19:40:57,285 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free RAM: 358822576128 , Requested RAM: 8589934592
2020-08-05 19:40:57,285 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Host has enough CPU and RAM available
2020-08-05 19:40:57,285 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:8000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,285 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 8589934592,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,285 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=8000 RAM=8589934592
2020-08-05 19:40:57,286 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 3
2020-08-05 19:40:57,287 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking if host: 2 has enough capacity for requested CPU: 12000 and requested RAM: 12884901888 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,288 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,288 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free CPU: 123708 , Requested CPU: 12000
2020-08-05 19:40:57,288 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free RAM: 358822576128 , Requested RAM: 12884901888
2020-08-05 19:40:57,288 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Host has enough CPU and RAM available
2020-08-05 19:40:57,288 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:12000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,288 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 12884901888,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,288 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=12000 RAM=12884901888
2020-08-05 19:40:57,289 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 4
2020-08-05 19:40:57,289 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Checking if host: 2 has enough capacity for requested CPU: 16000 and requested RAM: 17179869184 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,290 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,290 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free CPU: 123708 , Requested CPU: 16000
2020-08-05 19:40:57,290 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Free RAM: 358822576128 , Requested RAM: 17179869184
2020-08-05 19:40:57,290 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Host has enough CPU and RAM available
2020-08-05 19:40:57,290 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:16000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,290 DEBUG [c.c.c.CapacityManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 17179869184,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,290 DEBUG [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=16000 RAM=17179869184
2020-08-05 19:40:57,290 INFO [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Suitable hosts found in datacenter ID: 1be9f1f9-e6d2-4133-942d-8582d645ca8f, creating deployment destination
2020-08-05 19:40:57,299 INFO [c.c.k.c.KubernetesClusterManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) Kubernetes cluster name: kubetest and ID: ef124fc7-6eee-4df9-99d0-3e8a3129efaf has been created
2020-08-05 19:40:57,307 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:2217690b) Add job-3438 into job monitoring
2020-08-05 19:40:57,320 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) submit async job-3438, details: AsyncJobVO {id:3438, userId: 9, accountId: 9, instanceType: VirtualMachine, instanceId: 16, cmd: org.apache.cloudstack.api.command.user.kubernetes.cluster.CreateKubernetesClusterCmd, cmdInfo: {"keypair":"kirill.pushkin@example.com","description":"kubetest","httpmethod":"GET","ctxAccountId":"9","uuid":"ef124fc7-6eee-4df9-99d0-3e8a3129efaf","cmdEventType":"KUBERNETES.CLUSTER.CREATE","kubernetesversionid":"4841cf7a-9033-462f-9bfe-f7425303daf2","size":"2","serviceofferingid":"3e833db6-175c-42b6-be5e-632168d453db","response":"json","ctxUserId":"9","name":"kubetest","zoneid":"1be9f1f9-e6d2-4133-942d-8582d645ca8f","networkid":"02e37ff6-48ab-4c87-888e-09f248845fd0","ctxStartEventId":"5814","id":"16","ctxDetails":"{\"interface com.cloud.kubernetes.version.KubernetesSupportedVersion\":\"4841cf7a-9033-462f-9bfe-f7425303daf2\",\"interface com.cloud.offering.ServiceOffering\":\"3e833db6-175c-42b6-be5e-632168d453db\",\"interface com.cloud.dc.DataCenter\":\"1be9f1f9-e6d2-4133-942d-8582d645ca8f\",\"interface com.cloud.network.Network\":\"02e37ff6-48ab-4c87-888e-09f248845fd0\"}","noderootdisksize":"30"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 46783616671, completeMsid: null, lastUpdated: null, lastPolled: null, created: null, removed: null}
2020-08-05 19:40:57,321 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32214:ctx-650b7760 ctx-295292e8) (logid:4ebb8691) ===END=== 146.253.34.63 -- GET name=kubetest&description=kubetest&zoneid=1be9f1f9-e6d2-4133-942d-8582d645ca8f&kubernetesversionid=4841cf7a-9033-462f-9bfe-f7425303daf2&serviceofferingid=3e833db6-175c-42b6-be5e-632168d453db&size=2&noderootdisksize=30&networkid=02e37ff6-48ab-4c87-888e-09f248845fd0&keypair=kirill.pushkin@example.com&command=createKubernetesCluster&response=json
2020-08-05 19:40:57,322 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Executing AsyncJobVO {id:3438, userId: 9, accountId: 9, instanceType: VirtualMachine, instanceId: 16, cmd: org.apache.cloudstack.api.command.user.kubernetes.cluster.CreateKubernetesClusterCmd, cmdInfo: {"keypair":"kirill.pushkin@example.com","description":"kubetest","httpmethod":"GET","ctxAccountId":"9","uuid":"ef124fc7-6eee-4df9-99d0-3e8a3129efaf","cmdEventType":"KUBERNETES.CLUSTER.CREATE","kubernetesversionid":"4841cf7a-9033-462f-9bfe-f7425303daf2","size":"2","serviceofferingid":"3e833db6-175c-42b6-be5e-632168d453db","response":"json","ctxUserId":"9","name":"kubetest","zoneid":"1be9f1f9-e6d2-4133-942d-8582d645ca8f","networkid":"02e37ff6-48ab-4c87-888e-09f248845fd0","ctxStartEventId":"5814","id":"16","ctxDetails":"{\"interface com.cloud.kubernetes.version.KubernetesSupportedVersion\":\"4841cf7a-9033-462f-9bfe-f7425303daf2\",\"interface com.cloud.offering.ServiceOffering\":\"3e833db6-175c-42b6-be5e-632168d453db\",\"interface com.cloud.dc.DataCenter\":\"1be9f1f9-e6d2-4133-942d-8582d645ca8f\",\"interface com.cloud.network.Network\":\"02e37ff6-48ab-4c87-888e-09f248845fd0\"}","noderootdisksize":"30"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 46783616671, completeMsid: null, lastUpdated: null, lastPolled: null, created: null, removed: null}
2020-08-05 19:40:57,333 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,336 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,341 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to Ntwk[211|Guest|7] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,345 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to com.cloud.kubernetes.cluster.KubernetesClusterVO$$EnhancerByCGLIB$$3c0ec116@5c90236c granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,351 INFO [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Starting Kubernetes cluster ID: ef124fc7-6eee-4df9-99d0-3e8a3129efaf
2020-08-05 19:40:57,356 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking deployment destination for Kubernetes cluster ID: ef124fc7-6eee-4df9-99d0-3e8a3129efaf in zone ID: 1be9f1f9-e6d2-4133-942d-8582d645ca8f
2020-08-05 19:40:57,359 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 1
2020-08-05 19:40:57,359 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if host: 2 has enough capacity for requested CPU: 4000 and requested RAM: 4294967296 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,360 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,360 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free CPU: 123708 , Requested CPU: 4000
2020-08-05 19:40:57,360 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free RAM: 358822576128 , Requested RAM: 4294967296
2020-08-05 19:40:57,360 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Host has enough CPU and RAM available
2020-08-05 19:40:57,360 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:4000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,360 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 4294967296,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,360 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=4000 RAM=4294967296
2020-08-05 19:40:57,362 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 2
2020-08-05 19:40:57,362 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if host: 2 has enough capacity for requested CPU: 8000 and requested RAM: 8589934592 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,363 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,363 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free CPU: 123708 , Requested CPU: 8000
2020-08-05 19:40:57,363 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free RAM: 358822576128 , Requested RAM: 8589934592
2020-08-05 19:40:57,363 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Host has enough CPU and RAM available
2020-08-05 19:40:57,363 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:8000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,363 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 8589934592,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,363 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=8000 RAM=8589934592
2020-08-05 19:40:57,364 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 3
2020-08-05 19:40:57,364 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if host: 2 has enough capacity for requested CPU: 12000 and requested RAM: 12884901888 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,365 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,365 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free CPU: 123708 , Requested CPU: 12000
2020-08-05 19:40:57,365 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free RAM: 358822576128 , Requested RAM: 12884901888
2020-08-05 19:40:57,365 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Host has enough CPU and RAM available
2020-08-05 19:40:57,365 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:12000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,365 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 12884901888,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,365 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=12000 RAM=12884901888
2020-08-05 19:40:57,366 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for capacity already reserved 4
2020-08-05 19:40:57,367 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if host: 2 has enough capacity for requested CPU: 16000 and requested RAM: 17179869184 , cpuOverprovisioningFactor: 1.0
2020-08-05 19:40:57,368 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Hosts's actual total CPU: 145208 and CPU after applying overprovisioning: 145208
2020-08-05 19:40:57,368 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free CPU: 123708 , Requested CPU: 16000
2020-08-05 19:40:57,368 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Free RAM: 358822576128 , Requested RAM: 17179869184
2020-08-05 19:40:57,368 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Host has enough CPU and RAM available
2020-08-05 19:40:57,368 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc CPU from host: 2, used: 21500, reserved: 0, actual total: 145208, total with overprovisioning: 145208; requested cpu:16000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,368 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) STATS: Can alloc MEM from host: 2, used: 35970351104, reserved: 0, total: 394792927232; requested mem: 17179869184,alloc_from_last_host?:false ,considerReservedCapacity?: true
2020-08-05 19:40:57,368 DEBUG [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Found host ID: 9230b41d-5bcd-4f3b-8d55-190489e257ec for with enough capacity, CPU=16000 RAM=17179869184
2020-08-05 19:40:57,368 INFO [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Suitable hosts found in datacenter ID: 1be9f1f9-e6d2-4133-942d-8582d645ca8f, creating deployment destination
2020-08-05 19:40:57,370 DEBUG [o.a.c.e.o.NetworkOrchestrator] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Starting network Ntwk[211|Guest|7]...
2020-08-05 19:40:57,373 DEBUG [o.a.c.e.o.NetworkOrchestrator] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Network id=211 is already implemented
2020-08-05 19:40:57,373 INFO [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Network ID: 02e37ff6-48ab-4c87-888e-09f248845fd0 is started for the Kubernetes cluster ID: ef124fc7-6eee-4df9-99d0-3e8a3129efaf
2020-08-05 19:40:57,376 WARN [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Unable to retrieve VMs for Kubernetes cluster ID: ef124fc7-6eee-4df9-99d0-3e8a3129efaf
2020-08-05 19:40:57,376 WARN [c.c.k.c.a.KubernetesClusterActionWorker] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Unable to retrieve master VM for Kubernetes cluster ID: ef124fc7-6eee-4df9-99d0-3e8a3129efaf
2020-08-05 19:40:57,485 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32221:ctx-5611894d) (logid:7200b5cd) ===START=== 146.253.34.63 -- GET jobId=fef53aca-d97c-4445-abc8-7214a7f144f2&command=queryAsyncJobResult&response=json
2020-08-05 19:40:57,493 DEBUG [c.c.a.ApiServer] (qtp1710989308-32221:ctx-5611894d ctx-83b40047) (logid:7200b5cd) CIDRs from which account 'Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com]' is allowed to perform API calls: 0.0.0.0/0,::/0
2020-08-05 19:40:57,506 DEBUG [c.c.u.AccountManagerImpl] (qtp1710989308-32221:ctx-5611894d ctx-83b40047) (logid:7200b5cd) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:57,523 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32219:ctx-0a03d3fb) (logid:9a8dc4b7) ===START=== 146.253.34.63 -- GET listall=true&page=1&pagesize=20&command=listKubernetesClusters&response=json
2020-08-05 19:40:57,529 DEBUG [c.c.a.ApiServer] (qtp1710989308-32219:ctx-0a03d3fb ctx-6dfbea44) (logid:9a8dc4b7) CIDRs from which account 'Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com]' is allowed to perform API calls: 0.0.0.0/0,::/0
2020-08-05 19:40:57,546 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32219:ctx-0a03d3fb ctx-6dfbea44) (logid:9a8dc4b7) ===END=== 146.253.34.63 -- GET listall=true&page=1&pagesize=20&command=listKubernetesClusters&response=json
2020-08-05 19:40:57,575 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32221:ctx-5611894d ctx-83b40047) (logid:7200b5cd) ===END=== 146.253.34.63 -- GET jobId=fef53aca-d97c-4445-abc8-7214a7f144f2&command=queryAsyncJobResult&response=json
2020-08-05 19:40:58,097 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:58,101 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] to com.cloud.service.ServiceOfferingVO$$EnhancerByCGLIB$$444baa64@65d08772 by AffinityGroupAccessChecker
2020-08-05 19:40:58,102 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] to null by AffinityGroupAccessChecker
2020-08-05 19:40:58,108 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:58,116 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if amount of resources of Type = 'user_vm' for Account Name = kirill.pushkin@example.com in Domain Id = 2 is exceeded: Account Resource Limit = 10, Current Account Resource Amount = 1, Requested Resource Amount = 1.
2020-08-05 19:40:58,117 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if domain resource limits of Type 'user_vm' for Domain Id = 2 is exceeded: Domain Resource Limit = -1, Current Domain Resource Amount = 4, Requested Resource Amount = 1.
2020-08-05 19:40:58,121 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if amount of resources of Type = 'cpu' for Account Name = kirill.pushkin@example.com in Domain Id = 2 is exceeded: Account Resource Limit = 10, Current Account Resource Amount = 2, Requested Resource Amount = 2.
2020-08-05 19:40:58,122 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if domain resource limits of Type 'cpu' for Domain Id = 2 is exceeded: Domain Resource Limit = 112, Current Domain Resource Amount = 11, Requested Resource Amount = 2.
2020-08-05 19:40:58,125 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if amount of resources of Type = 'memory' for Account Name = kirill.pushkin@example.com in Domain Id = 2 is exceeded: Account Resource Limit = 16384, Current Account Resource Amount = 4096, Requested Resource Amount = 4096.
2020-08-05 19:40:58,126 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if domain resource limits of Type 'memory' for Domain Id = 2 is exceeded: Domain Resource Limit = 778240, Current Domain Resource Amount = 21504, Requested Resource Amount = 4096.
2020-08-05 19:40:58,130 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if amount of resources of Type = 'volume' for Account Name = kirill.pushkin@example.com in Domain Id = 2 is exceeded: Account Resource Limit = 10, Current Account Resource Amount = 1, Requested Resource Amount = 1.
2020-08-05 19:40:58,130 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if domain resource limits of Type 'volume' for Domain Id = 2 is exceeded: Domain Resource Limit = -1, Current Domain Resource Amount = 5, Requested Resource Amount = 1.
2020-08-05 19:40:58,134 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if amount of resources of Type = 'primary_storage' for Account Name = kirill.pushkin@example.com in Domain Id = 2 is exceeded: Account Resource Limit = 107374182400, Current Account Resource Amount = 32212254720, Requested Resource Amount = 32212254720.
2020-08-05 19:40:58,135 DEBUG [c.c.r.ResourceLimitManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Checking if domain resource limits of Type 'primary_storage' for Domain Id = 2 is exceeded: Domain Resource Limit = 7280965999132672, Current Domain Resource Amount = 137438953472, Requested Resource Amount = 32212254720.
2020-08-05 19:40:58,138 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Access to Tmpl[241-VHD-241-2-1666096b-aa90-3b43-af53-75cf72214d1a granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:40:58,143 DEBUG [c.c.n.NetworkModelImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438 ctx-a3b080d4) (logid:fef53aca) Service SecurityGroup is not supported in the network id=211
2020-08-05 19:40:58,153 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Unexpected exception while executing org.apache.cloudstack.api.command.user.kubernetes.cluster.CreateKubernetesClusterCmd
java.lang.NullPointerException
at com.cloud.vm.UserVmManagerImpl.createVirtualMachine(UserVmManagerImpl.java:3764)
at com.cloud.vm.UserVmManagerImpl.createAdvancedVirtualMachine(UserVmManagerImpl.java:3405)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:344)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:198)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:163)
at org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:107)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:175)
at com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:175)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:95)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:212)
at com.sun.proxy.$Proxy176.createAdvancedVirtualMachine(Unknown Source)
at com.cloud.kubernetes.cluster.actionworkers.KubernetesClusterStartWorker.createKubernetesMaster(KubernetesClusterStartWorker.java:203)
at com.cloud.kubernetes.cluster.actionworkers.KubernetesClusterStartWorker.provisionKubernetesClusterMasterVm(KubernetesClusterStartWorker.java:271)
at com.cloud.kubernetes.cluster.actionworkers.KubernetesClusterStartWorker.startKubernetesClusterOnCreate(KubernetesClusterStartWorker.java:498)
at com.cloud.kubernetes.cluster.KubernetesClusterManagerImpl.startKubernetesCluster(KubernetesClusterManagerImpl.java:1057)
at org.apache.cloudstack.api.command.user.kubernetes.cluster.CreateKubernetesClusterCmd.execute(CreateKubernetesClusterCmd.java:273)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:156)
at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:603)
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:551)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2020-08-05 19:40:58,156 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Complete async job-3438, jobStatus: FAILED, resultCode: 530, result: org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530}
2020-08-05 19:40:58,156 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Publish async job-3438 complete on message bus
2020-08-05 19:40:58,156 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Wake up jobs related to job-3438
2020-08-05 19:40:58,156 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Update db status for job-3438
2020-08-05 19:40:58,157 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Wake up jobs joined with job-3438 and disjoin all subjobs created from job- 3438
2020-08-05 19:40:58,168 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Done executing org.apache.cloudstack.api.command.user.kubernetes.cluster.CreateKubernetesClusterCmd for job-3438
2020-08-05 19:40:58,168 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-46:ctx-00e8fa57 job-3438) (logid:fef53aca) Remove job-3438 from job monitoring
2020-08-05 19:40:58,438 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-206:ctx-6fda11fe) (logid:e6b4b2b3) Seq 2-2120632474538095764: Response Received:
2020-08-05 19:40:58,438 DEBUG [c.c.a.t.Request] (StatsCollector-5:ctx-193db09e) (logid:e6b4b2b3) Seq 2-2120632474538095764: Received: { Ans: , MgmtId: 46783616671, via: 2(xen3), Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2020-08-05 19:40:58,440 DEBUG [c.c.h.o.r.Ovm3HypervisorGuru] (StatsCollector-5:ctx-193db09e) (logid:e6b4b2b3) getCommandHostDelegation: class com.cloud.agent.api.GetStorageStatsCommand
2020-08-05 19:40:58,440 DEBUG [c.c.h.XenServerGuru] (StatsCollector-5:ctx-193db09e) (logid:e6b4b2b3) We are returning the default host to execute commands because the command is not of Copy type.
2020-08-05 19:40:58,442 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-410:ctx-501c583e) (logid:ec5987de) Seq 3-3554184530925466093: Executing request
2020-08-05 19:40:59,223 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-410:ctx-501c583e) (logid:e6b4b2b3) Seq 3-3554184530925466093: Response Received:
2020-08-05 19:40:59,223 DEBUG [c.c.a.t.Request] (StatsCollector-5:ctx-193db09e) (logid:e6b4b2b3) Seq 3-3554184530925466093: Received: { Ans: , MgmtId: 46783616671, via: 3(xen1), Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2020-08-05 19:40:59,688 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-407:ctx-310859a1) (logid:b16b2415) Seq 1-3540955207020065559: Executing request
2020-08-05 19:40:59,828 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-407:ctx-310859a1) (logid:1cf4d37e) Vm cpu utilization 0.19388888888888883
2020-08-05 19:40:59,829 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-407:ctx-310859a1) (logid:1cf4d37e) Vm cpu utilization 0.7177777777777777
2020-08-05 19:40:59,829 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-407:ctx-310859a1) (logid:1cf4d37e) Vm cpu utilization 0.21166666666666664
2020-08-05 19:40:59,829 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-407:ctx-310859a1) (logid:1cf4d37e) Vm cpu utilization 1.514722222222222
2020-08-05 19:40:59,829 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-407:ctx-310859a1) (logid:1cf4d37e) Vm cpu utilization 2.2134259259259257
2020-08-05 19:40:59,829 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-407:ctx-310859a1) (logid:1cf4d37e) Seq 1-3540955207020065559: Response Received:
2020-08-05 19:40:59,829 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-f61b9046) (logid:1cf4d37e) Seq 1-3540955207020065559: Received: { Ans: , MgmtId: 46783616671, via: 1(xen2), Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2020-08-05 19:40:59,849 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-104:ctx-dd172354) (logid:fc22e6c6) Seq 2-2120632474538095765: Executing request
2020-08-05 19:40:59,907 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-104:ctx-dd172354) (logid:1cf4d37e) Vm cpu utilization 0.27597222222222223
2020-08-05 19:40:59,907 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-104:ctx-dd172354) (logid:1cf4d37e) Vm cpu utilization 0.6987499999999999
2020-08-05 19:40:59,908 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-104:ctx-dd172354) (logid:1cf4d37e) Vm cpu utilization 1.094722222222222
2020-08-05 19:40:59,908 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-104:ctx-dd172354) (logid:1cf4d37e) Seq 2-2120632474538095765: Response Received:
2020-08-05 19:40:59,908 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-f61b9046) (logid:1cf4d37e) Seq 2-2120632474538095765: Received: { Ans: , MgmtId: 46783616671, via: 2(xen3), Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2020-08-05 19:40:59,927 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-416:ctx-920b0ae2) (logid:d0a3683d) Seq 3-3554184530925466094: Executing request
2020-08-05 19:40:59,957 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-416:ctx-920b0ae2) (logid:1cf4d37e) Vm cpu utilization 4.493194444444444
2020-08-05 19:40:59,957 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-416:ctx-920b0ae2) (logid:1cf4d37e) Vm cpu utilization 0.5555555555555555
2020-08-05 19:40:59,957 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-416:ctx-920b0ae2) (logid:1cf4d37e) Vm cpu utilization 0.20388888888888893
2020-08-05 19:40:59,957 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-416:ctx-920b0ae2) (logid:1cf4d37e) Vm cpu utilization 0.3055555555555556
2020-08-05 19:40:59,957 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-416:ctx-920b0ae2) (logid:1cf4d37e) Seq 3-3554184530925466094: Response Received:
2020-08-05 19:40:59,957 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-f61b9046) (logid:1cf4d37e) Seq 3-3554184530925466094: Received: { Ans: , MgmtId: 46783616671, via: 3(xen1), Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2020-08-05 19:41:00,750 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32225:ctx-e649d450) (logid:6d2cbfb8) ===START=== 146.253.34.63 -- GET jobId=fef53aca-d97c-4445-abc8-7214a7f144f2&command=queryAsyncJobResult&response=json
2020-08-05 19:41:00,757 DEBUG [c.c.a.ApiServer] (qtp1710989308-32225:ctx-e649d450 ctx-69de42a4) (logid:6d2cbfb8) CIDRs from which account 'Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com]' is allowed to perform API calls: 0.0.0.0/0,::/0
2020-08-05 19:41:00,769 DEBUG [c.c.u.AccountManagerImpl] (qtp1710989308-32225:ctx-e649d450 ctx-69de42a4) (logid:6d2cbfb8) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:41:00,776 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32225:ctx-e649d450 ctx-69de42a4) (logid:6d2cbfb8) ===END=== 146.253.34.63 -- GET jobId=fef53aca-d97c-4445-abc8-7214a7f144f2&command=queryAsyncJobResult&response=json
2020-08-05 19:41:01,376 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32214:ctx-fda1acea) (logid:2124e888) ===START=== 146.253.34.63 -- GET jobid=fef53aca-d97c-4445-abc8-7214a7f144f2&command=queryAsyncJobResult&response=json
2020-08-05 19:41:01,383 DEBUG [c.c.a.ApiServer] (qtp1710989308-32214:ctx-fda1acea ctx-1b28e9b9) (logid:2124e888) CIDRs from which account 'Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com]' is allowed to perform API calls: 0.0.0.0/0,::/0
2020-08-05 19:41:01,395 DEBUG [c.c.u.AccountManagerImpl] (qtp1710989308-32214:ctx-fda1acea ctx-1b28e9b9) (logid:2124e888) Access to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] granted to Acct[95977b7d-3e85-44b2-b555-107d1c78248a-kirill.pushkin@example.com] by DomainChecker
2020-08-05 19:41:01,402 DEBUG [c.c.a.ApiServlet] (qtp1710989308-32214:ctx-fda1acea ctx-1b28e9b9) (logid:2124e888) ===END=== 146.253.34.63 -- GET jobid=fef53aca-d97c-4445-abc8-7214a7f144f2&command=queryAsyncJobResult&response=json
2020-08-05 19:41:01,931 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-14:null) (logid:) SeqA 5-398979: Processing Seq 5-398979: { Cmd , MgmtId: -1, via: 5, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2020-08-05 19:41:01,933 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-14:null) (logid:) SeqA 5-398979: Sending Seq 5-398979: { Ans: , MgmtId: 46783616671, via: 5, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2020-08-05 19:41:11,931 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-1:null) (logid:) SeqA 5-398980: Processing Seq 5-398980: { Cmd , MgmtId: -1, via: 5, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2020-08-05 19:41:11,932 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-1:null) (logid:) SeqA 5-398980: Sending Seq 5-398980: { Ans: , MgmtId: 46783616671, via: 5, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2020-08-05 19:41:16,932 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-2:null) (logid:) SeqA 5-398981: Processing Seq 5-398981: { Cmd , MgmtId: -1, via: 5, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2020-08-05 19:41:16,934 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-2:null) (logid:) SeqA 5-398981: Sending Seq 5-398981: { Ans: , MgmtId: 46783616671, via: 5, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment