This job view page is being replaced by Spyglass soon. Check out the new job view.
PRMartinForReal: [release-1.24] fix: should update dns label if target pip already have one and it needs update
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2022-11-04 08:06
Elapsed5m57s
Revision658ab19dcb8d5ec7fcf71cedc6d3bbd064fa591f
Refs 2701

No Test Failures!


Error lines from build-log.txt

... skipping 1225 lines ...
    --- PASS: TestNodeUpdateRetryTimeout/count_1 (0.00s)
    --- PASS: TestNodeUpdateRetryTimeout/count_2 (0.00s)
    --- PASS: TestNodeUpdateRetryTimeout/count_3 (0.00s)
    --- PASS: TestNodeUpdateRetryTimeout/count_50 (0.00s)
=== RUN   TestNewCloudCIDRAllocator
I1104 08:11:48.147095   24503 cloud_cidr_allocator.go:100] Sending events to api server.
E1104 08:11:48.147814   24503 range_allocator.go:325] Error filtering out service cidr out cluster cidr:10.10.0.0/24 (index:0) 10.10.0.0/25: CIDR: 0.0.0.0/0 is out of the range of CIDR allocator
I1104 08:11:48.147924   24503 cloud_cidr_allocator.go:200] Using cloud CIDR allocator (provider: azure)
--- PASS: TestNewCloudCIDRAllocator (0.00s)
=== RUN   TestUpdateMaxSubnetSizes
=== RUN   TestUpdateMaxSubnetSizes/updateMaxSubnetSizes_should_calculate_the_correct_max_sizes
=== RUN   TestUpdateMaxSubnetSizes/updateMaxSubnetSizes_should_work_with_the_dual_stack_cidrs
--- PASS: TestUpdateMaxSubnetSizes (0.00s)
... skipping 24 lines ...
I1104 08:11:48.155455   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.155727   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
=== RUN   TestOccupyPreExistingCIDR/success,_dual_stack_both_allocated_correctly
I1104 08:11:48.157087   24503 range_allocator.go:82] Sending events to api server.
I1104 08:11:48.157571   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.157665   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
=== RUN   TestOccupyPreExistingCIDR/fail,_single_stack_incorrect_node_allocation
I1104 08:11:48.158463   24503 range_allocator.go:82] Sending events to api server.
I1104 08:11:48.158806   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.158850   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
=== RUN   TestOccupyPreExistingCIDR/fail,_dualstack_node_allocating_from_non_existing_cidr
I1104 08:11:48.159790   24503 range_allocator.go:82] Sending events to api server.
I1104 08:11:48.160715   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.160849   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
=== RUN   TestOccupyPreExistingCIDR/fail,_dualstack_node_allocating_bad_v4
I1104 08:11:48.161265   24503 range_allocator.go:82] Sending events to api server.
I1104 08:11:48.161671   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.161713   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
=== RUN   TestOccupyPreExistingCIDR/fail,_dualstack_node_allocating_bad_v6
I1104 08:11:48.162274   24503 range_allocator.go:82] Sending events to api server.
I1104 08:11:48.162474   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.162506   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
--- PASS: TestOccupyPreExistingCIDR (0.01s)
    --- PASS: TestOccupyPreExistingCIDR/success,_single_stack_no_node_allocation (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/success,_dual_stack_no_node_allocation (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/success,_single_stack_correct_node_allocation (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/success,_dual_stack_both_allocated_correctly (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/fail,_single_stack_incorrect_node_allocation (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/fail,_dualstack_node_allocating_from_non_existing_cidr (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/fail,_dualstack_node_allocating_bad_v4 (0.00s)
    --- PASS: TestOccupyPreExistingCIDR/fail,_dualstack_node_allocating_bad_v6 (0.00s)
=== RUN   TestAllocateOrOccupyCIDRSuccess
I1104 08:11:48.163161   24503 range_allocator.go:82] Sending events to api server.
I1104 08:11:48.163358   24503 range_allocator.go:110] No Service CIDR provided. Skipping filtering out service addresses.
I1104 08:11:48.163394   24503 range_allocator.go:116] No Secondary Service CIDR provided. Skipping filtering out secondary service addresses.
I1104 08:11:48.163612   24503 range_allocator.go:172] Starting range CIDR allocator
I1104 08:11:48.163738   24503 shared_informer.go:255] Waiting for caches to sync for cidrallocator
... skipping 224 lines ...
--- PASS: TestGetPrivateIPsForMachine (0.00s)
=== RUN   TestGetIPForMachineWithRetry
W1104 08:12:18.752735   25343 azure_wrap.go:77] Unable to find node vm: instance not found
E1104 08:12:18.752791   25343 azure_backoff.go:141] GetIPForMachineWithRetry(vm): backoff failure, will retry,err=instance not found
--- PASS: TestGetIPForMachineWithRetry (0.00s)
=== RUN   TestCreateOrUpdateSecurityGroupCanceled
W1104 08:12:18.753441   25343 azure_backoff.go:164] CreateOrUpdateSecurityGroup(sg) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: canceledandsupersededduetoanotheroperation, NSG request: {}
--- PASS: TestCreateOrUpdateSecurityGroupCanceled (0.00s)
=== RUN   TestCreateOrUpdateLB
W1104 08:12:18.754196   25343 azure_backoff.go:225] LoadBalancerClient.CreateOrUpdate(lb) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 412, RawError: %!w(<nil>), LoadBalancer request: {}
W1104 08:12:18.754661   25343 azure_backoff.go:225] LoadBalancerClient.CreateOrUpdate(lb) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: canceledandsupersededduetoanotheroperation, LoadBalancer request: {}
W1104 08:12:18.755094   25343 azure_backoff.go:225] LoadBalancerClient.CreateOrUpdate(lb) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: Code="ReferencedResourceNotProvisioned" Message="Cannot proceed with operation because resource /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Network/publicIPAddresses/pip used by resource /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Network/loadBalancers/lb is not in Succeeded state. Resource is in Failed state and the last operation that updated/is updating the resource is PutPublicIpAddressOperation.", LoadBalancer request: {}
--- PASS: TestCreateOrUpdateLB (0.00s)
=== RUN   TestListAgentPoolLBs
E1104 08:12:18.756764   25343 azure_backoff.go:381] LoadBalancerClient.List(rg) failure with err=&{false 500 0001-01-01 00:00:00 +0000 UTC <nil>}
W1104 08:12:18.757545   25343 azure_backoff.go:333] ListManagedLBs: no LBs found
--- PASS: TestListAgentPoolLBs (0.00s)
=== RUN   TestListPIP
E1104 08:12:18.758562   25343 azure_backoff.go:399] PublicIPAddressesClient.List(rg) failure with err=&{false 500 0001-01-01 00:00:00 +0000 UTC <nil>}
--- PASS: TestListPIP (0.00s)
=== RUN   TestCreateOrUpdatePIP
W1104 08:12:18.759525   25343 azure_backoff.go:421] PublicIPAddressesClient.CreateOrUpdate(rg, nic) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 412, RawError: %!w(<nil>), PublicIP request: {}
W1104 08:12:18.760357   25343 azure_backoff.go:421] PublicIPAddressesClient.CreateOrUpdate(rg, nic) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: canceledandsupersededduetoanotheroperation, PublicIP request: {}
W1104 08:12:18.760994   25343 azure_backoff.go:421] PublicIPAddressesClient.CreateOrUpdate(rg, nic) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: %!w(<nil>), PublicIP request: {}
--- PASS: TestCreateOrUpdatePIP (0.00s)
=== RUN   TestCreateOrUpdateInterface
E1104 08:12:18.761525   25343 azure_backoff.go:448] InterfacesClient.CreateOrUpdate(nic) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: %!w(<nil>)
--- PASS: TestCreateOrUpdateInterface (0.00s)
=== RUN   TestDeletePublicIP
E1104 08:12:18.762253   25343 azure_backoff.go:463] PublicIPAddressesClient.Delete(pip) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: %!w(<nil>)
--- PASS: TestDeletePublicIP (0.00s)
=== RUN   TestDeleteLB
E1104 08:12:18.762658   25343 azure_backoff.go:491] LoadBalancerClient.Delete(lb) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: %!w(<nil>)
--- PASS: TestDeleteLB (0.00s)
=== RUN   TestCreateOrUpdateRouteTable
W1104 08:12:18.763089   25343 azure_backoff.go:509] RouteTablesClient.CreateOrUpdate(rt) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 412, RawError: %!w(<nil>), RouteTable request: {}
E1104 08:12:18.763203   25343 azure_backoff.go:521] RouteTablesClient.CreateOrUpdate(rt) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 412, RawError: %!w(<nil>)
W1104 08:12:18.763552   25343 azure_backoff.go:509] RouteTablesClient.CreateOrUpdate(rt) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: canceledandsupersededduetoanotheroperation, RouteTable request: {}
E1104 08:12:18.763649   25343 azure_backoff.go:521] RouteTablesClient.CreateOrUpdate(rt) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: canceledandsupersededduetoanotheroperation
--- PASS: TestCreateOrUpdateRouteTable (0.00s)
=== RUN   TestCreateOrUpdateRoute
--- PASS: TestCreateOrUpdateRoute (0.00s)
=== RUN   TestDeleteRouteWithName
E1104 08:12:18.765246   25343 azure_backoff.go:560] RoutesClient.Delete(rt, rt) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: %!w(<nil>)
--- PASS: TestDeleteRouteWithName (0.00s)
=== RUN   TestCreateOrUpdateVMSS
E1104 08:12:18.766081   25343 azure_backoff.go:574] CreateOrUpdateVMSS: error getting vmss(vmss): &{false 500 0001-01-01 00:00:00 +0000 UTC <nil>}
E1104 08:12:18.766258   25343 azure_backoff.go:574] CreateOrUpdateVMSS: error getting vmss(vmss): &{false 429 0001-01-01 00:00:00 +0000 UTC <nil>}
E1104 08:12:18.766388   25343 azure_backoff.go:574] CreateOrUpdateVMSS: error getting vmss(vmss): &{false 0 0001-01-01 00:00:00 +0000 UTC azure cloud provider rate limited(write) for operation CreateOrUpdate}
--- PASS: TestCreateOrUpdateVMSS (0.00s)
=== RUN   TestRequestBackoff
--- PASS: TestRequestBackoff (0.00s)
=== RUN   TestCreateOrUpdateLBBackendPool
--- PASS: TestCreateOrUpdateLBBackendPool (0.00s)
=== RUN   TestDeleteLBBackendPool
... skipping 12 lines ...
    --- PASS: TestGetConfigFromSecret/Error_should_be_reported_when_secret_doesn't_exists (0.00s)
    --- PASS: TestGetConfigFromSecret/Error_should_be_reported_when_secret_exists_but_cloud-config_data_is_not_provided (0.00s)
    --- PASS: TestGetConfigFromSecret/Error_should_be_reported_when_it_failed_to_parse_Azure_cloud-config (0.00s)
=== RUN   TestInitializeCloudFromSecret
=== RUN   TestInitializeCloudFromSecret/Azure_config_shouldn't_be_override_when_cloud_config_type_is_file
=== RUN   TestInitializeCloudFromSecret/Azure_config_shouldn't_be_override_when_cloud_config_type_is_unknown
E1104 08:12:18.785650   25343 azure_config.go:55] Failed to initialize Azure cloud provider: cloudConfigType UnknownConfigType is not supported, supported values are [file merge secret]
=== RUN   TestInitializeCloudFromSecret/Azure_config_should_be_override_when_cloud_config_type_is_secret
=== RUN   TestInitializeCloudFromSecret/Azure_config_should_be_override_when_cloud_config_type_is_merge
=== RUN   TestInitializeCloudFromSecret/Error_should_be_reported_when_secret_doesn't_exists
E1104 08:12:18.792776   25343 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
=== RUN   TestInitializeCloudFromSecret/Error_should_be_reported_when_secret_exists_but_cloud-config_data_is_not_provided
E1104 08:12:18.793723   25343 azure_config.go:45] Failed to get cloud-config from secret: cloud-config is not set in the secret (kube-system/azure-cloud-provider)
--- PASS: TestInitializeCloudFromSecret (0.01s)
    --- PASS: TestInitializeCloudFromSecret/Azure_config_shouldn't_be_override_when_cloud_config_type_is_file (0.00s)
    --- PASS: TestInitializeCloudFromSecret/Azure_config_shouldn't_be_override_when_cloud_config_type_is_unknown (0.00s)
    --- PASS: TestInitializeCloudFromSecret/Azure_config_should_be_override_when_cloud_config_type_is_secret (0.00s)
    --- PASS: TestInitializeCloudFromSecret/Azure_config_should_be_override_when_cloud_config_type_is_merge (0.00s)
    --- PASS: TestInitializeCloudFromSecret/Error_should_be_reported_when_secret_doesn't_exists (0.00s)
... skipping 3 lines ...
=== RUN   TestConfigSecretMetadata/configSecretMetadata_should_set_the_secret_metadata_from_the_default_values
--- PASS: TestConfigSecretMetadata (0.00s)
    --- PASS: TestConfigSecretMetadata/configSecretMetadata_should_set_the_secret_metadata_from_the_given_parameters (0.00s)
    --- PASS: TestConfigSecretMetadata/configSecretMetadata_should_set_the_secret_metadata_from_the_default_values (0.00s)
=== RUN   TestCommonAttachDisk
W1104 08:12:18.796151   25343 azure_wrap.go:77] Unable to find node vm1: instance not found
E1104 08:12:18.796203   25343 azure_controller_common.go:466] error of getting data disks for node vm1: instance not found
W1104 08:12:18.797882   25343 azure_controller_common.go:183] size of disk(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name) is 4096GB which is bigger than limit(4096GB), set cacheMode as None
W1104 08:12:18.798661   25343 azure_controller_common.go:183] size of disk(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name) is 4096GB which is bigger than limit(4096GB), set cacheMode as None
W1104 08:12:18.799025   25343 azure_controller_common.go:162] volume /subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name is actually attached to current node vm1, invalidate vm cache and return error
--- PASS: TestCommonAttachDisk (0.00s)
=== RUN   TestCommonAttachDiskWithVMSS
E1104 08:12:18.800561   25343 azure_controller_common.go:466] error of getting data disks for node vm1: error of converting vmSet (&{%!q(*provider.Cloud=&{{{ tenant     false  subscription    } {{false 0 0 0 0} <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil> <nil>}  rg westus   vnet rg subnet nsg rg rt rg as vmss vmss  map[]      false false false false false  0 0 <nil> <nil> 250 0 0 0 0 0 60 120 120 120 120 600 120 0  nodeIPConfiguration 0 rg} {  } {                              {            }} 0xc0003f9400 0xc0003f94a0 0xc0003f93b0 0xc0003f9440 0xc0003f93c0 0xc0003f93d0 0xc0003f9480 0xc0003f9550 <nil> 0xc0003f9350 0xc0003f9390 <nil> 0xc0003f94d0 0xc0003f9510 <nil> <nil> <nil> <nil> <nil> <nil> <nil> 0xc0003f9590 {0 0 0 0 0} <nil> 0xc0003f95d0 0xc0003f97d0 false false {{0 0} 0 0 0 0} map[] map[] map[] map[] map[] map[] 0x228f400 {0 0} map[] map[westus:[1 2 3]] {{0 0} 0 0 0 0} <nil> <nil> 0xc0003f9310 <nil> 0xc0002b2180 0xc0002b2270 0xc0002b2360 0xc0002b2450 0xc0002b2540 0xc0002b2630 <nil> {0 0} 0xc000322bc0 0xc0002b62a0}) %!q(*cache.TimedCache=&{0xc000519590 {0 0} 0x2309a40 600000000000})}) to ScaleSet with vmType "vmss"
E1104 08:12:18.800739   25343 azure_controller_common.go:466] error of getting data disks for node vmss1: not a vmss instance
--- PASS: TestCommonAttachDiskWithVMSS (0.00s)
=== RUN   TestCommonDetachDisk
W1104 08:12:18.801098   25343 azure_wrap.go:77] Unable to find node vm1: instance not found
W1104 08:12:18.801152   25343 azure_standard.go:520] Unable to find node vm1: instance not found
W1104 08:12:18.801181   25343 azure_controller_common.go:315] azureDisk - failed to get azure instance id(vm1), DetachDisk(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name) will assume disk is already detached
--- PASS: TestCommonDetachDisk (0.00s)
=== RUN   TestCommonUpdateVM
--- PASS: TestCommonUpdateVM (0.00s)
=== RUN   TestGetDiskLun
--- PASS: TestGetDiskLun (0.00s)
=== RUN   TestSetDiskLun
... skipping 11 lines ...
=== RUN   TestFilterNonExistingDisks
E1104 08:12:18.811375   25343 azure_controller_common.go:588] disk(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/DiskName2) does not exist, removed from data disk list
E1104 08:12:18.811463   25343 azure_controller_common.go:588] disk(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/DiskName3) does not exist, removed from data disk list
E1104 08:12:18.811532   25343 azure_controller_common.go:588] disk(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/DiskName4) does not exist, removed from data disk list
--- PASS: TestFilterNonExistingDisks (0.00s)
=== RUN   TestFilterNonExistingDisksWithSpecialHTTPStatusCode
E1104 08:12:18.811797   25343 azure_controller_common.go:583] checkDiskExists(/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/specialdisk) failed with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 400, RawError: instance not found
--- PASS: TestFilterNonExistingDisksWithSpecialHTTPStatusCode (0.00s)
=== RUN   TestIsInstanceNotFoundError
--- PASS: TestIsInstanceNotFoundError (0.00s)
=== RUN   TestAttachDiskRequestFuncs
--- PASS: TestAttachDiskRequestFuncs (0.00s)
=== RUN   TestDetachDiskRequestFuncs
--- PASS: TestDetachDiskRequestFuncs (0.00s)
=== RUN   TestStandardAttachDisk
W1104 08:12:18.813896   25343 azure_wrap.go:77] Unable to find node vm2: instance not found
E1104 08:12:18.814845   25343 azure_controller_standard.go:105] azureDisk - attach disk list(map[uri:%!s(*provider.AttachDiskOptions=&{ReadOnly  DiskEncryptionSetID false 0})]) on rg(rg) vm(vm1) failed, err: &{false 404 0001-01-01 00:00:00 +0000 UTC instance not found}
E1104 08:12:18.814942   25343 azure_controller_standard.go:107] azureDisk - begin to filterNonExistingDisks(map[uri:0xc0003129c0]) on rg(rg) vm(vm1)
E1104 08:12:18.814982   25343 azure_controller_common.go:583] checkDiskExists(uri) failed with error: invalid disk URI: uri
--- PASS: TestStandardAttachDisk (0.00s)
=== RUN   TestStandardDetachDisk
W1104 08:12:18.815407   25343 azure_wrap.go:77] Unable to find node vm2: instance not found
W1104 08:12:18.815456   25343 azure_controller_standard.go:139] azureDisk - cannot find node vm2, skip detaching disk list(map[])
E1104 08:12:18.815803   25343 azure_controller_standard.go:168] detach azure disk on node(vm1): disk list(map[/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/diskx:diskx]) not found
E1104 08:12:18.817481   25343 azure_controller_standard.go:168] detach azure disk on node(vm1): disk list(map[]) not found
E1104 08:12:18.817579   25343 azure_controller_standard.go:197] azureDisk - detach disk list(map[]) on rg(rg) vm(vm1) failed, err: &{false 404 0001-01-01 00:00:00 +0000 UTC instance not found}
E1104 08:12:18.817645   25343 azure_controller_standard.go:199] azureDisk - begin to filterNonExistingDisks(map[]) on rg(rg) vm(vm1)
--- PASS: TestStandardDetachDisk (0.00s)
=== RUN   TestStandardUpdateVM
--- PASS: TestStandardUpdateVM (0.00s)
=== RUN   TestGetDataDisks
W1104 08:12:18.819395   25343 azure_wrap.go:77] Unable to find node vm2: instance not found
--- PASS: TestGetDataDisks (2.00s)
=== RUN   TestAttachDiskWithVMSS
--- PASS: TestAttachDiskWithVMSS (0.00s)
=== RUN   TestDetachDiskWithVMSS
E1104 08:12:20.835626   25343 azure_controller_vmss.go:201] azureDisk - detach disk list(map[/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name:disk-name]) on rg(rg) vm(vmss00-vm-000000) failed, err: &{false 404 0001-01-01 00:00:00 +0000 UTC instance not found}
E1104 08:12:20.835756   25343 azure_controller_vmss.go:203] azureDisk - begin to filterNonExistingDisks(map[/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name:disk-name]) on rg(rg) vm(vmss00-vm-000000)
E1104 08:12:20.837401   25343 azure_controller_vmss.go:171] detach azure disk on node(vmss00-vm-000000): disk list(map[/subscriptions/subscription/resourceGroups/rg/providers/Microsoft.Compute/disks/disk-name-err:disk-name-err]) not found
--- PASS: TestDetachDiskWithVMSS (0.01s)
=== RUN   TestUpdateVMWithVMSS
--- PASS: TestUpdateVMWithVMSS (0.01s)
=== RUN   TestGetDataDisksWithVMSS
... skipping 14 lines ...
E1104 08:12:20.899525   25343 azure_backoff.go:141] GetIPForMachineWithRetry(vm2): backoff failure, will retry,err=instance not found
--- PASS: TestNodeAddresses (0.03s)
=== RUN   TestInstanceExistsByProviderID
W1104 08:12:20.912770   25343 azure_wrap.go:77] Unable to find node vm3: instance not found
W1104 08:12:20.912838   25343 azure_standard.go:520] Unable to find node vm3: instance not found
E1104 08:12:20.917213   25343 azure_vmss.go:415] Unable to find node by providerID azure:///subscriptions/script/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/vmssee6c2/virtualMachines/0: instance not found
E1104 08:12:20.918141   25343 azure_vmss.go:756] VirtualMachineScaleSetVMsClient.List(rg, missing-vmss) failed: &{false 404 0001-01-01 00:00:00 +0000 UTC <nil>}
E1104 08:12:20.918223   25343 azure_vmss.go:415] Unable to find node by providerID azure:///subscriptions/script/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/missing-vmss/virtualMachines/0: instance not found
--- PASS: TestInstanceExistsByProviderID (0.01s)
=== RUN   TestNodeAddressesByProviderID
--- PASS: TestNodeAddressesByProviderID (0.01s)
=== RUN   TestCurrentNodeName
--- PASS: TestCurrentNodeName (0.00s)
... skipping 19 lines ...
--- PASS: TestCleanupVMSetFromBackendPoolForInstanceNotFound (0.00s)
=== RUN   TestReconcileBackendPoolsNodeIPConfig
--- PASS: TestReconcileBackendPoolsNodeIPConfig (0.00s)
=== RUN   TestReconcileBackendPoolsNodeIPConfigPreConfigured
--- PASS: TestReconcileBackendPoolsNodeIPConfigPreConfigured (0.00s)
=== RUN   TestReconcileBackendPoolsNodeIPToIPConfig
E1104 08:12:20.939786   25343 azure_loadbalancer_backendpool.go:172] bc.ReconcileBackendPools for service (default/test): failed to cleanup IP based backend pool testCluster: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: create or update LB backend pool error
--- PASS: TestReconcileBackendPoolsNodeIPToIPConfig (0.00s)
=== RUN   TestReconcileBackendPoolsNodeIP
--- PASS: TestReconcileBackendPoolsNodeIP (0.00s)
=== RUN   TestReconcileBackendPoolsNodeIPPreConfigured
--- PASS: TestReconcileBackendPoolsNodeIPPreConfigured (0.00s)
=== RUN   TestReconcileBackendPoolsNodeIPConfigToIP
E1104 08:12:20.940861   25343 azure_loadbalancer_backendpool.go:463] bi.ReconcileBackendPools for service (default/test): failed to EnsureBackendPoolDeleted: delete LB backend pool error
--- PASS: TestReconcileBackendPoolsNodeIPConfigToIP (0.00s)
=== RUN   TestRemoveNodeIPAddressFromBackendPool
--- PASS: TestRemoveNodeIPAddressFromBackendPool (0.00s)
=== RUN   TestGetBackendPrivateIPsNodeIPConfig
W1104 08:12:20.941329   25343 azure_loadbalancer_backendpool.go:256] bc.GetBackendPrivateIPs for service (default/svc1): failed to get private IPs of node node2
--- PASS: TestGetBackendPrivateIPsNodeIPConfig (0.00s)
=== RUN   TestGetLoadBalancer
--- PASS: TestGetLoadBalancer (0.00s)
=== RUN   TestFindProbe
--- PASS: TestFindProbe (0.00s)
=== RUN   TestFindRule
--- PASS: TestFindRule (0.00s)
=== RUN   TestSubnet
--- PASS: TestSubnet (0.00s)
=== RUN   TestEnsureLoadBalancerDeleted
E1104 08:12:20.959910   25343 azure_loadbalancer.go:139] reconcileLoadBalancer(default/service6) failed: findMatchedPIPByLoadBalancerIP: cannot find public IP with IP address 1.2.3.4 in resource group random-rg
E1104 08:12:20.961225   25343 azure_loadbalancer.go:139] reconcileLoadBalancer(default/service7) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 404, RawError: instance not found
W1104 08:12:20.961647   25343 azure_backoff.go:333] ListManagedLBs: no LBs found
W1104 08:12:20.961779   25343 azure_backoff.go:333] ListManagedLBs: no LBs found
--- PASS: TestEnsureLoadBalancerDeleted (0.02s)
=== RUN   TestServiceOwnsPublicIP
=== RUN   TestServiceOwnsPublicIP/false_should_be_returned_when_pip_is_nil
W1104 08:12:20.962374   25343 azure_loadbalancer.go:3373] serviceOwnsPublicIP: nil service or public IP
... skipping 119 lines ...
W1104 08:12:21.017311   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
=== RUN   TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv6
W1104 08:12:21.017717   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
=== RUN   TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv6#01
W1104 08:12:21.018267   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
    azure_loadbalancer_test.go:4417: 
        	Error Trace:	/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/pkg/provider/azure_loadbalancer_test.go:4417
        	Error:      	Not equal: 
        	            	expected: &network.PublicIPAddress{Response:autorest.Response{Response:(*http.Response)(nil)}, ExtendedLocation:(*network.ExtendedLocation)(nil), Sku:(*network.PublicIPAddressSku)(nil), PublicIPAddressPropertiesFormat:(*network.PublicIPAddressPropertiesFormat)(0xc000763550), Etag:(*string)(nil), Zones:(*[]string)(nil), ID:(*string)(0xc0007be170), Name:(*string)(0xc0007be160), Type:(*string)(nil), Location:(*string)(nil), Tags:map[string]*string(nil)}
        	            	actual  : &network.PublicIPAddress{Response:autorest.Response{Response:(*http.Response)(nil)}, ExtendedLocation:(*network.ExtendedLocation)(nil), Sku:(*network.PublicIPAddressSku)(nil), PublicIPAddressPropertiesFormat:(*network.PublicIPAddressPropertiesFormat)(0xc0007e0c60), Etag:(*string)(nil), Zones:(*[]string)(nil), ID:(*string)(0xc000806820), Name:(*string)(0xc000806610), Type:(*string)(nil), Location:(*string)(nil), Tags:map[string]*string{"k8s-azure-dns-label-service":(*string)(0xc0008066a0)}}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
... skipping 5 lines ...
        	            	+ }
        	            	 })
        	Test:       	TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv6#01
=== RUN   TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv4
W1104 08:12:21.019969   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
    azure_loadbalancer_test.go:4417: 
        	Error Trace:	/home/prow/go/src/sigs.k8s.io/cloud-provider-azure/pkg/provider/azure_loadbalancer_test.go:4417
        	Error:      	Not equal: 
        	            	expected: &network.PublicIPAddress{Response:autorest.Response{Response:(*http.Response)(nil)}, ExtendedLocation:(*network.ExtendedLocation)(nil), Sku:(*network.PublicIPAddressSku)(nil), PublicIPAddressPropertiesFormat:(*network.PublicIPAddressPropertiesFormat)(0xc0007636b0), Etag:(*string)(nil), Zones:(*[]string)(nil), ID:(*string)(0xc0007be1c0), Name:(*string)(0xc0007be1b0), Type:(*string)(nil), Location:(*string)(nil), Tags:map[string]*string(nil)}
        	            	actual  : &network.PublicIPAddress{Response:autorest.Response{Response:(*http.Response)(nil)}, ExtendedLocation:(*network.ExtendedLocation)(nil), Sku:(*network.PublicIPAddressSku)(nil), PublicIPAddressPropertiesFormat:(*network.PublicIPAddressPropertiesFormat)(0xc0007e0f20), Etag:(*string)(nil), Zones:(*[]string)(nil), ID:(*string)(0xc000806ed0), Name:(*string)(0xc000806cc0), Type:(*string)(nil), Location:(*string)(nil), Tags:map[string]*string{"k8s-azure-dns-label-service":(*string)(0xc000806d50)}}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
... skipping 12 lines ...
=== RUN   TestEnsurePublicIPExists/shall_tag_the_service_name_to_the_pip_correctly
W1104 08:12:21.022651   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
=== RUN   TestEnsurePublicIPExists/shall_not_call_the_PUT_API_for_IPV6_pip_if_it_is_not_necessary
W1104 08:12:21.023078   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
=== RUN   TestEnsurePublicIPExists/shall_update_pip_tags_if_there_is_any_change
W1104 08:12:21.023500   25343 azure_loadbalancer.go:3378] serviceOwnsPublicIP: empty pip.IPAddress
--- FAIL: TestEnsurePublicIPExists (0.01s)
    --- PASS: TestEnsurePublicIPExists/shall_return_existed_PIP_if_there_is_any (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_create_a_new_pip_if_there_is_no_existed_pip (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_delete_DNS_from_PIP_if_DNS_label_is_set_empty (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_not_delete_DNS_from_PIP_if_DNS_label_annotation_is_not_set (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv6 (0.00s)
    --- FAIL: TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv6#01 (0.00s)
    --- FAIL: TestEnsurePublicIPExists/shall_update_existed_PIP's_dns_label_for_IPv4 (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_report_an_conflict_error_if_the_DNS_label_is_conflicted (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_return_the_pip_without_calling_PUT_API_if_the_tags_are_good (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_tag_the_service_name_to_the_pip_correctly (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_not_call_the_PUT_API_for_IPV6_pip_if_it_is_not_necessary (0.00s)
    --- PASS: TestEnsurePublicIPExists/shall_update_pip_tags_if_there_is_any_change (0.00s)
=== RUN   TestEnsurePublicIPExistsWithExtendedLocation
... skipping 20 lines ...
--- PASS: TestIsFrontendIPConfigIsUnsafeToDelete (0.00s)
=== RUN   TestCheckLoadBalancerResourcesConflicted
--- PASS: TestCheckLoadBalancerResourcesConflicted (0.00s)
=== RUN   TestEnsurePIPTagged
=== RUN   TestEnsurePIPTagged/ensurePIPTagged_should_ensure_the_pip_is_tagged_as_configured
W1104 08:12:21.032846   25343 azure_utils.go:115] parseTags: empty key, ignoring this key-value pair
W1104 08:12:21.032913   25343 azure_utils.go:110] parseTags: error when parsing key-value pair ghi, would ignore this one
=== RUN   TestEnsurePIPTagged/ensurePIPTagged_should_delete_the_old_tags_if_the_SystemTags_is_set
W1104 08:12:21.033353   25343 azure_utils.go:115] parseTags: empty key, ignoring this key-value pair
W1104 08:12:21.033400   25343 azure_utils.go:110] parseTags: error when parsing key-value pair ghi, would ignore this one
=== RUN   TestEnsurePIPTagged/ensurePIPTagged_should_support_TagsMap
W1104 08:12:21.033726   25343 azure_utils.go:115] parseTags: empty key, ignoring this key-value pair
W1104 08:12:21.033826   25343 azure_utils.go:110] parseTags: error when parsing key-value pair ghi, would ignore this one
--- PASS: TestEnsurePIPTagged (0.00s)
    --- PASS: TestEnsurePIPTagged/ensurePIPTagged_should_ensure_the_pip_is_tagged_as_configured (0.00s)
    --- PASS: TestEnsurePIPTagged/ensurePIPTagged_should_delete_the_old_tags_if_the_SystemTags_is_set (0.00s)
    --- PASS: TestEnsurePIPTagged/ensurePIPTagged_should_support_TagsMap (0.00s)
=== RUN   TestEnsureLoadBalancerTagged
=== RUN   TestEnsureLoadBalancerTagged/ensureLoadBalancerTagged_should_not_delete_the_old_tags_if_SystemTags_is_not_specified
... skipping 16 lines ...
=== RUN   TestRemoveFrontendIPConfigurationFromLoadBalancerUpdate
=== RUN   TestRemoveFrontendIPConfigurationFromLoadBalancerUpdate/removeFrontendIPConfigurationFromLoadBalancer_should_remove_the_unwanted_frontend_IP_configuration_and_update_the_LB_if_there_are_remaining_frontend_IP_configurations
--- PASS: TestRemoveFrontendIPConfigurationFromLoadBalancerUpdate (0.00s)
    --- PASS: TestRemoveFrontendIPConfigurationFromLoadBalancerUpdate/removeFrontendIPConfigurationFromLoadBalancer_should_remove_the_unwanted_frontend_IP_configuration_and_update_the_LB_if_there_are_remaining_frontend_IP_configurations (0.00s)
=== RUN   TestCleanOrphanedLoadBalancerLBInUseByVMSS
=== RUN   TestCleanOrphanedLoadBalancerLBInUseByVMSS/cleanOrphanedLoadBalancer_should_retry_deleting_lb_when_meeting_LoadBalancerInUseByVirtualMachineScaleSet
E1104 08:12:21.037509   25343 azure_backoff.go:491] LoadBalancerClient.Delete(test) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: {
	"error": {
    	"code": "LoadBalancerInUseByVirtualMachineScaleSet",
    	"message": "Cannot delete load balancer /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Network/loadBalancers/lb since its child resources lb are in use by virtual machine scale set /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/vmss.",
    	"details": []
  	}
}
W1104 08:12:21.037561   25343 azure_loadbalancer.go:483] cleanOrphanedLoadBalancer(test, default/test, test): failed to DeleteLB: &{false 0 0001-01-01 00:00:00 +0000 UTC {
	"error": {
    	"code": "LoadBalancerInUseByVirtualMachineScaleSet",
    	"message": "Cannot delete load balancer /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Network/loadBalancers/lb since its child resources lb are in use by virtual machine scale set /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/vmss.",
    	"details": []
  	}
}}
=== RUN   TestCleanOrphanedLoadBalancerLBInUseByVMSS/cleanupOrphanedLoadBalancer_should_not_call_delete_api_if_the_lb_does_not_exist
--- PASS: TestCleanOrphanedLoadBalancerLBInUseByVMSS (0.00s)
    --- PASS: TestCleanOrphanedLoadBalancerLBInUseByVMSS/cleanOrphanedLoadBalancer_should_retry_deleting_lb_when_meeting_LoadBalancerInUseByVirtualMachineScaleSet (0.00s)
    --- PASS: TestCleanOrphanedLoadBalancerLBInUseByVMSS/cleanupOrphanedLoadBalancer_should_not_call_delete_api_if_the_lb_does_not_exist (0.00s)
=== RUN   TestReconcileZonesForFrontendIPConfigs
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_reconcile_the_zones_for_the_new_fip_config
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_reconcile_the_zones_for_the_new_internal_fip_config
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_report_an_error_if_failed_to_get_zones
E1104 08:12:21.040467   25343 azure_zones.go:100] getRegionZonesMapWrapper: failed to fetch zones information: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: get zone failed
E1104 08:12:21.040546   25343 azure_loadbalancer.go:1912] reconcileLoadBalancer for service (default/test)(true): failed to getFrontendZones: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: get zone failed
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_use_the_nil_zones_of_the_existing_frontend
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_use_the_non-nil_zones_of_the_existing_frontend
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_reuse_the_existing_private_IP_for_internal_services_when_subnet_does_not_change
=== RUN   TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_not_reuse_the_existing_private_IP_for_internal_services_when_subnet_changes
--- PASS: TestReconcileZonesForFrontendIPConfigs (0.00s)
    --- PASS: TestReconcileZonesForFrontendIPConfigs/reconcileFrontendIPConfigs_should_reconcile_the_zones_for_the_new_fip_config (0.00s)
... skipping 8 lines ...
=== RUN   TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_do_nothing_if_the_basic_load_balancer_is_used
W1104 08:12:21.043420   25343 azure_backoff.go:333] ListManagedLBs: no LBs found
=== RUN   TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_do_nothing_if_`nodes`_is_nil
W1104 08:12:21.043779   25343 azure_backoff.go:333] ListManagedLBs: no LBs found
=== RUN   TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_do_nothing_if_the_vmSet_is_not_sharing_the_primary_slb
=== RUN   TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_report_an_error_if_failed_to_list_managed_load_balancers
E1104 08:12:21.044859   25343 azure_backoff.go:381] LoadBalancerClient.List(rg) failure with err=&{false 0 0001-01-01 00:00:00 +0000 UTC error}
--- PASS: TestReconcileSharedLoadBalancer (0.00s)
    --- PASS: TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_decouple_the_vmSet_from_its_dedicated_lb_if_the_vmSet_is_sharing_the_primary_slb (0.00s)
    --- PASS: TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_do_nothing_if_the_basic_load_balancer_is_used (0.00s)
    --- PASS: TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_do_nothing_if_`nodes`_is_nil (0.00s)
    --- PASS: TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_do_nothing_if_the_vmSet_is_not_sharing_the_primary_slb (0.00s)
    --- PASS: TestReconcileSharedLoadBalancer/reconcileSharedLoadBalancer_should_report_an_error_if_failed_to_list_managed_load_balancers (0.00s)
... skipping 11 lines ...
--- PASS: TestDeleteManagedDisk (0.00s)
=== RUN   TestGetDisk
--- PASS: TestGetDisk (0.00s)
=== RUN   TestResizeDisk
--- PASS: TestResizeDisk (0.00s)
=== RUN   TestGetLabelsForVolume
E1104 08:12:21.050567   25343 azure_managedDiskController.go:415] Failed to get information for AzureDisk fakeGetDiskFailed: &{false 0 0001-01-01 00:00:00 +0000 UTC Get Disk failed}
E1104 08:12:21.050865   25343 azure_managedDiskController.go:402] Failed to get resource group for AzureDisk invalidDiskURI: invalid disk URI: invalidDiskURI
--- PASS: TestGetLabelsForVolume (0.00s)
=== RUN   TestReconcilePrivateLinkService
--- PASS: TestReconcilePrivateLinkService (0.00s)
=== RUN   TestDisablePLSNetworkPolicy
--- PASS: TestDisablePLSNetworkPolicy (0.00s)
=== RUN   TestSafeDeletePLS
... skipping 62 lines ...
--- PASS: TestInitializeCloudProviderRateLimitConfig (0.00s)
=== RUN   TestDeleteRoute
--- PASS: TestDeleteRoute (0.10s)
=== RUN   TestDeleteRouteDualStack
--- PASS: TestDeleteRouteDualStack (0.20s)
=== RUN   TestCreateRoute
E1104 08:12:21.471116   25343 azure_routes.go:130] getRouteTable() failed with error: Route table name is not configured
E1104 08:12:21.471293   25343 azure_routes.go:438] CreateRoute failed for node "node" with error: Route table name is not configured
W1104 08:12:21.674408   25343 azure_backoff.go:509] RouteTablesClient.CreateOrUpdate(rt2) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error, RouteTable request: {"location":"location","properties":{"routes":[{"name":"node","properties":{"addressPrefix":"1.2.3.4/24","nextHopIpAddress":"2.4.6.8","nextHopType":"VirtualAppliance"}}]}}
E1104 08:12:21.674852   25343 azure_backoff.go:521] RouteTablesClient.CreateOrUpdate(rt2) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error
E1104 08:12:21.675062   25343 azure_routes.go:207] CreateOrUpdateRouteTable() failed with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error
E1104 08:12:21.675233   25343 azure_routes.go:438] CreateRoute failed for node "node" with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error
W1104 08:12:21.872369   25343 azure_backoff.go:509] RouteTablesClient.CreateOrUpdate(rt4) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error, RouteTable request: {"location":"location","properties":{}}
E1104 08:12:21.872466   25343 azure_backoff.go:521] RouteTablesClient.CreateOrUpdate(rt4) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error
E1104 08:12:21.872501   25343 azure_routes.go:138] createRouteTable() failed with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error
E1104 08:12:21.872678   25343 azure_routes.go:438] CreateRoute failed for node "node" with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: CreateOrUpdate error
E1104 08:12:21.972562   25343 azure_routes.go:144] getRouteTable() failed with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: Get error
E1104 08:12:21.972840   25343 azure_routes.go:438] CreateRoute failed for node "node" with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: Get error
E1104 08:12:22.072191   25343 azure_routes.go:130] getRouteTable() failed with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: Get error
E1104 08:12:22.072268   25343 azure_routes.go:438] CreateRoute failed for node "node" with error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 500, RawError: Get error
E1104 08:12:22.072599   25343 azure_backoff.go:141] GetIPForMachineWithRetry(node): backoff failure, will retry,err=getIP error
--- PASS: TestCreateRoute (0.80s)
=== RUN   TestCreateRouteTable
--- PASS: TestCreateRouteTable (0.00s)
=== RUN   TestProcessRoutes
--- PASS: TestProcessRoutes (0.00s)
=== RUN   TestFindFirstIPByFamily
... skipping 54 lines ...
--- PASS: TestGetIPConfigByIPFamily (0.00s)
=== RUN   TestGetBackendPoolName
--- PASS: TestGetBackendPoolName (0.00s)
=== RUN   TestGetStandardInstanceIDByNodeName
W1104 08:12:22.181412   25343 azure_wrap.go:77] Unable to find node vm2: instance not found
W1104 08:12:22.181467   25343 azure_standard.go:520] Unable to find node vm2: instance not found
E1104 08:12:22.182015   25343 azure_standard.go:539] convertResourceGroupNameToLower failed with error: "/subscriptions/subscription/resourceGroups/rg/Microsoft.Compute/virtualMachines/vm4" isn't in Azure resource ID format ".*/subscriptions/(?:.*)/resourceGroups/(.+)/providers/(?:.*)"
--- PASS: TestGetStandardInstanceIDByNodeName (0.00s)
=== RUN   TestGetStandardVMPowerStatusByNodeName
W1104 08:12:22.182312   25343 azure_wrap.go:77] Unable to find node vm1: instance not found
--- PASS: TestGetStandardVMPowerStatusByNodeName (0.00s)
=== RUN   TestGetStandardVMProvisioningStateByNodeName
W1104 08:12:22.183166   25343 azure_wrap.go:77] Unable to find node vm1: instance not found
... skipping 7 lines ...
E1104 08:12:22.185272   25343 azure_standard.go:761] as.GetVMSetNames - No availability sets found for nodes in the cluster, node count(1)
E1104 08:12:22.185425   25343 azure_standard.go:773] as.GetVMSetNames - Availability set (vm2) in service annotation not found
--- PASS: TestGetStandardVMSetNames (0.00s)
=== RUN   TestExtractResourceGroupByNicID
--- PASS: TestExtractResourceGroupByNicID (0.00s)
=== RUN   TestStandardEnsureHostInPool
E1104 08:12:22.187125   25343 azure_standard.go:931] error: az.EnsureHostInPool(vm2), az.VMSet.GetPrimaryInterface.Get(vm2, availabilityset-1), err=resource name was missing from identifier
W1104 08:12:22.187732   25343 azure_standard.go:936] EnsureHostInPool skips node vm3 because its primary nic nic3 is in Failed state
--- PASS: TestStandardEnsureHostInPool (0.01s)
=== RUN   TestStandardEnsureHostsInPool
--- PASS: TestStandardEnsureHostsInPool (0.00s)
=== RUN   TestServiceOwnsFrontendIP
W1104 08:12:22.193953   25343 azure_standard.go:366] serviceOwnsFrontendIP: unexpected error when finding match public IP of the service  with loadBalancerLP 1.2.3.4: findMatchedPIPByLoadBalancerIP: cannot find public IP with IP address 1.2.3.4 in resource group rg
--- PASS: TestServiceOwnsFrontendIP (0.00s)
=== RUN   TestStandardEnsureBackendPoolDeleted
--- PASS: TestStandardEnsureBackendPoolDeleted (0.00s)
=== RUN   TestStandardGetNodeNameByIPConfigurationID
--- PASS: TestStandardGetNodeNameByIPConfigurationID (0.00s)
=== RUN   TestGetAvailabilitySetByNodeName
W1104 08:12:22.197608   25343 azure_standard.go:1280] Unable to find node vm-2: instance not found
E1104 08:12:22.197791   25343 azure_standard.go:469] AvailabilitySetsClient.List failed: &{false 0 0001-01-01 00:00:00 +0000 UTC error during vmas list}
--- PASS: TestGetAvailabilitySetByNodeName (0.00s)
=== RUN   TestGetNodeCIDRMasksByProviderIDAvailabilitySet
=== RUN   TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMasksByProviderID_should_report_an_error_if_the_providerID_is_not_valid
=== RUN   TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes
=== RUN   TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_report_cloudprovider.InstanceNotFound_if_there_is_no_matching_vmas
W1104 08:12:22.200466   25343 azure_standard.go:1280] Unable to find node vm-1: instance not found
=== RUN   TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes_even_if_some_of_the_tags_are_not_specified
=== RUN   TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_not_fail_even_if_some_of_the_tag_is_invalid
E1104 08:12:22.202375   25343 azure_standard.go:1306] GetNodeCIDRMasksByProviderID: error when paring the value of the ipv4 mask size abc: strconv.Atoi: parsing "abc": invalid syntax
--- PASS: TestGetNodeCIDRMasksByProviderIDAvailabilitySet (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMasksByProviderID_should_report_an_error_if_the_providerID_is_not_valid (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_report_cloudprovider.InstanceNotFound_if_there_is_no_matching_vmas (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes_even_if_some_of_the_tags_are_not_specified (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderIDAvailabilitySet/GetNodeCIDRMaksByProviderID_should_not_fail_even_if_some_of_the_tag_is_invalid (0.00s)
... skipping 4 lines ...
--- PASS: TestGetAvailabilitySetNameByID (0.00s)
    --- PASS: TestGetAvailabilitySetNameByID/getAvailabilitySetNameByID_should_return_empty_string_if_the_given_ID_is_empty (0.00s)
    --- PASS: TestGetAvailabilitySetNameByID/getAvailabilitySetNameByID_should_report_an_error_if_the_format_of_the_given_ID_is_wrong (0.00s)
    --- PASS: TestGetAvailabilitySetNameByID/getAvailabilitySetNameByID_should_extract_the_VMAS_name_from_the_given_ID (0.00s)
=== RUN   TestGetNodeVMSetName
W1104 08:12:22.203366   25343 azure_standard.go:795] as.GetNodeVMSetName: cannot get host name from node 
E1104 08:12:22.203467   25343 azure_backoff.go:98] VirtualMachinesClient.List(rg) failure with err=&{false 0 0001-01-01 00:00:00 +0000 UTC error}
E1104 08:12:22.203539   25343 azure_standard.go:801] as.GetNodeVMSetName - ListVirtualMachines failed, err=Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
E1104 08:12:22.203653   25343 azure_standard.go:813] as.GetNodeVMSetName: failed to get last segment of ID /: resource name was missing from identifier
--- PASS: TestGetNodeVMSetName (0.00s)
=== RUN   TestCreateFileShare
--- PASS: TestCreateFileShare (0.00s)
=== RUN   TestDeleteFileShare
--- PASS: TestDeleteFileShare (0.00s)
=== RUN   TestResizeFileShare
... skipping 26 lines ...
=== RUN   TestFlipServiceInternalAnnotation
--- PASS: TestFlipServiceInternalAnnotation (0.00s)
=== RUN   TestAddPort
--- PASS: TestAddPort (0.00s)
=== RUN   TestLoadBalancerInternalServiceModeSelection
E1104 08:12:22.271858   25343 azure_loadbalancer.go:814] selectLoadBalancer: cluster(testCluster) service(default/service-2) isInternal(true) -  all available load balancers have exceeded maximum rule limit 1, vmSetNames ([as])
E1104 08:12:22.271929   25343 azure_loadbalancer.go:1535] reconcileLoadBalancer: failed to get load balancer for service "default/service-2", error: selectLoadBalancer: cluster(testCluster) service(default/service-2) isInternal(true) -  all available load balancers have exceeded maximum rule limit 1, vmSetNames ([as])
E1104 08:12:22.271960   25343 azure_loadbalancer.go:139] reconcileLoadBalancer(default/service-2) failed: selectLoadBalancer: cluster(testCluster) service(default/service-2) isInternal(true) -  all available load balancers have exceeded maximum rule limit 1, vmSetNames ([as])
W1104 08:12:22.289046   25343 azure_loadbalancer.go:1434] isFrontendIPConfigUnsafeToDelete: frontend IP configuration with ID fip on LB testCluster-internal cannot be deleted because it is being referenced by load balancing rules of other services
W1104 08:12:22.290962   25343 azure_loadbalancer.go:1434] isFrontendIPConfigUnsafeToDelete: frontend IP configuration with ID fip on LB as-3-internal cannot be deleted because it is being referenced by load balancing rules of other services
W1104 08:12:22.292821   25343 azure_loadbalancer.go:1434] isFrontendIPConfigUnsafeToDelete: frontend IP configuration with ID fip on LB as-2-internal cannot be deleted because it is being referenced by load balancing rules of other services
W1104 08:12:22.294511   25343 azure_loadbalancer.go:1434] isFrontendIPConfigUnsafeToDelete: frontend IP configuration with ID fip on LB as-1-internal cannot be deleted because it is being referenced by load balancing rules of other services
W1104 08:12:22.295834   25343 azure_loadbalancer.go:1434] isFrontendIPConfigUnsafeToDelete: frontend IP configuration with ID fip on LB testCluster-internal cannot be deleted because it is being referenced by load balancing rules of other services
--- PASS: TestLoadBalancerInternalServiceModeSelection (0.09s)
=== RUN   TestLoadBalancerExternalServiceModeSelection
E1104 08:12:22.442283   25343 azure_loadbalancer.go:814] selectLoadBalancer: cluster(testCluster) service(default/service-2) isInternal(false) -  all available load balancers have exceeded maximum rule limit 1, vmSetNames ([as])
E1104 08:12:22.442354   25343 azure_loadbalancer.go:1535] reconcileLoadBalancer: failed to get load balancer for service "default/service-2", error: selectLoadBalancer: cluster(testCluster) service(default/service-2) isInternal(false) -  all available load balancers have exceeded maximum rule limit 1, vmSetNames ([as])
E1104 08:12:22.442383   25343 azure_loadbalancer.go:139] reconcileLoadBalancer(default/service-2) failed: selectLoadBalancer: cluster(testCluster) service(default/service-2) isInternal(false) -  all available load balancers have exceeded maximum rule limit 1, vmSetNames ([as])
--- PASS: TestLoadBalancerExternalServiceModeSelection (0.19s)
=== RUN   TestReconcileLoadBalancerAddServiceOnInternalSubnet
--- PASS: TestReconcileLoadBalancerAddServiceOnInternalSubnet (0.00s)
=== RUN   TestReconcileSecurityGroupFromAnyDestinationAddressPrefixToLoadBalancerIP
--- PASS: TestReconcileSecurityGroupFromAnyDestinationAddressPrefixToLoadBalancerIP (0.00s)
=== RUN   TestReconcileSecurityGroupDynamicLoadBalancerIP
... skipping 28 lines ...
--- PASS: TestReconcileSecurityGroupRemoveService (0.00s)
=== RUN   TestReconcileSecurityGroupRemoveServiceRemovesPort
--- PASS: TestReconcileSecurityGroupRemoveServiceRemovesPort (0.00s)
=== RUN   TestReconcileSecurityWithSourceRanges
--- PASS: TestReconcileSecurityWithSourceRanges (0.00s)
=== RUN   TestReconcileSecurityGroupEtagMismatch
W1104 08:12:22.534226   25343 azure_backoff.go:164] CreateOrUpdateSecurityGroup(nsg) failed: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 412, RawError: PreconditionFailedEtagMismatch, NSG request: {"properties":{"securityRules":[{"name":"aservice1-TCP-80-Internet","properties":{"access":"Allow","destinationAddressPrefix":"1.2.3.4","destinationPortRange":"80","direction":"Inbound","priority":500,"protocol":"Tcp","sourceAddressPrefix":"Internet","sourcePortRange":"*"}}]}}
--- PASS: TestReconcileSecurityGroupEtagMismatch (0.00s)
=== RUN   TestReconcilePublicIPWithNewService
--- PASS: TestReconcilePublicIPWithNewService (0.00s)
=== RUN   TestReconcilePublicIPRemoveService
--- PASS: TestReconcilePublicIPRemoveService (0.00s)
=== RUN   TestReconcilePublicIPWithInternalService
... skipping 107 lines ...
=== RUN   TestVMSSVMCacheWithDeletingNodes
W1104 08:12:24.787129   25343 azure_vmss.go:743] Unable to find node vmssee6c2000000: instance not found
W1104 08:12:24.787289   25343 azure_vmss.go:743] Unable to find node vmssee6c2000001: instance not found
W1104 08:12:24.787428   25343 azure_vmss.go:743] Unable to find node vmssee6c2000002: instance not found
--- PASS: TestVMSSVMCacheWithDeletingNodes (0.00s)
=== RUN   TestVMSSVMCacheClearedWhenRGDeleted
W1104 08:12:24.788596   25343 azure_vmss_cache.go:70] Skip caching vmss for resource group rg due to error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 404, RawError: %!w(<nil>)
W1104 08:12:24.788678   25343 azure_vmss_cache.go:70] Skip caching vmss for resource group rg due to error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 404, RawError: %!w(<nil>)
W1104 08:12:24.788702   25343 azure_vmss.go:743] Unable to find node vmssee6c2000000: instance not found
--- PASS: TestVMSSVMCacheClearedWhenRGDeleted (0.00s)
=== RUN   TestGetScaleSetVMInstanceID
--- PASS: TestGetScaleSetVMInstanceID (0.00s)
=== RUN   TestGetNodeIdentityByNodeName
W1104 08:12:24.790111   25343 azure_vmss.go:743] Unable to find node agente6c2000005: instance not found
... skipping 4 lines ...
--- PASS: TestGetInstanceIDByNodeName (0.00s)
=== RUN   TestGetZoneByNodeName
W1104 08:12:24.796186   25343 azure_vmss.go:743] Unable to find node agente6c2000005: instance not found
--- PASS: TestGetZoneByNodeName (0.00s)
=== RUN   TestGetIPByNodeName
W1104 08:12:24.798549   25343 azure_vmss.go:743] Unable to find node agente6c2000005: instance not found
E1104 08:12:24.798617   25343 azure_vmss.go:872] error: ss.GetPrimaryInterface(agente6c2000005), ss.getVmssVM(agente6c2000005), err=instance not found
E1104 08:12:24.798646   25343 azure_vmss.go:510] error: ss.GetIPByNodeName(agente6c2000005), GetPrimaryInterface("agente6c2000005"), err=instance not found
--- PASS: TestGetIPByNodeName (0.00s)
=== RUN   TestGetNodeNameByIPConfigurationID
E1104 08:12:24.801033   25343 azure_vmss.go:1498] Unable to find node by ipConfigurationID /subscriptions/script/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/scaleset2/virtualMachines/3/networkInterfaces/scaleset1/ipConfigurations/ipconfig1: instance not found
E1104 08:12:24.801294   25343 azure_vmss.go:1487] Unable to find node by IPConfigurationID invalid-configuration-id: invalid ip config ID invalid-configuration-id
--- PASS: TestGetNodeNameByIPConfigurationID (0.00s)
=== RUN   TestExtractResourceGroupByVMSSNicID
--- PASS: TestExtractResourceGroupByVMSSNicID (0.00s)
=== RUN   TestGetVMSS
E1104 08:12:24.802449   25343 azure_vmss_cache.go:74] VirtualMachineScaleSetsClient.List failed: &{false 0 0001-01-01 00:00:00 +0000 UTC error during vmss list}
--- PASS: TestGetVMSS (0.00s)
=== RUN   TestGetVmssVM
W1104 08:12:24.806041   25343 azure_vmss.go:216] Unable to find node vmss-vm-000001: instance not found
--- PASS: TestGetVmssVM (0.00s)
=== RUN   TestGetPowerStatusByNodeName
--- PASS: TestGetPowerStatusByNodeName (0.00s)
=== RUN   TestGetProvisioningStateByNodeName
--- PASS: TestGetProvisioningStateByNodeName (0.00s)
=== RUN   TestGetVmssVMByInstanceID
--- PASS: TestGetVmssVMByInstanceID (0.00s)
=== RUN   TestGetVmssVMByNodeIdentity
=== RUN   TestGetVmssVMByNodeIdentity/getVmssVMByNodeIdentity_should_return_the_correct_VMSS_VM
=== RUN   TestGetVmssVMByNodeIdentity/getVmssVMByNodeIdentity_should_not_panic_with_a_gone_VMSS_VM_but_cache_for_the_VMSS_VM_entry_still_exists
W1104 08:12:24.811765   25343 azure_vmss.go:173] failed to get VM with vmssVirtualMachinesEntry on Node "vmss-vm-000001"
W1104 08:12:24.811976   25343 azure_vmss.go:173] failed to get VM with vmssVirtualMachinesEntry on Node "vmss-vm-000001"
W1104 08:12:24.812230   25343 azure_vmss.go:216] Unable to find node vmss-vm-000001: instance not found
--- PASS: TestGetVmssVMByNodeIdentity (0.00s)
    --- PASS: TestGetVmssVMByNodeIdentity/getVmssVMByNodeIdentity_should_return_the_correct_VMSS_VM (0.00s)
    --- PASS: TestGetVmssVMByNodeIdentity/getVmssVMByNodeIdentity_should_not_panic_with_a_gone_VMSS_VM_but_cache_for_the_VMSS_VM_entry_still_exists (0.00s)
=== RUN   TestGetInstanceTypeByNodeName
E1104 08:12:24.813415   25343 azure_backoff.go:98] VirtualMachinesClient.List(rg) failure with err=&{false 0 0001-01-01 00:00:00 +0000 UTC error}
E1104 08:12:24.813518   25343 azure_vmss.go:431] Failed to check isNodeManagedByAvailabilitySet: newAvailabilitySetNodesCache: failed to list vms in the resource group rg: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
--- PASS: TestGetInstanceTypeByNodeName (0.00s)
=== RUN   TestGetPrimaryInterfaceID
--- PASS: TestGetPrimaryInterfaceID (0.00s)
=== RUN   TestGetPrimaryInterface
E1104 08:12:24.815046   25343 azure_backoff.go:98] VirtualMachinesClient.List(rg) failure with err=&{false 0 0001-01-01 00:00:00 +0000 UTC error}
E1104 08:12:24.815136   25343 azure_vmss.go:857] Failed to check isNodeManagedByAvailabilitySet: newAvailabilitySetNodesCache: failed to list vms in the resource group rg: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
E1104 08:12:24.815393   25343 azure_vmss_cache.go:74] VirtualMachineScaleSetsClient.List failed: &{false 0 0001-01-01 00:00:00 +0000 UTC error}
E1104 08:12:24.815451   25343 azure_vmss.go:872] error: ss.GetPrimaryInterface(vmss-vm-000000), ss.getVmssVM(vmss-vm-000000), err=Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
E1104 08:12:24.815988   25343 azure_vmss.go:878] error: ss.GetPrimaryInterface(vmss-vm-000000), ss.getPrimaryInterfaceID(), err=failed to find a primary nic for the vm. vmname="vmss_0"
E1104 08:12:24.816602   25343 azure_vmss.go:884] error: ss.GetPrimaryInterface(vmss-vm-000000), getLastSegment(invalid/id/), err=resource name was missing from identifier
E1104 08:12:24.817530   25343 azure_vmss.go:900] error: ss.GetPrimaryInterface(vmss-vm-000000), ss.GetVirtualMachineScaleSetNetworkInterface.Get(rg, vmss, vmss-vm-000000), err=&{false 0 0001-01-01 00:00:00 +0000 UTC error}
--- PASS: TestGetPrimaryInterface (0.00s)
=== RUN   TestGetVMSSPublicIPAddress
--- PASS: TestGetVMSSPublicIPAddress (0.00s)
=== RUN   TestGetPrivateIPsByNodeName
E1104 08:12:24.821077   25343 azure_backoff.go:98] VirtualMachinesClient.List(rg) failure with err=&{false 0 0001-01-01 00:00:00 +0000 UTC error}
E1104 08:12:24.821179   25343 azure_vmss.go:857] Failed to check isNodeManagedByAvailabilitySet: newAvailabilitySetNodesCache: failed to list vms in the resource group rg: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
E1104 08:12:24.821202   25343 azure_vmss.go:573] error: ss.GetIPByNodeName(vmss-vm-000000), GetPrimaryInterface("vmss-vm-000000"), err=newAvailabilitySetNodesCache: failed to list vms in the resource group rg: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
--- PASS: TestGetPrivateIPsByNodeName (0.00s)
=== RUN   TestGetVmssMachineID
--- PASS: TestGetVmssMachineID (0.00s)
=== RUN   TestExtractScaleSetNameByProviderID
--- PASS: TestExtractScaleSetNameByProviderID (0.00s)
=== RUN   TestExtractResourceGroupByProviderID
--- PASS: TestExtractResourceGroupByProviderID (0.00s)
=== RUN   TestListScaleSets
E1104 08:12:24.822582   25343 azure_vmss.go:664] VirtualMachineScaleSetsClient.List failed: &{false 0 0001-01-01 00:00:00 +0000 UTC error}
--- PASS: TestListScaleSets (0.00s)
=== RUN   TestListScaleSetVMs
E1104 08:12:24.822914   25343 azure_vmss.go:756] VirtualMachineScaleSetVMsClient.List(rg, vmss) failed: &{false 0 0001-01-01 00:00:00 +0000 UTC error}
--- PASS: TestListScaleSetVMs (0.00s)
=== RUN   TestGetAgentPoolScaleSets
--- PASS: TestGetAgentPoolScaleSets (0.00s)
=== RUN   TestGetVMSetNames
W1104 08:12:24.824518   25343 azure_loadbalancer.go:3329] single standard load balancer doesn't work with annotation "service.beta.kubernetes.io/azure-load-balancer-mode", would ignore it
W1104 08:12:24.824915   25343 azure_vmss_cache.go:195] skip caching vmssVM vmss-vm-000003 since its network profile hasn't initialized yet (probably still under creating)
W1104 08:12:24.825930   25343 azure_vmss_cache.go:195] skip caching vmssVM vmss-vm-000003 since its network profile hasn't initialized yet (probably still under creating)
E1104 08:12:24.826307   25343 azure_vmss.go:834] ss.GetVMSetNames - scale set (vmss-1) in service annotation not found
W1104 08:12:24.826819   25343 azure_vmss_cache.go:195] skip caching vmssVM vmss-vm-000003 since its network profile hasn't initialized yet (probably still under creating)
W1104 08:12:24.827508   25343 azure_vmss_cache.go:195] skip caching vmssVM vmss-vm-000003 since its network profile hasn't initialized yet (probably still under creating)
W1104 08:12:24.827848   25343 azure_vmss.go:216] Unable to find node vmss-vm-000003: instance not found
E1104 08:12:24.827903   25343 azure_vmss.go:816] ss.GetVMSetNames - getAgentPoolScaleSets failed err=(instance not found)
W1104 08:12:24.828385   25343 azure_vmss_cache.go:195] skip caching vmssVM vmss-vm-000003 since its network profile hasn't initialized yet (probably still under creating)
--- PASS: TestGetVMSetNames (0.00s)
=== RUN   TestGetPrimaryNetworkInterfaceConfigurationForScaleSet
--- PASS: TestGetPrimaryNetworkInterfaceConfigurationForScaleSet (0.00s)
=== RUN   TestGetPrimaryIPConfigFromVMSSNetworkConfig
--- PASS: TestGetPrimaryIPConfigFromVMSSNetworkConfig (0.00s)
=== RUN   TestGetConfigForScaleSetByIPFamily
--- PASS: TestGetConfigForScaleSetByIPFamily (0.00s)
=== RUN   TestEnsureHostInPool
W1104 08:12:24.832645   25343 azure_vmss.go:173] failed to get VM with vmssVirtualMachinesEntry on Node "vmss-vm-000000"
W1104 08:12:24.832738   25343 azure_vmss.go:173] failed to get VM with vmssVirtualMachinesEntry on Node "vmss-vm-000000"
W1104 08:12:24.832859   25343 azure_vmss.go:173] failed to get VM with vmssVirtualMachinesEntry on Node "vmss-vm-000000"
W1104 08:12:24.832900   25343 azure_vmss.go:216] Unable to find node vmss-vm-000000: instance not found
I1104 08:12:24.832939   25343 azure_vmss.go:992] EnsureHostInPool: skipping node vmss-vm-000000 because it is not found
--- PASS: TestEnsureHostInPool (0.00s)
=== RUN   TestGetVmssAndResourceGroupNameByVMProviderID
--- PASS: TestGetVmssAndResourceGroupNameByVMProviderID (0.00s)
=== RUN   TestEnsureVMSSInPool
... skipping 6 lines ...
W1104 08:12:24.849162   25343 azure_vmss.go:216] Unable to find node vmss-vm-000001: instance not found
I1104 08:12:24.849228   25343 azure_vmss.go:1413] ensureBackendPoolDeletedFromNode: skipping node vmss-vm-000001 because it is not found
--- PASS: TestEnsureBackendPoolDeletedFromNode (0.00s)
=== RUN   TestGetScaleSetAndResourceGroupNameByIPConfigurationID
--- PASS: TestGetScaleSetAndResourceGroupNameByIPConfigurationID (0.00s)
=== RUN   TestEnsureBackendPoolDeletedFromVMSS
E1104 08:12:24.855817   25343 azure_backoff.go:585] CreateOrUpdateVMSS: error CreateOrUpdate vmss(vmss): &{false 0 0001-01-01 00:00:00 +0000 UTC error}
E1104 08:12:24.855938   25343 azure_vmss.go:1838] EnsureBackendPoolDeletedFromVMSets CreateOrUpdateVMSS(vmss) with new backendPoolID /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Network/loadBalancers/lb/backendAddressPools/backendpool-0, err: &{false 0 0001-01-01 00:00:00 +0000 UTC error}
--- PASS: TestEnsureBackendPoolDeletedFromVMSS (0.00s)
=== RUN   TestEnsureBackendPoolDeleted
E1104 08:12:24.858742   25343 azure_vmss.go:1487] Unable to find node by IPConfigurationID /invalid/id: invalid ip config ID /invalid/id
E1104 08:12:24.861853   25343 azure_vmss.go:1716] "Failed to update VMs for VMSS" operation="EnsureBackendPoolDeleted UpdateVMSSVMs" vmssName="vmss" resourceGroup="rg" backendPoolID="/subscriptions/sub/resourceGroups/rg/providers/Microsoft.Network/loadBalancers/lb/backendAddressPools/backendpool-0"
E1104 08:12:24.864856   25343 azure_vmss.go:1498] Unable to find node by ipConfigurationID /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/vmss/virtualMachines/6/networkInterfaces/nic: instance not found
I1104 08:12:24.864944   25343 azure_vmss.go:1652] EnsureBackendPoolDeleted(/): skipping ip config /subscriptions/sub/resourceGroups/rg/providers/Microsoft.Compute/virtualMachineScaleSets/vmss/virtualMachines/6/networkInterfaces/nic because the corresponding vmss vm is not found
--- PASS: TestEnsureBackendPoolDeleted (0.01s)
=== RUN   TestEnsureBackendPoolDeletedConcurrently
E1104 08:12:24.868404   25343 azure_vmss.go:1774] ensureBackendPoolDeletedFromVMSS: failed to get VMSS vmss-2: instance not found
--- PASS: TestEnsureBackendPoolDeletedConcurrently (0.01s)
=== RUN   TestGetNodeCIDRMasksByProviderID
=== RUN   TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMasksByProviderID_should_report_an_error_if_the_providerID_is_not_valid
=== RUN   TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes
=== RUN   TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes_even_if_some_of_the_tags_are_not_specified
=== RUN   TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMaksByProviderID_should_not_fail_even_if_some_of_the_tag_is_invalid
E1104 08:12:24.873930   25343 azure_vmss.go:1753] GetNodeCIDRMasksByProviderID: error when paring the value of the ipv4 mask size abc: strconv.Atoi: parsing "abc": invalid syntax
--- PASS: TestGetNodeCIDRMasksByProviderID (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMasksByProviderID_should_report_an_error_if_the_providerID_is_not_valid (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMaksByProviderID_should_return_the_correct_mask_sizes_even_if_some_of_the_tags_are_not_specified (0.00s)
    --- PASS: TestGetNodeCIDRMasksByProviderID/GetNodeCIDRMaksByProviderID_should_not_fail_even_if_some_of_the_tag_is_invalid (0.00s)
=== RUN   TestGetAgentPoolVMSetNamesMixedInstances
--- PASS: TestGetAgentPoolVMSetNamesMixedInstances (0.00s)
=== RUN   TestGetNodeVMSetNameVMSS
W1104 08:12:24.875718   25343 azure_vmss.go:1899] ss.GetNodeVMSetName: the provider ID invalid of node  does not match the format of a VMSS instance, assuming it is managed by an availability set
--- PASS: TestGetNodeVMSetNameVMSS (0.00s)
=== RUN   TestScaleSet_VMSSBatchSize
=== RUN   TestScaleSet_VMSSBatchSize/failed_to_get_vmss
E1104 08:12:24.876223   25343 azure_vmss_cache.go:74] VirtualMachineScaleSetsClient.List failed: &{false 0 0001-01-01 00:00:00 +0000 UTC list vmss error}
=== RUN   TestScaleSet_VMSSBatchSize/vmss_contains_batch_operation_tag
=== RUN   TestScaleSet_VMSSBatchSize/vmss_doesn't_contain_batch_operation_tag
--- PASS: TestScaleSet_VMSSBatchSize (0.00s)
    --- PASS: TestScaleSet_VMSSBatchSize/failed_to_get_vmss (0.00s)
    --- PASS: TestScaleSet_VMSSBatchSize/vmss_contains_batch_operation_tag (0.00s)
    --- PASS: TestScaleSet_VMSSBatchSize/vmss_doesn't_contain_batch_operation_tag (0.00s)
... skipping 24 lines ...
=== RUN   TestGetZoneByProviderID
--- PASS: TestGetZoneByProviderID (0.00s)
=== RUN   TestAvailabilitySetGetZoneByNodeName
--- PASS: TestAvailabilitySetGetZoneByNodeName (0.00s)
=== RUN   TestSyncRegionZonesMap
=== RUN   TestSyncRegionZonesMap/syncRegionZonesMap_should_report_an_error_if_failed_to_get_zones
W1104 08:12:24.896930   25343 azure_zones.go:51] syncRegionZonesMap: error when get zones: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error, will retry after 30m0s
=== RUN   TestSyncRegionZonesMap/syncRegionZonesMap_should_not_report_an_error_if_the_given_zones_map_is_empty
W1104 08:12:24.897108   25343 azure_zones.go:55] syncRegionZonesMap: empty zone list
--- PASS: TestSyncRegionZonesMap (0.00s)
    --- PASS: TestSyncRegionZonesMap/syncRegionZonesMap_should_report_an_error_if_failed_to_get_zones (0.00s)
    --- PASS: TestSyncRegionZonesMap/syncRegionZonesMap_should_not_report_an_error_if_the_given_zones_map_is_empty (0.00s)
=== RUN   TestGetRegionZonesBackoff
=== RUN   TestGetRegionZonesBackoff/syncRegionZonesMap_should_report_an_error_if_failed_to_get_zones
E1104 08:12:24.897523   25343 azure_zones.go:100] getRegionZonesMapWrapper: failed to fetch zones information: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 0, RawError: error
=== RUN   TestGetRegionZonesBackoff/syncRegionZonesMap_should_not_report_an_error_if_the_given_zones_map_is_empty
--- PASS: TestGetRegionZonesBackoff (0.00s)
    --- PASS: TestGetRegionZonesBackoff/syncRegionZonesMap_should_report_an_error_if_failed_to_get_zones (0.00s)
    --- PASS: TestGetRegionZonesBackoff/syncRegionZonesMap_should_not_report_an_error_if_the_given_zones_map_is_empty (0.00s)
FAIL
FAIL	sigs.k8s.io/cloud-provider-azure/pkg/provider	6.259s
FAIL
make: *** [Makefile:313: test-unit] Error 1
+ EXIT_VALUE=2
+ set +o xtrace