This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: feat: add registrar liveness probe on Linux
ResultABORTED
Tests 0 failed / 22 succeeded
Started2021-10-09 01:28
Elapsed1h2m
Revision0ffe1d9aef94892f42e0927b126d8b550c3937a1
Refs 1043

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   215k      0 --:--:-- --:--:-- --:--:--  215k
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-885cfdb204b3a00a8e44c86e4e4bcc91fe2bf584 || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:v1.8.0-885cfdb204b3a00a8e44c86e4e4bcc91fe2bf584 not found: manifest unknown: manifest tagged by "v1.8.0-885cfdb204b3a00a8e44c86e4e4bcc91fe2bf584" 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-885cfdb204b3a00a8e44c86e4e4bcc91fe2bf584 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=885cfdb204b3a00a8e44c86e4e4bcc91fe2bf584 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-10-09T01:33:20Z -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 1764 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 851 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 175 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
W1009 01:44:34.567272   12528 azure_disk_utils.go:144] InitializeCloudFromSecret: failed to get cloud config from secret /: failed to get secret /: resource name may not be empty
W1009 01:44:34.568249   12528 azuredisk.go:159] DisableAvailabilitySetNodes for controller is set as false while current VMType is vmss
I1009 01:44:34.568578   12528 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I1009 01:44:34.568600   12528 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I1009 01:44:34.568605   12528 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I1009 01:44:34.568611   12528 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I1009 01:44:34.568616   12528 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  9 01:44:36.573: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-bkhx4" in namespace "azuredisk-8081" to be "Succeeded or Failed"
Oct  9 01:44:36.635: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 62.787855ms
Oct  9 01:44:38.694: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.121406052s
Oct  9 01:44:40.754: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 4.181302274s
Oct  9 01:44:42.817: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 6.244822311s
Oct  9 01:44:44.876: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 8.303615241s
Oct  9 01:44:46.935: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 10.362010566s
... skipping 5 lines ...
Oct  9 01:44:59.291: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 22.718045484s
Oct  9 01:45:01.349: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 24.776698723s
Oct  9 01:45:03.409: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 26.836544173s
Oct  9 01:45:05.470: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Pending", Reason="", readiness=false. Elapsed: 28.897035999s
Oct  9 01:45:07.529: INFO: Pod "azuredisk-volume-tester-bkhx4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 30.956156447s
STEP: Saw pod success
Oct  9 01:45:07.529: INFO: Pod "azuredisk-volume-tester-bkhx4" satisfied condition "Succeeded or Failed"
Oct  9 01:45:07.529: INFO: deleting Pod "azuredisk-8081"/"azuredisk-volume-tester-bkhx4"
Oct  9 01:45:07.642: INFO: Pod azuredisk-volume-tester-bkhx4 has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-bkhx4 in namespace azuredisk-8081
STEP: validating provisioned PV
STEP: checking the PV
... skipping 46 lines ...
Oct  9 01:45:52.278: INFO: PersistentVolumeClaim pvc-m5ggt found but phase is Pending instead of Bound.
Oct  9 01:45:54.350: INFO: PersistentVolumeClaim pvc-m5ggt found and phase=Bound (4.190887838s)
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  9 01:45:54.526: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-9std9" in namespace "azuredisk-1318" to be "Succeeded or Failed"
Oct  9 01:45:54.583: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 56.978418ms
Oct  9 01:45:56.643: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116712038s
Oct  9 01:45:58.702: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 4.175797526s
Oct  9 01:46:00.761: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 6.234852209s
Oct  9 01:46:02.821: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.294580475s
Oct  9 01:46:04.881: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 10.354564948s
Oct  9 01:46:06.942: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 12.41551555s
Oct  9 01:46:09.002: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 14.475108347s
Oct  9 01:46:11.061: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 16.534208279s
Oct  9 01:46:13.118: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 18.592066555s
Oct  9 01:46:15.178: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Pending", Reason="", readiness=false. Elapsed: 20.651566773s
Oct  9 01:46:17.237: INFO: Pod "azuredisk-volume-tester-9std9": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.710491139s
STEP: Saw pod success
Oct  9 01:46:17.237: INFO: Pod "azuredisk-volume-tester-9std9" satisfied condition "Succeeded or Failed"
Oct  9 01:46:17.237: INFO: deleting Pod "azuredisk-1318"/"azuredisk-volume-tester-9std9"
Oct  9 01:46:17.347: INFO: Pod azuredisk-volume-tester-9std9 has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-9std9 in namespace azuredisk-1318
Oct  9 01:46:17.418: INFO: deleting PVC "azuredisk-1318"/"pvc-m5ggt"
Oct  9 01:46:17.418: INFO: Deleting PersistentVolumeClaim "pvc-m5ggt"
... 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  9 01:46:59.826: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-bckx2" in namespace "azuredisk-694" to be "Succeeded or Failed"
Oct  9 01:46:59.886: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 60.777122ms
Oct  9 01:47:01.946: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.120751129s
Oct  9 01:47:04.005: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.179708147s
Oct  9 01:47:06.066: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.240677658s
Oct  9 01:47:08.127: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.30093611s
Oct  9 01:47:10.187: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.360913662s
... skipping 14 lines ...
Oct  9 01:47:41.082: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 41.255920575s
Oct  9 01:47:43.140: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 43.31446823s
Oct  9 01:47:45.200: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 45.373782766s
Oct  9 01:47:47.259: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Pending", Reason="", readiness=false. Elapsed: 47.433390451s
Oct  9 01:47:49.319: INFO: Pod "azuredisk-volume-tester-bckx2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 49.493217886s
STEP: Saw pod success
Oct  9 01:47:49.319: INFO: Pod "azuredisk-volume-tester-bckx2" satisfied condition "Succeeded or Failed"
Oct  9 01:47:49.319: INFO: deleting Pod "azuredisk-694"/"azuredisk-volume-tester-bckx2"
Oct  9 01:47:49.408: INFO: Pod azuredisk-volume-tester-bckx2 has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-bckx2 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  9 01:48:54.292: INFO: deleting Pod "azuredisk-3274"/"azuredisk-volume-tester-ww8pn"
Oct  9 01:48:54.352: INFO: Error getting logs for pod azuredisk-volume-tester-ww8pn: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-ww8pn)
STEP: Deleting pod azuredisk-volume-tester-ww8pn in namespace azuredisk-3274
STEP: validating provisioned PV
STEP: checking the PV
Oct  9 01:48:54.529: INFO: deleting PVC "azuredisk-3274"/"pvc-d75vb"
Oct  9 01:48:54.529: INFO: Deleting PersistentVolumeClaim "pvc-d75vb"
STEP: waiting for claim's PV "pvc-b381ae4f-0164-4ef6-bb9d-97def3b239a1" 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  9 01:49:42.038: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-cnq5d" in namespace "azuredisk-495" to be "Succeeded or Failed"
Oct  9 01:49:42.095: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 57.180317ms
Oct  9 01:49:44.155: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116545296s
Oct  9 01:49:46.215: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 4.176962395s
Oct  9 01:49:48.278: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 6.239580263s
Oct  9 01:49:50.336: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 8.297554857s
Oct  9 01:49:52.394: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 10.355626156s
... skipping 3 lines ...
Oct  9 01:50:00.630: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 18.592256405s
Oct  9 01:50:02.689: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 20.651348131s
Oct  9 01:50:04.748: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 22.709973356s
Oct  9 01:50:06.807: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Pending", Reason="", readiness=false. Elapsed: 24.768754991s
Oct  9 01:50:08.865: INFO: Pod "azuredisk-volume-tester-cnq5d": Phase="Succeeded", Reason="", readiness=false. Elapsed: 26.827342547s
STEP: Saw pod success
Oct  9 01:50:08.865: INFO: Pod "azuredisk-volume-tester-cnq5d" satisfied condition "Succeeded or Failed"
Oct  9 01:50:08.865: INFO: deleting Pod "azuredisk-495"/"azuredisk-volume-tester-cnq5d"
Oct  9 01:50:08.930: INFO: Pod azuredisk-volume-tester-cnq5d has the following logs: e2e-test

STEP: Deleting pod azuredisk-volume-tester-cnq5d 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  9 01:50:51.536: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-8cs7x" in namespace "azuredisk-9947" to be "Error status code"
Oct  9 01:50:51.594: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 58.030942ms
Oct  9 01:50:53.654: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117982741s
Oct  9 01:50:55.714: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 4.178001175s
Oct  9 01:50:57.773: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 6.236503539s
Oct  9 01:50:59.833: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 8.296873938s
Oct  9 01:51:01.893: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 10.356525231s
... skipping 23 lines ...
Oct  9 01:51:51.346: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 59.809851252s
Oct  9 01:51:53.405: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 1m1.868981323s
Oct  9 01:51:55.462: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 1m3.926429296s
Oct  9 01:51:57.521: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 1m5.984580376s
Oct  9 01:51:59.580: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 1m8.043810857s
Oct  9 01:52:01.639: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.102864829s
Oct  9 01:52:03.698: INFO: Pod "azuredisk-volume-tester-8cs7x": Phase="Failed", Reason="", readiness=false. Elapsed: 1m12.162266234s
STEP: Saw pod failure
Oct  9 01:52:03.698: INFO: Pod "azuredisk-volume-tester-8cs7x" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Oct  9 01:52:03.806: INFO: deleting Pod "azuredisk-9947"/"azuredisk-volume-tester-8cs7x"
Oct  9 01:52:03.868: INFO: Pod azuredisk-volume-tester-8cs7x has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azuredisk-volume-tester-8cs7x in namespace azuredisk-9947
STEP: validating provisioned PV
... skipping 333 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  9 02:00:09.164: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-2ml47" in namespace "azuredisk-3721" to be "Succeeded or Failed"
Oct  9 02:00:09.222: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 57.793066ms
Oct  9 02:00:11.282: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117619262s
Oct  9 02:00:13.343: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 4.178651188s
Oct  9 02:00:15.402: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 6.237939114s
Oct  9 02:00:17.461: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 8.296629391s
Oct  9 02:00:19.524: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 10.359813042s
... skipping 2 lines ...
Oct  9 02:00:25.704: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 16.539313557s
Oct  9 02:00:27.765: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 18.600746415s
Oct  9 02:00:29.824: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Pending", Reason="", readiness=false. Elapsed: 20.659806414s
Oct  9 02:00:31.883: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Running", Reason="", readiness=true. Elapsed: 22.718446933s
Oct  9 02:00:33.943: INFO: Pod "azuredisk-volume-tester-2ml47": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.778501896s
STEP: Saw pod success
Oct  9 02:00:33.943: INFO: Pod "azuredisk-volume-tester-2ml47" 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  9 02:00:34.208: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-9gdwp" in namespace "azuredisk-3721" to be "Succeeded or Failed"
Oct  9 02:00:34.268: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 59.969249ms
Oct  9 02:00:36.328: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.120028436s
Oct  9 02:00:38.388: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.18012011s
Oct  9 02:00:40.449: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.240184029s
Oct  9 02:00:42.507: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.298843517s
Oct  9 02:00:44.566: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 10.357914362s
... skipping 9 lines ...
Oct  9 02:01:05.162: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 30.954072952s
Oct  9 02:01:07.223: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 33.01440343s
Oct  9 02:01:09.284: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 35.0752288s
Oct  9 02:01:11.342: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Pending", Reason="", readiness=false. Elapsed: 37.133556601s
Oct  9 02:01:13.412: INFO: Pod "azuredisk-volume-tester-9gdwp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.203966463s
STEP: Saw pod success
Oct  9 02:01:13.413: INFO: Pod "azuredisk-volume-tester-9gdwp" satisfied condition "Succeeded or Failed"
Oct  9 02:01:13.413: INFO: deleting Pod "azuredisk-3721"/"azuredisk-volume-tester-9gdwp"
Oct  9 02:01:13.532: INFO: Pod azuredisk-volume-tester-9gdwp has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-9gdwp in namespace azuredisk-3721
STEP: validating provisioned PV
STEP: checking the PV
... skipping 59 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  9 02:02:37.213: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-ktvgl" in namespace "azuredisk-2888" to be "Succeeded or Failed"
Oct  9 02:02:37.270: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 57.300437ms
Oct  9 02:02:39.330: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116951475s
Oct  9 02:02:41.391: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.177913979s
Oct  9 02:02:43.456: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.243557804s
Oct  9 02:02:45.517: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.303806502s
Oct  9 02:02:47.577: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.363922607s
Oct  9 02:02:49.638: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 12.424923173s
Oct  9 02:02:51.696: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.483478145s
Oct  9 02:02:53.756: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.543470359s
Oct  9 02:02:55.816: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 18.603678813s
Oct  9 02:02:57.877: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Pending", Reason="", readiness=false. Elapsed: 20.664203342s
Oct  9 02:02:59.937: INFO: Pod "azuredisk-volume-tester-ktvgl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.724119887s
STEP: Saw pod success
Oct  9 02:02:59.937: INFO: Pod "azuredisk-volume-tester-ktvgl" 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  9 02:03:00.178: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-mqjf4" in namespace "azuredisk-2888" to be "Succeeded or Failed"
Oct  9 02:03:00.235: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 57.041374ms
Oct  9 02:03:02.294: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.115913371s
Oct  9 02:03:04.353: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 4.175012371s
Oct  9 02:03:06.412: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 6.233872161s
Oct  9 02:03:08.471: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 8.292436727s
Oct  9 02:03:10.530: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 10.351251678s
... skipping 9 lines ...
Oct  9 02:03:31.120: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 30.942045626s
Oct  9 02:03:33.181: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 33.00212863s
Oct  9 02:03:35.242: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 35.063539812s
Oct  9 02:03:37.302: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Pending", Reason="", readiness=false. Elapsed: 37.123254526s
Oct  9 02:03:39.360: INFO: Pod "azuredisk-volume-tester-mqjf4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.181513507s
STEP: Saw pod success
Oct  9 02:03:39.360: INFO: Pod "azuredisk-volume-tester-mqjf4" satisfied condition "Succeeded or Failed"
Oct  9 02:03:39.360: INFO: deleting Pod "azuredisk-2888"/"azuredisk-volume-tester-mqjf4"
Oct  9 02:03:39.422: INFO: Pod azuredisk-volume-tester-mqjf4 has the following logs: 20.0G

STEP: Deleting pod azuredisk-volume-tester-mqjf4 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  9 02:04:32.802: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-xhp89" in namespace "azuredisk-8510" to be "Succeeded or Failed"
Oct  9 02:04:32.871: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 69.301665ms
Oct  9 02:04:34.930: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 2.127766594s
Oct  9 02:04:36.990: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 4.188108807s
Oct  9 02:04:39.052: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 6.249778861s
Oct  9 02:04:41.111: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 8.308851707s
Oct  9 02:04:43.169: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 10.366995783s
Oct  9 02:04:45.227: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 12.425427022s
Oct  9 02:04:47.288: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 14.485566028s
Oct  9 02:04:49.348: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 16.546326556s
Oct  9 02:04:51.407: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 18.605401845s
Oct  9 02:04:53.467: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Pending", Reason="", readiness=false. Elapsed: 20.664603012s
Oct  9 02:04:55.525: INFO: Pod "azuredisk-volume-tester-xhp89": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.723469886s
STEP: Saw pod success
Oct  9 02:04:55.526: INFO: Pod "azuredisk-volume-tester-xhp89" satisfied condition "Succeeded or Failed"
Oct  9 02:04:55.526: INFO: deleting Pod "azuredisk-8510"/"azuredisk-volume-tester-xhp89"
Oct  9 02:04:55.587: INFO: Pod azuredisk-volume-tester-xhp89 has the following logs: hello world
hello world
hello world

STEP: Deleting pod azuredisk-volume-tester-xhp89 in namespace azuredisk-8510
... skipping 70 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  9 02:05:59.214: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-8kn2j" in namespace "azuredisk-5561" to be "Succeeded or Failed"
Oct  9 02:05:59.273: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 58.87699ms
Oct  9 02:06:01.332: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117761696s
Oct  9 02:06:03.391: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 4.176392524s
Oct  9 02:06:05.471: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 6.256139759s
Oct  9 02:06:07.531: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 8.31671298s
Oct  9 02:06:09.591: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 10.37663967s
Oct  9 02:06:11.650: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 12.435964512s
Oct  9 02:06:13.710: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 14.495264435s
Oct  9 02:06:15.769: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 16.554107746s
Oct  9 02:06:17.828: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 18.613342922s
Oct  9 02:06:19.894: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Pending", Reason="", readiness=false. Elapsed: 20.680071778s
Oct  9 02:06:21.954: INFO: Pod "azuredisk-volume-tester-8kn2j": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.739874092s
STEP: Saw pod success
Oct  9 02:06:21.954: INFO: Pod "azuredisk-volume-tester-8kn2j" satisfied condition "Succeeded or Failed"
Oct  9 02:06:21.954: INFO: deleting Pod "azuredisk-5561"/"azuredisk-volume-tester-8kn2j"
Oct  9 02:06:22.033: INFO: Pod azuredisk-volume-tester-8kn2j has the following logs: 100+0 records in
100+0 records out
104857600 bytes (100.0MB) copied, 0.053500 seconds, 1.8GB/s
hello world

... skipping 53 lines ...
Oct  9 02:07:14.882: INFO: >>> kubeConfig: /root/tmp1914389075/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  9 02:07:15.066: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-d2kcd" in namespace "azuredisk-4376" to be "Succeeded or Failed"
Oct  9 02:07:15.130: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 63.998869ms
Oct  9 02:07:17.188: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.122172431s
Oct  9 02:07:19.247: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.181327377s
Oct  9 02:07:21.306: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.239821068s
Oct  9 02:07:23.366: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.299729516s
Oct  9 02:07:25.424: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.358476541s
Oct  9 02:07:27.484: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 12.418353035s
Oct  9 02:07:29.544: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 14.478007461s
Oct  9 02:07:31.603: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 16.536736225s
Oct  9 02:07:33.661: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 18.595349048s
Oct  9 02:07:35.721: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Pending", Reason="", readiness=false. Elapsed: 20.654929353s
Oct  9 02:07:37.791: INFO: Pod "azuredisk-volume-tester-d2kcd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.725572403s
STEP: Saw pod success
Oct  9 02:07:37.791: INFO: Pod "azuredisk-volume-tester-d2kcd" satisfied condition "Succeeded or Failed"
STEP: Checking Prow test resource group
2021/10/09 02:07:37 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
2021/10/09 02:07:37 Reading credentials file /etc/azure-cred/credentials
STEP: Prow test resource group: kubetest-0halwhiw
STEP: Creating external resource group: azuredisk-csi-driver-test-acf27044-28a5-11ec-908f-12db9f3c5f75
STEP: creating volume snapshot class with external rg azuredisk-csi-driver-test-acf27044-28a5-11ec-908f-12db9f3c5f75
... 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  9 02:07:54.219: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-qwmmj" in namespace "azuredisk-4376" to be "Succeeded or Failed"
Oct  9 02:07:54.276: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 57.59111ms
Oct  9 02:07:56.334: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.115094542s
Oct  9 02:07:58.390: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.171874162s
Oct  9 02:08:00.447: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.228534852s
Oct  9 02:08:02.504: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.285230493s
Oct  9 02:08:04.561: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.342580314s
... skipping 9 lines ...
Oct  9 02:08:25.128: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 30.909279222s
Oct  9 02:08:27.185: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 32.966092738s
Oct  9 02:08:29.241: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 35.022472332s
Oct  9 02:08:31.298: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Pending", Reason="", readiness=false. Elapsed: 37.079244676s
Oct  9 02:08:33.353: INFO: Pod "azuredisk-volume-tester-qwmmj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 39.134419678s
STEP: Saw pod success
Oct  9 02:08:33.353: INFO: Pod "azuredisk-volume-tester-qwmmj" satisfied condition "Succeeded or Failed"
Oct  9 02:08:33.353: INFO: deleting Pod "azuredisk-4376"/"azuredisk-volume-tester-qwmmj"
Oct  9 02:08:33.439: INFO: Pod azuredisk-volume-tester-qwmmj has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-qwmmj in namespace azuredisk-4376
STEP: validating provisioned PV
STEP: checking the PV
... skipping 56 lines ...
Oct  9 02:10:43.896: INFO: >>> kubeConfig: /root/tmp1914389075/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  9 02:10:44.077: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-4grmb" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Oct  9 02:10:44.134: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 56.674586ms
Oct  9 02:10:46.191: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.113909596s
Oct  9 02:10:48.248: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.170563291s
Oct  9 02:10:50.305: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.228035484s
Oct  9 02:10:52.362: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.284366364s
Oct  9 02:10:54.419: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 10.341544544s
... skipping 2 lines ...
Oct  9 02:11:00.590: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 16.512966946s
Oct  9 02:11:02.647: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 18.569415409s
Oct  9 02:11:04.704: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 20.626845494s
Oct  9 02:11:06.761: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Pending", Reason="", readiness=false. Elapsed: 22.683837454s
Oct  9 02:11:08.819: INFO: Pod "azuredisk-volume-tester-4grmb": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.741287207s
STEP: Saw pod success
Oct  9 02:11:08.819: INFO: Pod "azuredisk-volume-tester-4grmb" satisfied condition "Succeeded or Failed"
STEP: Checking Prow test resource group
2021/10/09 02:11:08 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
2021/10/09 02:11:08 Reading credentials file /etc/azure-cred/credentials
STEP: Prow test resource group: kubetest-0halwhiw
STEP: Creating external resource group: azuredisk-csi-driver-test-2abab862-28a6-11ec-908f-12db9f3c5f75
STEP: creating volume snapshot class with external rg azuredisk-csi-driver-test-2abab862-28a6-11ec-908f-12db9f3c5f75
STEP: setting up the VolumeSnapshotClass
STEP: creating a VolumeSnapshotClass
STEP: taking snapshots
STEP: creating a VolumeSnapshot for pvc-65scx
STEP: deploying a new pod to overwrite pv data
STEP: checking that the pod's command exits with no error
Oct  9 02:11:09.854: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-nbtqv" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Oct  9 02:11:09.909: INFO: Pod "azuredisk-volume-tester-nbtqv": Phase="Pending", Reason="", readiness=false. Elapsed: 54.338796ms
Oct  9 02:11:11.964: INFO: Pod "azuredisk-volume-tester-nbtqv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109541777s
Oct  9 02:11:14.019: INFO: Pod "azuredisk-volume-tester-nbtqv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.165023236s
Oct  9 02:11:16.075: INFO: Pod "azuredisk-volume-tester-nbtqv": Phase="Succeeded", Reason="", readiness=false. Elapsed: 6.220397296s
STEP: Saw pod success
Oct  9 02:11:16.075: INFO: Pod "azuredisk-volume-tester-nbtqv" satisfied condition "Succeeded or Failed"
STEP: waiting for VolumeSnapshot to be ready to use - volume-snapshot-tjzvd
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  9 02:11:31.310: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-tcfkd" in namespace "azuredisk-1577" to be "Succeeded or Failed"
Oct  9 02:11:31.365: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 55.048794ms
Oct  9 02:11:33.421: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.110990225s
Oct  9 02:11:35.477: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.167147448s
Oct  9 02:11:37.533: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.223246358s
Oct  9 02:11:39.589: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.279052949s
Oct  9 02:11:41.644: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.334206281s
Oct  9 02:11:43.701: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 12.391484652s
Oct  9 02:11:45.758: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 14.448368266s
Oct  9 02:11:47.815: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 16.505309466s
Oct  9 02:11:49.871: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 18.561461751s
Oct  9 02:11:51.931: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Pending", Reason="", readiness=false. Elapsed: 20.620685031s
Oct  9 02:11:53.986: INFO: Pod "azuredisk-volume-tester-tcfkd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.675762724s
STEP: Saw pod success
Oct  9 02:11:53.986: INFO: Pod "azuredisk-volume-tester-tcfkd" satisfied condition "Succeeded or Failed"
Oct  9 02:11:53.986: INFO: deleting Pod "azuredisk-1577"/"azuredisk-volume-tester-tcfkd"
Oct  9 02:11:54.045: INFO: Pod azuredisk-volume-tester-tcfkd has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-tcfkd 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  9 02:14:03.991: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-4n7mh" in namespace "azuredisk-953" to be "Succeeded or Failed"
Oct  9 02:14:04.047: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 55.8657ms
Oct  9 02:14:06.102: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.111079089s
Oct  9 02:14:08.160: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.168816389s
Oct  9 02:14:10.216: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.225627928s
Oct  9 02:14:12.273: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.28175536s
Oct  9 02:14:14.330: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.338795636s
Oct  9 02:14:16.387: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 12.396539151s
Oct  9 02:14:18.444: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 14.453629569s
Oct  9 02:14:20.501: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 16.509810758s
Oct  9 02:14:22.556: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 18.56561161s
Oct  9 02:14:24.613: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Pending", Reason="", readiness=false. Elapsed: 20.622435122s
Oct  9 02:14:26.669: INFO: Pod "azuredisk-volume-tester-4n7mh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.67850811s
STEP: Saw pod success
Oct  9 02:14:26.669: INFO: Pod "azuredisk-volume-tester-4n7mh" satisfied condition "Succeeded or Failed"
Oct  9 02:14:26.669: INFO: deleting Pod "azuredisk-953"/"azuredisk-volume-tester-4n7mh"
Oct  9 02:14:26.728: INFO: Pod azuredisk-volume-tester-4n7mh has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-4n7mh in namespace azuredisk-953
STEP: validating provisioned PV
STEP: checking the PV
... skipping 346 lines ...
Oct  9 02:24:41.490: INFO: stdout: "hello world\n"
STEP: cordoning node 0
STEP: deleting the pod for deployment
Oct  9 02:24:41.615: INFO: Deleting pod "azuredisk-volume-tester-slljj-765dfd985c-xrkb5" in namespace "azuredisk-8582"
Oct  9 02:24:41.675: INFO: Waiting for pod "azuredisk-volume-tester-slljj-765dfd985c-xrkb5" in namespace "azuredisk-8582" to be fully deleted
STEP: checking again that the pod is running
{"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-10-09T02:25:33Z"}