This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: fix: install updated packages to fix CVE issues
ResultABORTED
Tests 0 failed / 22 succeeded
Started2021-10-22 02:53
Elapsed59m26s
Revisionf1b2555c1cd2fa49e1323851157cdacf86d2d9b9
Refs 1055

No Test Failures!


Show 22 Passed Tests

Show 31 Skipped Tests

Error lines from build-log.txt

... skipping 73 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11248  100 11248    0     0   207k      0 --:--:-- --:--:-- --:--:--  207k
Downloading https://get.helm.sh/helm-v3.7.1-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.9.0-1b680534b06fba2343908c128f061512dc806aef || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:v1.9.0-1b680534b06fba2343908c128f061512dc806aef not found: manifest unknown: manifest tagged by "v1.9.0-1b680534b06fba2343908c128f061512dc806aef" 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.9.0-1b680534b06fba2343908c128f061512dc806aef -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=1b680534b06fba2343908c128f061512dc806aef -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-10-22T02:58:27Z -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 1747 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 859 lines ...
        - name: csi-resizer-disk
          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 175 lines ...
Go Version: go1.17.2
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
STEP: Building a namespace api object, basename azuredisk
W1022 03:07:32.024091   12682 azure_disk_utils.go:144] InitializeCloudFromSecret: failed to get cloud config from secret /: failed to get secret /: resource name may not be empty
W1022 03:07:32.024865   12682 azuredisk.go:159] DisableAvailabilitySetNodes for controller is set as false while current VMType is vmss
I1022 03:07:32.025020   12682 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I1022 03:07:32.025034   12682 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I1022 03:07:32.025037   12682 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I1022 03:07:32.025041   12682 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I1022 03:07:32.025044   12682 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
Oct 22 03:07:33.922: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-hbp5m" in namespace "azuredisk-8081" to be "Succeeded or Failed"
Oct 22 03:07:33.975: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 53.082218ms
Oct 22 03:07:36.029: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 2.107381313s
Oct 22 03:07:38.085: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163353445s
Oct 22 03:07:40.142: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 6.2195521s
Oct 22 03:07:42.197: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274888301s
Oct 22 03:07:44.253: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 10.33094357s
... skipping 5 lines ...
Oct 22 03:07:56.588: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 22.665766422s
Oct 22 03:07:58.644: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 24.721991667s
Oct 22 03:08:00.700: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 26.7782718s
Oct 22 03:08:02.756: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Pending", Reason="", readiness=false. Elapsed: 28.834366652s
Oct 22 03:08:04.812: INFO: Pod "azuredisk-volume-tester-hbp5m": Phase="Succeeded", Reason="", readiness=false. Elapsed: 30.889805404s
STEP: Saw pod success
Oct 22 03:08:04.812: INFO: Pod "azuredisk-volume-tester-hbp5m" satisfied condition "Succeeded or Failed"
Oct 22 03:08:04.812: INFO: deleting Pod "azuredisk-8081"/"azuredisk-volume-tester-hbp5m"
Oct 22 03:08:04.900: INFO: Pod azuredisk-volume-tester-hbp5m has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-hbp5m in namespace azuredisk-8081
STEP: validating provisioned PV
STEP: checking the PV
... skipping 46 lines ...
Oct 22 03:08:49.579: INFO: PersistentVolumeClaim pvc-sz6sc found but phase is Pending instead of Bound.
Oct 22 03:08:51.635: INFO: PersistentVolumeClaim pvc-sz6sc found and phase=Bound (4.164737646s)
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
Oct 22 03:08:51.797: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-5n6cn" in namespace "azuredisk-1318" to be "Succeeded or Failed"
Oct 22 03:08:51.850: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 53.173771ms
Oct 22 03:08:53.906: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109207185s
Oct 22 03:08:55.963: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165448077s
Oct 22 03:08:58.017: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219716077s
Oct 22 03:09:00.072: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274524986s
Oct 22 03:09:02.127: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330136483s
Oct 22 03:09:04.183: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 12.385542102s
Oct 22 03:09:06.238: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 14.440812904s
Oct 22 03:09:08.298: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 16.501181713s
Oct 22 03:09:10.353: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Pending", Reason="", readiness=false. Elapsed: 18.555906401s
Oct 22 03:09:12.410: INFO: Pod "azuredisk-volume-tester-5n6cn": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.612753608s
STEP: Saw pod success
Oct 22 03:09:12.410: INFO: Pod "azuredisk-volume-tester-5n6cn" satisfied condition "Succeeded or Failed"
Oct 22 03:09:12.410: INFO: deleting Pod "azuredisk-1318"/"azuredisk-volume-tester-5n6cn"
Oct 22 03:09:12.467: INFO: Pod azuredisk-volume-tester-5n6cn has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-5n6cn in namespace azuredisk-1318
Oct 22 03:09:12.528: INFO: deleting PVC "azuredisk-1318"/"pvc-sz6sc"
Oct 22 03:09:12.528: INFO: Deleting PersistentVolumeClaim "pvc-sz6sc"
... skipping 38 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
Oct 22 03:09:59.844: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-6ctjn" in namespace "azuredisk-694" to be "Succeeded or Failed"
Oct 22 03:09:59.897: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 53.667789ms
Oct 22 03:10:01.952: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108356926s
Oct 22 03:10:04.008: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.16410952s
Oct 22 03:10:06.062: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218716224s
Oct 22 03:10:08.118: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274174796s
Oct 22 03:10:10.173: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 10.32893046s
... skipping 13 lines ...
Oct 22 03:10:38.954: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 39.11036591s
Oct 22 03:10:41.012: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 41.167775836s
Oct 22 03:10:43.067: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 43.222918282s
Oct 22 03:10:45.123: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Pending", Reason="", readiness=false. Elapsed: 45.279543537s
Oct 22 03:10:47.179: INFO: Pod "azuredisk-volume-tester-6ctjn": Phase="Succeeded", Reason="", readiness=false. Elapsed: 47.335505173s
STEP: Saw pod success
Oct 22 03:10:47.179: INFO: Pod "azuredisk-volume-tester-6ctjn" satisfied condition "Succeeded or Failed"
Oct 22 03:10:47.179: INFO: deleting Pod "azuredisk-694"/"azuredisk-volume-tester-6ctjn"
Oct 22 03:10:47.260: INFO: Pod azuredisk-volume-tester-6ctjn has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-6ctjn 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
Oct 22 03:11:51.832: INFO: deleting Pod "azuredisk-3274"/"azuredisk-volume-tester-m9r2c"
Oct 22 03:11:51.888: INFO: Error getting logs for pod azuredisk-volume-tester-m9r2c: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-m9r2c)
STEP: Deleting pod azuredisk-volume-tester-m9r2c in namespace azuredisk-3274
STEP: validating provisioned PV
STEP: checking the PV
Oct 22 03:11:52.055: INFO: deleting PVC "azuredisk-3274"/"pvc-wf8w9"
Oct 22 03:11:52.055: INFO: Deleting PersistentVolumeClaim "pvc-wf8w9"
STEP: waiting for claim's PV "pvc-4cbcc817-b063-4bb1-a319-f555a3227a7d" 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
Oct 22 03:12:39.390: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-v57sk" in namespace "azuredisk-495" to be "Succeeded or Failed"
Oct 22 03:12:39.444: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 53.753049ms
Oct 22 03:12:41.500: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109643484s
Oct 22 03:12:43.555: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164674534s
Oct 22 03:12:45.610: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.220101349s
Oct 22 03:12:47.666: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275603471s
Oct 22 03:12:49.721: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330821187s
... skipping 2 lines ...
Oct 22 03:12:55.885: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 16.495250388s
Oct 22 03:12:57.940: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550384154s
Oct 22 03:12:59.997: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 20.606844743s
Oct 22 03:13:02.052: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Pending", Reason="", readiness=false. Elapsed: 22.662208861s
Oct 22 03:13:04.109: INFO: Pod "azuredisk-volume-tester-v57sk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.718657054s
STEP: Saw pod success
Oct 22 03:13:04.109: INFO: Pod "azuredisk-volume-tester-v57sk" satisfied condition "Succeeded or Failed"
Oct 22 03:13:04.109: INFO: deleting Pod "azuredisk-495"/"azuredisk-volume-tester-v57sk"
Oct 22 03:13:04.171: INFO: Pod azuredisk-volume-tester-v57sk has the following logs: e2e-test

STEP: Deleting pod azuredisk-volume-tester-v57sk in namespace azuredisk-495
STEP: validating provisioned PV
STEP: checking the PV
... skipping 39 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
Oct 22 03:13:46.589: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-gzhdb" in namespace "azuredisk-9947" to be "Error status code"
Oct 22 03:13:46.644: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 55.157428ms
Oct 22 03:13:48.699: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.110750616s
Oct 22 03:13:50.755: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.166746782s
Oct 22 03:13:52.810: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.221068489s
Oct 22 03:13:54.865: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.276626322s
Oct 22 03:13:56.922: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 10.333182148s
... skipping 23 lines ...
Oct 22 03:14:46.260: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 59.671170918s
Oct 22 03:14:48.314: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 1m1.725572114s
Oct 22 03:14:50.369: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 1m3.780159515s
Oct 22 03:14:52.424: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 1m5.83582826s
Oct 22 03:14:54.480: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 1m7.890960293s
Oct 22 03:14:56.534: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Pending", Reason="", readiness=false. Elapsed: 1m9.944997027s
Oct 22 03:14:58.590: INFO: Pod "azuredisk-volume-tester-gzhdb": Phase="Failed", Reason="", readiness=false. Elapsed: 1m12.000998381s
STEP: Saw pod failure
Oct 22 03:14:58.590: INFO: Pod "azuredisk-volume-tester-gzhdb" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Oct 22 03:14:58.685: INFO: deleting Pod "azuredisk-9947"/"azuredisk-volume-tester-gzhdb"
Oct 22 03:14:58.742: INFO: Pod azuredisk-volume-tester-gzhdb has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azuredisk-volume-tester-gzhdb 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
Oct 22 03:23:16.098: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-97nbw" in namespace "azuredisk-3721" to be "Succeeded or Failed"
Oct 22 03:23:16.152: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 53.981485ms
Oct 22 03:23:18.209: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.110365864s
Oct 22 03:23:20.262: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164135094s
Oct 22 03:23:22.317: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218245699s
Oct 22 03:23:24.372: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.273735521s
Oct 22 03:23:26.426: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.327336199s
... skipping 2 lines ...
Oct 22 03:23:32.593: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 16.495141528s
Oct 22 03:23:34.648: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 18.550131229s
Oct 22 03:23:36.705: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 20.606378637s
Oct 22 03:23:38.762: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Pending", Reason="", readiness=false. Elapsed: 22.663508948s
Oct 22 03:23:40.819: INFO: Pod "azuredisk-volume-tester-97nbw": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.720990764s
STEP: Saw pod success
Oct 22 03:23:40.819: INFO: Pod "azuredisk-volume-tester-97nbw" 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
Oct 22 03:23:41.037: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-lcr6n" in namespace "azuredisk-3721" to be "Succeeded or Failed"
Oct 22 03:23:41.092: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 54.298274ms
Oct 22 03:23:43.147: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 2.10941348s
Oct 22 03:23:45.203: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165317263s
Oct 22 03:23:47.261: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 6.224007291s
Oct 22 03:23:49.316: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 8.278868506s
Oct 22 03:23:51.374: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 10.33650829s
... skipping 9 lines ...
Oct 22 03:24:11.937: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 30.899735933s
Oct 22 03:24:13.993: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 32.95601377s
Oct 22 03:24:16.048: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 35.010988085s
Oct 22 03:24:18.104: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Pending", Reason="", readiness=false. Elapsed: 37.066692104s
Oct 22 03:24:20.159: INFO: Pod "azuredisk-volume-tester-lcr6n": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.121946553s
STEP: Saw pod success
Oct 22 03:24:20.160: INFO: Pod "azuredisk-volume-tester-lcr6n" satisfied condition "Succeeded or Failed"
Oct 22 03:24:20.160: INFO: deleting Pod "azuredisk-3721"/"azuredisk-volume-tester-lcr6n"
Oct 22 03:24:20.255: INFO: Pod azuredisk-volume-tester-lcr6n has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-lcr6n 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
Oct 22 03:25:13.153: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-lt5js" in namespace "azuredisk-2888" to be "Succeeded or Failed"
Oct 22 03:25:13.207: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 54.177673ms
Oct 22 03:25:15.263: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 2.110269025s
Oct 22 03:25:17.318: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165547531s
Oct 22 03:25:19.375: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 6.222035392s
Oct 22 03:25:21.430: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 8.277524261s
Oct 22 03:25:23.486: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 10.333734587s
Oct 22 03:25:25.542: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 12.389501946s
Oct 22 03:25:27.596: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 14.44368207s
Oct 22 03:25:29.652: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 16.498871433s
Oct 22 03:25:31.707: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 18.553946979s
Oct 22 03:25:33.762: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Pending", Reason="", readiness=false. Elapsed: 20.609327164s
Oct 22 03:25:35.820: INFO: Pod "azuredisk-volume-tester-lt5js": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.667634666s
STEP: Saw pod success
Oct 22 03:25:35.820: INFO: Pod "azuredisk-volume-tester-lt5js" 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
Oct 22 03:25:36.039: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-6pplr" in namespace "azuredisk-2888" to be "Succeeded or Failed"
Oct 22 03:25:36.092: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 53.364619ms
Oct 22 03:25:38.147: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108363014s
Oct 22 03:25:40.203: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164496029s
Oct 22 03:25:42.260: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 6.220654505s
Oct 22 03:25:44.315: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275852521s
Oct 22 03:25:46.370: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330771599s
... skipping 9 lines ...
Oct 22 03:26:06.926: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 30.886619432s
Oct 22 03:26:08.980: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 32.940936006s
Oct 22 03:26:11.036: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 34.997038812s
Oct 22 03:26:13.098: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Pending", Reason="", readiness=false. Elapsed: 37.059356269s
Oct 22 03:26:15.154: INFO: Pod "azuredisk-volume-tester-6pplr": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.114710873s
STEP: Saw pod success
Oct 22 03:26:15.154: INFO: Pod "azuredisk-volume-tester-6pplr" satisfied condition "Succeeded or Failed"
Oct 22 03:26:15.154: INFO: deleting Pod "azuredisk-2888"/"azuredisk-volume-tester-6pplr"
Oct 22 03:26:15.211: INFO: Pod azuredisk-volume-tester-6pplr has the following logs: 20.0G

STEP: Deleting pod azuredisk-volume-tester-6pplr 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
Oct 22 03:27:08.331: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-nll64" in namespace "azuredisk-8510" to be "Succeeded or Failed"
Oct 22 03:27:08.384: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 53.389921ms
Oct 22 03:27:10.439: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108373374s
Oct 22 03:27:12.493: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 4.162490759s
Oct 22 03:27:14.549: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 6.218056791s
Oct 22 03:27:16.603: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 8.272174323s
Oct 22 03:27:18.658: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 10.327607386s
... skipping 9 lines ...
Oct 22 03:27:39.219: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 30.88837124s
Oct 22 03:27:41.275: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 32.944842408s
Oct 22 03:27:43.331: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 35.000136523s
Oct 22 03:27:45.387: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Pending", Reason="", readiness=false. Elapsed: 37.056435061s
Oct 22 03:27:47.442: INFO: Pod "azuredisk-volume-tester-nll64": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.111484551s
STEP: Saw pod success
Oct 22 03:27:47.442: INFO: Pod "azuredisk-volume-tester-nll64" satisfied condition "Succeeded or Failed"
Oct 22 03:27:47.442: INFO: deleting Pod "azuredisk-8510"/"azuredisk-volume-tester-nll64"
Oct 22 03:27:47.499: INFO: Pod azuredisk-volume-tester-nll64 has the following logs: hello world
hello world
hello world

STEP: Deleting pod azuredisk-volume-tester-nll64 in namespace azuredisk-8510
... skipping 68 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
Oct 22 03:28:40.823: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-lhr78" in namespace "azuredisk-5561" to be "Succeeded or Failed"
Oct 22 03:28:40.878: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 54.980997ms
Oct 22 03:28:42.932: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 2.1095846s
Oct 22 03:28:44.987: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164851056s
Oct 22 03:28:47.043: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219970935s
Oct 22 03:28:49.097: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274876249s
Oct 22 03:28:51.154: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 10.331577424s
Oct 22 03:28:53.211: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 12.388342778s
Oct 22 03:28:55.266: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 14.443921423s
Oct 22 03:28:57.324: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 16.50132203s
Oct 22 03:28:59.380: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 18.557594018s
Oct 22 03:29:01.435: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Pending", Reason="", readiness=false. Elapsed: 20.612721708s
Oct 22 03:29:03.490: INFO: Pod "azuredisk-volume-tester-lhr78": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.667548122s
STEP: Saw pod success
Oct 22 03:29:03.490: INFO: Pod "azuredisk-volume-tester-lhr78" satisfied condition "Succeeded or Failed"
Oct 22 03:29:03.490: INFO: deleting Pod "azuredisk-5561"/"azuredisk-volume-tester-lhr78"
Oct 22 03:29:03.553: INFO: Pod azuredisk-volume-tester-lhr78 has the following logs: 100+0 records in
100+0 records out
104857600 bytes (100.0MB) copied, 0.078488 seconds, 1.2GB/s
hello world

... skipping 53 lines ...
Oct 22 03:29:56.298: INFO: >>> kubeConfig: /root/tmp4274791325/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
Oct 22 03:29:56.465: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-5vqz5" in namespace "azuredisk-4376" to be "Succeeded or Failed"
Oct 22 03:29:56.519: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 53.828489ms
Oct 22 03:29:58.574: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109215336s
Oct 22 03:30:00.630: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165249809s
Oct 22 03:30:02.685: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.220108329s
Oct 22 03:30:04.740: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274949023s
Oct 22 03:30:06.796: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 10.331227192s
Oct 22 03:30:08.851: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 12.386362914s
Oct 22 03:30:10.909: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 14.443842318s
Oct 22 03:30:12.966: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 16.501173107s
Oct 22 03:30:15.022: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 18.556701561s
Oct 22 03:30:17.076: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Pending", Reason="", readiness=false. Elapsed: 20.611438915s
Oct 22 03:30:19.132: INFO: Pod "azuredisk-volume-tester-5vqz5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.666496233s
STEP: Saw pod success
Oct 22 03:30:19.132: INFO: Pod "azuredisk-volume-tester-5vqz5" satisfied condition "Succeeded or Failed"
STEP: Checking Prow test resource group
2021/10/22 03:30:19 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
2021/10/22 03:30:19 Reading credentials file /etc/azure-cred/credentials
STEP: Prow test resource group: kubetest-khzjyakf
STEP: Creating external resource group: azuredisk-csi-driver-test-6181ade9-32e8-11ec-9b0f-a6fd0f325ee6
STEP: creating volume snapshot class with external rg azuredisk-csi-driver-test-6181ade9-32e8-11ec-9b0f-a6fd0f325ee6
... 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
Oct 22 03:30:35.644: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-v5csc" in namespace "azuredisk-4376" to be "Succeeded or Failed"
Oct 22 03:30:35.698: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 53.974507ms
Oct 22 03:30:37.753: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108420591s
Oct 22 03:30:39.808: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163588104s
Oct 22 03:30:41.863: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 6.21926711s
Oct 22 03:30:43.920: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275282452s
Oct 22 03:30:45.974: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 10.329473224s
... skipping 2 lines ...
Oct 22 03:30:52.140: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 16.496088507s
Oct 22 03:30:54.194: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 18.549889756s
Oct 22 03:30:56.248: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 20.603929926s
Oct 22 03:30:58.304: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Pending", Reason="", readiness=false. Elapsed: 22.659802797s
Oct 22 03:31:00.359: INFO: Pod "azuredisk-volume-tester-v5csc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.714864795s
STEP: Saw pod success
Oct 22 03:31:00.359: INFO: Pod "azuredisk-volume-tester-v5csc" satisfied condition "Succeeded or Failed"
Oct 22 03:31:00.359: INFO: deleting Pod "azuredisk-4376"/"azuredisk-volume-tester-v5csc"
Oct 22 03:31:00.463: INFO: Pod azuredisk-volume-tester-v5csc has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-v5csc in namespace azuredisk-4376
STEP: validating provisioned PV
STEP: checking the PV
... skipping 56 lines ...
Oct 22 03:33:10.361: INFO: >>> kubeConfig: /root/tmp4274791325/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
Oct 22 03:33:10.530: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-nlp7t" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Oct 22 03:33:10.585: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 54.851974ms
Oct 22 03:33:12.641: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 2.111642682s
Oct 22 03:33:14.696: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165959346s
Oct 22 03:33:16.751: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 6.221093392s
Oct 22 03:33:18.806: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 8.276168479s
Oct 22 03:33:20.861: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 10.331609576s
... skipping 3 lines ...
Oct 22 03:33:29.082: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 18.551750208s
Oct 22 03:33:31.136: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 20.606281956s
Oct 22 03:33:33.190: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Pending", Reason="", readiness=false. Elapsed: 22.660120552s
Oct 22 03:33:35.246: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Running", Reason="", readiness=true. Elapsed: 24.715695311s
Oct 22 03:33:37.301: INFO: Pod "azuredisk-volume-tester-nlp7t": Phase="Succeeded", Reason="", readiness=false. Elapsed: 26.771133638s
STEP: Saw pod success
Oct 22 03:33:37.301: INFO: Pod "azuredisk-volume-tester-nlp7t" satisfied condition "Succeeded or Failed"
STEP: Checking Prow test resource group
2021/10/22 03:33:37 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
2021/10/22 03:33:37 Reading credentials file /etc/azure-cred/credentials
STEP: Prow test resource group: kubetest-khzjyakf
STEP: Creating external resource group: azuredisk-csi-driver-test-d79fde00-32e8-11ec-9b0f-a6fd0f325ee6
STEP: creating volume snapshot class with external rg azuredisk-csi-driver-test-d79fde00-32e8-11ec-9b0f-a6fd0f325ee6
STEP: setting up the VolumeSnapshotClass
STEP: creating a VolumeSnapshotClass
STEP: taking snapshots
STEP: creating a VolumeSnapshot for pvc-gtgnl
STEP: deploying a new pod to overwrite pv data
STEP: checking that the pod's command exits with no error
Oct 22 03:33:38.387: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-jrtkg" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Oct 22 03:33:38.440: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 53.19822ms
Oct 22 03:33:40.495: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108799397s
Oct 22 03:33:42.552: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165657623s
Oct 22 03:33:44.608: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 6.221942463s
Oct 22 03:33:46.663: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 8.276429154s
Oct 22 03:33:48.717: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.330909665s
... skipping 23 lines ...
Oct 22 03:34:38.047: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 59.660869479s
Oct 22 03:34:40.104: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 1m1.717075584s
Oct 22 03:34:42.160: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 1m3.773912576s
Oct 22 03:34:44.217: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Pending", Reason="", readiness=false. Elapsed: 1m5.830364946s
Oct 22 03:34:46.272: INFO: Pod "azuredisk-volume-tester-jrtkg": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m7.885540573s
STEP: Saw pod success
Oct 22 03:34:46.272: INFO: Pod "azuredisk-volume-tester-jrtkg" satisfied condition "Succeeded or Failed"
STEP: waiting for VolumeSnapshot to be ready to use - volume-snapshot-7zddw
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
Oct 22 03:35:01.496: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-6prqj" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Oct 22 03:35:01.551: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 54.684605ms
Oct 22 03:35:03.608: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.111117218s
Oct 22 03:35:05.664: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.167067671s
Oct 22 03:35:07.718: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.221989522s
Oct 22 03:35:09.773: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.276400537s
Oct 22 03:35:11.829: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.33252241s
Oct 22 03:35:13.884: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.387998707s
Oct 22 03:35:15.940: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 14.443338517s
Oct 22 03:35:17.996: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 16.499981251s
Oct 22 03:35:20.054: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 18.557624082s
Oct 22 03:35:22.110: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Pending", Reason="", readiness=false. Elapsed: 20.613269363s
Oct 22 03:35:24.166: INFO: Pod "azuredisk-volume-tester-6prqj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.669549339s
STEP: Saw pod success
Oct 22 03:35:24.166: INFO: Pod "azuredisk-volume-tester-6prqj" satisfied condition "Succeeded or Failed"
Oct 22 03:35:24.166: INFO: deleting Pod "azuredisk-1577"/"azuredisk-volume-tester-6prqj"
Oct 22 03:35:24.273: INFO: Pod azuredisk-volume-tester-6prqj has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-6prqj in namespace azuredisk-1577
STEP: validating provisioned PV
STEP: checking the PV
... skipping 67 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
Oct 22 03:37:35.090: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-r8xkp" in namespace "azuredisk-953" to be "Succeeded or Failed"
Oct 22 03:37:35.144: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 53.804167ms
Oct 22 03:37:37.200: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109465332s
Oct 22 03:37:39.256: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.166138593s
Oct 22 03:37:41.312: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.222178799s
Oct 22 03:37:43.368: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.277836017s
Oct 22 03:37:45.424: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 10.334145648s
... skipping 9 lines ...
Oct 22 03:38:05.978: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 30.887861831s
Oct 22 03:38:08.032: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 32.941738269s
Oct 22 03:38:10.088: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 34.997799775s
Oct 22 03:38:12.144: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Pending", Reason="", readiness=false. Elapsed: 37.053638773s
Oct 22 03:38:14.199: INFO: Pod "azuredisk-volume-tester-r8xkp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.109219076s
STEP: Saw pod success
Oct 22 03:38:14.199: INFO: Pod "azuredisk-volume-tester-r8xkp" satisfied condition "Succeeded or Failed"
Oct 22 03:38:14.199: INFO: deleting Pod "azuredisk-953"/"azuredisk-volume-tester-r8xkp"
Oct 22 03:38:14.265: INFO: Pod azuredisk-volume-tester-r8xkp has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-r8xkp in namespace azuredisk-953
STEP: validating provisioned PV
STEP: checking the PV
... skipping 84 lines ...