This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: feat: switch back to csi-proxy v1beta interface on Windows
ResultABORTED
Tests 0 failed / 0 succeeded
Started2021-09-29 14:58
Elapsed1h2m
Revision4be9f85bea3b5d9175d6605f15e2608b93cf4df8
Refs 1034

No Test Failures!


Error lines from build-log.txt

... skipping 73 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11248  100 11248    0     0   219k      0 --:--:-- --:--:-- --:--:--  219k
Downloading https://get.helm.sh/helm-v3.7.0-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.8.0-48d7bd18383c09b02fba2b10739a91587fbfde8c || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:v1.8.0-48d7bd18383c09b02fba2b10739a91587fbfde8c not found: manifest unknown: manifest tagged by "v1.8.0-48d7bd18383c09b02fba2b10739a91587fbfde8c" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver'
CGO_ENABLED=0 GOOS=windows go build -a -ldflags "-X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.driverVersion=v1.8.0-48d7bd18383c09b02fba2b10739a91587fbfde8c -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=48d7bd18383c09b02fba2b10739a91587fbfde8c -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-09-29T15:02:58Z -extldflags "-static""  -mod vendor -o _output/amd64/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
# enable qemu for arm64 build
# https://github.com/docker/buildx/issues/464#issuecomment-741507760
docker run --privileged --rm tonistiigi/binfmt --uninstall qemu-aarch64
Unable to find image 'tonistiigi/binfmt:latest' locally
... skipping 1661 lines ...
                    type: string
                type: object
              volumeSnapshotClassName:
                description: 'volumeSnapshotClassName is the name of the VolumeSnapshotClass
                  requested by the VolumeSnapshot. If not specified, the default snapshot
                  class will be used if one exists. If not specified, and there is
                  no default snapshot class, dynamic snapshot creation will fail.
                  Empty string is not allowed for this field. TODO(xiangqian): a webhook
                  validation on empty string. More info: https://kubernetes.io/docs/concepts/storage/volume-snapshot-classes'
                type: string
            required:
            - source
            type: object
... skipping 22 lines ...
                  pre-existing snapshot, this field will be filled with the "creation_time"
                  value returned from the CSI "ListSnapshots" gRPC call if the driver
                  supports it. If not specified, it indicates that the creation time
                  of the snapshot is unknown.
                format: date-time
                type: string
              error:
                description: error is the last observed error during snapshot creation,
                  if any. This field could be helpful to upper level controllers(i.e.,
                  application controller) to decide whether they should continue on
                  waiting for the snapshot to be created based on the type of error
                  reported.
                properties:
                  message:
                    description: 'message is a string detailing the encountered error
                      during snapshot creation if specified. NOTE: message may be
                      logged, and it should not contain sensitive information.'
                    type: string
                  time:
                    description: time is the timestamp when the error was encountered.
                    format: date-time
                    type: string
                type: object
              readyToUse:
                description: readyToUse indicates if a snapshot is ready to be used
                  to restore a volume. In dynamic snapshot creation case, this field
... skipping 10 lines ...
                  in bytes. In dynamic snapshot creation case, this field will be
                  filled in with the "size_bytes" value returned from CSI "CreateSnapshotRequest"
                  gRPC call. For a pre-existing snapshot, this field will be filled
                  with the "size_bytes" value returned from the CSI "ListSnapshots"
                  gRPC call if the driver supports it. When restoring a volume from
                  this snapshot, the size of the volume MUST NOT be smaller than the
                  restoreSize if it is specified, otherwise the restoration will fail.
                  If not specified, it indicates that the size is unknown.
                pattern: ^(\+|-)?(([0-9]+(\.[0-9]*)?)|(\.[0-9]+))(([KMGTPE]i)|[numkMGTPE]|([eE](\+|-)?(([0-9]+(\.[0-9]*)?)|(\.[0-9]+))))?$
                x-kubernetes-int-or-string: true
            type: object
        required:
        - spec
... skipping 265 lines ...
                  supports it. If not specified, it indicates the creation time is
                  unknown. The format of this field is a Unix nanoseconds time encoded
                  as an int64. On Unix, the command `date +%s%N` returns the current
                  time in nanoseconds since 1970-01-01 00:00:00 UTC.
                format: int64
                type: integer
              error:
                description: error is the latest observed error during snapshot creation,
                  if any.
                properties:
                  message:
                    description: 'message is a string detailing the encountered error
                      during snapshot creation if specified. NOTE: message may be
                      logged, and it should not contain sensitive information.'
                    type: string
                  time:
                    description: time is the timestamp when the error was encountered.
                    format: date-time
                    type: string
                type: object
              readyToUse:
                description: readyToUse indicates if a snapshot is ready to be used
                  to restore a volume. In dynamic snapshot creation case, this field
... skipping 9 lines ...
                  in bytes. In dynamic snapshot creation case, this field will be
                  filled in with the "size_bytes" value returned from CSI "CreateSnapshotRequest"
                  gRPC call. For a pre-existing snapshot, this field will be filled
                  with the "size_bytes" value returned from the CSI "ListSnapshots"
                  gRPC call if the driver supports it. When restoring a volume from
                  this snapshot, the size of the volume MUST NOT be smaller than the
                  restoreSize if it is specified, otherwise the restoration will fail.
                  If not specified, it indicates that the size is unknown.
                format: int64
                minimum: 0
                type: integer
              snapshotHandle:
                description: snapshotHandle is the CSI "snapshot_id" of a snapshot
                  on the underlying storage system. If not specified, it indicates
                  that dynamic snapshot creation has either failed or it is still
                  in progress.
                type: string
            type: object
        required:
        - spec
        type: object
... skipping 857 lines ...
        - name: csi-resizer
          image: "mcr.microsoft.com/oss/kubernetes-csi/csi-resizer:v1.3.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 181 lines ...
Go Version: go1.17.1
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
STEP: Building a namespace api object, basename azuredisk
W0929 15:15:24.676057   12742 azure_disk_utils.go:144] InitializeCloudFromSecret: failed to get cloud config from secret /: failed to get secret /: resource name may not be empty
W0929 15:15:24.698014   12742 azuredisk.go:156] DisableAvailabilitySetNodes for controller is set as false while current VMType is vmss
I0929 15:15:24.698690   12742 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0929 15:15:24.698737   12742 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0929 15:15:24.698748   12742 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0929 15:15:24.698754   12742 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0929 15:15:24.698760   12742 driver.go:81] Enabling controller service capability: CLONE_VOLUME
... skipping 17 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
Sep 29 15:15:26.608: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-l5t4z" in namespace "azuredisk-8081" to be "Succeeded or Failed"
Sep 29 15:15:26.663: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 54.479557ms
Sep 29 15:15:28.717: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108872847s
Sep 29 15:15:30.772: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164314803s
Sep 29 15:15:32.828: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219474797s
Sep 29 15:15:34.883: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275000048s
Sep 29 15:15:36.939: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330771756s
... skipping 5 lines ...
Sep 29 15:15:49.272: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 22.66438955s
Sep 29 15:15:51.329: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 24.72111047s
Sep 29 15:15:53.385: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 26.776564825s
Sep 29 15:15:55.440: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Pending", Reason="", readiness=false. Elapsed: 28.831498532s
Sep 29 15:15:57.495: INFO: Pod "azuredisk-volume-tester-l5t4z": Phase="Succeeded", Reason="", readiness=false. Elapsed: 30.887068815s
STEP: Saw pod success
Sep 29 15:15:57.495: INFO: Pod "azuredisk-volume-tester-l5t4z" satisfied condition "Succeeded or Failed"
Sep 29 15:15:57.495: INFO: deleting Pod "azuredisk-8081"/"azuredisk-volume-tester-l5t4z"
Sep 29 15:15:57.607: INFO: Pod azuredisk-volume-tester-l5t4z has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-l5t4z in namespace azuredisk-8081
STEP: validating provisioned PV
STEP: checking the PV
... skipping 46 lines ...
Sep 29 15:16:42.135: INFO: PersistentVolumeClaim pvc-k87ds found but phase is Pending instead of Bound.
Sep 29 15:16:44.189: INFO: PersistentVolumeClaim pvc-k87ds found and phase=Bound (4.16230635s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 29 15:16:44.376: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-8sp7w" in namespace "azuredisk-1318" to be "Succeeded or Failed"
Sep 29 15:16:44.452: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 76.534457ms
Sep 29 15:16:46.523: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 2.147126644s
Sep 29 15:16:48.578: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 4.201617454s
Sep 29 15:16:50.632: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 6.255970986s
Sep 29 15:16:52.687: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 8.311507906s
Sep 29 15:16:54.743: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 10.367111204s
... skipping 2 lines ...
Sep 29 15:17:00.916: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 16.539655854s
Sep 29 15:17:02.971: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 18.595422455s
Sep 29 15:17:05.027: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 20.650690739s
Sep 29 15:17:07.082: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Pending", Reason="", readiness=false. Elapsed: 22.706216109s
Sep 29 15:17:09.138: INFO: Pod "azuredisk-volume-tester-8sp7w": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.761806935s
STEP: Saw pod success
Sep 29 15:17:09.138: INFO: Pod "azuredisk-volume-tester-8sp7w" satisfied condition "Succeeded or Failed"
Sep 29 15:17:09.138: INFO: deleting Pod "azuredisk-1318"/"azuredisk-volume-tester-8sp7w"
Sep 29 15:17:09.227: INFO: Pod azuredisk-volume-tester-8sp7w has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-8sp7w in namespace azuredisk-1318
Sep 29 15:17:09.289: INFO: deleting PVC "azuredisk-1318"/"pvc-k87ds"
Sep 29 15:17:09.289: INFO: Deleting PersistentVolumeClaim "pvc-k87ds"
... skipping 33 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
Sep 29 15:17:31.322: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-wzdsw" in namespace "azuredisk-694" to be "Succeeded or Failed"
Sep 29 15:17:31.376: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 53.846054ms
Sep 29 15:17:33.431: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108176059s
Sep 29 15:17:35.485: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.16281984s
Sep 29 15:17:37.540: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218076899s
Sep 29 15:17:39.597: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274720113s
Sep 29 15:17:41.651: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.329041558s
... skipping 14 lines ...
Sep 29 15:18:12.494: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 41.171599523s
Sep 29 15:18:14.550: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 43.227737757s
Sep 29 15:18:16.607: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 45.284655204s
Sep 29 15:18:18.661: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Pending", Reason="", readiness=false. Elapsed: 47.338973588s
Sep 29 15:18:20.717: INFO: Pod "azuredisk-volume-tester-wzdsw": Phase="Succeeded", Reason="", readiness=false. Elapsed: 49.394343142s
STEP: Saw pod success
Sep 29 15:18:20.717: INFO: Pod "azuredisk-volume-tester-wzdsw" satisfied condition "Succeeded or Failed"
Sep 29 15:18:20.717: INFO: deleting Pod "azuredisk-694"/"azuredisk-volume-tester-wzdsw"
Sep 29 15:18:20.799: INFO: Pod azuredisk-volume-tester-wzdsw has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-wzdsw in namespace azuredisk-694
STEP: validating provisioned PV
STEP: checking the PV
... skipping 41 lines ...
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 has 'FailedMount' event
Sep 29 15:19:25.342: INFO: deleting Pod "azuredisk-3274"/"azuredisk-volume-tester-7q26l"
Sep 29 15:19:25.399: INFO: Error getting logs for pod azuredisk-volume-tester-7q26l: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-7q26l)
STEP: Deleting pod azuredisk-volume-tester-7q26l in namespace azuredisk-3274
STEP: validating provisioned PV
STEP: checking the PV
Sep 29 15:19:25.561: INFO: deleting PVC "azuredisk-3274"/"pvc-77k4t"
Sep 29 15:19:25.561: INFO: Deleting PersistentVolumeClaim "pvc-77k4t"
STEP: waiting for claim's PV "pvc-b5b869c2-718e-4beb-abf7-6f76a8d8b87f" to be deleted
... skipping 37 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
Sep 29 15:20:12.951: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-662w6" in namespace "azuredisk-495" to be "Succeeded or Failed"
Sep 29 15:20:13.006: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 54.687445ms
Sep 29 15:20:15.061: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.110200489s
Sep 29 15:20:17.116: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164917629s
Sep 29 15:20:19.171: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.220120582s
Sep 29 15:20:21.227: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275851418s
Sep 29 15:20:23.283: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.331710325s
... skipping 2 lines ...
Sep 29 15:20:29.447: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 16.495625873s
Sep 29 15:20:31.503: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 18.551368731s
Sep 29 15:20:33.557: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 20.605871993s
Sep 29 15:20:35.612: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Pending", Reason="", readiness=false. Elapsed: 22.660924338s
Sep 29 15:20:37.668: INFO: Pod "azuredisk-volume-tester-662w6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.716272685s
STEP: Saw pod success
Sep 29 15:20:37.668: INFO: Pod "azuredisk-volume-tester-662w6" satisfied condition "Succeeded or Failed"
Sep 29 15:20:37.668: INFO: deleting Pod "azuredisk-495"/"azuredisk-volume-tester-662w6"
Sep 29 15:20:37.734: INFO: Pod azuredisk-volume-tester-662w6 has the following logs: e2e-test

STEP: Deleting pod azuredisk-volume-tester-662w6 in namespace azuredisk-495
STEP: validating provisioned PV
STEP: checking the PV
... skipping 35 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
Sep 29 15:20:59.934: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-hlq54" in namespace "azuredisk-9947" to be "Error status code"
Sep 29 15:20:59.988: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 53.278833ms
Sep 29 15:21:02.043: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108488827s
Sep 29 15:21:04.098: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163878917s
Sep 29 15:21:06.154: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219688729s
Sep 29 15:21:08.209: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274371057s
Sep 29 15:21:10.263: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 10.328810848s
... skipping 23 lines ...
Sep 29 15:21:59.590: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 59.65529284s
Sep 29 15:22:01.645: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 1m1.710884856s
Sep 29 15:22:03.700: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 1m3.765407628s
Sep 29 15:22:05.755: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 1m5.820718666s
Sep 29 15:22:07.813: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 1m7.878512306s
Sep 29 15:22:09.867: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Pending", Reason="", readiness=false. Elapsed: 1m9.933002569s
Sep 29 15:22:11.921: INFO: Pod "azuredisk-volume-tester-hlq54": Phase="Failed", Reason="", readiness=false. Elapsed: 1m11.986907017s
STEP: Saw pod failure
Sep 29 15:22:11.921: INFO: Pod "azuredisk-volume-tester-hlq54" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep 29 15:22:12.010: INFO: deleting Pod "azuredisk-9947"/"azuredisk-volume-tester-hlq54"
Sep 29 15:22:12.067: INFO: Pod azuredisk-volume-tester-hlq54 has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azuredisk-volume-tester-hlq54 in namespace azuredisk-9947
STEP: validating provisioned PV
... skipping 336 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
Sep 29 15:30:33.398: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-hzvb8" in namespace "azuredisk-3721" to be "Succeeded or Failed"
Sep 29 15:30:33.451: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 53.122018ms
Sep 29 15:30:35.506: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.107867184s
Sep 29 15:30:37.563: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165205628s
Sep 29 15:30:39.619: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.220528372s
Sep 29 15:30:41.675: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 8.277192772s
Sep 29 15:30:43.730: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 10.331772952s
... skipping 2 lines ...
Sep 29 15:30:49.895: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 16.496996844s
Sep 29 15:30:51.949: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550898316s
Sep 29 15:30:54.004: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 20.605940439s
Sep 29 15:30:56.060: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Pending", Reason="", readiness=false. Elapsed: 22.661530094s
Sep 29 15:30:58.115: INFO: Pod "azuredisk-volume-tester-hzvb8": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.716823012s
STEP: Saw pod success
Sep 29 15:30:58.115: INFO: Pod "azuredisk-volume-tester-hzvb8" satisfied condition "Succeeded or Failed"
STEP: cloning existing volume
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying a second pod with cloned volume
STEP: checking that the pod's command exits with no error
Sep 29 15:30:58.334: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-mzxj8" in namespace "azuredisk-3721" to be "Succeeded or Failed"
Sep 29 15:30:58.388: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 53.155409ms
Sep 29 15:31:00.441: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.107065495s
Sep 29 15:31:02.496: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 4.162007213s
Sep 29 15:31:04.552: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218039125s
Sep 29 15:31:06.608: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 8.273727985s
Sep 29 15:31:08.665: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330204857s
... skipping 9 lines ...
Sep 29 15:31:29.221: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 30.886702628s
Sep 29 15:31:31.275: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 32.940950682s
Sep 29 15:31:33.332: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 34.997448254s
Sep 29 15:31:35.387: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Pending", Reason="", readiness=false. Elapsed: 37.052145587s
Sep 29 15:31:37.442: INFO: Pod "azuredisk-volume-tester-mzxj8": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.107456946s
STEP: Saw pod success
Sep 29 15:31:37.442: INFO: Pod "azuredisk-volume-tester-mzxj8" satisfied condition "Succeeded or Failed"
Sep 29 15:31:37.442: INFO: deleting Pod "azuredisk-3721"/"azuredisk-volume-tester-mzxj8"
Sep 29 15:31:37.527: INFO: Pod azuredisk-volume-tester-mzxj8 has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-mzxj8 in namespace azuredisk-3721
STEP: validating provisioned PV
STEP: checking the PV
... skipping 53 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
Sep 29 15:32:30.520: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-sgrqx" in namespace "azuredisk-2888" to be "Succeeded or Failed"
Sep 29 15:32:30.575: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 54.235628ms
Sep 29 15:32:32.630: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109243559s
Sep 29 15:32:34.684: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163477912s
Sep 29 15:32:36.739: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218935592s
Sep 29 15:32:38.795: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.2747001s
Sep 29 15:32:40.849: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 10.32911702s
Sep 29 15:32:42.905: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 12.384776232s
Sep 29 15:32:44.961: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 14.441139148s
Sep 29 15:32:47.016: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 16.495421918s
Sep 29 15:32:49.071: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550307351s
Sep 29 15:32:51.127: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Pending", Reason="", readiness=false. Elapsed: 20.606955301s
Sep 29 15:32:53.183: INFO: Pod "azuredisk-volume-tester-sgrqx": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.662361768s
STEP: Saw pod success
Sep 29 15:32:53.183: INFO: Pod "azuredisk-volume-tester-sgrqx" satisfied condition "Succeeded or Failed"
STEP: cloning existing volume
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying a second pod with cloned volume
STEP: checking that the pod's command exits with no error
Sep 29 15:32:53.405: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-7pmdh" in namespace "azuredisk-2888" to be "Succeeded or Failed"
Sep 29 15:32:53.458: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 53.228341ms
Sep 29 15:32:55.512: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.107701828s
Sep 29 15:32:57.567: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.162330304s
Sep 29 15:32:59.623: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218041436s
Sep 29 15:33:01.677: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.272303986s
Sep 29 15:33:03.732: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.327634279s
... skipping 9 lines ...
Sep 29 15:33:24.287: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 30.882287419s
Sep 29 15:33:26.343: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 32.937776809s
Sep 29 15:33:28.398: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 34.992981037s
Sep 29 15:33:30.452: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Pending", Reason="", readiness=false. Elapsed: 37.047621839s
Sep 29 15:33:32.508: INFO: Pod "azuredisk-volume-tester-7pmdh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.103406958s
STEP: Saw pod success
Sep 29 15:33:32.508: INFO: Pod "azuredisk-volume-tester-7pmdh" satisfied condition "Succeeded or Failed"
Sep 29 15:33:32.508: INFO: deleting Pod "azuredisk-2888"/"azuredisk-volume-tester-7pmdh"
Sep 29 15:33:32.565: INFO: Pod azuredisk-volume-tester-7pmdh has the following logs: 20.0G

STEP: Deleting pod azuredisk-volume-tester-7pmdh in namespace azuredisk-2888
STEP: validating provisioned PV
STEP: checking the PV
... skipping 63 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
Sep 29 15:34:25.726: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-jgmqb" in namespace "azuredisk-8510" to be "Succeeded or Failed"
Sep 29 15:34:25.780: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 54.230362ms
Sep 29 15:34:27.835: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109346579s
Sep 29 15:34:29.890: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164049095s
Sep 29 15:34:31.945: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219224677s
Sep 29 15:34:33.999: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.273356137s
Sep 29 15:34:36.055: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 10.329517941s
Sep 29 15:34:38.110: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 12.38425015s
Sep 29 15:34:40.165: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 14.439625133s
Sep 29 15:34:42.221: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 16.494905851s
Sep 29 15:34:44.276: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550654568s
Sep 29 15:34:46.335: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Pending", Reason="", readiness=false. Elapsed: 20.609344858s
Sep 29 15:34:48.390: INFO: Pod "azuredisk-volume-tester-jgmqb": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.663743765s
STEP: Saw pod success
Sep 29 15:34:48.390: INFO: Pod "azuredisk-volume-tester-jgmqb" satisfied condition "Succeeded or Failed"
Sep 29 15:34:48.390: INFO: deleting Pod "azuredisk-8510"/"azuredisk-volume-tester-jgmqb"
Sep 29 15:34:48.447: INFO: Pod azuredisk-volume-tester-jgmqb has the following logs: hello world
hello world
hello world

STEP: Deleting pod azuredisk-volume-tester-jgmqb in namespace azuredisk-8510
... skipping 76 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
Sep 29 15:36:22.313: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-bft9f" in namespace "azuredisk-5561" to be "Succeeded or Failed"
Sep 29 15:36:22.367: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 53.82944ms
Sep 29 15:36:24.421: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108249734s
Sep 29 15:36:26.477: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164098844s
Sep 29 15:36:28.531: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218331206s
Sep 29 15:36:30.587: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274221463s
Sep 29 15:36:32.643: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 10.329634361s
... skipping 9 lines ...
Sep 29 15:36:53.196: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 30.882796808s
Sep 29 15:36:55.251: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 32.9381555s
Sep 29 15:36:57.306: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 34.993066685s
Sep 29 15:36:59.361: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Pending", Reason="", readiness=false. Elapsed: 37.04791544s
Sep 29 15:37:01.417: INFO: Pod "azuredisk-volume-tester-bft9f": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.10354271s
STEP: Saw pod success
Sep 29 15:37:01.417: INFO: Pod "azuredisk-volume-tester-bft9f" satisfied condition "Succeeded or Failed"
Sep 29 15:37:01.417: INFO: deleting Pod "azuredisk-5561"/"azuredisk-volume-tester-bft9f"
Sep 29 15:37:01.501: INFO: Pod azuredisk-volume-tester-bft9f has the following logs: 100+0 records in
100+0 records out
104857600 bytes (100.0MB) copied, 0.070812 seconds, 1.4GB/s
hello world

... skipping 59 lines ...
Sep 29 15:38:24.806: INFO: >>> kubeConfig: /root/tmp2595346514/kubeconfig/kubeconfig.westus2.json
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
Sep 29 15:38:24.975: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-kn764" in namespace "azuredisk-4376" to be "Succeeded or Failed"
Sep 29 15:38:25.029: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 53.049151ms
Sep 29 15:38:27.084: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108287242s
Sep 29 15:38:29.139: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163828325s
Sep 29 15:38:31.194: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218935592s
Sep 29 15:38:33.250: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274434764s
Sep 29 15:38:35.305: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 10.329002815s
Sep 29 15:38:37.360: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 12.384629503s
Sep 29 15:38:39.415: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 14.439022517s
Sep 29 15:38:41.470: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 16.494196338s
Sep 29 15:38:43.526: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550164483s
Sep 29 15:38:45.581: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Pending", Reason="", readiness=false. Elapsed: 20.605418025s
Sep 29 15:38:47.636: INFO: Pod "azuredisk-volume-tester-kn764": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.660486648s
STEP: Saw pod success
Sep 29 15:38:47.636: INFO: Pod "azuredisk-volume-tester-kn764" satisfied condition "Succeeded or Failed"
STEP: Checking Prow test resource group
2021/09/29 15:38:47 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
2021/09/29 15:38:47 Reading credentials file /etc/azure-cred/credentials
STEP: Prow test resource group: kubetest-fimjuy1l
STEP: Creating external resource group: azuredisk-csi-driver-test-564df6b4-213b-11ec-91f6-c68a0a66593e
STEP: creating volume snapshot class with external rg azuredisk-csi-driver-test-564df6b4-213b-11ec-91f6-c68a0a66593e
... skipping 5 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 a pod with a volume restored from the snapshot
STEP: checking that the pod's command exits with no error
Sep 29 15:39:04.086: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-prrwn" in namespace "azuredisk-4376" to be "Succeeded or Failed"
Sep 29 15:39:04.139: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 53.011373ms
Sep 29 15:39:06.193: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.106547837s
Sep 29 15:39:08.248: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.162252995s
Sep 29 15:39:10.303: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.217194305s
Sep 29 15:39:12.358: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.271446073s
Sep 29 15:39:14.413: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 10.326740856s
Sep 29 15:39:16.468: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 12.382231285s
Sep 29 15:39:18.523: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 14.43643342s
Sep 29 15:39:20.577: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 16.491223222s
Sep 29 15:39:22.633: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 18.546577993s
Sep 29 15:39:24.688: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Pending", Reason="", readiness=false. Elapsed: 20.601517853s
Sep 29 15:39:26.743: INFO: Pod "azuredisk-volume-tester-prrwn": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.657109441s
STEP: Saw pod success
Sep 29 15:39:26.743: INFO: Pod "azuredisk-volume-tester-prrwn" satisfied condition "Succeeded or Failed"
Sep 29 15:39:26.743: INFO: deleting Pod "azuredisk-4376"/"azuredisk-volume-tester-prrwn"
Sep 29 15:39:26.852: INFO: Pod azuredisk-volume-tester-prrwn has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-prrwn in namespace azuredisk-4376
STEP: validating provisioned PV
STEP: checking the PV
... skipping 56 lines ...
Sep 29 15:41:36.342: INFO: >>> kubeConfig: /root/tmp2595346514/kubeconfig/kubeconfig.westus2.json
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
Sep 29 15:41:36.512: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-xclvd" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Sep 29 15:41:36.566: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 53.41309ms
Sep 29 15:41:38.620: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.107582204s
Sep 29 15:41:40.674: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.161860059s
Sep 29 15:41:42.729: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.216902692s
Sep 29 15:41:44.788: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275284231s
Sep 29 15:41:46.843: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330235504s
Sep 29 15:41:48.897: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 12.384644345s
Sep 29 15:41:50.952: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 14.43978784s
Sep 29 15:41:53.008: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 16.495269011s
Sep 29 15:41:55.062: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 18.549889436s
Sep 29 15:41:57.118: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Pending", Reason="", readiness=false. Elapsed: 20.605279522s
Sep 29 15:41:59.173: INFO: Pod "azuredisk-volume-tester-xclvd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.660201007s
STEP: Saw pod success
Sep 29 15:41:59.173: INFO: Pod "azuredisk-volume-tester-xclvd" satisfied condition "Succeeded or Failed"
STEP: Checking Prow test resource group
2021/09/29 15:41:59 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
2021/09/29 15:41:59 Reading credentials file /etc/azure-cred/credentials
STEP: Prow test resource group: kubetest-fimjuy1l
STEP: Creating external resource group: azuredisk-csi-driver-test-c878085a-213b-11ec-91f6-c68a0a66593e
STEP: creating volume snapshot class with external rg azuredisk-csi-driver-test-c878085a-213b-11ec-91f6-c68a0a66593e
STEP: setting up the VolumeSnapshotClass
STEP: creating a VolumeSnapshotClass
STEP: taking snapshots
STEP: creating a VolumeSnapshot for pvc-nsktk
STEP: deploying a new pod to overwrite pv data
STEP: checking that the pod's command exits with no error
Sep 29 15:41:59.927: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-v4pmq" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Sep 29 15:41:59.980: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 53.163531ms
Sep 29 15:42:02.038: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 2.111186861s
Sep 29 15:42:04.092: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165456416s
Sep 29 15:42:06.146: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219641609s
Sep 29 15:42:08.202: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275018118s
Sep 29 15:42:10.257: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330343179s
Sep 29 15:42:12.312: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 12.385769871s
Sep 29 15:42:14.367: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 14.440307697s
Sep 29 15:42:16.422: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 16.495703903s
Sep 29 15:42:18.477: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550245152s
Sep 29 15:42:20.533: INFO: Pod "azuredisk-volume-tester-v4pmq": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.605987868s
STEP: Saw pod success
Sep 29 15:42:20.533: INFO: Pod "azuredisk-volume-tester-v4pmq" satisfied condition "Succeeded or Failed"
STEP: waiting for VolumeSnapshot to be ready to use - volume-snapshot-v4r2r
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 a pod with a volume restored from the snapshot
STEP: checking that the pod's command exits with no error
Sep 29 15:42:35.764: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-8bfqh" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Sep 29 15:42:35.817: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 53.423631ms
Sep 29 15:42:37.872: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108923695s
Sep 29 15:42:39.927: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163260311s
Sep 29 15:42:41.981: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.217128416s
Sep 29 15:42:44.035: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.271691917s
Sep 29 15:42:46.092: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.328073737s
Sep 29 15:42:48.146: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 12.382437609s
Sep 29 15:42:50.201: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 14.436958979s
Sep 29 15:42:52.255: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 16.491534966s
Sep 29 15:42:54.310: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 18.546493945s
Sep 29 15:42:56.366: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Pending", Reason="", readiness=false. Elapsed: 20.602151346s
Sep 29 15:42:58.421: INFO: Pod "azuredisk-volume-tester-8bfqh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.657182048s
STEP: Saw pod success
Sep 29 15:42:58.421: INFO: Pod "azuredisk-volume-tester-8bfqh" satisfied condition "Succeeded or Failed"
Sep 29 15:42:58.421: INFO: deleting Pod "azuredisk-1577"/"azuredisk-volume-tester-8bfqh"
Sep 29 15:42:58.508: INFO: Pod azuredisk-volume-tester-8bfqh has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-8bfqh in namespace azuredisk-1577
STEP: validating provisioned PV
STEP: checking the PV
... skipping 75 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
Sep 29 15:45:49.392: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-gvngk" in namespace "azuredisk-953" to be "Succeeded or Failed"
Sep 29 15:45:49.448: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 55.83189ms
Sep 29 15:45:51.508: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116372645s
Sep 29 15:45:53.564: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.171516016s
Sep 29 15:45:55.619: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.227069941s
Sep 29 15:45:57.675: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.283045653s
Sep 29 15:45:59.731: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.338515911s
Sep 29 15:46:01.785: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 12.393051076s
Sep 29 15:46:03.841: INFO: Pod "azuredisk-volume-tester-gvngk": Phase="Pending", Reason="", readiness=false. Elapsed: 14.448962564s
{"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-09-29T15:46:04Z"}
++ early_exit_handler
++ '[' -n 164 ']'
++ kill -TERM 164
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...