This job view page is being replaced by Spyglass soon. Check out the new job view.
PRalice-zheyan-yu: [V2] fix: enhance UpdateCRIWithRetry to fix the cachedClient Get not found…
ResultABORTED
Tests 0 failed / 16 succeeded
Started2023-02-28 23:23
Elapsed39m29s
Revision250b874cb812582c5397de01d413d7e68d630b67
Refs 1739

No Test Failures!


Show 16 Passed Tests

Show 100 Skipped Tests

Error lines from build-log.txt

... skipping 106 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11345  100 11345    0     0   191k      0 --:--:-- --:--:-- --:--:--  194k
Downloading https://get.helm.sh/helm-v3.11.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:latest-v2-a39ff78f6156932a4db851c58bd7b4fb923bd0a5 || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:latest-v2-a39ff78f6156932a4db851c58bd7b4fb923bd0a5 not found: manifest unknown: manifest tagged by "latest-v2-a39ff78f6156932a4db851c58bd7b4fb923bd0a5" 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=latest-v2-a39ff78f6156932a4db851c58bd7b4fb923bd0a5 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=a39ff78f6156932a4db851c58bd7b4fb923bd0a5 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2023-02-28T23:28:26Z -extldflags "-static"" -tags azurediskv2 -mod vendor -o _output/amd64/azurediskpluginv2.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 1499 lines ...
                    type: string
                type: object
                oneOf:
                - required: ["persistentVolumeClaimName"]
                - required: ["volumeSnapshotContentName"]
              volumeSnapshotClassName:
                description: 'VolumeSnapshotClassName is the name of the VolumeSnapshotClass requested by the VolumeSnapshot. VolumeSnapshotClassName may be left nil to indicate that the default SnapshotClass should be used. A given cluster may have multiple default Volume SnapshotClasses: one default per CSI Driver. If a VolumeSnapshot does not specify a SnapshotClass, VolumeSnapshotSource will be checked to figure out what the associated CSI Driver is, and the default VolumeSnapshotClass associated with that CSI Driver will be used. If more than one VolumeSnapshotClass exist for a given CSI Driver and more than one have been marked as default, CreateSnapshot will fail and generate an event. Empty string is not allowed for this field.'
                type: string
            required:
            - source
            type: object
          status:
            description: status represents the current information of a snapshot. Consumers must verify binding between VolumeSnapshot and VolumeSnapshotContent objects is successful (by validating that both VolumeSnapshot and VolumeSnapshotContent point at each other) before using this object.
... skipping 2 lines ...
                description: 'boundVolumeSnapshotContentName is the name of the VolumeSnapshotContent object to which this VolumeSnapshot object intends to bind to. If not specified, it indicates that the VolumeSnapshot object has not been successfully bound to a VolumeSnapshotContent object yet. NOTE: To avoid possible security issues, consumers must verify binding between VolumeSnapshot and VolumeSnapshotContent objects is successful (by validating that both VolumeSnapshot and VolumeSnapshotContent point at each other) before using this object.'
                type: string
              creationTime:
                description: creationTime is the timestamp when the point-in-time snapshot is taken by the underlying storage system. In dynamic snapshot creation case, this field will be filled in by the snapshot controller with the "creation_time" value returned from CSI "CreateSnapshot" gRPC call. For a 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 may indicate 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. The snapshot controller will keep retrying when an error occurs during the snapshot creation. Upon success, this error field will be cleared.
                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 the snapshot is ready to be used to restore a volume. In dynamic snapshot creation case, this field will be filled in by the snapshot controller with the "ready_to_use" value returned from CSI "CreateSnapshot" gRPC call. For a pre-existing snapshot, this field will be filled with the "ready_to_use" value returned from the CSI "ListSnapshots" gRPC call if the driver supports it, otherwise, this field will be set to "True". If not specified, it means the readiness of a snapshot is unknown.
                type: boolean
              restoreSize:
                type: string
                description: restoreSize represents the minimum size of volume required to create a volume from this snapshot. In dynamic snapshot creation case, this field will be filled in by the snapshot controller with the "size_bytes" value returned from CSI "CreateSnapshot" 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
        type: object
... skipping 60 lines ...
                    type: string
                  volumeSnapshotContentName:
                    description: volumeSnapshotContentName specifies the name of a pre-existing VolumeSnapshotContent object representing an existing volume snapshot. This field should be set if the snapshot already exists and only needs a representation in Kubernetes. This field is immutable.
                    type: string
                type: object
              volumeSnapshotClassName:
                description: 'VolumeSnapshotClassName is the name of the VolumeSnapshotClass requested by the VolumeSnapshot. VolumeSnapshotClassName may be left nil to indicate that the default SnapshotClass should be used. A given cluster may have multiple default Volume SnapshotClasses: one default per CSI Driver. If a VolumeSnapshot does not specify a SnapshotClass, VolumeSnapshotSource will be checked to figure out what the associated CSI Driver is, and the default VolumeSnapshotClass associated with that CSI Driver will be used. If more than one VolumeSnapshotClass exist for a given CSI Driver and more than one have been marked as default, CreateSnapshot will fail and generate an event. Empty string is not allowed for this field.'
                type: string
            required:
            - source
            type: object
          status:
            description: status represents the current information of a snapshot. Consumers must verify binding between VolumeSnapshot and VolumeSnapshotContent objects is successful (by validating that both VolumeSnapshot and VolumeSnapshotContent point at each other) before using this object.
... skipping 2 lines ...
                description: 'boundVolumeSnapshotContentName is the name of the VolumeSnapshotContent object to which this VolumeSnapshot object intends to bind to. If not specified, it indicates that the VolumeSnapshot object has not been successfully bound to a VolumeSnapshotContent object yet. NOTE: To avoid possible security issues, consumers must verify binding between VolumeSnapshot and VolumeSnapshotContent objects is successful (by validating that both VolumeSnapshot and VolumeSnapshotContent point at each other) before using this object.'
                type: string
              creationTime:
                description: creationTime is the timestamp when the point-in-time snapshot is taken by the underlying storage system. In dynamic snapshot creation case, this field will be filled in by the snapshot controller with the "creation_time" value returned from CSI "CreateSnapshot" gRPC call. For a 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 may indicate 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. The snapshot controller will keep retrying when an error occurs during the snapshot creation. Upon success, this error field will be cleared.
                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 the snapshot is ready to be used to restore a volume. In dynamic snapshot creation case, this field will be filled in by the snapshot controller with the "ready_to_use" value returned from CSI "CreateSnapshot" gRPC call. For a pre-existing snapshot, this field will be filled with the "ready_to_use" value returned from the CSI "ListSnapshots" gRPC call if the driver supports it, otherwise, this field will be set to "True". If not specified, it means the readiness of a snapshot is unknown.
                type: boolean
              restoreSize:
                type: string
                description: restoreSize represents the minimum size of volume required to create a volume from this snapshot. In dynamic snapshot creation case, this field will be filled in by the snapshot controller with the "size_bytes" value returned from CSI "CreateSnapshot" 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
        type: object
... skipping 254 lines ...
            description: status represents the current information of a snapshot.
            properties:
              creationTime:
                description: creationTime is the timestamp when the point-in-time snapshot is taken by the underlying storage system. In dynamic snapshot creation case, this field will be filled in by the CSI snapshotter sidecar with the "creation_time" value returned from CSI "CreateSnapshot" gRPC call. For a 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 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 last observed error during snapshot creation, if any. Upon success after retry, this error field will be cleared.
                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 will be filled in by the CSI snapshotter sidecar with the "ready_to_use" value returned from CSI "CreateSnapshot" gRPC call. For a pre-existing snapshot, this field will be filled with the "ready_to_use" value returned from the CSI "ListSnapshots" gRPC call if the driver supports it, otherwise, this field will be set to "True". If not specified, it means the readiness of a snapshot is unknown.
                type: boolean
              restoreSize:
                description: restoreSize represents the complete size of the snapshot in bytes. In dynamic snapshot creation case, this field will be filled in by the CSI snapshotter sidecar with the "size_bytes" value returned from CSI "CreateSnapshot" 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
    served: true
... skipping 108 lines ...
            description: status represents the current information of a snapshot.
            properties:
              creationTime:
                description: creationTime is the timestamp when the point-in-time snapshot is taken by the underlying storage system. In dynamic snapshot creation case, this field will be filled in by the CSI snapshotter sidecar with the "creation_time" value returned from CSI "CreateSnapshot" gRPC call. For a 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 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 last observed error during snapshot creation, if any. Upon success after retry, this error field will be cleared.
                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 will be filled in by the CSI snapshotter sidecar with the "ready_to_use" value returned from CSI "CreateSnapshot" gRPC call. For a pre-existing snapshot, this field will be filled with the "ready_to_use" value returned from the CSI "ListSnapshots" gRPC call if the driver supports it, otherwise, this field will be set to "True". If not specified, it means the readiness of a snapshot is unknown.
                type: boolean
              restoreSize:
                description: restoreSize represents the complete size of the snapshot in bytes. In dynamic snapshot creation case, this field will be filled in by the CSI snapshotter sidecar with the "size_bytes" value returned from CSI "CreateSnapshot" 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
    served: true
... skipping 359 lines ...
            - volumeName
            - volume_context
            - volume_id
            type: object
          status:
            description: status represents the current state of AzVolumeAttachment.
              includes error, state, and attachment status Required
            properties:
              detail:
                description: Status summarizes the current attachment state of the
                  volume attachment Nil Status indicates that the volume has not yet
                  been attached to the node
                properties:
... skipping 7 lines ...
                  role:
                    description: The current attachment role.
                    type: string
                required:
                - role
                type: object
              error:
                description: Error occurred during attach/detach of volume
                properties:
                  code:
                    type: string
                  message:
                    type: string
                  parameters:
... skipping 90 lines ...
            - volumeName
            - volume_context
            - volume_id
            type: object
          status:
            description: status represents the current state of AzVolumeAttachment.
              includes error, state, and attachment status
            properties:
              annotation:
                additionalProperties:
                  type: string
                description: Annotations contains additional resource information
                  to guide driver actions
... skipping 13 lines ...
                  role:
                    description: The current attachment role.
                    type: string
                required:
                - role
                type: object
              error:
                description: Error occurred during attach/detach of volume
                properties:
                  code:
                    type: string
                  message:
                    type: string
                  parameters:
... skipping 169 lines ...
            - maxMountReplicaCount
            - volumeCapability
            - volumeName
            type: object
          status:
            description: status represents the current state of AzVolume. includes
              error, state, and volume status
            properties:
              detail:
                description: Current status detail of the AzVolume Nil detail indicates
                  that the volume has not been created
                properties:
                  accessible_topology:
... skipping 28 lines ...
                    type: string
                required:
                - capacity_bytes
                - node_expansion_required
                - volume_id
                type: object
              error:
                description: Error occurred during creation/deletion of volume
                properties:
                  code:
                    type: string
                  message:
                    type: string
                  parameters:
... skipping 154 lines ...
            - maxMountReplicaCount
            - volumeCapability
            - volumeName
            type: object
          status:
            description: status represents the current state of AzVolume. includes
              error, state, and volume status
            properties:
              annotation:
                additionalProperties:
                  type: string
                description: Annotations contains additional resource information
                  to guide driver actions
... skipping 34 lines ...
                    type: string
                required:
                - capacity_bytes
                - node_expansion_required
                - volume_id
                type: object
              error:
                description: Error occurred during creation/deletion of volume
                properties:
                  code:
                    type: string
                  message:
                    type: string
                  parameters:
... skipping 1069 lines ...
          image: "mcr.microsoft.com/oss/kubernetes-csi/csi-resizer:v1.5.0"
          args:
            - "-csi-address=$(ADDRESS)"
            - "-v=2"
            - "-leader-election"
            - "--leader-election-namespace=kube-system"
            - '-handle-volume-inuse-error=false'
            - '-feature-gates=RecoverVolumeExpansionFailure=true'
            - "-timeout=240s"
          env:
            - name: ADDRESS
              value: /csi/csi.sock
          volumeMounts:
... skipping 292 lines ...
STEP: setting up the StorageClass 02/28/23 23:36:23.782
STEP: creating a StorageClass  02/28/23 23:36:23.783
STEP: setting up the PVC and PV 02/28/23 23:36:23.815
STEP: creating a PVC 02/28/23 23:36:23.815
STEP: setting up the pod 02/28/23 23:36:23.856
STEP: deploying the pod 02/28/23 23:36:23.856
STEP: checking that the pod's command exits with no error 02/28/23 23:36:23.889
Feb 28 23:36:23.889: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-8lp8k" in namespace "azuredisk-8987" to be "Succeeded or Failed"
Feb 28 23:36:23.927: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 38.048794ms
Feb 28 23:36:25.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 2.07177627s
Feb 28 23:36:27.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 4.071746444s
Feb 28 23:36:29.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 6.071763436s
Feb 28 23:36:31.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 8.070153293s
Feb 28 23:36:33.960: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 10.070410356s
Feb 28 23:36:35.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 12.07012241s
Feb 28 23:36:37.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 14.071863885s
Feb 28 23:36:39.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 16.071609368s
Feb 28 23:36:41.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 18.071767358s
Feb 28 23:36:43.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 20.070147744s
Feb 28 23:36:45.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.070231394s
STEP: Saw pod success 02/28/23 23:36:45.959
Feb 28 23:36:45.960: INFO: Pod "azuredisk-volume-tester-8lp8k" satisfied condition "Succeeded or Failed"
Feb 28 23:36:45.960: INFO: deleting Pod "azuredisk-8987"/"azuredisk-volume-tester-8lp8k"
Feb 28 23:36:46.016: INFO: Pod azuredisk-volume-tester-8lp8k has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-8lp8k in namespace azuredisk-8987 02/28/23 23:36:46.016
STEP: validating provisioned PV 02/28/23 23:36:46.082
STEP: checking the PV 02/28/23 23:36:46.114
... skipping 33 lines ...
    STEP: setting up the StorageClass 02/28/23 23:36:23.782
    STEP: creating a StorageClass  02/28/23 23:36:23.783
    STEP: setting up the PVC and PV 02/28/23 23:36:23.815
    STEP: creating a PVC 02/28/23 23:36:23.815
    STEP: setting up the pod 02/28/23 23:36:23.856
    STEP: deploying the pod 02/28/23 23:36:23.856
    STEP: checking that the pod's command exits with no error 02/28/23 23:36:23.889
    Feb 28 23:36:23.889: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-8lp8k" in namespace "azuredisk-8987" to be "Succeeded or Failed"
    Feb 28 23:36:23.927: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 38.048794ms
    Feb 28 23:36:25.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 2.07177627s
    Feb 28 23:36:27.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 4.071746444s
    Feb 28 23:36:29.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 6.071763436s
    Feb 28 23:36:31.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 8.070153293s
    Feb 28 23:36:33.960: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 10.070410356s
    Feb 28 23:36:35.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 12.07012241s
    Feb 28 23:36:37.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 14.071863885s
    Feb 28 23:36:39.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 16.071609368s
    Feb 28 23:36:41.961: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 18.071767358s
    Feb 28 23:36:43.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Pending", Reason="", readiness=false. Elapsed: 20.070147744s
    Feb 28 23:36:45.959: INFO: Pod "azuredisk-volume-tester-8lp8k": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.070231394s
    STEP: Saw pod success 02/28/23 23:36:45.959
    Feb 28 23:36:45.960: INFO: Pod "azuredisk-volume-tester-8lp8k" satisfied condition "Succeeded or Failed"
    Feb 28 23:36:45.960: INFO: deleting Pod "azuredisk-8987"/"azuredisk-volume-tester-8lp8k"
    Feb 28 23:36:46.016: INFO: Pod azuredisk-volume-tester-8lp8k has the following logs: hello world

    STEP: Deleting pod azuredisk-volume-tester-8lp8k in namespace azuredisk-8987 02/28/23 23:36:46.016
    STEP: validating provisioned PV 02/28/23 23:36:46.082
    STEP: checking the PV 02/28/23 23:36:46.114
... skipping 142 lines ...
STEP: setting up the PVC and PV 02/28/23 23:37:29.142
STEP: creating a PVC 02/28/23 23:37:29.142
STEP: setting up the pod 02/28/23 23:37:29.176
STEP: deploying the pod 02/28/23 23:37:29.176
STEP: checking that the pod has 'FailedMount' event 02/28/23 23:37:29.208
Feb 28 23:37:47.274: INFO: deleting Pod "azuredisk-6500"/"azuredisk-volume-tester-x9qqs"
Feb 28 23:37:47.306: INFO: Error getting logs for pod azuredisk-volume-tester-x9qqs: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-x9qqs)
STEP: Deleting pod azuredisk-volume-tester-x9qqs in namespace azuredisk-6500 02/28/23 23:37:47.306
STEP: validating provisioned PV 02/28/23 23:37:47.37
STEP: checking the PV 02/28/23 23:37:47.401
Feb 28 23:37:47.401: INFO: deleting PVC "azuredisk-6500"/"pvc-zpmcs"
Feb 28 23:37:47.401: INFO: Deleting PersistentVolumeClaim "pvc-zpmcs"
STEP: waiting for claim's PV "pvc-f322c57f-5935-4c08-bf41-831b1a2e5ec8" to be deleted 02/28/23 23:37:47.436
... skipping 53 lines ...
    STEP: setting up the PVC and PV 02/28/23 23:37:29.142
    STEP: creating a PVC 02/28/23 23:37:29.142
    STEP: setting up the pod 02/28/23 23:37:29.176
    STEP: deploying the pod 02/28/23 23:37:29.176
    STEP: checking that the pod has 'FailedMount' event 02/28/23 23:37:29.208
    Feb 28 23:37:47.274: INFO: deleting Pod "azuredisk-6500"/"azuredisk-volume-tester-x9qqs"
    Feb 28 23:37:47.306: INFO: Error getting logs for pod azuredisk-volume-tester-x9qqs: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-x9qqs)
    STEP: Deleting pod azuredisk-volume-tester-x9qqs in namespace azuredisk-6500 02/28/23 23:37:47.306
    STEP: validating provisioned PV 02/28/23 23:37:47.37
    STEP: checking the PV 02/28/23 23:37:47.401
    Feb 28 23:37:47.401: INFO: deleting PVC "azuredisk-6500"/"pvc-zpmcs"
    Feb 28 23:37:47.401: INFO: Deleting PersistentVolumeClaim "pvc-zpmcs"
    STEP: waiting for claim's PV "pvc-f322c57f-5935-4c08-bf41-831b1a2e5ec8" to be deleted 02/28/23 23:37:47.436
... skipping 50 lines ...
STEP: setting up the StorageClass 02/28/23 23:40:14.173
STEP: creating a StorageClass  02/28/23 23:40:14.173
STEP: setting up the PVC and PV 02/28/23 23:40:14.205
STEP: creating a PVC 02/28/23 23:40:14.205
STEP: setting up the pod 02/28/23 23:40:14.238
STEP: deploying the pod 02/28/23 23:40:14.238
STEP: checking that the pod's command exits with no error 02/28/23 23:40:14.271
Feb 28 23:40:14.271: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-gn5bx" in namespace "azuredisk-3428" to be "Succeeded or Failed"
Feb 28 23:40:14.303: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 31.64317ms
Feb 28 23:40:16.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.06454049s
Feb 28 23:40:18.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.065475856s
Feb 28 23:40:20.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.064683139s
Feb 28 23:40:22.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.066177711s
Feb 28 23:40:24.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 10.06470339s
Feb 28 23:40:26.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 12.065145221s
Feb 28 23:40:28.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 14.065291509s
Feb 28 23:40:30.335: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 16.063417186s
Feb 28 23:40:32.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 18.065472988s
Feb 28 23:40:34.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 20.066114463s
Feb 28 23:40:36.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.064994961s
STEP: Saw pod success 02/28/23 23:40:36.336
Feb 28 23:40:36.336: INFO: Pod "azuredisk-volume-tester-gn5bx" satisfied condition "Succeeded or Failed"
Feb 28 23:40:36.336: INFO: deleting Pod "azuredisk-3428"/"azuredisk-volume-tester-gn5bx"
Feb 28 23:40:36.390: INFO: Pod azuredisk-volume-tester-gn5bx has the following logs: e2e-test

STEP: Deleting pod azuredisk-volume-tester-gn5bx in namespace azuredisk-3428 02/28/23 23:40:36.39
STEP: validating provisioned PV 02/28/23 23:40:36.46
STEP: checking the PV 02/28/23 23:40:36.492
... skipping 33 lines ...
    STEP: setting up the StorageClass 02/28/23 23:40:14.173
    STEP: creating a StorageClass  02/28/23 23:40:14.173
    STEP: setting up the PVC and PV 02/28/23 23:40:14.205
    STEP: creating a PVC 02/28/23 23:40:14.205
    STEP: setting up the pod 02/28/23 23:40:14.238
    STEP: deploying the pod 02/28/23 23:40:14.238
    STEP: checking that the pod's command exits with no error 02/28/23 23:40:14.271
    Feb 28 23:40:14.271: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-gn5bx" in namespace "azuredisk-3428" to be "Succeeded or Failed"
    Feb 28 23:40:14.303: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 31.64317ms
    Feb 28 23:40:16.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.06454049s
    Feb 28 23:40:18.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.065475856s
    Feb 28 23:40:20.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.064683139s
    Feb 28 23:40:22.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.066177711s
    Feb 28 23:40:24.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 10.06470339s
    Feb 28 23:40:26.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 12.065145221s
    Feb 28 23:40:28.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 14.065291509s
    Feb 28 23:40:30.335: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 16.063417186s
    Feb 28 23:40:32.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 18.065472988s
    Feb 28 23:40:34.337: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Pending", Reason="", readiness=false. Elapsed: 20.066114463s
    Feb 28 23:40:36.336: INFO: Pod "azuredisk-volume-tester-gn5bx": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.064994961s
    STEP: Saw pod success 02/28/23 23:40:36.336
    Feb 28 23:40:36.336: INFO: Pod "azuredisk-volume-tester-gn5bx" satisfied condition "Succeeded or Failed"
    Feb 28 23:40:36.336: INFO: deleting Pod "azuredisk-3428"/"azuredisk-volume-tester-gn5bx"
    Feb 28 23:40:36.390: INFO: Pod azuredisk-volume-tester-gn5bx has the following logs: e2e-test

    STEP: Deleting pod azuredisk-volume-tester-gn5bx in namespace azuredisk-3428 02/28/23 23:40:36.39
    STEP: validating provisioned PV 02/28/23 23:40:36.46
    STEP: checking the PV 02/28/23 23:40:36.492
... skipping 104 lines ...
STEP: setting up the StorageClass 02/28/23 23:41:18.84
STEP: creating a StorageClass  02/28/23 23:41:18.84
STEP: setting up the PVC and PV 02/28/23 23:41:18.872
STEP: creating a PVC 02/28/23 23:41:18.872
STEP: setting up the pod 02/28/23 23:41:18.906
STEP: deploying the pod 02/28/23 23:41:18.906
STEP: checking that the pod's command exits with an error 02/28/23 23:41:18.938
Feb 28 23:41:18.938: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-77rgg" in namespace "azuredisk-1905" to be "Error status code"
Feb 28 23:41:18.970: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 32.185132ms
Feb 28 23:41:21.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.06604206s
Feb 28 23:41:23.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.066112247s
Feb 28 23:41:25.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 6.066195656s
Feb 28 23:41:27.002: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 8.064338828s
Feb 28 23:41:29.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.06547196s
Feb 28 23:41:31.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 12.065145076s
Feb 28 23:41:33.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 14.065787405s
Feb 28 23:41:35.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 16.065319064s
Feb 28 23:41:37.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 18.06577106s
Feb 28 23:41:39.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 20.066371293s
Feb 28 23:41:41.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Failed", Reason="", readiness=false. Elapsed: 22.064911538s
STEP: Saw pod failure 02/28/23 23:41:41.003
Feb 28 23:41:41.003: INFO: Pod "azuredisk-volume-tester-77rgg" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message 02/28/23 23:41:41.003
Feb 28 23:41:41.038: INFO: deleting Pod "azuredisk-1905"/"azuredisk-volume-tester-77rgg"
Feb 28 23:41:41.072: INFO: Pod azuredisk-volume-tester-77rgg has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azuredisk-volume-tester-77rgg in namespace azuredisk-1905 02/28/23 23:41:41.072
STEP: validating provisioned PV 02/28/23 23:41:41.141
... skipping 34 lines ...
    STEP: setting up the StorageClass 02/28/23 23:41:18.84
    STEP: creating a StorageClass  02/28/23 23:41:18.84
    STEP: setting up the PVC and PV 02/28/23 23:41:18.872
    STEP: creating a PVC 02/28/23 23:41:18.872
    STEP: setting up the pod 02/28/23 23:41:18.906
    STEP: deploying the pod 02/28/23 23:41:18.906
    STEP: checking that the pod's command exits with an error 02/28/23 23:41:18.938
    Feb 28 23:41:18.938: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-77rgg" in namespace "azuredisk-1905" to be "Error status code"
    Feb 28 23:41:18.970: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 32.185132ms
    Feb 28 23:41:21.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.06604206s
    Feb 28 23:41:23.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.066112247s
    Feb 28 23:41:25.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 6.066195656s
    Feb 28 23:41:27.002: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 8.064338828s
    Feb 28 23:41:29.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.06547196s
    Feb 28 23:41:31.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 12.065145076s
    Feb 28 23:41:33.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 14.065787405s
    Feb 28 23:41:35.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 16.065319064s
    Feb 28 23:41:37.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 18.06577106s
    Feb 28 23:41:39.004: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Pending", Reason="", readiness=false. Elapsed: 20.066371293s
    Feb 28 23:41:41.003: INFO: Pod "azuredisk-volume-tester-77rgg": Phase="Failed", Reason="", readiness=false. Elapsed: 22.064911538s
    STEP: Saw pod failure 02/28/23 23:41:41.003
    Feb 28 23:41:41.003: INFO: Pod "azuredisk-volume-tester-77rgg" satisfied condition "Error status code"
    STEP: checking that pod logs contain expected message 02/28/23 23:41:41.003
    Feb 28 23:41:41.038: INFO: deleting Pod "azuredisk-1905"/"azuredisk-volume-tester-77rgg"
    Feb 28 23:41:41.072: INFO: Pod azuredisk-volume-tester-77rgg has the following logs: touch: /mnt/test-1/data: Read-only file system

    STEP: Deleting pod azuredisk-volume-tester-77rgg in namespace azuredisk-1905 02/28/23 23:41:41.072
    STEP: validating provisioned PV 02/28/23 23:41:41.141
... skipping 371 lines ...
Feb 28 23:47:44.920: INFO: Pod "azuredisk-volume-tester-b6smr-644fc8784f-m8tbh": Phase="Pending", Reason="", readiness=false. Elapsed: 31.761928ms
Feb 28 23:47:46.954: INFO: Pod "azuredisk-volume-tester-b6smr-644fc8784f-m8tbh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.065345701s
Feb 28 23:47:48.954: INFO: Pod "azuredisk-volume-tester-b6smr-644fc8784f-m8tbh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.066178111s
Feb 28 23:47:50.953: INFO: Pod "azuredisk-volume-tester-b6smr-644fc8784f-m8tbh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.064393229s
Feb 28 23:47:52.954: INFO: Pod "azuredisk-volume-tester-b6smr-644fc8784f-m8tbh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.065494262s
Feb 28 23:47:54.954: INFO: Pod "azuredisk-volume-tester-b6smr-644fc8784f-m8tbh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.065852198s
{"component":"entrypoint","file":"k8s.io/test-infra/prow/entrypoint/run.go:168","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2023-02-28T23:47:56Z"}
++ early_exit_handler
++ '[' -n 170 ']'
++ kill -TERM 170
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...