azure-sdk-for-java: [BUG] AzureResourceManager throws NullPointerException in context of ContainerGroup

Describe the bug When using many Azure container instances with private IP address, then the AzureResourceManager throws time to time NullPointerException in context of ContainerGroup.

Exception or Stack Trace

2022-02-11 09:01:42.674+0000 [id=74]    WARNING c.m.j.c.aci.AciCloud#lambda$provision$1: AciCloud: Provision agent test-private-n4s69 failed: java.lang.NullPointerException
java.lang.NullPointerException
        at com.azure.resourcemanager.containerinstance.implementation.ContainerGroupImpl.initializeChildrenFromInner(ContainerGroupImpl.java:217)
        at com.azure.resourcemanager.resources.fluentcore.arm.models.implementation.GroupableParentResourceImpl.<init>(GroupableParentResourceImpl.java:32)
        at com.azure.resourcemanager.containerinstance.implementation.ContainerGroupImpl.<init>(ContainerGroupImpl.java:73)
        at com.azure.resourcemanager.containerinstance.implementation.ContainerGroupsImpl.wrapModel(ContainerGroupsImpl.java:43)
        at com.azure.resourcemanager.containerinstance.implementation.ContainerGroupsImpl.wrapModel(ContainerGroupsImpl.java:24)
        at reactor.core.publisher.FluxMapFuseable$MapFuseableSubscriber.onNext(FluxMapFuseable.java:113)
        at reactor.core.publisher.Operators$MonoSubscriber.complete(Operators.java:1816)
        at reactor.core.publisher.MonoFlatMap$FlatMapMain.onNext(MonoFlatMap.java:151)
        at reactor.core.publisher.FluxContextWrite$ContextWriteSubscriber.onNext(FluxContextWrite.java:107)
        at reactor.core.publisher.Operators$MonoSubscriber.complete(Operators.java:1816)
        at reactor.core.publisher.MonoFlatMap$FlatMapInner.onNext(MonoFlatMap.java:249)
        at reactor.core.publisher.FluxSwitchIfEmpty$SwitchIfEmptySubscriber.onNext(FluxSwitchIfEmpty.java:74)
        at reactor.core.publisher.Operators$MonoSubscriber.complete(Operators.java:1816)
        at reactor.core.publisher.MonoFlatMap$FlatMapInner.onNext(MonoFlatMap.java:249)
        at reactor.core.publisher.Operators$MonoSubscriber.complete(Operators.java:1816)
        at reactor.core.publisher.MonoFlatMap$FlatMapInner.onNext(MonoFlatMap.java:249)
        at reactor.core.publisher.Operators$ScalarSubscription.request(Operators.java:2398)
        at reactor.core.publisher.MonoFlatMap$FlatMapInner.onSubscribe(MonoFlatMap.java:238)
        at reactor.core.publisher.MonoJust.subscribe(MonoJust.java:55)

To Reproduce Steps to reproduce the behavior: see issue https://github.com/jenkinsci/azure-container-agents-plugin/issues/101

Code Snippet see

Expected behavior No NullPointerException is thrown.

Setup (please complete the following information):

  • OS: Ubuntu 18.04
  • Library/Libraries: com.azure.resourcemanager:azure-resourcemanager-containerinstance:2.9.0
  • Java version: 11
  • Frameworks: Jenkins Plugin Azure Container Agent

Information Checklist Kindly make sure that you have added all the following information above and checkoff the required fields otherwise we will treat the issuer as an incomplete report

  • Bug Description Added
  • Repro Steps Added
  • Setup information Added

About this issue

  • Original URL
  • State: closed
  • Created 2 years ago
  • Comments: 16 (10 by maintainers)

Most upvoted comments

@XiaofeiCao Thank you for mentioning me. I opened a PR in https://github.com/jenkinsci/azure-sdk-plugin/. After releasing this plugin, I can test it in the original plugin.

@sparsick Thanks, the log is great! I can tell from your log that the templates are actually the same. I’ll add some protections for this situation. You can expect it to be fixed in the next sdk release.

In the meanwhile, if you want, you can add a protocol to the container port in your template. I believe this will also resolve this issue.

@XiaofeiCao I generate a new log output where the deployment template is also logged.

The whole log output when starting 4 agents at the same time based on the same deployment template.
Feb 18, 2022 1:40:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionStart ACI container for label test-private workLoad 1
Feb 18, 2022 1:40:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionUsing ACI Container template: test-private
Feb 18, 2022 1:40:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1Add ACI node: test-private-9r35v
Feb 18, 2022 1:40:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCleanTask$DeploymentRegistrar registerDeploymentAzureAciCleanUpTask: registerDeployment: Registering deployment test-private-4khhmf5w in jenkins-rg
Feb 18, 2022 1:40:38 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template: {"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-9r35v","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","d73f77d555f38d92a752f76807ec82ea4ef1df0e6b24ef45045e6beea530922c","test-private-9r35v"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}}
Feb 18, 2022 1:40:38 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template Parameter: {}
Feb 18, 2022 1:40:39 PM INFO com.azure.core.util.logging.ClientLogger performLoggingAzure Identity => getToken() result for scopes [https://management.core.windows.net//.default]: SUCCESS
Feb 18, 2022 1:40:39 PM INFO com.azure.core.util.logging.ClientLogger infoAcquired a new access token at 199 seconds after expiry. Retry may be attempted after 30 seconds.
Feb 18, 2022 1:40:39 PM INFO com.azure.core.util.logging.ClientLogger info--> PUT https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:40:38 GMTAuthorization:REDACTEDContent-Length:1682x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:6d9f1f9b-b7bb-4df1-826a-91a2727e8487Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)1682-byte body:{"properties":{"template":{"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-9r35v","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","d73f77d555f38d92a752f76807ec82ea4ef1df0e6b24ef45045e6beea530922c","test-private-9r35v"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}},"parameters":{},"mode":"Incremental"}}--> END PUT
Feb 18, 2022 1:40:39 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentWaiting for deployment test-private-4khhmf5w
Feb 18, 2022 1:40:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:40:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:802f06d5-d2e6-455c-a048-1e317b418818Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:40:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 201 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (422 ms, 622-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:40:39.5222023Z","duration":"PT0.000559S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:40:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (50 ms, 622-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:40:39.5222023Z","duration":"PT0.000559S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:40:49 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:40:49 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:8aa0879f-e0b1-4ea4-a48c-7948cde72bd2Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:40:49 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (33 ms, 622-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:40:42.5036612Z","duration":"PT2.9820179S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:40:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:40:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:767daee4-439e-454b-b730-f705257a8d5eAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:40:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (28 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:40:57.9202471Z","duration":"PT18.3986038S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:08 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionStart ACI container for label test-private workLoad 1
Feb 18, 2022 1:41:08 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionUsing ACI Container template: test-private
Feb 18, 2022 1:41:08 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1Add ACI node: test-private-3msb5
Feb 18, 2022 1:41:08 PM INFO com.microsoft.jenkins.containeragents.aci.AciCleanTask$DeploymentRegistrar registerDeploymentAzureAciCleanUpTask: registerDeployment: Registering deployment test-private-03fl3k2d in jenkins-rg
Feb 18, 2022 1:41:08 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template: {"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-3msb5","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}}
Feb 18, 2022 1:41:08 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template Parameter: {}
Feb 18, 2022 1:41:08 PM INFO com.azure.core.util.logging.ClientLogger info--> PUT https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:08 GMTAuthorization:REDACTEDContent-Length:1682x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:f5c80ffc-4074-429d-9219-7d9aee38f47aAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)1682-byte body:{"properties":{"template":{"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-3msb5","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}},"parameters":{},"mode":"Incremental"}}--> END PUT
Feb 18, 2022 1:41:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 201 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (278 ms, 621-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:41:09.179435Z","duration":"PT0.000098S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:09 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentWaiting for deployment test-private-03fl3k2d
Feb 18, 2022 1:41:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:e04a6587-54b6-439f-bee7-3777a42c2f10Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (21 ms, 621-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:41:09.179435Z","duration":"PT0.000098S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:b3c40aec-b01b-4dcc-a975-b96d61e62fd8Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (23 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:40:57.9202471Z","duration":"PT18.3986038S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:19 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:b39d5bcb-41c8-47cc-92a3-473a520d4de2Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (23 ms, 622-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:41:11.1288141Z","duration":"PT1.9494771S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:19 PM INFO com.microsoft.jenkins.containeragents.strategy.ContainerOnceRetentionStrategy doneterminating test-private-9r35v since PlaceholderExecutable:ExecutorStepExecution.PlaceholderTask{runId=test-private#98,label=test-private-9r35v,context=CpsStepContext[3:node]:Owner[test-private/98:test-private #98],cookie=4ebe3fb5-e218-4259-81a9-c7ed96b0fa00,auth=null} seems to be finished
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger performLoggingAzure Identity => getToken() result for scopes [https://management.core.windows.net//.default]: SUCCESS
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger infoAcquired a new access token.
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-9r35v?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:19 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:49b85269-c093-47b4-8162-45a5564b60aeAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:19 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:76154a10-a3aa-47d3-af71-074703a47a5cAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:19 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentDeployment test-private-4khhmf5w succeed
Feb 18, 2022 1:41:19 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud waitToOnlineWaiting agent test-private-9r35v to online
Feb 18, 2022 1:41:19 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1AciCloud: Provision agent test-private-9r35v failed: java.lang.IllegalStateException: Agent node has been deleted
Feb 18, 2022 1:41:19 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (54 ms, 877-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:41:13.3970479Z","duration":"PT33.8754046S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.4"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-9r35v"}]}} <-- END HTTP
Feb 18, 2022 1:41:20 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI Container Group: test-private-9r35v successfully
Feb 18, 2022 1:41:20 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:20 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:1a76c7c4-84d1-4c7a-a132-683f2285c09cAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:20 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-9r35v?api-version=2021-09-01 (509 ms, 1946-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-9r35v","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","d73f77d555f38d92a752f76807ec82ea4ef1df0e6b24ef45045e6beea530922c","test-private-9r35v"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:41:02.623Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:40:52Z","lastTimestamp":"2022-02-18T13:40:52Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent@sha256:a73b495c2c13ca951979429dc708c5c3e41ac76521266984081bf67c66e66c61\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:40:52Z","lastTimestamp":"2022-02-18T13:40:52Z","name":"Pulling","message":"pulling image \"jenkins/inbound-agent@sha256:a73b495c2c13ca951979429dc708c5c3e41ac76521266984081bf67c66e66c61\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:02Z","lastTimestamp":"2022-02-18T13:41:02Z","name":"Started","message":"Started container","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.4","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-9r35v","name":"test-private-9r35v","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:41:20 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceChecking deployment: test-private-4khhmf5w, provisioning state: Succeeded
Feb 18, 2022 1:41:20 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:20 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:64d8efaf-41ff-4a1b-8eb8-38568b12b802Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:41:20 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (38 ms, 877-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w","name":"test-private-4khhmf5w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"1973954935877653782","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:41:13.3970479Z","duration":"PT33.8754046S","correlationId":"3c985061-65b3-42c0-a7be-967527dd63cf","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.4"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-9r35v"}]}} <-- END HTTP
Feb 18, 2022 1:41:20 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:41:20 PM INFO com.azure.core.util.logging.ClientLogger info<-- 202 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-4khhmf5w?api-version=2021-01-01 (137 ms, 0-byte body), Response body: <-- END HTTP
Feb 18, 2022 1:41:28 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:41:29 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:29 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:872cb6e0-7833-43e7-9bb1-9da97a5eb2a0Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:29 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (28 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:41:26.3758521Z","duration":"PT17.1965151S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:35 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:41:35 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:41:35 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI deployment: test-private-4khhmf5w successfully
Feb 18, 2022 1:41:38 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:41:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:ff3de97c-e94d-4607-883f-43890d247685Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (23 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:41:26.3758521Z","duration":"PT17.1965151S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:48 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:41:49 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:49 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:4fbc6059-2ff5-4d94-86c2-9437884cfe18Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:49 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (33 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:41:41.7482068Z","duration":"PT32.5688698S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:41:58 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:41:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:41:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:69f60e74-2a47-4b31-9e26-794246d6c30bAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:41:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (21 ms, 621-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:41:57.130522Z","duration":"PT47.951185S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:42:08 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:42:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:3ef3431d-add6-4e39-993a-2cb9f66fb6a5Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (29 ms, 621-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:41:57.130522Z","duration":"PT47.951185S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:42:18 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:42:19 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:19 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:65fd14da-aaf8-4959-9232-df6afa1bdbefAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:19 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (22 ms, 624-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:42:12.4625505Z","duration":"PT1M3.2832135S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:42:29 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:29 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:fe9aeccd-e41b-4d11-ade5-ba675d80603fAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:29 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentDeployment test-private-03fl3k2d succeed
Feb 18, 2022 1:42:29 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud waitToOnlineWaiting agent test-private-3msb5 to online
Feb 18, 2022 1:42:29 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:29 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:d8770abb-87ee-4201-9021-65e2f8e944c2Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:29 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (22 ms, 879-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:42:28.9056758Z","duration":"PT1M19.7263388S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.5"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5"}]}} <-- END HTTP
Feb 18, 2022 1:42:29 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud waitToOnlineWaiting for Agent test-private-3msb5 produces a NullPointerException, but it is ignored.
Feb 18, 2022 1:42:29 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:29 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:585624a4-271a-4247-9b97-f1b4b781b031Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:29 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=2021-09-01 (66 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-3msb5","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:42:22Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:46Z","lastTimestamp":"2022-02-18T13:41:46Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 34.987210424s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Created","message":"Created container test-private-3msb5","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:22Z","lastTimestamp":"2022-02-18T13:42:22Z","name":"Started","message":"Started container test-private-3msb5","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:14Z","lastTimestamp":"2022-02-18T13:41:14Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:17Z","lastTimestamp":"2022-02-18T13:41:17Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5","name":"test-private-3msb5","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:42:29 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud addIpEnvDuring asking for IP address of Agent test-private-3msb5 NullPointerException is thrown,but it is ignored.
Feb 18, 2022 1:42:29 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=2021-09-01 (92 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-3msb5","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:42:22Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:46Z","lastTimestamp":"2022-02-18T13:41:46Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 34.987210424s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Created","message":"Created container test-private-3msb5","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:22Z","lastTimestamp":"2022-02-18T13:42:22Z","name":"Started","message":"Started container test-private-3msb5","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:14Z","lastTimestamp":"2022-02-18T13:41:14Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:17Z","lastTimestamp":"2022-02-18T13:41:17Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5","name":"test-private-3msb5","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:42:34 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:34 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:524ad6b2-e428-4823-936b-6ea60107029fAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:34 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud addIpEnvDuring asking for IP address of Agent test-private-3msb5 NullPointerException is thrown,but it is ignored.
Feb 18, 2022 1:42:34 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=2021-09-01 (74 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-3msb5","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:42:22Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:46Z","lastTimestamp":"2022-02-18T13:41:46Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 34.987210424s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Created","message":"Created container test-private-3msb5","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:22Z","lastTimestamp":"2022-02-18T13:42:22Z","name":"Started","message":"Started container test-private-3msb5","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:14Z","lastTimestamp":"2022-02-18T13:41:14Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:17Z","lastTimestamp":"2022-02-18T13:41:17Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5","name":"test-private-3msb5","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:42:38 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:42:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:9c8d1cf0-ce8c-4809-93d5-69e2dc005065Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:39 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud addIpEnvDuring asking for IP address of Agent test-private-3msb5 NullPointerException is thrown,but it is ignored.
Feb 18, 2022 1:42:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=2021-09-01 (64 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-3msb5","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:42:22Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:46Z","lastTimestamp":"2022-02-18T13:41:46Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 34.987210424s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Created","message":"Created container test-private-3msb5","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:22Z","lastTimestamp":"2022-02-18T13:42:22Z","name":"Started","message":"Started container test-private-3msb5","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:14Z","lastTimestamp":"2022-02-18T13:41:14Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:17Z","lastTimestamp":"2022-02-18T13:41:17Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5","name":"test-private-3msb5","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:42:41 PM INFO com.microsoft.jenkins.containeragents.strategy.ContainerOnceRetentionStrategy doneterminating test-private-3msb5 since PlaceholderExecutable:ExecutorStepExecution.PlaceholderTask{runId=test-private#97,label=test-private-3msb5,context=CpsStepContext[3:node]:Owner[test-private/97:test-private #97],cookie=2305cfcd-8b23-4b84-bf8a-695e5a6880ba,auth=null} seems to be finished
Feb 18, 2022 1:42:41 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:42:41 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:42:41 PM INFO com.azure.core.util.logging.ClientLogger performLoggingAzure Identity => getToken() result for scopes [https://management.core.windows.net//.default]: SUCCESS
Feb 18, 2022 1:42:41 PM INFO com.azure.core.util.logging.ClientLogger infoAcquired a new access token.
Feb 18, 2022 1:42:41 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:41 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:ebf749d1-54fb-4924-ae38-3fa2289e39a9Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:42:42 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI Container Group: test-private-3msb5 successfully
Feb 18, 2022 1:42:42 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:42 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:29db7b81-86df-4c5e-9022-f89bf677aa91Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:42 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=2021-09-01 (567 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-3msb5","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","1c1f9fecd5455ce7fb80190d372251234767d99d3e0e7b7647e942d3deed5da5","test-private-3msb5"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:42:22Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:46Z","lastTimestamp":"2022-02-18T13:41:46Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 34.987210424s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:21Z","lastTimestamp":"2022-02-18T13:42:21Z","name":"Created","message":"Created container test-private-3msb5","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:42:22Z","lastTimestamp":"2022-02-18T13:42:22Z","name":"Started","message":"Started container test-private-3msb5","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:13Z","lastTimestamp":"2022-02-18T13:41:13Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:14Z","lastTimestamp":"2022-02-18T13:41:14Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:41:17Z","lastTimestamp":"2022-02-18T13:41:17Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5","name":"test-private-3msb5","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:42:42 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceChecking deployment: test-private-03fl3k2d, provisioning state: Succeeded
Feb 18, 2022 1:42:42 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:42 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:f240761a-a9c2-4810-a812-e34bc2ef4c1dAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:42:42 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (25 ms, 879-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d","name":"test-private-03fl3k2d","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6673565467646336307","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:42:28.9056758Z","duration":"PT1M19.7263388S","correlationId":"49a5efbd-b117-40e0-8f79-2201ac0c5b54","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.5"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5"}]}} <-- END HTTP
Feb 18, 2022 1:42:42 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:42:42 PM INFO com.azure.core.util.logging.ClientLogger info<-- 202 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-03fl3k2d?api-version=2021-01-01 (75 ms, 0-byte body), Response body: <-- END HTTP
Feb 18, 2022 1:42:44 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:42:44 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:4b2ec4b7-2755-4dab-8a14-3867c7e34da8Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:42:44 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1AciCloud: Provision agent test-private-3msb5 failed: com.azure.core.management.exception.ManagementException: Status code 404, "{"error":{"code":"ResourceNotFound","message":"The Resource 'Microsoft.ContainerInstance/containerGroups/test-private-3msb5' under resource group 'jenkins-rg' was not found. For more details please go to https://aka.ms/ARMResourceNotFoundFix"}}": The Resource 'Microsoft.ContainerInstance/containerGroups/test-private-3msb5' under resource group 'jenkins-rg' was not found. For more details please go to https://aka.ms/ARMResourceNotFoundFix
Feb 18, 2022 1:42:44 PM INFO com.azure.core.util.logging.ClientLogger info<-- 404 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-3msb5?api-version=2021-09-01 (31 ms, 244-byte body), Response body:{"error":{"code":"ResourceNotFound","message":"The Resource 'Microsoft.ContainerInstance/containerGroups/test-private-3msb5' under resource group 'jenkins-rg' was not found. For more details please go to https://aka.ms/ARMResourceNotFoundFix"}} <-- END HTTP
Feb 18, 2022 1:42:57 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:42:57 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:42:57 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI deployment: test-private-03fl3k2d successfully
Feb 18, 2022 1:45:31 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:50:31 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:55:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionStart ACI container for label test-private workLoad 1
Feb 18, 2022 1:55:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionUsing ACI Container template: test-private
Feb 18, 2022 1:55:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1Add ACI node: test-private-rsxkt
Feb 18, 2022 1:55:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCleanTask$DeploymentRegistrar registerDeploymentAzureAciCleanUpTask: registerDeployment: Registering deployment test-private-32dqk25w in jenkins-rg
Feb 18, 2022 1:55:38 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template: {"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-rsxkt","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","0629cf297c5c6f1086d137fcbc596481b684c6463fa4e599f6f6429fa50443cb","test-private-rsxkt"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}}
Feb 18, 2022 1:55:38 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template Parameter: {}
Feb 18, 2022 1:55:39 PM INFO com.azure.core.util.logging.ClientLogger info--> PUT https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:55:38 GMTAuthorization:REDACTEDContent-Length:1682x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:a3c5318b-a39b-4fc0-b335-62fa03a14f68Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)1682-byte body:{"properties":{"template":{"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-rsxkt","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","0629cf297c5c6f1086d137fcbc596481b684c6463fa4e599f6f6429fa50443cb","test-private-rsxkt"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}},"parameters":{},"mode":"Incremental"}}--> END PUT
Feb 18, 2022 1:55:39 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentWaiting for deployment test-private-32dqk25w
Feb 18, 2022 1:55:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:55:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:0669d8cc-42be-4cd3-b33d-a4d8b6620b59Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:55:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 201 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (608 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:55:39.5310717Z","duration":"PT0.0008707S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:55:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (53 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:55:39.5310717Z","duration":"PT0.0008707S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:55:49 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:55:49 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:10ac0f33-c215-4528-9577-dd27faa8bf42Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:55:49 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (23 ms, 622-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:55:42.9341375Z","duration":"PT3.4039365S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:55:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:55:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:28401495-8378-4235-aef5-15b83c266edfAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:55:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (55 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:55:58.2571444Z","duration":"PT18.7269434S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:b56df38c-03ed-47e3-94b7-7883df3facffAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (32 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:55:58.2571444Z","duration":"PT18.7269434S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:19 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:19 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:a1f145f5-2f26-413a-8d14-ffd959d3b4aaAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:19 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (23 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:14.3534682Z","duration":"PT34.8232672S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:29 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:29 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:e97
Febc1-90f2-49da-9bd3-18e2b853b1e3Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:29 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (20 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:14.3534682Z","duration":"PT34.8232672S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionStart ACI container for label test-private workLoad 1
Feb 18, 2022 1:56:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud provisionUsing ACI Container template: test-private
Feb 18, 2022 1:56:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1Add ACI node: test-private-468wz
Feb 18, 2022 1:56:38 PM INFO com.microsoft.jenkins.containeragents.aci.AciCleanTask$DeploymentRegistrar registerDeploymentAzureAciCleanUpTask: registerDeployment: Registering deployment test-private-84lg6jbb in jenkins-rg
Feb 18, 2022 1:56:38 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template: {"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-468wz","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","cdf186de7eebedc88ff58
Febc4888516454988bfde23966cd0e428c82be86e13","test-private-468wz"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}}
Feb 18, 2022 1:56:38 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceDeployment ARM Template Parameter: {}
Feb 18, 2022 1:56:38 PM INFO com.azure.core.util.logging.ClientLogger info--> PUT https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:38 GMTAuthorization:REDACTEDContent-Length:1682x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:04aa1209-10c4-4285-81ac-09aca4c1dbf8Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)1682-byte body:{"properties":{"template":{"$schema":"https://schema.management.azure.com/schemas/2019-04-01/deploymentTemplate.json#","contentVersion":"1.0.0.0","parameters":{},"variables":{"containerName":"test-private-468wz","containerImage":"jenkins/inbound-agent","cpu":"1","memory":"1.5","osType":"Linux","jenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a","ipType":"Private","vnetResourceGroupName":"jenkins-vnet-rg","vnetName":"jenkins-vnet","subnetName":"jenkins-agent-subnet"},"resources":[{"name":"[variables('containerName')]","type":"Microsoft.ContainerInstance/containerGroups","apiVersion":"2021-09-01","tags":{"JenkinsInstance":"[variables('jenkinsInstance')]"},"location":"[resourceGroup().location]","properties":{"containers":[{"name":"[variables('containerName')]","properties":{"command":["jenkins-agent","-url","http://104.46.15.57:8080/","cdf186de7eebedc88ff58
Febc4888516454988bfde23966cd0e428c82be86e13","test-private-468wz"],"image":"[variables('containerImage')]","resources":{"requests":{"cpu":"[variables('cpu')]","memoryInGb":"[variables('memory')]"}},"ports":[{"port":"80"}],"environmentVariables":[],"volumeMounts":[]}}],"osType":"[variables('osType')]","volumes":[],"ipAddress":{"type":"[variables('ipType')]","ports":[{"protocol":"tcp","port":"80"}]},"imageRegistryCredentials":[],"subnetIds":[{"id":"[resourceId(variables('vnetResourceGroupName'), 'Microsoft.Network/virtualNetworks/subnets', variables('vnetName'), variables('subnetName'))]"}]}}],"outputs":{"containerIPv4Address":{"type":"string","value":"[reference(resourceId('Microsoft.ContainerInstance/containerGroups/', variables('containerName'))).ipAddress.ip]"}}},"parameters":{},"mode":"Incremental"}}--> END PUT
Feb 18, 2022 1:56:39 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentWaiting for deployment test-private-84lg6jbb
Feb 18, 2022 1:56:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 201 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (395 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:56:39.3191409Z","duration":"PT0.0001433S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:1937944d-cb53-46af-96dc-6a33351bbe12Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (24 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Accepted","timestamp":"2022-02-18T13:56:39.3191409Z","duration":"PT0.0001433S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:31576bea-2b97-4de7-aa52-4bce514881c9Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (30 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:30.1428197Z","duration":"PT50.6126187S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:49 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:49 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:4438d382-1ab9-4591-8353-f1758cd533feAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:49 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (35 ms, 622-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:41.2643702Z","duration":"PT1.9453726S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:49 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:49 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:4dcf6630-f539-4ed6-983d-256e601fb9c9Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:49 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (53 ms, 624-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:45.6001024Z","duration":"PT1M6.0699014S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:09b9bf71-0428-498f-8b6c-065ca4bb7330Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (37 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:56.4653868Z","duration":"PT17.1463892S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:56:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:56:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:cafe8886-1ad5-4b8e-b37c-f86069995713Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:56:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (21 ms, 624-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:45.6001024Z","duration":"PT1M6.0699014S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:57:05 PM INFO com.microsoft.jenkins.containeragents.strategy.ContainerOnceRetentionStrategy doneterminating test-private-rsxkt since PlaceholderExecutable:ExecutorStepExecution.PlaceholderTask{runId=test-private#96,label=test-private-rsxkt,context=CpsStepContext[3:node]:Owner[test-private/96:test-private #96],cookie=bdec2fd4-0f76-405f-bb9d-e363fa8e0b87,auth=null} seems to be finished
Feb 18, 2022 1:57:05 PM INFO com.azure.core.util.logging.ClientLogger performLoggingAzure Identity => getToken() result for scopes [https://management.core.windows.net//.default]: SUCCESS
Feb 18, 2022 1:57:05 PM INFO com.azure.core.util.logging.ClientLogger infoAcquired a new access token.
Feb 18, 2022 1:57:05 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-rsxkt?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:05 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:ac7a8dbb-91df-4102-b1ea-ba5cd7620f5eAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:57:06 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI Container Group: test-private-rsxkt successfully
Feb 18, 2022 1:57:06 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:06 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:08608c2d-695d-4925-b4df-8c6edb36ad01Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:06 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-rsxkt?api-version=2021-09-01 (469 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-rsxkt","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","0629cf297c5c6f1086d137fcbc596481b684c6463fa4e599f6f6429fa50443cb","test-private-rsxkt"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:56:47Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:56:12Z","lastTimestamp":"2022-02-18T13:56:12Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:46Z","lastTimestamp":"2022-02-18T13:56:46Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 33.736892326s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:47Z","lastTimestamp":"2022-02-18T13:56:47Z","name":"Created","message":"Created container test-private-rsxkt","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:47Z","lastTimestamp":"2022-02-18T13:56:47Z","name":"Started","message":"Started container test-private-rsxkt","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.4","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:55:44Z","lastTimestamp":"2022-02-18T13:55:44Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:55:44Z","lastTimestamp":"2022-02-18T13:55:44Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:55:45Z","lastTimestamp":"2022-02-18T13:55:45Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:55:48Z","lastTimestamp":"2022-02-18T13:55:48Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-rsxkt","name":"test-private-rsxkt","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:57:06 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceChecking deployment: test-private-32dqk25w, provisioning state: Succeeded
Feb 18, 2022 1:57:06 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:06 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:44285292-0398-406e-a6b1-abaf3742b43fAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:57:06 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (28 ms, 875-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w","name":"test-private-32dqk25w","type":"Microsoft.Resources/deployments","properties":{"templateHash":"7460641030561785043","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:57:01.804801Z","duration":"PT1M22.2746S","correlationId":"728af5af-29d5-4221-ab9b-ea869bf24a0c","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.4"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-rsxkt"}]}} <-- END HTTP
Feb 18, 2022 1:57:06 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:57:06 PM INFO com.azure.core.util.logging.ClientLogger info<-- 202 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (83 ms, 0-byte body), Response body: <-- END HTTP
Feb 18, 2022 1:57:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:208f45d5-2674-4cf6-abdf-2376b5fe8641Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (31 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:56:56.4653868Z","duration":"PT17.1463892S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:57:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:7b114580-fbef-4545-b117-9c60a1da6375Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:09 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1AciCloud: Provision agent test-private-rsxkt failed: com.azure.core.management.exception.ManagementException: Status code 404, "{"error":{"code":"DeploymentNotFound","message":"Deployment 'test-private-32dqk25w' could not be found."}}": Deployment 'test-private-32dqk25w' could not be found.
Feb 18, 2022 1:57:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 404 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-32dqk25w?api-version=2021-01-01 (25 ms, 106-byte body), Response body:{"error":{"code":"DeploymentNotFound","message":"Deployment 'test-private-32dqk25w' could not be found."}} <-- END HTTP
Feb 18, 2022 1:57:18 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:57:19 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:19 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:7a5116c4-c925-4f8e-a0ea-ce3b85662e9aAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:19 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (21 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:57:11.8987793Z","duration":"PT32.5797817S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:57:21 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:57:21 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:57:21 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI deployment: test-private-32dqk25w successfully
Feb 18, 2022 1:57:28 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:57:29 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:29 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:c89b2db1-d070-4d9b-99ad-a979a23e6882Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:29 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (23 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:57:27.2909901Z","duration":"PT47.9719925S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:57:38 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:57:39 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:39 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:8132fb93-a76e-446d-b4d1-ce99710a9376Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:39 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (22 ms, 623-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:57:27.2909901Z","duration":"PT47.9719925S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:57:48 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:57:49 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:49 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:3234d0de-9d20-423c-877e-3c65ff220009Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:49 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (29 ms, 624-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Running","timestamp":"2022-02-18T13:57:42.7873012Z","duration":"PT1M3.4683036S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[]}} <-- END HTTP
Feb 18, 2022 1:57:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:7965308a-2c81-4867-91ba-6d3948f73eaaAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (24 ms, 879-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:57:58.2107158Z","duration":"PT1M18.8917182S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.5"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz"}]}} <-- END HTTP
Feb 18, 2022 1:57:59 PM INFO com.microsoft.jenkins.containeragents.aci.AciService createDeploymentDeployment test-private-84lg6jbb succeed
Feb 18, 2022 1:57:59 PM INFO com.microsoft.jenkins.containeragents.aci.AciCloud waitToOnlineWaiting agent test-private-468wz to online
Feb 18, 2022 1:57:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:1f2c9589-ae06-41f2-8e98-5413d84fdd15Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:59 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud waitToOnlineWaiting for Agent test-private-468wz produces a NullPointerException, but it is ignored.
Feb 18, 2022 1:57:59 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:57:59 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:e6180967-e066-456b-b59b-ffe8329a1a03Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:57:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=2021-09-01 (70 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-468wz","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","cdf186de7eebedc88ff58
Febc4888516454988bfde23966cd0e428c82be86e13","test-private-468wz"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:57:50Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:57:13Z","lastTimestamp":"2022-02-18T13:57:13Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:49Z","lastTimestamp":"2022-02-18T13:57:49Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 36.574490402s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Created","message":"Created container test-private-468wz","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Started","message":"Started container test-private-468wz","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:56:44Z","lastTimestamp":"2022-02-18T13:56:44Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:45Z","lastTimestamp":"2022-02-18T13:56:45Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:48Z","lastTimestamp":"2022-02-18T13:56:48Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:54Z","lastTimestamp":"2022-02-18T13:56:54Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz","name":"test-private-468wz","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:57:59 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=2021-09-01 (67 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-468wz","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","cdf186de7eebedc88ff58
Febc4888516454988bfde23966cd0e428c82be86e13","test-private-468wz"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:57:50Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:57:13Z","lastTimestamp":"2022-02-18T13:57:13Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:49Z","lastTimestamp":"2022-02-18T13:57:49Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 36.574490402s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Created","message":"Created container test-private-468wz","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Started","message":"Started container test-private-468wz","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:56:44Z","lastTimestamp":"2022-02-18T13:56:44Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:45Z","lastTimestamp":"2022-02-18T13:56:45Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:48Z","lastTimestamp":"2022-02-18T13:56:48Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:54Z","lastTimestamp":"2022-02-18T13:56:54Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz","name":"test-private-468wz","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:57:59 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud addIpEnvDuring asking for IP address of Agent test-private-468wz NullPointerException is thrown,but it is ignored.
Feb 18, 2022 1:58:04 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:58:04 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:fde5c0bd-f028-4182-aa86-84bbc2bba83bAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:58:04 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud addIpEnvDuring asking for IP address of Agent test-private-468wz NullPointerException is thrown,but it is ignored.
Feb 18, 2022 1:58:04 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=2021-09-01 (68 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-468wz","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","cdf186de7eebedc88ff58
Febc4888516454988bfde23966cd0e428c82be86e13","test-private-468wz"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:57:50Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:57:13Z","lastTimestamp":"2022-02-18T13:57:13Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:49Z","lastTimestamp":"2022-02-18T13:57:49Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 36.574490402s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Created","message":"Created container test-private-468wz","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Started","message":"Started container test-private-468wz","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:56:44Z","lastTimestamp":"2022-02-18T13:56:44Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:45Z","lastTimestamp":"2022-02-18T13:56:45Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:48Z","lastTimestamp":"2022-02-18T13:56:48Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:54Z","lastTimestamp":"2022-02-18T13:56:54Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz","name":"test-private-468wz","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:58:07 PM INFO com.microsoft.jenkins.containeragents.strategy.ContainerOnceRetentionStrategy doneterminating test-private-468wz since PlaceholderExecutable:ExecutorStepExecution.PlaceholderTask{runId=test-private#99,label=test-private-468wz,context=CpsStepContext[3:node]:Owner[test-private/99:test-private #99],cookie=42d4bd4b-60e7-43c3-8206-cb4c7cfb46c2,auth=null} seems to be finished
Feb 18, 2022 1:58:07 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:58:07 PM INFO com.azure.core.util.logging.ClientLogger performLoggingAzure Identity => getToken() result for scopes [https://management.core.windows.net//.default]: SUCCESS
Feb 18, 2022 1:58:07 PM INFO com.azure.core.util.logging.ClientLogger infoAcquired a new access token.
Feb 18, 2022 1:58:07 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:58:07 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:1f82d2f7-5dbe-4f8c-a6e2-b64fecd15abaAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:58:07 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI Container Group: test-private-468wz successfully
Feb 18, 2022 1:58:07 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:58:07 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:4e210afa-18cf-41ed-ab9c-c2d5d43b3e1eAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:58:07 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=2021-09-01 (404 ms, 2703-byte body), Response body:{"properties":{"sku":"Standard","provisioningState":"Succeeded","containers":[{"name":"test-private-468wz","properties":{"image":"jenkins/inbound-agent","command":["jenkins-agent","-url","http://104.46.15.57:8080/","cdf186de7eebedc88ff58
Febc4888516454988bfde23966cd0e428c82be86e13","test-private-468wz"],"ports":[{"port":80}],"environmentVariables":[],"instanceView":{"restartCount":0,"currentState":{"state":"Running","startTime":"2022-02-18T13:57:50Z","detailStatus":""},"events":[{"count":1,"firstTimestamp":"2022-02-18T13:57:13Z","lastTimestamp":"2022-02-18T13:57:13Z","name":"Pulling","message":"Pulling image \"jenkins/inbound-agent\"","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:49Z","lastTimestamp":"2022-02-18T13:57:49Z","name":"Pulled","message":"Successfully pulled image \"jenkins/inbound-agent\" in 36.574490402s","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Created","message":"Created container test-private-468wz","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:57:50Z","lastTimestamp":"2022-02-18T13:57:50Z","name":"Started","message":"Started container test-private-468wz","type":"Normal"}]},"resources":{"requests":{"memoryInGB":1.5,"cpu":1.0}}}}],"initContainers":[],"imageRegistryCredentials":[],"ipAddress":{"ports":[{"port":80}],"ip":"10.0.2.5","type":"Private","dnsNameLabelReusePolicy":"Unsecure"},"osType":"Linux","instanceView":{"events":[{"count":1,"firstTimestamp":"2022-02-18T13:56:44Z","lastTimestamp":"2022-02-18T13:56:44Z","name":"PrepareNetwork","message":"Prepare network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:45Z","lastTimestamp":"2022-02-18T13:56:45Z","name":"JoinNetwork","message":"Join network succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:48Z","lastTimestamp":"2022-02-18T13:56:48Z","name":"DelegateSubnet","message":"Delegate subnet succeeded.","type":"Normal"},{"count":1,"firstTimestamp":"2022-02-18T13:56:54Z","lastTimestamp":"2022-02-18T13:56:54Z","name":"ProvisionNetworkInterface","message":"Provision network interface succeeded.","type":"Normal"}],"state":"Running"},"subnetIds":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-vnet-rg/providers/Microsoft.Network/virtualNetworks/jenkins-vnet/subnets/jenkins-agent-subnet"}]},"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz","name":"test-private-468wz","type":"Microsoft.ContainerInstance/containerGroups","location":"northeurope","tags":{"JenkinsInstance":"00f3038298bbb562b8f4196bc587cd3a"}} <-- END HTTP
Feb 18, 2022 1:58:08 PM FINE com.microsoft.jenkins.containeragents.aci.AciServiceChecking deployment: test-private-84lg6jbb, provisioning state: Succeeded
Feb 18, 2022 1:58:08 PM INFO com.azure.core.util.logging.ClientLogger info--> DELETE https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:58:08 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:1949ebc5-5592-4d12-89ca-c8c90f1abb77Accept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.resources/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END DELETE
Feb 18, 2022 1:58:08 PM INFO com.azure.core.util.logging.ClientLogger info<-- 200 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (75 ms, 879-byte body), Response body:{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb","name":"test-private-84lg6jbb","type":"Microsoft.Resources/deployments","properties":{"templateHash":"6726754617272928997","parameters":{},"mode":"Incremental","provisioningState":"Succeeded","timestamp":"2022-02-18T13:57:58.2107158Z","duration":"PT1M18.8917182S","correlationId":"084ed39f-ec79-49a7-9d46-6475267d3ef9","providers":[{"namespace":"Microsoft.ContainerInstance","resourceTypes":[{"resourceType":"containerGroups","locations":["northeurope"]}]}],"dependencies":[],"outputs":{"containerIPv4Address":{"type":"String","value":"10.0.2.5"}},"outputResources":[{"id":"/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz"}]}} <-- END HTTP
Feb 18, 2022 1:58:08 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:58:08 PM INFO com.azure.core.util.logging.ClientLogger info<-- 202 https://management.azure.com/subscriptions/xxxx/resourcegroups/jenkins-rg/providers/Microsoft.Resources/deployments/test-private-84lg6jbb?api-version=2021-01-01 (100 ms, 0-byte body), Response body: <-- END HTTP
Feb 18, 2022 1:58:08 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud canProvisionCannot provision: template for label test-private is not available now, because it failed to provision last time. 
Feb 18, 2022 1:58:09 PM INFO com.azure.core.util.logging.ClientLogger info--> GET https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=REDACTEDTry count: 1Date:Fri, 18 
Feb 2022 13:58:09 GMTAuthorization:REDACTEDContent-Length:0x-ms-return-client-request-id:trueContent-Type:application/jsonx-ms-client-request-id:78f17cc2-da2e-433b-81f1-1874cd1fb81eAccept:application/jsonUser-Agent:azsdk-java-com.azure.resourcemanager.containerinstance/2.10.0 (11.0.13; Linux; 5.4.0-1069-azure)(empty body)--> END GET
Feb 18, 2022 1:58:09 PM INFO com.azure.core.util.logging.ClientLogger info<-- 404 https://management.azure.com/subscriptions/xxxx/resourceGroups/jenkins-rg/providers/Microsoft.ContainerInstance/containerGroups/test-private-468wz?api-version=2021-09-01 (25 ms, 244-byte body), Response body:{"error":{"code":"ResourceNotFound","message":"The Resource 'Microsoft.ContainerInstance/containerGroups/test-private-468wz' under resource group 'jenkins-rg' was not found. For more details please go to https://aka.ms/ARMResourceNotFoundFix"}} <-- END HTTP
Feb 18, 2022 1:58:09 PM WARNING com.microsoft.jenkins.containeragents.aci.AciCloud lambda$provision$1AciCloud: Provision agent test-private-468wz failed: com.azure.core.management.exception.ManagementException: Status code 404, "{"error":{"code":"ResourceNotFound","message":"The Resource 'Microsoft.ContainerInstance/containerGroups/test-private-468wz' under resource group 'jenkins-rg' was not found. For more details please go to https://aka.ms/ARMResourceNotFoundFix"}}": The Resource 'Microsoft.ContainerInstance/containerGroups/test-private-468wz' under resource group 'jenkins-rg' was not found. For more details please go to https://aka.ms/ARMResourceNotFoundFix
Feb 18, 2022 1:58:23 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:58:23 PM INFO com.azure.core.util.logging.ClientLogger infoIgnoring decoding of null or empty value to:java.lang.Void
Feb 18, 2022 1:58:23 PM INFO com.microsoft.jenkins.containeragents.aci.AciService deleteAciContainerGroupDelete ACI deployment: test-private-84lg6jbb successfully

@timja I hear you. I doubt that empty ports will result in NPE…

However, I tried @sparsick 's armTemplate and to find protocol to be empty in the created ContainerGroup… I also did a few experiments and it seems that deployment service ignores the protocol property when the ContainerGroup has a Container that has the same port with that in IpAddress but without protocol, as in the provided template sample. Not sure why they did that.

That said, for quick fix, you can add a protocol here so that deployment service will not ignore it.

Or you can wait for next released sdk, which will have NPE fixed in this situation.

@sparsick Thanks for your template sample and yes it’s actually setting the protocol…

Since you mentioned that the NPE is from time to time and the templates are constructed dynamically, can it be that some of them are without protocol ?

To be sure whether this is the case, a log for the actual ARM template used here before the NPE will be of great help!