This job view page is being replaced by Spyglass soon. Check out the new job view.
PRfeiskyer: Route updates wait a while for new routes to take effect
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2021-03-04 06:40
Elapsed37m52s
Revisiona16a87d7f5b90f0a523c13329f27d5433c7c7ef9
Refs 528

No Test Failures!


Error lines from build-log.txt

... skipping 76 lines ...
Mar  4 06:51:34.059: INFO: Creating deployment servicelb-test
[It] should make outbound IP of pod same as in SLB's outbound rules
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/standard_lb.go:119
Mar  4 06:51:34.132: INFO: Creating service servicelb-test in namespace e2e-tests-service-lb-n5kpv
Mar  4 06:51:34.267: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-n5kpv
Mar  4 06:51:34.267: INFO: Waiting service to expose...
Mar  4 06:51:34.340: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 06:51:44.401: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 06:51:54.400: INFO: Exposure successfully, get external ip: 52.148.145.19
Mar  4 06:51:54.400: INFO: Getting public IPs in the resourceGroup kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06
Mar  4 06:51:55.027: INFO: Getting public IP frontend configuration ID
Mar  4 06:51:55.027: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/providers/Microsoft.Network/loadBalancers/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/frontendIPConfigurations/a63ef123e02ba4d968c1d0d20552b466
Mar  4 06:51:55.027: INFO: Getting loadBalancer name from pipFrontendConfigurationID
Mar  4 06:51:55.028: INFO: Got loadBalancerName "kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06"
... skipping 25 lines ...
Mar  4 06:52:52.024: INFO: Creating deployment servicelb-test
[It] should have no operation since no change in service when update [Slow]
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:221
Mar  4 06:52:52.084: INFO: Creating public IP resource named service-lb-public-remain773b
Mar  4 06:52:56.565: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-9b428
STEP: Waiting for exposure of the original service with assigned lb private IP
Mar  4 06:52:56.622: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 06:53:06.682: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 06:53:16.683: INFO: Exposure successfully, get external ip: 52.137.96.226
STEP: Update without changing the service and wait for a while
Mar  4 06:53:16.683: INFO: External IP is now 52.137.96.226
Mar  4 06:53:16.740: INFO: testlabel
Mar  4 06:53:16.862: INFO: External IP is still 52.137.96.226
Mar  4 06:53:26.921: INFO: External IP is still 52.137.96.226
... skipping 56 lines ...
Mar  4 07:02:56.921: INFO: External IP is still 52.137.96.226
Mar  4 07:03:06.922: INFO: External IP is still 52.137.96.226
Mar  4 07:03:16.922: INFO: External IP is still 52.137.96.226
Mar  4 07:03:16.981: INFO: External IP is still 52.137.96.226
STEP: Cleaning up
Mar  4 07:03:17.054: INFO: Deleting public IP resource named service-lb-public-remain773b in resource group kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06
Mar  4 07:03:17.496: INFO: error: %!w(autorest.DetailedError={0xc0002ee060 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:03:19.692: INFO: error: %!w(autorest.DetailedError={0xc0004168a0 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
[AfterEach] Ensure LoadBalancer
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:76
Mar  4 07:03:21.786: INFO: Deleting namespace e2e-tests-service-lb-9b428

• [SLOW TEST:658.169 seconds]
Ensure LoadBalancer
... skipping 14 lines ...
Mar  4 07:03:50.194: INFO: Creating deployment servicelb-test
[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:175
Mar  4 07:03:50.361: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-4r72q
Mar  4 07:03:50.361: INFO: Creating public IP resource named service-lb-internal-none-public-IP2920
STEP: Waiting for exposure of the original service without assigned lb private IP
Mar  4 07:03:54.670: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:04:04.730: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:04:14.733: INFO: Exposure successfully, get external ip: 10.240.0.6
Mar  4 07:04:14.849: INFO: Events list:
Mar  4 07:04:14.849: INFO: 0. {{ } {servicelb-test-76bf687774-g9j6b.166911b99b7f1fc9  e2e-tests-service-lb-4r72q  63d9ba6b-e3b8-4ac9-98b1-b26695ec6fb8 4627 0 2021-03-04 07:03:50 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-scheduler Update v1 2021-03-04 07:03:50 +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-4r72q servicelb-test-76bf687774-g9j6b 3cc0158f-1b6e-4a0f-9988-0c983c757eeb v1 4616 } Scheduled Successfully assigned e2e-tests-service-lb-4r72q/servicelb-test-76bf687774-g9j6b to k8s-agentpool1-58516308-1 {default-scheduler } 2021-03-04 07:03:50 +0000 UTC 2021-03-04 07:03:50 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
Mar  4 07:04:14.849: INFO: 1. {{ } {servicelb-test-76bf687774-g9j6b.166911b9e160873e  e2e-tests-service-lb-4r72q  524fa0a0-0d89-4029-bc11-a9e6950cbda5 4660 0 2021-03-04 07:03:51 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2021-03-04 07:03:51 +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-4r72q servicelb-test-76bf687774-g9j6b 3cc0158f-1b6e-4a0f-9988-0c983c757eeb v1 4619 spec.containers{test-app}} Pulling Pulling image "nginx:1.15" {kubelet k8s-agentpool1-58516308-1} 2021-03-04 07:03:51 +0000 UTC 2021-03-04 07:03:51 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
Mar  4 07:04:14.849: INFO: 2. {{ } {servicelb-test-76bf687774-g9j6b.166911ba2f5dc86c  e2e-tests-service-lb-4r72q  feaff9d1-b481-453c-8562-cc66e31035a9 4673 0 2021-03-04 07:03:52 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2021-03-04 07:03:52 +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-4r72q servicelb-test-76bf687774-g9j6b 3cc0158f-1b6e-4a0f-9988-0c983c757eeb v1 4619 spec.containers{test-app}} Pulled Successfully pulled image "nginx:1.15" in 1.308414927s {kubelet k8s-agentpool1-58516308-1} 2021-03-04 07:03:52 +0000 UTC 2021-03-04 07:03:52 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
Mar  4 07:04:14.849: INFO: 3. {{ } {servicelb-test-76bf687774-g9j6b.166911ba35d0c7b6  e2e-tests-service-lb-4r72q  b5202a95-467f-4c55-83e9-2c08dde5074d 4678 0 2021-03-04 07:03:52 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2021-03-04 07:03:52 +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-4r72q servicelb-test-76bf687774-g9j6b 3cc0158f-1b6e-4a0f-9988-0c983c757eeb v1 4619 spec.containers{test-app}} Created Created container test-app {kubelet k8s-agentpool1-58516308-1} 2021-03-04 07:03:52 +0000 UTC 2021-03-04 07:03:52 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
... skipping 31 lines ...
Mar  4 07:04:15.025: INFO: still unmatched external IP, retry it in 10s seconds
Mar  4 07:04:25.085: INFO: still unmatched external IP, retry it in 10s seconds
Mar  4 07:04:35.089: INFO: still unmatched external IP, retry it in 10s seconds
Mar  4 07:04:45.093: INFO: Exposure successfully
STEP: Cleaning up
Mar  4 07:04:45.171: INFO: Deleting public IP resource named service-lb-internal-none-public-IP2920 in resource group kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06
Mar  4 07:04:45.506: INFO: error: %!w(autorest.DetailedError={0xc00034b740 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:04:47.714: INFO: error: %!w(autorest.DetailedError={0xc00034b9e0 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
[AfterEach] Ensure LoadBalancer
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:76
Mar  4 07:04:49.800: INFO: Deleting namespace e2e-tests-service-lb-4r72q

• [SLOW TEST:88.016 seconds]
Ensure LoadBalancer
... skipping 51 lines ...
STEP: resource group e2e-07f2 created
STEP: creating test PIP in the test resource group
Mar  4 07:05:25.705: INFO: Creating public IP resource named testPIP-024f
STEP: Creating service annotation-test in namespace e2e-tests-service-8gjrc
Mar  4 07:05:29.380: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-8gjrc
STEP: Waiting service to expose...
Mar  4 07:05:29.438: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:05:39.499: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:05:49.501: INFO: Exposure successfully, get external ip: 40.125.100.159
Mar  4 07:05:49.501: INFO: Getting public IPs in the resourceGroup e2e-07f2
Mar  4 07:05:49.592: INFO: Getting public IP frontend configuration ID
Mar  4 07:05:49.593: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/providers/Microsoft.Network/loadBalancers/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/frontendIPConfigurations/a284b96ecc54345a8b5b5a6e72a3b546
Mar  4 07:05:49.593: INFO: Getting loadBalancer name from pipFrontendConfigurationID
Mar  4 07:05:49.593: INFO: Got loadBalancerName "kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06"
... skipping 84 lines ...
[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:132
Mar  4 07:08:00.653: INFO: Getting virtual network list
Mar  4 07:08:00.854: INFO: found one k8s virtual network k8s-vnet-58516308
Mar  4 07:08:01.451: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-kw6xc
STEP: Waiting for exposure of internal service with specific IP
Mar  4 07:08:01.515: INFO: Fail to get ingress, retry it in 10s seconds
Mar  4 07:08:11.577: INFO: Fail to get ingress, retry it in 10s seconds
Mar  4 07:08:21.576: INFO: Fail to get ingress, retry it in 10s seconds
Mar  4 07:08:31.577: INFO: Exposure successfully
Mar  4 07:08:31.741: INFO: Events list:
Mar  4 07:08:31.741: INFO: 0. {{ } {servicelb-test-76bf687774-2ng22.166911f3eb550165  e2e-tests-service-lb-kw6xc  532231c6-dac1-4dd2-98e9-0a38eb6bd846 6023 0 2021-03-04 07:08:00 +0000 UTC <nil> <nil> map[] map[] [] []  [{kube-scheduler Update v1 2021-03-04 07:08:00 +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-kw6xc servicelb-test-76bf687774-2ng22 8ee4657c-e8a6-417b-9d66-7abb5a0a50c3 v1 6007 } Scheduled Successfully assigned e2e-tests-service-lb-kw6xc/servicelb-test-76bf687774-2ng22 to k8s-agentpool1-58516308-1 {default-scheduler } 2021-03-04 07:08:00 +0000 UTC 2021-03-04 07:08:00 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
Mar  4 07:08:31.741: INFO: 1. {{ } {servicelb-test-76bf687774-2ng22.166911f42f9e5c56  e2e-tests-service-lb-kw6xc  d4081394-b0c9-4436-ba30-a188d262974d 6038 0 2021-03-04 07:08:01 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2021-03-04 07:08:01 +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-kw6xc servicelb-test-76bf687774-2ng22 8ee4657c-e8a6-417b-9d66-7abb5a0a50c3 v1 6012 spec.containers{test-app}} Pulling Pulling image "nginx:1.15" {kubelet k8s-agentpool1-58516308-1} 2021-03-04 07:08:01 +0000 UTC 2021-03-04 07:08:01 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
Mar  4 07:08:31.741: INFO: 2. {{ } {servicelb-test-76bf687774-2ng22.166911f513aaca99  e2e-tests-service-lb-kw6xc  5453be31-27df-41a2-827e-bbed8a47d9ef 6085 0 2021-03-04 07:08:05 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2021-03-04 07:08:05 +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-kw6xc servicelb-test-76bf687774-2ng22 8ee4657c-e8a6-417b-9d66-7abb5a0a50c3 v1 6012 spec.containers{test-app}} Pulled Successfully pulled image "nginx:1.15" in 3.826004508s {kubelet k8s-agentpool1-58516308-1} 2021-03-04 07:08:05 +0000 UTC 2021-03-04 07:08:05 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
Mar  4 07:08:31.741: INFO: 3. {{ } {servicelb-test-76bf687774-2ng22.166911f51822f94c  e2e-tests-service-lb-kw6xc  358afa92-f97d-4478-819f-7d6314d0f208 6086 0 2021-03-04 07:08:05 +0000 UTC <nil> <nil> map[] map[] [] []  [{kubelet Update v1 2021-03-04 07:08:05 +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-kw6xc servicelb-test-76bf687774-2ng22 8ee4657c-e8a6-417b-9d66-7abb5a0a50c3 v1 6012 spec.containers{test-app}} Created Created container test-app {kubelet k8s-agentpool1-58516308-1} 2021-03-04 07:08:05 +0000 UTC 2021-03-04 07:08:05 +0000 UTC 1 Normal 0001-01-01 00:00:00 +0000 UTC nil  nil  }
... skipping 56 lines ...
Mar  4 07:09:17.035: INFO: Creating a test namespace
Mar  4 07:09:17.093: INFO: Creating a kubernetes client
Mar  4 07:09:17.093: INFO: Kubernetes configuration file name: /root/tmp945248523/kubeconfig/kubeconfig.westus2.json
Mar  4 07:09:17.266: INFO: Creating deployment servicelb-test
[It] should support multiple internal services sharing one IP address
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:349
Mar  4 07:09:17.499: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:09:27.561: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:09:37.559: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:09:47.556: INFO: Exposure successfully, get external ip: 10.240.0.6
Mar  4 07:09:47.556: INFO: Successfully created LoadBalancer service1 in namespace e2e-tests-service-lb-kprpp with IP 10.240.0.6
Mar  4 07:09:47.692: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:09:57.750: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:10:07.750: INFO: Exposure successfully, get external ip: 10.240.0.6
Mar  4 07:10:07.750: INFO: Successfully created LoadBalancer service2 in namespace e2e-tests-service-lb-kprpp with IP 10.240.0.6
STEP: Cleaning up
[AfterEach] Ensure LoadBalancer
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:76
Mar  4 07:10:07.965: INFO: Deleting namespace e2e-tests-service-lb-kprpp
... skipping 20 lines ...
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/service_annotations.go:378
STEP: Creating two test pips
Mar  4 07:10:42.437: INFO: Creating public IP resource named pip1
Mar  4 07:10:47.338: INFO: Creating public IP resource named pip2
STEP: Creating a service referring to the first pip
STEP: Waiting for the service to expose
Mar  4 07:10:50.583: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:11:00.644: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:11:10.644: INFO: Exposure successfully, get external ip: 40.125.99.193
STEP: Updating the service to refer to the second service
STEP: Waiting for service IP to be updated
Mar  4 07:11:10.831: INFO: Exposure successfully, get external ip: 40.125.99.193
Mar  4 07:11:20.892: INFO: Exposure successfully, get external ip: 40.125.99.193
Mar  4 07:11:30.896: INFO: Exposure successfully, get external ip: 40.125.99.193
... skipping 31 lines ...
Mar  4 07:12:32.214: INFO: found one k8s virtual network k8s-vnet-58516308
STEP: Test subnet doesn't exist. Creating a new one...
Mar  4 07:12:34.654: INFO: creating a new subnet lb-subnet, 10.0.0.0/24
Mar  4 07:12:34.955: INFO: Creating service annotation-test in namespace e2e-tests-service-p5s96
Mar  4 07:12:35.042: INFO: Successfully created LoadBalancer service annotation-test in namespace e2e-tests-service-p5s96
Mar  4 07:12:35.042: INFO: Waiting service to expose...
Mar  4 07:12:35.104: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:12:45.164: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:12:55.165: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:13:05.164: INFO: Exposure successfully, get external ip: 10.0.0.4
Mar  4 07:13:05.164: INFO: Get External IP: 10.0.0.4
STEP: Validating external ip in target subnet
Mar  4 07:13:05.164: INFO: cleaning up test service annotation-test
Mar  4 07:13:05.243: INFO: Deleting service annotation-test in namespace e2e-tests-service-p5s96
Mar  4 07:13:27.356: INFO: cleaning up test subnet lb-subnet
... skipping 21 lines ...
Mar  4 07:13:40.047: INFO: Creating deployment servicelb-test
[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:80
Mar  4 07:13:40.104: INFO: Creating service servicelb-test in namespace e2e-tests-service-lb-fq8wf
Mar  4 07:13:40.232: INFO: Successfully created LoadBalancer service servicelb-test in namespace e2e-tests-service-lb-fq8wf
Mar  4 07:13:40.232: INFO: Waiting service to expose...
Mar  4 07:13:40.294: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:13:50.352: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:14:00.355: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:14:10.354: INFO: Exposure successfully, get external ip: 40.64.80.33
Mar  4 07:14:10.354: INFO: Getting public IPs in the resourceGroup kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06
Mar  4 07:14:10.768: INFO: Getting public IP frontend configuration ID
Mar  4 07:14:10.768: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/providers/Microsoft.Network/loadBalancers/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/frontendIPConfigurations/a36c7150c35954f4eb82cb9506194f10
Mar  4 07:14:10.768: INFO: Getting loadBalancer name from pipFrontendConfigurationID
Mar  4 07:14:10.768: INFO: Got loadBalancerName "kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06"
... skipping 21 lines ...
Mar  4 07:14:49.437: INFO: Kubernetes configuration file name: /root/tmp945248523/kubeconfig/kubeconfig.westus2.json
Mar  4 07:14:49.609: INFO: Creating deployment servicelb-test
[It] should support node label `node.kubernetes.io/exclude-from-external-load-balancers`
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:380
STEP: Checking the number of the node pools
STEP: Creating a service to trigger the LB reconcile
Mar  4 07:14:49.863: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:14:59.923: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:15:09.921: INFO: Exposure successfully, get external ip: 40.64.80.33
STEP: Checking the initial node number in the LB backend pool
Mar  4 07:15:09.921: INFO: Getting public IPs in the resourceGroup kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06
Mar  4 07:15:10.101: INFO: Getting public IP frontend configuration ID
Mar  4 07:15:10.101: INFO: Successfully obtained PIP front config id: /subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/providers/Microsoft.Network/loadBalancers/kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06/frontendIPConfigurations/a0606aaa096514cc2a7731bf6920b79c
Mar  4 07:15:10.101: INFO: Getting loadBalancer name from pipFrontendConfigurationID
... skipping 48 lines ...
Mar  4 07:16:01.689: INFO: Creating a kubernetes client
Mar  4 07:16:01.690: INFO: Kubernetes configuration file name: /root/tmp945248523/kubeconfig/kubeconfig.westus2.json
Mar  4 07:16:01.862: INFO: Creating deployment servicelb-test
[It] should support multiple external services sharing one preset public IP address
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:261
Mar  4 07:16:01.932: INFO: Creating public IP resource named service-lb-public-remain151d
Mar  4 07:16:06.303: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:16:16.364: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:16:26.363: INFO: Exposure successfully, get external ip: 40.64.81.54
Mar  4 07:16:26.363: INFO: Successfully created LoadBalancer service1 in namespace e2e-tests-service-lb-4w5g9 with IP 40.64.81.54
Mar  4 07:16:26.496: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:16:36.556: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:16:46.557: INFO: Exposure successfully, get external ip: 40.64.81.54
Mar  4 07:16:46.557: INFO: Successfully created LoadBalancer service2 in namespace e2e-tests-service-lb-4w5g9 with IP 40.64.81.54
STEP: Cleaning up
Mar  4 07:16:46.702: INFO: Deleting public IP resource named service-lb-public-remain151d in resource group kubetest-7ad1538f-7cb4-11eb-bc2b-820a87a9de06
Mar  4 07:16:47.010: INFO: error: %!w(autorest.DetailedError={0xc000315b00 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:16:49.199: INFO: error: %!w(autorest.DetailedError={0xc000315f20 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:16:51.205: INFO: error: %!w(autorest.DetailedError={0xc00038bec0 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:16:53.202: INFO: error: %!w(autorest.DetailedError={0xc00010e180 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:16:55.232: INFO: error: %!w(autorest.DetailedError={0xc00033c9c0 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:16:57.210: INFO: error: %!w(autorest.DetailedError={0xc00010e360 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:16:59.212: INFO: error: %!w(autorest.DetailedError={0xc00036cae0 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:17:01.198: INFO: error: %!w(autorest.DetailedError={0xc00010e5a0 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
Mar  4 07:17:03.200: INFO: error: %!w(autorest.DetailedError={0xc00036d020 network.PublicIPAddressesClient Delete 0 Failure sending request [] <nil>}), will retry soon
[AfterEach] Ensure LoadBalancer
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:76
Mar  4 07:17:05.294: INFO: Deleting namespace e2e-tests-service-lb-4w5g9

• [SLOW TEST:91.839 seconds]
Ensure LoadBalancer
... skipping 11 lines ...
Mar  4 07:17:33.469: INFO: Creating a test namespace
Mar  4 07:17:33.527: INFO: Creating a kubernetes client
Mar  4 07:17:33.527: INFO: Kubernetes configuration file name: /root/tmp945248523/kubeconfig/kubeconfig.westus2.json
Mar  4 07:17:33.699: INFO: Creating deployment servicelb-test
[It] should support multiple external services sharing one newly created public IP address
  /home/prow/go/src/sigs.k8s.io/cloud-provider-azure/tests/e2e/network/ensureloadbalancer.go:298
Mar  4 07:17:33.924: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:17:43.983: INFO: Fail to find ingress, retry it in 10 seconds
Mar  4 07:17:53.985: INFO: Exposure successfully, get external ip: 40.64.80.86
Mar  4 07:17:53.985: INFO: Successfully created LoadBalancer service1 in namespace e2e-tests-service-lb-d2m4t with IP 40.64.80.86
Mar  4 07:17:54.118: INFO: Fail to find ingress, retry it in 10 seconds
{"component":"entrypoint","file":"prow/entrypoint/run.go:169","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2021-03-04T07:18:03Z"}