This job view page is being replaced by Spyglass soon. Check out the new job view.
PRdependabot[bot]: chore(deps): bump github.com/Azure/azure-sdk-for-go from 63.3.0+incompatible to 63.4.0+incompatible
ResultABORTED
Tests 0 failed / 0 succeeded
Started2022-04-27 05:00
Elapsed43m14s
Revision4d3ffb824e87031b0ff3a03ef511c123924699e6
Refs 1582

No Test Failures!


Error lines from build-log.txt

... skipping 79 lines ...
Apr 27 05:06:18.529: INFO: Creating deployment servicelb-test [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:82]
[It] should support updating internal IP when updating internal service
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:181
Apr 27 05:06:18.586: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:35]
Apr 27 05:06:20.454: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-79krd [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:189]
STEP: Waiting for exposure of internal service with specific IP
Apr 27 05:06:20.487: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:06:30.523: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:06:40.523: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:06:50.523: INFO: Exposure successfully, get external ip: 10.240.0.6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:06:50.625: INFO: Events list: [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:202]
Apr 27 05:06:50.625: INFO: 0. {{ } {servicelb-test-76bf687774-4f4nc.16e9a87998b034be  e2e-tests-service-lb-79krd  26fdcd72-fb88-46d0-9c25-2cd35658d829 1884 0 2022-04-27 05:06:18 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-scheduler Update v1 2022-04-27 05:06:18 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-79krd servicelb-test-76bf687774-4f4nc 3083d24b-c70d-4e7c-aa91-f3eaa54f6d5f v1 1878 } Scheduled Successfully assigned e2e-tests-service-lb-79krd/servicelb-test-76bf687774-4f4nc to k8s-agentpool1-21123635-vmss000001 {default-scheduler } 2022-04-27 05:06:18 +0000 UTC 2022-04-27 05:06:18 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:204]
Apr 27 05:06:50.625: INFO: 1. {{ } {servicelb-test-76bf687774-4f4nc.16e9a879d0df0333  e2e-tests-service-lb-79krd  def9add4-9ac5-4e12-aca4-cb204d48018c 1917 0 2022-04-27 05:06:19 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2022-04-27 05:06:19 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:fieldPath":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{},"f:host":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-79krd servicelb-test-76bf687774-4f4nc 3083d24b-c70d-4e7c-aa91-f3eaa54f6d5f v1 1882 spec.containers{test-app}} Pulling Pulling image "nginx:1.15" {kubelet k8s-agentpool1-21123635-vmss000001} 2022-04-27 05:06:19 +0000 UTC 2022-04-27 05:06:19 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:204]
Apr 27 05:06:50.625: INFO: 2. {{ } {servicelb-test-76bf687774-4f4nc.16e9a87aa543f34c  e2e-tests-service-lb-79krd  0136179f-38b5-4f2f-a238-558aad963eb6 1959 0 2022-04-27 05:06:23 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2022-04-27 05:06:23 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:fieldPath":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{},"f:host":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-79krd servicelb-test-76bf687774-4f4nc 3083d24b-c70d-4e7c-aa91-f3eaa54f6d5f v1 1882 spec.containers{test-app}} Pulled Successfully pulled image "nginx:1.15" in 3.563370357s {kubelet k8s-agentpool1-21123635-vmss000001} 2022-04-27 05:06:23 +0000 UTC 2022-04-27 05:06:23 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:204]
Apr 27 05:06:50.625: INFO: 3. {{ } {servicelb-test-76bf687774-4f4nc.16e9a87aa734b1a7  e2e-tests-service-lb-79krd  4bafad64-6674-424b-9ec0-921a86e338a2 1960 0 2022-04-27 05:06:23 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2022-04-27 05:06:23 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:fieldPath":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{},"f:host":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-79krd servicelb-test-76bf687774-4f4nc 3083d24b-c70d-4e7c-aa91-f3eaa54f6d5f v1 1882 spec.containers{test-app}} Created Created container test-app {kubelet k8s-agentpool1-21123635-vmss000001} 2022-04-27 05:06:23 +0000 UTC 2022-04-27 05:06:23 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:204]
... skipping 61 lines ...
[It] should support service annotation 'service.beta.kubernetes.io/azure-load-balancer-health-probe-num-of-probe' and port specific configs
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:418
STEP: Creating a service with health probe annotations
Apr 27 05:08:02.769: INFO: Creating service annotation-test in namespace e2e-tests-service-lp6p6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:08:02.827: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-lp6p6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:08:02.827: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:08:02.860: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:08:12.897: INFO: Exposure successfully, get external ip: 20.97.146.16 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:08:12.897: INFO: checking the connectivity of the public IP 20.97.146.16 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:08:42.897: INFO: got error Get "http://20.97.146.16:80": dial tcp 20.97.146.16:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:09:02.968: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:09:02.968: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
Apr 27 05:09:02.968: INFO: Getting public IPs in the resourceGroup kubetest-zc34zqms [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:732]
Apr 27 05:09:03.465: INFO: Getting public IP frontend configuration ID [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:736]
Apr 27 05:09:03.465: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-zc34zqms/providers/Microsoft.Network/loadBalancers/kubetest-zc34zqms/frontendIPConfigurations/a8360e956eb324d25b8efc9da01b1768 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:749]
Apr 27 05:09:03.465: INFO: Getting loadBalancer name from pipFrontendConfigurationID [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:751]
... skipping 27 lines ...
[It] can set source IP prefixes automatically according to corresponding service tag
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:140
STEP: Creating service and wait it to expose
Apr 27 05:10:00.254: INFO: Creating service nsg-test in namespace e2e-tests-nsg-klh97 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:145]
Apr 27 05:10:00.345: INFO: Successfully created LoadBalancer service nsg-test in namespace e2e-tests-nsg-klh97 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:149]
STEP: Waiting for the service to be exposed
Apr 27 05:10:00.379: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:10:10.414: INFO: Exposure successfully, get external ip: 20.97.146.168 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
STEP: Validating if the corresponding IP prefix existing in nsg
Apr 27 05:10:10.414: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:124]
Apr 27 05:10:10.700: INFO: got sg list, length = 1 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:146]
[AfterEach] Network security group
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:74
... skipping 23 lines ...
[It] should support service annotation `service.beta.kubernetes.io/azure-shared-securityrule`
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:296
STEP: Exposing two services with shared security rule
Apr 27 05:11:03.113: INFO: Creating service annotation-test in namespace e2e-tests-service-vf5b5 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:11:03.169: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-vf5b5 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:11:03.169: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:11:03.203: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:11:13.238: INFO: Exposure successfully, get external ip: 20.186.162.115 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:11:13.238: INFO: checking the connectivity of the public IP 20.186.162.115 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:11:43.239: INFO: got error Get "http://20.186.162.115:80": dial tcp 20.186.162.115:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:12:33.239: INFO: got error Get "http://20.186.162.115:80": dial tcp 20.186.162.115:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:12:43.307: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:12:43.307: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
Apr 27 05:12:43.307: INFO: Creating service annotation-test-share in namespace e2e-tests-service-vf5b5 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:12:43.553: INFO: Successfully created LoadBalancer service annotation-test-share in namespace e2e-tests-service-vf5b5 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:12:43.553: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:12:43.586: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:12:53.623: INFO: Exposure successfully, get external ip: 20.97.146.240 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:12:53.623: INFO: checking the connectivity of the public IP 20.97.146.240 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:13:09.158: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:13:09.158: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
STEP: Validate shared security rule exists
Apr 27 05:13:09.158: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:124]
... skipping 25 lines ...
Apr 27 05:14:12.207: INFO: Creating deployment servicelb-test [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:82]
[It] should support updating an internal service to a public service with assigned IP
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:223
Apr 27 05:14:12.317: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-cxtsl [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:229]
Apr 27 05:14:12.317: INFO: Creating public IP resource named service-lb-internal-none-public-IPddda [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:175]
STEP: Waiting for exposure of the original service without assigned lb private IP
Apr 27 05:14:15.234: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:14:25.270: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:14:35.271: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:14:45.270: INFO: Exposure successfully, get external ip: 10.240.0.6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:14:45.335: INFO: Events list: [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:249]
Apr 27 05:14:45.335: INFO: 0. {{ } {servicelb-test-76bf687774-8gcmj.16e9a8e7e486856f  e2e-tests-service-lb-cxtsl  c95d131f-7511-4bfb-a92c-37a00315c543 4374 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-scheduler Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-cxtsl servicelb-test-76bf687774-8gcmj b778390f-62cf-44e0-a096-645cf00d606d v1 4354 } Scheduled Successfully assigned e2e-tests-service-lb-cxtsl/servicelb-test-76bf687774-8gcmj to k8s-agentpool1-21123635-vmss000001 {default-scheduler } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.336: INFO: 1. {{ } {servicelb-test-76bf687774-8gcmj.16e9a8e81fdc2136  e2e-tests-service-lb-cxtsl  7432fcc4-bdaf-4503-992a-88b6d602ef73 4386 0 2022-04-27 05:14:13 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2022-04-27 05:14:13 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:fieldPath":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{},"f:host":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-cxtsl servicelb-test-76bf687774-8gcmj b778390f-62cf-44e0-a096-645cf00d606d v1 4363 spec.containers{test-app}} Pulling Pulling image "nginx:1.15" {kubelet k8s-agentpool1-21123635-vmss000001} 2022-04-27 05:14:13 +0000 UTC 2022-04-27 05:14:13 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.336: INFO: 2. {{ } {servicelb-test-76bf687774-8gcmj.16e9a8e83fe2d810  e2e-tests-service-lb-cxtsl  413e704d-9516-42c2-90c8-d850267d4f40 4399 0 2022-04-27 05:14:13 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2022-04-27 05:14:13 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:fieldPath":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{},"f:host":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-cxtsl servicelb-test-76bf687774-8gcmj b778390f-62cf-44e0-a096-645cf00d606d v1 4363 spec.containers{test-app}} Pulled Successfully pulled image "nginx:1.15" in 537.296638ms {kubelet k8s-agentpool1-21123635-vmss000001} 2022-04-27 05:14:13 +0000 UTC 2022-04-27 05:14:13 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.336: INFO: 3. {{ } {servicelb-test-76bf687774-8gcmj.16e9a8e841c85c83  e2e-tests-service-lb-cxtsl  bd10b477-59c2-46a8-8a23-85943b10dda6 4400 0 2022-04-27 05:14:13 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2022-04-27 05:14:13 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:fieldPath":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{},"f:host":{}},"f:type":{}}}]} {Pod e2e-tests-service-lb-cxtsl servicelb-test-76bf687774-8gcmj b778390f-62cf-44e0-a096-645cf00d606d v1 4363 spec.containers{test-app}} Created Created container test-app {kubelet k8s-agentpool1-21123635-vmss000001} 2022-04-27 05:14:13 +0000 UTC 2022-04-27 05:14:13 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
... skipping 22 lines ...
Apr 27 05:14:45.337: INFO: 26. {{ } {servicelb-test-76bf687774.16e9a8e7e1437e56  e2e-tests-service-lb-cxtsl  aee0af1b-7c3c-43f2-9b50-c262b64ea99e 4355 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-controller-manager Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {ReplicaSet e2e-tests-service-lb-cxtsl servicelb-test-76bf687774 a12f623e-9ec6-4054-a484-1b59d552b449 apps/v1 4341 } SuccessfulCreate Created pod: servicelb-test-76bf687774-mvlmm {replicaset-controller } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 27. {{ } {servicelb-test-76bf687774.16e9a8e7e18207a3  e2e-tests-service-lb-cxtsl  36f184f6-ab52-45bb-9764-309131c81aea 4362 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-controller-manager Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {ReplicaSet e2e-tests-service-lb-cxtsl servicelb-test-76bf687774 a12f623e-9ec6-4054-a484-1b59d552b449 apps/v1 4341 } SuccessfulCreate Created pod: servicelb-test-76bf687774-nm4kp {replicaset-controller } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 28. {{ } {servicelb-test-76bf687774.16e9a8e7e2939268  e2e-tests-service-lb-cxtsl  af845203-0f10-4fc6-8239-ef6a4e9a55cc 4371 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-controller-manager Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {ReplicaSet e2e-tests-service-lb-cxtsl servicelb-test-76bf687774 a12f623e-9ec6-4054-a484-1b59d552b449 apps/v1 4341 } SuccessfulCreate Created pod: servicelb-test-76bf687774-8gcmj {replicaset-controller } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 29. {{ } {servicelb-test-76bf687774.16e9a8e7e3be9dd1  e2e-tests-service-lb-cxtsl  11c1c644-8a41-411f-ab2a-9b7a47e6cf7f 4376 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-controller-manager Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {ReplicaSet e2e-tests-service-lb-cxtsl servicelb-test-76bf687774 a12f623e-9ec6-4054-a484-1b59d552b449 apps/v1 4341 } SuccessfulCreate Created pod: servicelb-test-76bf687774-95kkv {replicaset-controller } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 30. {{ } {servicelb-test.16e9a8e7df34699a  e2e-tests-service-lb-cxtsl  de8ab11e-1cfe-4908-b0b4-0d2845ec956c 4343 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-controller-manager Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {Deployment e2e-tests-service-lb-cxtsl servicelb-test 067b638f-5dc1-462d-b004-b22c6706ec58 apps/v1 4340 } ScalingReplicaSet Scaled up replica set servicelb-test-76bf687774 to 5 {deployment-controller } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 31. {{ } {servicelb-test.16e9a8e7e29c2fe0  e2e-tests-service-lb-cxtsl  6ebf8aa6-3c7c-4fdf-b9b5-08ef99e89ee9 4361 0 2022-04-27 05:14:12 +0000 UTC <nil> <nil> map[] map[] [] []  [{cloud-controller-manager Update v1 2022-04-27 05:14:12 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {Service e2e-tests-service-lb-cxtsl servicelb-test 77bc52cf-0a17-4062-9912-8b1c61fac872 v1 4357 } EnsuringLoadBalancer Ensuring load balancer {service-controller } 2022-04-27 05:14:12 +0000 UTC 2022-04-27 05:14:12 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 32. {{ } {servicelb-test.16e9a8e8513b2a3b  e2e-tests-service-lb-cxtsl  f2c85c58-7666-48f5-952a-9722cab21575 4412 0 2022-04-27 05:14:14 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-controller-manager Update v1 2022-04-27 05:14:14 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {Endpoints e2e-tests-service-lb-cxtsl servicelb-test 21b64879-4280-4a31-a4b0-de6cd50d9624 v1 4365 } FailedToUpdateEndpoint Failed to update endpoint e2e-tests-service-lb-cxtsl/servicelb-test: Operation cannot be fulfilled on endpoints "servicelb-test": the object has been modified; please apply your changes to the latest version and try again {endpoint-controller } 2022-04-27 05:14:14 +0000 UTC 2022-04-27 05:14:14 +0000 UTC 1 Warning 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
Apr 27 05:14:45.337: INFO: 33. {{ } {servicelb-test.16e9a8ee08137785  e2e-tests-service-lb-cxtsl  d7964e88-3473-4e24-8c89-8f7417e9424f 4523 0 2022-04-27 05:14:38 +0000 UTC <nil> <nil> map[] map[] [] []  [{cloud-controller-manager Update v1 2022-04-27 05:14:38 +0000 UTC FieldsV1 {"f:count":{},"f:firstTimestamp":{},"f:involvedObject":{"f:apiVersion":{},"f:kind":{},"f:name":{},"f:namespace":{},"f:resourceVersion":{},"f:uid":{}},"f:lastTimestamp":{},"f:message":{},"f:reason":{},"f:source":{"f:component":{}},"f:type":{}}}]} {Service e2e-tests-service-lb-cxtsl servicelb-test 77bc52cf-0a17-4062-9912-8b1c61fac872 v1 4357 } EnsuredLoadBalancer Ensured load balancer {service-controller } 2022-04-27 05:14:38 +0000 UTC 2022-04-27 05:14:38 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  } [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:251]
STEP: Updating service to bound to specific public IP
Apr 27 05:14:45.337: INFO: will update IP to 20.98.220.243, 13 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:255]
Apr 27 05:14:45.459: INFO: expected IP is 20.98.220.243, current IP is 10.240.0.6, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:134]
Apr 27 05:14:55.496: INFO: expected IP is 20.98.220.243, current IP is 10.240.0.6, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:134]
Apr 27 05:15:05.498: INFO: expected IP is 20.98.220.243, current IP is 10.240.0.6, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:134]
... skipping 88 lines ...
[It] should support BYO public IP
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:100
STEP: creating a public IP with tags
Apr 27 05:16:36.592: INFO: Creating public IP resource named service-lb-public-IPdfce [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:175]
Apr 27 05:16:40.416: INFO: created pip with address 20.94.71.161 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:112]
STEP: creating a service referencing the public IP
Apr 27 05:16:40.503: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:16:50.539: INFO: Exposure successfully, get external ip: 20.94.71.161 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:16:50.539: INFO: checking the connectivity of the public IP 20.94.71.161 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:17:20.539: INFO: got error Get "http://20.94.71.161:80": dial tcp 20.94.71.161:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:17:47.687: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:17:47.687: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
STEP: deleting the service
Apr 27 05:17:47.687: INFO: Deleting service servicelb-test in namespace e2e-tests-service-lb-b7z9g [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:47]
STEP: test if the pip still exists
STEP: test if the tags are changed
... skipping 24 lines ...
Apr 27 05:18:12.732: INFO: Creating deployment servicelb-test [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/standard_lb.go:64]
[It] should add all nodes in different agent pools to backends [MultipleAgentPools]
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/standard_lb.go:82
Apr 27 05:18:12.774: INFO: Creating service servicelb-test in namespace e2e-tests-service-lb-sx6hv [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:18:12.866: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-sx6hv [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:18:12.866: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:18:12.899: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:18:22.935: INFO: Exposure successfully, get external ip: 20.75.112.254 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:18:22.935: INFO: checking the connectivity of the public IP 20.75.112.254 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:18:52.935: INFO: got error Get "http://20.75.112.254:80": dial tcp 20.75.112.254:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:19:12.997: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:19:12.997: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
Apr 27 05:19:12.998: INFO: Getting public IPs in the resourceGroup kubetest-zc34zqms [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:732]
Apr 27 05:19:13.456: INFO: Getting public IP frontend configuration ID [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:736]
Apr 27 05:19:13.456: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-zc34zqms/providers/Microsoft.Network/loadBalancers/kubetest-zc34zqms/frontendIPConfigurations/a8f726170ba944b54966a478cd42fcaf [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:749]
Apr 27 05:19:13.456: INFO: Getting loadBalancer name from pipFrontendConfigurationID [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:751]
... skipping 30 lines ...
[It] should support service annotation 'service.beta.kubernetes.io/azure-dns-label-name'
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:112
STEP: Create service
Apr 27 05:19:52.335: INFO: Creating service annotation-test in namespace e2e-tests-service-lz8k9 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:19:52.390: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-lz8k9 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:19:52.390: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:19:52.430: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:20:02.465: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:20:12.465: INFO: Exposure successfully, get external ip: 20.96.85.183 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:20:12.465: INFO: checking the connectivity of the public IP 20.96.85.183 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:20:42.465: INFO: got error Get "http://20.96.85.183:80": dial tcp 20.96.85.183:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:21:02.537: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:21:02.537: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
STEP: Validating External domain name
Apr 27 05:21:02.538: INFO: Get domain name: annotation-test9a8a7fdf-faec-4f08-a5b0-f534519ffca0.eastus2.cloudapp.azure.com [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:74]
Apr 27 05:21:02.664: INFO: cleaning up test service annotation-test [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:123]
Apr 27 05:21:02.664: INFO: Deleting service annotation-test in namespace e2e-tests-service-lz8k9 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:47]
... skipping 21 lines ...
[It] should add the rule when expose a service
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:86
STEP: Creating a service and expose it
Apr 27 05:21:43.157: INFO: Creating service nsg-test in namespace e2e-tests-nsg-jkjxr [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:21:43.251: INFO: Successfully created LoadBalancer service nsg-test in namespace e2e-tests-nsg-jkjxr [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:21:43.251: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:21:43.292: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:21:53.327: INFO: Exposure successfully, get external ip: 20.85.48.39 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:21:53.327: INFO: checking the connectivity of the public IP 20.85.48.39 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:22:08.805: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:22:08.805: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
STEP: Validating ip exists in Security Group
Apr 27 05:22:08.805: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:124]
... skipping 31 lines ...
Apr 27 05:22:39.825: INFO: Kubernetes configuration file name: /root/tmp3583022089/kubeconfig/kubeconfig.eastus2.json [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/utils.go:65]
Apr 27 05:22:39.927: INFO: Creating deployment nsg-test [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:68]
[It] should support service annotation `service.beta.kubernetes.io/azure-deny-all-except-load-balancer-source-ranges`
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/network_security_group.go:178
STEP: Creating a test service with the deny rule annotation but without `service.Spec.LoadBalancerSourceRanges`
STEP: Waiting for the service to expose
Apr 27 05:22:40.066: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:22:50.101: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:23:00.101: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:23:10.101: INFO: Exposure successfully, get external ip: 10.240.0.6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
STEP: Checking if there is a deny_all rule
Apr 27 05:23:10.101: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:124]
Apr 27 05:23:10.278: INFO: got sg list, length = 1 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:146]
STEP: Deleting the service
Apr 27 05:23:10.278: INFO: Deleting service nsg-test in namespace e2e-tests-nsg-p59b6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:47]
STEP: Creating a test service with the deny rule annotation and `service.Spec.LoadBalancerSourceRanges`
STEP: Waiting for the service to expose
Apr 27 05:24:02.506: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:24:12.541: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:24:22.542: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:24:32.542: INFO: Exposure successfully, get external ip: 10.240.0.6 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
STEP: Checking if there is a LoadBalancerSourceRanges rule
Apr 27 05:24:32.542: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:124]
Apr 27 05:24:32.668: INFO: got sg list, length = 1 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:146]
STEP: Checking if there is a deny_all rule
[AfterEach] Network security group
... skipping 77 lines ...
Apr 27 05:25:54.405: INFO: Kubernetes configuration file name: /root/tmp3583022089/kubeconfig/kubeconfig.eastus2.json [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/utils.go:65]
[It] should support service annotation 'service.beta.kubernetes.io/azure-load-balancer-tcp-idle-timeout'
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:220
Apr 27 05:25:54.475: INFO: Creating service annotation-test in namespace e2e-tests-service-29x77 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:25:54.531: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-29x77 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:25:54.531: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:25:54.564: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:26:04.600: INFO: Exposure successfully, get external ip: 20.22.33.228 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:26:04.600: INFO: checking the connectivity of the public IP 20.22.33.228 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:92]
Apr 27 05:26:34.600: INFO: got error Get "http://20.22.33.228:80": dial tcp 20.22.33.228:80: i/o timeout, will retry [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:169]
Apr 27 05:26:54.662: INFO: succeeded [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:174]
Apr 27 05:26:54.662: INFO: validation finished [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:182]
Apr 27 05:26:54.663: INFO: Getting public IPs in the resourceGroup kubetest-zc34zqms [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:732]
Apr 27 05:26:54.866: INFO: Getting public IP frontend configuration ID [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:736]
Apr 27 05:26:54.867: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-zc34zqms/providers/Microsoft.Network/loadBalancers/kubetest-zc34zqms/frontendIPConfigurations/a3e80aa8693e44d3d978161b7b188b0a [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:749]
Apr 27 05:26:54.867: INFO: Getting loadBalancer name from pipFrontendConfigurationID [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:751]
... skipping 30 lines ...
Apr 27 05:27:33.373: INFO: Getting virtual network list [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:35]
STEP: Test subnet doesn't exist. Creating a new one...
Apr 27 05:27:35.258: INFO: creating a new subnet a--------------------------------------------------z, 10.0.0.0/24 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/network_utils.go:72]
Apr 27 05:27:35.498: INFO: Creating service annotation-test in namespace e2e-tests-service-hxxwc [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:770]
Apr 27 05:27:35.552: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-hxxwc [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:774]
Apr 27 05:27:35.552: INFO: Waiting service to expose... [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:777]
Apr 27 05:27:35.587: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:27:45.622: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:27:55.622: INFO: Fail to find ingress, retry in 10 seconds [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:128]
Apr 27 05:28:05.621: INFO: Exposure successfully, get external ip: 10.0.0.4 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:143]
Apr 27 05:28:05.626: INFO: Get External IP: 10.0.0.4 [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:212]
STEP: Validating external ip in target subnet
Apr 27 05:28:05.627: INFO: cleaning up test service annotation-test [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:208]
Apr 27 05:28:05.627: INFO: Deleting service annotation-test in namespace e2e-tests-service-hxxwc [/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/utils/service_utils.go:47]
{"component":"entrypoint","file":"k8s.io/test-infra/prow/entrypoint/run.go:169","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2022-04-27T05:28:21Z"}
++ early_exit_handler
++ '[' -n 173 ']'
++ kill -TERM 173
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...