This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: feat: support topology.kubernetes.io/zone key
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2021-04-11 11:29
Elapsed1h14m
Revision5af4a63acf0710dd3be67210d9c7459e8f45584e
Refs 785

No Test Failures!


Error lines from build-log.txt

... skipping 62 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11248  100 11248    0     0   183k      0 --:--:-- --:--:-- --:--:--  183k
Downloading https://get.helm.sh/helm-v3.5.3-linux-amd64.tar.gz
Verifying checksum... Done.
Preparing to install helm into /usr/local/bin
helm installed into /usr/local/bin/helm
docker pull k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700 || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700 not found: manifest unknown: manifest tagged by "v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver'
CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.driverVersion=v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=4391adb6ed0594c6f3e6dcbdd848eca0723ee700 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-04-11T11:35:14Z -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static""  -mod vendor -o _output/azurediskplugin ./pkg/azurediskplugin
CGO_ENABLED=0 GOOS=windows go build -a -ldflags "-X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.driverVersion=v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=4391adb6ed0594c6f3e6dcbdd848eca0723ee700 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-04-11T11:36:07Z -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static""  -mod vendor -o _output/azurediskplugin.exe ./pkg/azurediskplugin
docker buildx rm container-builder || true
error: no builder "container-builder" found
docker buildx create --use --name=container-builder
container-builder
make container-linux
make[3]: Entering directory '/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver'
docker buildx build --pull --output=type=registry --platform="linux/amd64" --build-arg PLUGIN_NAME=azurediskplugin \
	-t k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700-linux-amd64 -f ./pkg/azurediskplugin/Dockerfile .
... skipping 1525 lines ...
        - name: csi-resizer
          image: "mcr.microsoft.com/oss/kubernetes-csi/csi-resizer:v1.1.0"
          args:
            - "-csi-address=$(ADDRESS)"
            - "-v=2"
            - "-leader-election"
            - '-handle-volume-inuse-error=true'
          env:
            - name: ADDRESS
              value: /csi/csi.sock
          volumeMounts:
            - name: socket-dir
              mountPath: /csi
... skipping 117 lines ...
Go Version: go1.16.1
Platform: linux/amd64
Topology Key: N/A

Streaming logs below:
STEP: Building a namespace api object, basename azuredisk
W0411 11:40:23.943111   13014 azure_config.go:46] Failed to get cloud-config from secret: failed to get secret azure-cloud-provider: secrets "azure-cloud-provider" not found, skip initializing from secret
I0411 11:40:23.943876   13014 azuredisk.go:150] disable UseInstanceMetadata for controller
I0411 11:40:23.944056   13014 driver.go:80] Enabling controller service capability: CREATE_DELETE_VOLUME
I0411 11:40:23.944080   13014 driver.go:80] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0411 11:40:23.944084   13014 driver.go:80] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0411 11:40:23.944088   13014 driver.go:80] Enabling controller service capability: LIST_SNAPSHOTS
I0411 11:40:23.944092   13014 driver.go:80] Enabling controller service capability: CLONE_VOLUME
... skipping 13 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Apr 11 11:40:24.991: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-x8jjk" in namespace "azuredisk-8081" to be "Succeeded or Failed"
Apr 11 11:40:25.032: INFO: Pod "azuredisk-volume-tester-x8jjk": Phase="Pending", Reason="", readiness=false. Elapsed: 41.690638ms
Apr 11 11:40:27.067: INFO: Pod "azuredisk-volume-tester-x8jjk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.076384674s
Apr 11 11:40:29.101: INFO: Pod "azuredisk-volume-tester-x8jjk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.11064027s
Apr 11 11:40:31.135: INFO: Pod "azuredisk-volume-tester-x8jjk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.144549016s
Apr 11 11:40:33.170: INFO: Pod "azuredisk-volume-tester-x8jjk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.179722512s
Apr 11 11:40:35.206: INFO: Pod "azuredisk-volume-tester-x8jjk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.215508109s
... skipping 440 lines ...
STEP: validating provisioned PV
Apr 11 11:55:26.643: INFO: deleting StorageClass azuredisk-8081-kubernetes.io-azure-disk-dynamic-sc-68znz
STEP: Collecting events from namespace "azuredisk-8081".
STEP: Found 6 events.
Apr 11 11:55:26.712: INFO: At 2021-04-11 11:40:24 +0000 UTC - event for pvc-w2z6f: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 11:55:26.712: INFO: At 2021-04-11 11:40:25 +0000 UTC - event for pvc-w2z6f: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-8081/pvc-w2z6f"
Apr 11 11:55:26.712: INFO: At 2021-04-11 11:40:25 +0000 UTC - event for pvc-w2z6f: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-8081-kubernetes.io-azure-disk-dynamic-sc-68znz": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000000
Apr 11 11:55:26.712: INFO: At 2021-04-11 11:40:25 +0000 UTC - event for pvc-w2z6f: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 11:55:26.712: INFO: At 2021-04-11 11:50:25 +0000 UTC - event for azuredisk-volume-tester-x8jjk: {default-scheduler } FailedScheduling: error while running "VolumeBinding" prebind plugin for pod "azuredisk-volume-tester-x8jjk": Failed to bind volumes: timed out waiting for the condition
Apr 11 11:55:26.712: INFO: At 2021-04-11 11:55:26 +0000 UTC - event for azuredisk-volume-tester-x8jjk: {default-scheduler } FailedScheduling: skip schedule deleting pod: azuredisk-8081/azuredisk-volume-tester-x8jjk
Apr 11 11:55:26.745: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Apr 11 11:55:26.745: INFO: 
Apr 11 11:55:26.809: INFO: 
Logging node info for node 1894k8s00000000
Apr 11 11:55:26.842: INFO: Node Info: &Node{ObjectMeta:{1894k8s00000000   /api/v1/nodes/1894k8s00000000 04e3c016-5afa-4abc-8d02-c61ea8c62092 4644 0 2021-04-11 11:34:21 +0000 UTC <nil> <nil> map[agentpool:windowspool1 beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:Standard_D4s_v3 beta.kubernetes.io/os:windows failure-domain.beta.kubernetes.io/region:eastus2 failure-domain.beta.kubernetes.io/zone:0 kubernetes.azure.com/cluster:kubetest-z2lhaqf4 kubernetes.azure.com/role:agent kubernetes.io/arch:amd64 kubernetes.io/hostname:1894k8s00000000 kubernetes.io/os:windows kubernetes.io/role:agent node-role.kubernetes.io/agent: node.kubernetes.io/instance-type:Standard_D4s_v3 node.kubernetes.io/windows-build:10.0.17763 storageprofile:managed storagetier:Premium_LRS topology.kubernetes.io/region:eastus2 topology.kubernetes.io/zone:0] map[csi.volume.kubernetes.io/nodeid:{"disk.csi.azure.com":"1894k8s00000000"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kube-controller-manager Update v1 2021-04-11 11:34:21 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}}}} {kubectl-label Update v1 2021-04-11 11:34:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:kubernetes.io/role":{},"f:node-role.kubernetes.io/agent":{}}}}} {kubelet.exe Update v1 2021-04-11 11:40:24 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:csi.volume.kubernetes.io/nodeid":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:agentpool":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/instance-type":{},"f:beta.kubernetes.io/os":{},"f:failure-domain.beta.kubernetes.io/region":{},"f:failure-domain.beta.kubernetes.io/zone":{},"f:kubernetes.azure.com/cluster":{},"f:kubernetes.azure.com/role":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{},"f:node.kubernetes.io/instance-type":{},"f:node.kubernetes.io/windows-build":{},"f:storageprofile":{},"f:storagetier":{},"f:topology.kubernetes.io/region":{},"f:topology.kubernetes.io/zone":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}}]},Spec:NodeSpec{PodCIDR:,DoNotUseExternalID:,ProviderID:azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-z2lhaqf4/providers/Microsoft.Compute/virtualMachineScaleSets/1894k8s00/virtualMachines/0,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{17179398144 0} {<nil>} 16776756Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Allocatable:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{15031914496 0} {<nil>} 14679604Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-04-11 11:55:22 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-04-11 11:55:22 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-04-11 11:55:22 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-04-11 11:55:22 +0000 UTC,LastTransitionTime:2021-04-11 11:34:31 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:Hostname,Address:1894k8s00000000,},NodeAddress{Type:InternalIP,Address:10.240.0.34,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1894k8s00000000,SystemUUID:EB5A631D-2774-49B5-BF35-4058676A4A3B,BootID:,KernelVersion:10.0.17763.1697,OSImage:Windows Server 2019 Datacenter,ContainerRuntimeVersion:docker://19.3.11,KubeletVersion:v1.19.7,KubeProxyVersion:v1.19.7,OperatingSystem:windows,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[mcr.microsoft.com/windows/servercore@sha256:997e32161f322f5f301023ae675f39329bd60888f5f9a373100eba77f55cbb31 mcr.microsoft.com/windows/servercore:ltsc2019],SizeBytes:5206241784,},ContainerImage{Names:[k8sprow.azurecr.io/azuredisk-csi@sha256:ebcabcee5317a7b7a83f040eee2f348766029dc2d2dec8aa0a4ed1fae86366d2 k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700],SizeBytes:304141976,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:b7d82802cca8523df1d5973332c64d558fe352617926fd01707b17e2f022755f mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.2.0],SizeBytes:268076147,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:fc5d14e9f26fe9a014c1fa348c6632412424b834b8cbf9a968af5a9599a818c1 mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v2.0.1],SizeBytes:267426993,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:92dd20279c1e36282bd52e3db2679297921d517ffe4e98025c0370ce25a03fac mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v1.2.1-alpha.1-windows-1809-amd64],SizeBytes:265664693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:d07f92ef2517c99cd011391ab1182c995cdd04ec01e47324abf07ea502476409 mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.0.1-alpha.1-windows-1809-amd64],SizeBytes:264960693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes/pause@sha256:0343aed381a7b6892e85ec85dc0f8862b864e75584f08f215795a4696e3adae8 kubletwin/pause:latest mcr.microsoft.com/oss/kubernetes/pause:1.4.0],SizeBytes:256011346,},ContainerImage{Names:[mcr.microsoft.com/windows/nanoserver@sha256:58157fff3c3cfec14b102082474e62fb01d54cc6594973dd0038bce979ec8161 mcr.microsoft.com/windows/nanoserver:1809],SizeBytes:251861212,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 74 lines ...
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:40
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:43
    should create a volume on demand with mount options [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:101

    Unexpected error:
        <*errors.errorString | 0xc0008b90d0>: {
            s: "Gave up after waiting 15m0s for pod \"azuredisk-volume-tester-x8jjk\" to be \"Succeeded or Failed\"",
        }
        Gave up after waiting 15m0s for pod "azuredisk-volume-tester-x8jjk" to be "Succeeded or Failed"
    occurred

    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/testsuites/testsuites.go:737
------------------------------
Dynamic Provisioning [single-az] 
  should receive FailedMount event with invalid mount options [kubernetes.io/azure-disk] [disk.csi.azure.com]
... skipping 64 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with an error
Apr 11 11:55:31.354: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-wwhj6" in namespace "azuredisk-3274" to be "Error status code"
Apr 11 11:55:31.389: INFO: Pod "azuredisk-volume-tester-wwhj6": Phase="Pending", Reason="", readiness=false. Elapsed: 34.394188ms
Apr 11 11:55:33.423: INFO: Pod "azuredisk-volume-tester-wwhj6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.069052227s
Apr 11 11:55:35.474: INFO: Pod "azuredisk-volume-tester-wwhj6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.119669676s
Apr 11 11:55:37.509: INFO: Pod "azuredisk-volume-tester-wwhj6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.154982917s
Apr 11 11:55:39.543: INFO: Pod "azuredisk-volume-tester-wwhj6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.189045117s
Apr 11 11:55:41.578: INFO: Pod "azuredisk-volume-tester-wwhj6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.223251456s
... skipping 293 lines ...
Apr 11 12:05:31.897: INFO: deleting StorageClass azuredisk-3274-kubernetes.io-azure-disk-dynamic-sc-swh2l
STEP: Collecting events from namespace "azuredisk-3274".
STEP: Found 6 events.
Apr 11 12:05:31.966: INFO: At 2021-04-11 11:55:31 +0000 UTC - event for pvc-tc6cc: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 12:05:31.966: INFO: At 2021-04-11 11:55:31 +0000 UTC - event for pvc-tc6cc: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:05:31.966: INFO: At 2021-04-11 11:55:31 +0000 UTC - event for pvc-tc6cc: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-3274/pvc-tc6cc"
Apr 11 12:05:31.966: INFO: At 2021-04-11 11:55:31 +0000 UTC - event for pvc-tc6cc: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-3274-kubernetes.io-azure-disk-dynamic-sc-swh2l": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000000
Apr 11 12:05:31.966: INFO: At 2021-04-11 12:05:31 +0000 UTC - event for azuredisk-volume-tester-wwhj6: {default-scheduler } FailedScheduling: error while running "VolumeBinding" prebind plugin for pod "azuredisk-volume-tester-wwhj6": Failed to bind volumes: timed out waiting for the condition
Apr 11 12:05:31.966: INFO: At 2021-04-11 12:05:31 +0000 UTC - event for azuredisk-volume-tester-wwhj6: {default-scheduler } FailedScheduling: skip schedule deleting pod: azuredisk-3274/azuredisk-volume-tester-wwhj6
Apr 11 12:05:32.002: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Apr 11 12:05:32.002: INFO: 
Apr 11 12:05:32.068: INFO: 
Logging node info for node 1894k8s00000000
Apr 11 12:05:32.102: INFO: Node Info: &Node{ObjectMeta:{1894k8s00000000   /api/v1/nodes/1894k8s00000000 04e3c016-5afa-4abc-8d02-c61ea8c62092 6538 0 2021-04-11 11:34:21 +0000 UTC <nil> <nil> map[agentpool:windowspool1 beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:Standard_D4s_v3 beta.kubernetes.io/os:windows failure-domain.beta.kubernetes.io/region:eastus2 failure-domain.beta.kubernetes.io/zone:0 kubernetes.azure.com/cluster:kubetest-z2lhaqf4 kubernetes.azure.com/role:agent kubernetes.io/arch:amd64 kubernetes.io/hostname:1894k8s00000000 kubernetes.io/os:windows kubernetes.io/role:agent node-role.kubernetes.io/agent: node.kubernetes.io/instance-type:Standard_D4s_v3 node.kubernetes.io/windows-build:10.0.17763 storageprofile:managed storagetier:Premium_LRS topology.kubernetes.io/region:eastus2 topology.kubernetes.io/zone:0] map[csi.volume.kubernetes.io/nodeid:{"disk.csi.azure.com":"1894k8s00000000"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kube-controller-manager Update v1 2021-04-11 11:34:21 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}}}} {kubectl-label Update v1 2021-04-11 11:34:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:kubernetes.io/role":{},"f:node-role.kubernetes.io/agent":{}}}}} {kubelet.exe Update v1 2021-04-11 11:40:24 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:csi.volume.kubernetes.io/nodeid":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:agentpool":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/instance-type":{},"f:beta.kubernetes.io/os":{},"f:failure-domain.beta.kubernetes.io/region":{},"f:failure-domain.beta.kubernetes.io/zone":{},"f:kubernetes.azure.com/cluster":{},"f:kubernetes.azure.com/role":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{},"f:node.kubernetes.io/instance-type":{},"f:node.kubernetes.io/windows-build":{},"f:storageprofile":{},"f:storagetier":{},"f:topology.kubernetes.io/region":{},"f:topology.kubernetes.io/zone":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}}]},Spec:NodeSpec{PodCIDR:,DoNotUseExternalID:,ProviderID:azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-z2lhaqf4/providers/Microsoft.Compute/virtualMachineScaleSets/1894k8s00/virtualMachines/0,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{17179398144 0} {<nil>} 16776756Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Allocatable:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{15031914496 0} {<nil>} 14679604Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-04-11 12:05:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-04-11 12:05:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-04-11 12:05:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-04-11 12:05:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:31 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:Hostname,Address:1894k8s00000000,},NodeAddress{Type:InternalIP,Address:10.240.0.34,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1894k8s00000000,SystemUUID:EB5A631D-2774-49B5-BF35-4058676A4A3B,BootID:,KernelVersion:10.0.17763.1697,OSImage:Windows Server 2019 Datacenter,ContainerRuntimeVersion:docker://19.3.11,KubeletVersion:v1.19.7,KubeProxyVersion:v1.19.7,OperatingSystem:windows,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[mcr.microsoft.com/windows/servercore@sha256:997e32161f322f5f301023ae675f39329bd60888f5f9a373100eba77f55cbb31 mcr.microsoft.com/windows/servercore:ltsc2019],SizeBytes:5206241784,},ContainerImage{Names:[k8sprow.azurecr.io/azuredisk-csi@sha256:ebcabcee5317a7b7a83f040eee2f348766029dc2d2dec8aa0a4ed1fae86366d2 k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700],SizeBytes:304141976,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:b7d82802cca8523df1d5973332c64d558fe352617926fd01707b17e2f022755f mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.2.0],SizeBytes:268076147,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:fc5d14e9f26fe9a014c1fa348c6632412424b834b8cbf9a968af5a9599a818c1 mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v2.0.1],SizeBytes:267426993,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:92dd20279c1e36282bd52e3db2679297921d517ffe4e98025c0370ce25a03fac mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v1.2.1-alpha.1-windows-1809-amd64],SizeBytes:265664693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:d07f92ef2517c99cd011391ab1182c995cdd04ec01e47324abf07ea502476409 mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.0.1-alpha.1-windows-1809-amd64],SizeBytes:264960693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes/pause@sha256:0343aed381a7b6892e85ec85dc0f8862b864e75584f08f215795a4696e3adae8 kubletwin/pause:latest mcr.microsoft.com/oss/kubernetes/pause:1.4.0],SizeBytes:256011346,},ContainerImage{Names:[mcr.microsoft.com/windows/nanoserver@sha256:58157fff3c3cfec14b102082474e62fb01d54cc6594973dd0038bce979ec8161 mcr.microsoft.com/windows/nanoserver:1809],SizeBytes:251861212,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 74 lines ...
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:40
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:43
    should create a volume on demand and mount it as readOnly in a pod [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:203

    Unexpected error:
        <*errors.errorString | 0xc000ca9780>: {
            s: "Gave up after waiting 10m0s for pod \"azuredisk-volume-tester-wwhj6\" to be \"Error status code\"",
        }
        Gave up after waiting 10m0s for pod "azuredisk-volume-tester-wwhj6" to be "Error status code"
    occurred

    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/testsuites/testsuites.go:776
------------------------------
Dynamic Provisioning [single-az] 
  should create multiple PV objects, bind to PVCs and attach all to different pods on the same node [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows]
... skipping 21 lines ...
Apr 11 12:10:34.841: INFO: deleting StorageClass azuredisk-495-kubernetes.io-azure-disk-dynamic-sc-kfrvf
STEP: Collecting events from namespace "azuredisk-495".
STEP: Found 5 events.
Apr 11 12:10:34.910: INFO: At 2021-04-11 12:05:34 +0000 UTC - event for pvc-2r94f: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 12:10:34.910: INFO: At 2021-04-11 12:05:34 +0000 UTC - event for pvc-2r94f: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:10:34.910: INFO: At 2021-04-11 12:05:34 +0000 UTC - event for pvc-2r94f: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-495/pvc-2r94f"
Apr 11 12:10:34.910: INFO: At 2021-04-11 12:05:34 +0000 UTC - event for pvc-2r94f: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-495-kubernetes.io-azure-disk-dynamic-sc-kfrvf": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000000
Apr 11 12:10:34.910: INFO: At 2021-04-11 12:10:34 +0000 UTC - event for azuredisk-volume-tester-wlgvs: {default-scheduler } FailedScheduling: skip schedule deleting pod: azuredisk-495/azuredisk-volume-tester-wlgvs
Apr 11 12:10:34.943: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Apr 11 12:10:34.943: INFO: 
Apr 11 12:10:35.007: INFO: 
Logging node info for node 1894k8s00000000
Apr 11 12:10:35.042: INFO: Node Info: &Node{ObjectMeta:{1894k8s00000000   /api/v1/nodes/1894k8s00000000 04e3c016-5afa-4abc-8d02-c61ea8c62092 7508 0 2021-04-11 11:34:21 +0000 UTC <nil> <nil> map[agentpool:windowspool1 beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:Standard_D4s_v3 beta.kubernetes.io/os:windows failure-domain.beta.kubernetes.io/region:eastus2 failure-domain.beta.kubernetes.io/zone:0 kubernetes.azure.com/cluster:kubetest-z2lhaqf4 kubernetes.azure.com/role:agent kubernetes.io/arch:amd64 kubernetes.io/hostname:1894k8s00000000 kubernetes.io/os:windows kubernetes.io/role:agent node-role.kubernetes.io/agent: node.kubernetes.io/instance-type:Standard_D4s_v3 node.kubernetes.io/windows-build:10.0.17763 storageprofile:managed storagetier:Premium_LRS topology.kubernetes.io/region:eastus2 topology.kubernetes.io/zone:0] map[csi.volume.kubernetes.io/nodeid:{"disk.csi.azure.com":"1894k8s00000000"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kube-controller-manager Update v1 2021-04-11 11:34:21 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}}}} {kubectl-label Update v1 2021-04-11 11:34:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:kubernetes.io/role":{},"f:node-role.kubernetes.io/agent":{}}}}} {kubelet.exe Update v1 2021-04-11 11:40:24 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:csi.volume.kubernetes.io/nodeid":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:agentpool":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/instance-type":{},"f:beta.kubernetes.io/os":{},"f:failure-domain.beta.kubernetes.io/region":{},"f:failure-domain.beta.kubernetes.io/zone":{},"f:kubernetes.azure.com/cluster":{},"f:kubernetes.azure.com/role":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{},"f:node.kubernetes.io/instance-type":{},"f:node.kubernetes.io/windows-build":{},"f:storageprofile":{},"f:storagetier":{},"f:topology.kubernetes.io/region":{},"f:topology.kubernetes.io/zone":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}}]},Spec:NodeSpec{PodCIDR:,DoNotUseExternalID:,ProviderID:azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-z2lhaqf4/providers/Microsoft.Compute/virtualMachineScaleSets/1894k8s00/virtualMachines/0,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{17179398144 0} {<nil>} 16776756Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Allocatable:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{15031914496 0} {<nil>} 14679604Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-04-11 12:10:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-04-11 12:10:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-04-11 12:10:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-04-11 12:10:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:31 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:Hostname,Address:1894k8s00000000,},NodeAddress{Type:InternalIP,Address:10.240.0.34,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1894k8s00000000,SystemUUID:EB5A631D-2774-49B5-BF35-4058676A4A3B,BootID:,KernelVersion:10.0.17763.1697,OSImage:Windows Server 2019 Datacenter,ContainerRuntimeVersion:docker://19.3.11,KubeletVersion:v1.19.7,KubeProxyVersion:v1.19.7,OperatingSystem:windows,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[mcr.microsoft.com/windows/servercore@sha256:997e32161f322f5f301023ae675f39329bd60888f5f9a373100eba77f55cbb31 mcr.microsoft.com/windows/servercore:ltsc2019],SizeBytes:5206241784,},ContainerImage{Names:[k8sprow.azurecr.io/azuredisk-csi@sha256:ebcabcee5317a7b7a83f040eee2f348766029dc2d2dec8aa0a4ed1fae86366d2 k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700],SizeBytes:304141976,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:b7d82802cca8523df1d5973332c64d558fe352617926fd01707b17e2f022755f mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.2.0],SizeBytes:268076147,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:fc5d14e9f26fe9a014c1fa348c6632412424b834b8cbf9a968af5a9599a818c1 mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v2.0.1],SizeBytes:267426993,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:92dd20279c1e36282bd52e3db2679297921d517ffe4e98025c0370ce25a03fac mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v1.2.1-alpha.1-windows-1809-amd64],SizeBytes:265664693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:d07f92ef2517c99cd011391ab1182c995cdd04ec01e47324abf07ea502476409 mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.0.1-alpha.1-windows-1809-amd64],SizeBytes:264960693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes/pause@sha256:0343aed381a7b6892e85ec85dc0f8862b864e75584f08f215795a4696e3adae8 kubletwin/pause:latest mcr.microsoft.com/oss/kubernetes/pause:1.4.0],SizeBytes:256011346,},ContainerImage{Names:[mcr.microsoft.com/windows/nanoserver@sha256:58157fff3c3cfec14b102082474e62fb01d54cc6594973dd0038bce979ec8161 mcr.microsoft.com/windows/nanoserver:1809],SizeBytes:251861212,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 74 lines ...
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:40
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:43
    should create multiple PV objects, bind to PVCs and attach all to different pods on the same node [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:232

    Unexpected error:
        <*errors.errorString | 0xc0003882c0>: {
            s: "timed out waiting for the condition",
        }
        timed out waiting for the condition
    occurred

... skipping 167 lines ...
Apr 11 12:15:31.050: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"azuredisk-volume-tester-xfz62-59d7b5c84f\" is progressing."}}, CollisionCount:(*int32)(nil)}
Apr 11 12:15:33.048: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"azuredisk-volume-tester-xfz62-59d7b5c84f\" is progressing."}}, CollisionCount:(*int32)(nil)}
Apr 11 12:15:35.050: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"azuredisk-volume-tester-xfz62-59d7b5c84f\" is progressing."}}, CollisionCount:(*int32)(nil)}
Apr 11 12:15:37.049: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"azuredisk-volume-tester-xfz62-59d7b5c84f\" is progressing."}}, CollisionCount:(*int32)(nil)}
Apr 11 12:15:37.082: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"azuredisk-volume-tester-xfz62-59d7b5c84f\" is progressing."}}, CollisionCount:(*int32)(nil)}
Apr 11 12:15:37.090: INFO: deleting Deployment "azuredisk-8047"/"azuredisk-volume-tester-xfz62"
Apr 11 12:15:37.090: INFO: Error getting logs for pod : resource name may not be empty
STEP: validating provisioned PV
Apr 11 12:15:37.162: INFO: deleting StorageClass azuredisk-8047-kubernetes.io-azure-disk-dynamic-sc-zzwtg
STEP: Collecting events from namespace "azuredisk-8047".
STEP: Found 6 events.
Apr 11 12:15:37.232: INFO: At 2021-04-11 12:10:36 +0000 UTC - event for azuredisk-volume-tester-xfz62: {deployment-controller } ScalingReplicaSet: Scaled up replica set azuredisk-volume-tester-xfz62-59d7b5c84f to 1
Apr 11 12:15:37.232: INFO: At 2021-04-11 12:10:36 +0000 UTC - event for azuredisk-volume-tester-xfz62-59d7b5c84f: {replicaset-controller } SuccessfulCreate: Created pod: azuredisk-volume-tester-xfz62-59d7b5c84f-rh4gw
Apr 11 12:15:37.232: INFO: At 2021-04-11 12:10:36 +0000 UTC - event for pvc-nv5xz: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 12:15:37.232: INFO: At 2021-04-11 12:10:36 +0000 UTC - event for pvc-nv5xz: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:15:37.232: INFO: At 2021-04-11 12:10:36 +0000 UTC - event for pvc-nv5xz: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-8047/pvc-nv5xz"
Apr 11 12:15:37.232: INFO: At 2021-04-11 12:10:36 +0000 UTC - event for pvc-nv5xz: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-8047-kubernetes.io-azure-disk-dynamic-sc-zzwtg": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000000
Apr 11 12:15:37.266: INFO: POD                                             NODE  PHASE    GRACE  CONDITIONS
Apr 11 12:15:37.266: INFO: azuredisk-volume-tester-xfz62-59d7b5c84f-rh4gw        Pending         []
Apr 11 12:15:37.266: INFO: 
Apr 11 12:15:37.330: INFO: 
Logging node info for node 1894k8s00000000
Apr 11 12:15:37.364: INFO: Node Info: &Node{ObjectMeta:{1894k8s00000000   /api/v1/nodes/1894k8s00000000 04e3c016-5afa-4abc-8d02-c61ea8c62092 8486 0 2021-04-11 11:34:21 +0000 UTC <nil> <nil> map[agentpool:windowspool1 beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:Standard_D4s_v3 beta.kubernetes.io/os:windows failure-domain.beta.kubernetes.io/region:eastus2 failure-domain.beta.kubernetes.io/zone:0 kubernetes.azure.com/cluster:kubetest-z2lhaqf4 kubernetes.azure.com/role:agent kubernetes.io/arch:amd64 kubernetes.io/hostname:1894k8s00000000 kubernetes.io/os:windows kubernetes.io/role:agent node-role.kubernetes.io/agent: node.kubernetes.io/instance-type:Standard_D4s_v3 node.kubernetes.io/windows-build:10.0.17763 storageprofile:managed storagetier:Premium_LRS topology.kubernetes.io/region:eastus2 topology.kubernetes.io/zone:0] map[csi.volume.kubernetes.io/nodeid:{"disk.csi.azure.com":"1894k8s00000000"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kube-controller-manager Update v1 2021-04-11 11:34:21 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}}}} {kubectl-label Update v1 2021-04-11 11:34:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:kubernetes.io/role":{},"f:node-role.kubernetes.io/agent":{}}}}} {kubelet.exe Update v1 2021-04-11 11:40:24 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:csi.volume.kubernetes.io/nodeid":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:agentpool":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/instance-type":{},"f:beta.kubernetes.io/os":{},"f:failure-domain.beta.kubernetes.io/region":{},"f:failure-domain.beta.kubernetes.io/zone":{},"f:kubernetes.azure.com/cluster":{},"f:kubernetes.azure.com/role":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{},"f:node.kubernetes.io/instance-type":{},"f:node.kubernetes.io/windows-build":{},"f:storageprofile":{},"f:storagetier":{},"f:topology.kubernetes.io/region":{},"f:topology.kubernetes.io/zone":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}}]},Spec:NodeSpec{PodCIDR:,DoNotUseExternalID:,ProviderID:azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-z2lhaqf4/providers/Microsoft.Compute/virtualMachineScaleSets/1894k8s00/virtualMachines/0,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{17179398144 0} {<nil>} 16776756Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Allocatable:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{15031914496 0} {<nil>} 14679604Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-04-11 12:15:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-04-11 12:15:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-04-11 12:15:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-04-11 12:15:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:31 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:Hostname,Address:1894k8s00000000,},NodeAddress{Type:InternalIP,Address:10.240.0.34,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1894k8s00000000,SystemUUID:EB5A631D-2774-49B5-BF35-4058676A4A3B,BootID:,KernelVersion:10.0.17763.1697,OSImage:Windows Server 2019 Datacenter,ContainerRuntimeVersion:docker://19.3.11,KubeletVersion:v1.19.7,KubeProxyVersion:v1.19.7,OperatingSystem:windows,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[mcr.microsoft.com/windows/servercore@sha256:997e32161f322f5f301023ae675f39329bd60888f5f9a373100eba77f55cbb31 mcr.microsoft.com/windows/servercore:ltsc2019],SizeBytes:5206241784,},ContainerImage{Names:[k8sprow.azurecr.io/azuredisk-csi@sha256:ebcabcee5317a7b7a83f040eee2f348766029dc2d2dec8aa0a4ed1fae86366d2 k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700],SizeBytes:304141976,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:b7d82802cca8523df1d5973332c64d558fe352617926fd01707b17e2f022755f mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.2.0],SizeBytes:268076147,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:fc5d14e9f26fe9a014c1fa348c6632412424b834b8cbf9a968af5a9599a818c1 mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v2.0.1],SizeBytes:267426993,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:92dd20279c1e36282bd52e3db2679297921d517ffe4e98025c0370ce25a03fac mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v1.2.1-alpha.1-windows-1809-amd64],SizeBytes:265664693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:d07f92ef2517c99cd011391ab1182c995cdd04ec01e47324abf07ea502476409 mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.0.1-alpha.1-windows-1809-amd64],SizeBytes:264960693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes/pause@sha256:0343aed381a7b6892e85ec85dc0f8862b864e75584f08f215795a4696e3adae8 kubletwin/pause:latest mcr.microsoft.com/oss/kubernetes/pause:1.4.0],SizeBytes:256011346,},ContainerImage{Names:[mcr.microsoft.com/windows/nanoserver@sha256:58157fff3c3cfec14b102082474e62fb01d54cc6594973dd0038bce979ec8161 mcr.microsoft.com/windows/nanoserver:1809],SizeBytes:251861212,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 74 lines ...
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:40
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:43
    should create a deployment object, write and read to it, delete the pod and write and read to it again [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:286

    Unexpected error:
        <*errors.errorString | 0xc000617760>: {
            s: "error waiting for deployment \"azuredisk-volume-tester-xfz62\" status to match expectation: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:\"Available\", Status:\"False\", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:\"MinimumReplicasUnavailable\", Message:\"Deployment does not have minimum availability.\"}, v1.DeploymentCondition{Type:\"Progressing\", Status:\"True\", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:\"ReplicaSetUpdated\", Message:\"ReplicaSet \\\"azuredisk-volume-tester-xfz62-59d7b5c84f\\\" is progressing.\"}}, CollisionCount:(*int32)(nil)}",
        }
        error waiting for deployment "azuredisk-volume-tester-xfz62" status to match expectation: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63753739836, loc:(*time.Location)(0x2ed4b20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"azuredisk-volume-tester-xfz62-59d7b5c84f\" is progressing."}}, CollisionCount:(*int32)(nil)}
    occurred

    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/testsuites/testsuites.go:482
------------------------------
Dynamic Provisioning [single-az] 
  should delete PV with reclaimPolicy "Delete" [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows]
... skipping 162 lines ...
Apr 11 12:20:37.030: INFO: PersistentVolumeClaim pvc-tw9kc found but phase is Pending instead of Bound.
Apr 11 12:20:39.065: INFO: PersistentVolumeClaim pvc-tw9kc found but phase is Pending instead of Bound.
STEP: Collecting events from namespace "azuredisk-5541".
STEP: Found 3 events.
Apr 11 12:20:41.099: INFO: At 2021-04-11 12:15:39 +0000 UTC - event for pvc-tw9kc: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:20:41.099: INFO: At 2021-04-11 12:15:39 +0000 UTC - event for pvc-tw9kc: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-5541/pvc-tw9kc"
Apr 11 12:20:41.099: INFO: At 2021-04-11 12:15:39 +0000 UTC - event for pvc-tw9kc: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-5541-kubernetes.io-azure-disk-dynamic-sc-8q9xb": error generating accessibility requirements: no available topology found
Apr 11 12:20:41.132: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Apr 11 12:20:41.132: INFO: 
Apr 11 12:20:41.196: INFO: 
Logging node info for node 1894k8s00000000
Apr 11 12:20:41.229: INFO: Node Info: &Node{ObjectMeta:{1894k8s00000000   /api/v1/nodes/1894k8s00000000 04e3c016-5afa-4abc-8d02-c61ea8c62092 9455 0 2021-04-11 11:34:21 +0000 UTC <nil> <nil> map[agentpool:windowspool1 beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:Standard_D4s_v3 beta.kubernetes.io/os:windows failure-domain.beta.kubernetes.io/region:eastus2 failure-domain.beta.kubernetes.io/zone:0 kubernetes.azure.com/cluster:kubetest-z2lhaqf4 kubernetes.azure.com/role:agent kubernetes.io/arch:amd64 kubernetes.io/hostname:1894k8s00000000 kubernetes.io/os:windows kubernetes.io/role:agent node-role.kubernetes.io/agent: node.kubernetes.io/instance-type:Standard_D4s_v3 node.kubernetes.io/windows-build:10.0.17763 storageprofile:managed storagetier:Premium_LRS topology.kubernetes.io/region:eastus2 topology.kubernetes.io/zone:0] map[csi.volume.kubernetes.io/nodeid:{"disk.csi.azure.com":"1894k8s00000000"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kube-controller-manager Update v1 2021-04-11 11:34:21 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}}}} {kubectl-label Update v1 2021-04-11 11:34:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:kubernetes.io/role":{},"f:node-role.kubernetes.io/agent":{}}}}} {kubelet.exe Update v1 2021-04-11 11:40:24 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:csi.volume.kubernetes.io/nodeid":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:agentpool":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/instance-type":{},"f:beta.kubernetes.io/os":{},"f:failure-domain.beta.kubernetes.io/region":{},"f:failure-domain.beta.kubernetes.io/zone":{},"f:kubernetes.azure.com/cluster":{},"f:kubernetes.azure.com/role":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{},"f:node.kubernetes.io/instance-type":{},"f:node.kubernetes.io/windows-build":{},"f:storageprofile":{},"f:storagetier":{},"f:topology.kubernetes.io/region":{},"f:topology.kubernetes.io/zone":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}}]},Spec:NodeSpec{PodCIDR:,DoNotUseExternalID:,ProviderID:azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-z2lhaqf4/providers/Microsoft.Compute/virtualMachineScaleSets/1894k8s00/virtualMachines/0,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{17179398144 0} {<nil>} 16776756Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Allocatable:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{15031914496 0} {<nil>} 14679604Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-04-11 12:20:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-04-11 12:20:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-04-11 12:20:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-04-11 12:20:23 +0000 UTC,LastTransitionTime:2021-04-11 11:34:31 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:Hostname,Address:1894k8s00000000,},NodeAddress{Type:InternalIP,Address:10.240.0.34,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1894k8s00000000,SystemUUID:EB5A631D-2774-49B5-BF35-4058676A4A3B,BootID:,KernelVersion:10.0.17763.1697,OSImage:Windows Server 2019 Datacenter,ContainerRuntimeVersion:docker://19.3.11,KubeletVersion:v1.19.7,KubeProxyVersion:v1.19.7,OperatingSystem:windows,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[mcr.microsoft.com/windows/servercore@sha256:997e32161f322f5f301023ae675f39329bd60888f5f9a373100eba77f55cbb31 mcr.microsoft.com/windows/servercore:ltsc2019],SizeBytes:5206241784,},ContainerImage{Names:[k8sprow.azurecr.io/azuredisk-csi@sha256:ebcabcee5317a7b7a83f040eee2f348766029dc2d2dec8aa0a4ed1fae86366d2 k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700],SizeBytes:304141976,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:b7d82802cca8523df1d5973332c64d558fe352617926fd01707b17e2f022755f mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.2.0],SizeBytes:268076147,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:fc5d14e9f26fe9a014c1fa348c6632412424b834b8cbf9a968af5a9599a818c1 mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v2.0.1],SizeBytes:267426993,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:92dd20279c1e36282bd52e3db2679297921d517ffe4e98025c0370ce25a03fac mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v1.2.1-alpha.1-windows-1809-amd64],SizeBytes:265664693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:d07f92ef2517c99cd011391ab1182c995cdd04ec01e47324abf07ea502476409 mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.0.1-alpha.1-windows-1809-amd64],SizeBytes:264960693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes/pause@sha256:0343aed381a7b6892e85ec85dc0f8862b864e75584f08f215795a4696e3adae8 kubletwin/pause:latest mcr.microsoft.com/oss/kubernetes/pause:1.4.0],SizeBytes:256011346,},ContainerImage{Names:[mcr.microsoft.com/windows/nanoserver@sha256:58157fff3c3cfec14b102082474e62fb01d54cc6594973dd0038bce979ec8161 mcr.microsoft.com/windows/nanoserver:1809],SizeBytes:251861212,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Apr 11 12:20:41.229: INFO: 
... skipping 73 lines ...
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:40
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:43
    should delete PV with reclaimPolicy "Delete" [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:320

    Unexpected error:
        <*errors.errorString | 0xc0009f5050>: {
            s: "PersistentVolumeClaims [pvc-tw9kc] not all in phase Bound within 5m0s",
        }
        PersistentVolumeClaims [pvc-tw9kc] not all in phase Bound within 5m0s
    occurred

... skipping 105 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Apr 11 12:20:46.307: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-87gtx" in namespace "azuredisk-1957" to be "Succeeded or Failed"
Apr 11 12:20:46.344: INFO: Pod "azuredisk-volume-tester-87gtx": Phase="Pending", Reason="", readiness=false. Elapsed: 36.790263ms
Apr 11 12:20:48.377: INFO: Pod "azuredisk-volume-tester-87gtx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.070538555s
Apr 11 12:20:50.411: INFO: Pod "azuredisk-volume-tester-87gtx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.104380688s
Apr 11 12:20:52.447: INFO: Pod "azuredisk-volume-tester-87gtx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.139591082s
Apr 11 12:20:54.480: INFO: Pod "azuredisk-volume-tester-87gtx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.173525616s
Apr 11 12:20:56.515: INFO: Pod "azuredisk-volume-tester-87gtx": Phase="Pending", Reason="", readiness=false. Elapsed: 10.2081897s
... skipping 444 lines ...
STEP: validating provisioned PV
Apr 11 12:35:48.175: INFO: deleting StorageClass azuredisk-1957-kubernetes.io-azure-disk-dynamic-sc-nzqrx
STEP: Collecting events from namespace "azuredisk-1957".
STEP: Found 14 events.
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-6tgvf: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-6tgvf: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-1957/pvc-6tgvf"
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-6tgvf: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-1957-kubernetes.io-azure-disk-dynamic-sc-f2zjk": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000001
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-6tgvf: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-q99fj: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-1957-kubernetes.io-azure-disk-dynamic-sc-6qbpf": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000001
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-q99fj: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-q99fj: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-q99fj: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-1957/pvc-q99fj"
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-vxp68: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "disk.csi.azure.com" or manually created by system administrator
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-vxp68: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } ProvisioningFailed: failed to provision volume with StorageClass "azuredisk-1957-kubernetes.io-azure-disk-dynamic-sc-nzqrx": error generating accessibility requirements: no topology key found on CSINode 1894k8s00000001
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-vxp68: {persistentvolume-controller } WaitForFirstConsumer: waiting for first consumer to be created before binding
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:20:46 +0000 UTC - event for pvc-vxp68: {disk.csi.azure.com_k8s-master-18941823-0_d518dee9-7287-453f-a731-05523e35bbae } Provisioning: External provisioner is provisioning volume for claim "azuredisk-1957/pvc-vxp68"
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:30:46 +0000 UTC - event for azuredisk-volume-tester-87gtx: {default-scheduler } FailedScheduling: error while running "VolumeBinding" prebind plugin for pod "azuredisk-volume-tester-87gtx": Failed to bind volumes: timed out waiting for the condition
Apr 11 12:35:48.278: INFO: At 2021-04-11 12:35:47 +0000 UTC - event for azuredisk-volume-tester-87gtx: {default-scheduler } FailedScheduling: skip schedule deleting pod: azuredisk-1957/azuredisk-volume-tester-87gtx
Apr 11 12:35:48.311: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Apr 11 12:35:48.311: INFO: 
Apr 11 12:35:48.346: INFO: 
Logging node info for node 1894k8s00000000
Apr 11 12:35:48.380: INFO: Node Info: &Node{ObjectMeta:{1894k8s00000000   /api/v1/nodes/1894k8s00000000 04e3c016-5afa-4abc-8d02-c61ea8c62092 12371 0 2021-04-11 11:34:21 +0000 UTC <nil> <nil> map[agentpool:windowspool1 beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:Standard_D4s_v3 beta.kubernetes.io/os:windows failure-domain.beta.kubernetes.io/region:eastus2 failure-domain.beta.kubernetes.io/zone:0 kubernetes.azure.com/cluster:kubetest-z2lhaqf4 kubernetes.azure.com/role:agent kubernetes.io/arch:amd64 kubernetes.io/hostname:1894k8s00000000 kubernetes.io/os:windows kubernetes.io/role:agent node-role.kubernetes.io/agent: node.kubernetes.io/instance-type:Standard_D4s_v3 node.kubernetes.io/windows-build:10.0.17763 storageprofile:managed storagetier:Premium_LRS topology.kubernetes.io/region:eastus2 topology.kubernetes.io/zone:0] map[csi.volume.kubernetes.io/nodeid:{"disk.csi.azure.com":"1894k8s00000000"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kube-controller-manager Update v1 2021-04-11 11:34:21 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}}}} {kubectl-label Update v1 2021-04-11 11:34:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:kubernetes.io/role":{},"f:node-role.kubernetes.io/agent":{}}}}} {kubelet.exe Update v1 2021-04-11 11:40:24 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:csi.volume.kubernetes.io/nodeid":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:agentpool":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/instance-type":{},"f:beta.kubernetes.io/os":{},"f:failure-domain.beta.kubernetes.io/region":{},"f:failure-domain.beta.kubernetes.io/zone":{},"f:kubernetes.azure.com/cluster":{},"f:kubernetes.azure.com/role":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{},"f:node.kubernetes.io/instance-type":{},"f:node.kubernetes.io/windows-build":{},"f:storageprofile":{},"f:storagetier":{},"f:topology.kubernetes.io/region":{},"f:topology.kubernetes.io/zone":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}}]},Spec:NodeSpec{PodCIDR:,DoNotUseExternalID:,ProviderID:azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/kubetest-z2lhaqf4/providers/Microsoft.Compute/virtualMachineScaleSets/1894k8s00/virtualMachines/0,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{17179398144 0} {<nil>} 16776756Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Allocatable:ResourceList{cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{136912564224 0} {<nil>} 133703676Ki BinarySI},memory: {{15031914496 0} {<nil>} 14679604Ki BinarySI},pods: {{30 0} {<nil>} 30 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-04-11 12:35:24 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-04-11 12:35:24 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-04-11 12:35:24 +0000 UTC,LastTransitionTime:2021-04-11 11:34:21 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-04-11 12:35:24 +0000 UTC,LastTransitionTime:2021-04-11 11:34:31 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:Hostname,Address:1894k8s00000000,},NodeAddress{Type:InternalIP,Address:10.240.0.34,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1894k8s00000000,SystemUUID:EB5A631D-2774-49B5-BF35-4058676A4A3B,BootID:,KernelVersion:10.0.17763.1697,OSImage:Windows Server 2019 Datacenter,ContainerRuntimeVersion:docker://19.3.11,KubeletVersion:v1.19.7,KubeProxyVersion:v1.19.7,OperatingSystem:windows,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[mcr.microsoft.com/windows/servercore@sha256:997e32161f322f5f301023ae675f39329bd60888f5f9a373100eba77f55cbb31 mcr.microsoft.com/windows/servercore:ltsc2019],SizeBytes:5206241784,},ContainerImage{Names:[k8sprow.azurecr.io/azuredisk-csi@sha256:ebcabcee5317a7b7a83f040eee2f348766029dc2d2dec8aa0a4ed1fae86366d2 k8sprow.azurecr.io/azuredisk-csi:v1.2.0-4391adb6ed0594c6f3e6dcbdd848eca0723ee700],SizeBytes:304141976,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:b7d82802cca8523df1d5973332c64d558fe352617926fd01707b17e2f022755f mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.2.0],SizeBytes:268076147,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:fc5d14e9f26fe9a014c1fa348c6632412424b834b8cbf9a968af5a9599a818c1 mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v2.0.1],SizeBytes:267426993,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar@sha256:92dd20279c1e36282bd52e3db2679297921d517ffe4e98025c0370ce25a03fac mcr.microsoft.com/oss/kubernetes-csi/csi-node-driver-registrar:v1.2.1-alpha.1-windows-1809-amd64],SizeBytes:265664693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes-csi/livenessprobe@sha256:d07f92ef2517c99cd011391ab1182c995cdd04ec01e47324abf07ea502476409 mcr.microsoft.com/oss/kubernetes-csi/livenessprobe:v2.0.1-alpha.1-windows-1809-amd64],SizeBytes:264960693,},ContainerImage{Names:[mcr.microsoft.com/oss/kubernetes/pause@sha256:0343aed381a7b6892e85ec85dc0f8862b864e75584f08f215795a4696e3adae8 kubletwin/pause:latest mcr.microsoft.com/oss/kubernetes/pause:1.4.0],SizeBytes:256011346,},ContainerImage{Names:[mcr.microsoft.com/windows/nanoserver@sha256:58157fff3c3cfec14b102082474e62fb01d54cc6594973dd0038bce979ec8161 mcr.microsoft.com/windows/nanoserver:1809],SizeBytes:251861212,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 74 lines ...
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:40
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:43
    should create multiple PV objects, bind to PVCs and attach all to a single pod [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:431

    Unexpected error:
        <*errors.errorString | 0xc000ad68f0>: {
            s: "Gave up after waiting 15m0s for pod \"azuredisk-volume-tester-87gtx\" to be \"Succeeded or Failed\"",
        }
        Gave up after waiting 15m0s for pod "azuredisk-volume-tester-87gtx" to be "Succeeded or Failed"
    occurred

    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/testsuites/testsuites.go:737
------------------------------
Dynamic Provisioning [single-az] 
  should create a raw block volume and a filesystem volume on demand and bind to the same pod [kubernetes.io/azure-disk] [disk.csi.azure.com]
... skipping 98 lines ...
STEP: creating a PVC
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Apr 11 12:35:53.937: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-sknhd" in namespace "azuredisk-1563" to be "Succeeded or Failed"
Apr 11 12:35:53.979: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 41.511599ms
Apr 11 12:35:56.013: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.075424821s
Apr 11 12:35:58.048: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.110611064s
Apr 11 12:36:00.082: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.145053825s
Apr 11 12:36:02.116: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.17886599s
Apr 11 12:36:04.152: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.214468784s
... skipping 236 lines ...
Apr 11 12:44:06.486: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8m12.548702881s
Apr 11 12:44:08.520: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8m14.582949793s
Apr 11 12:44:10.556: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8m16.618935788s
Apr 11 12:44:12.592: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8m18.654626661s
Apr 11 12:44:14.627: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8m20.689953529s
Apr 11 12:44:16.662: INFO: Pod "azuredisk-volume-tester-sknhd": Phase="Pending", Reason="", readiness=false. Elapsed: 8m22.724976077s
{"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-04-11T12:44:17Z"}