This job view page is being replaced by Spyglass soon. Check out the new job view.
PRsunpa93: [V2] fix: CRI recovery should not overwrite existing values
ResultABORTED
Tests 0 failed / 0 succeeded
Started2022-09-15 16:07
Elapsed48m29s
Revision6d7af3765621010525bb2ed9506fa4ddc05ec699
Refs 1518
uploadercrier

No Test Failures!


Error lines from build-log.txt

... skipping 101 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11156  100 11156    0     0  71512      0 --:--:-- --:--:-- --:--:-- 71974
Downloading https://get.helm.sh/helm-v3.9.4-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-18e0102e976f1646481f6f9956caeeab6732ef7c || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:latest-v2-18e0102e976f1646481f6f9956caeeab6732ef7c not found: manifest unknown: manifest tagged by "latest-v2-18e0102e976f1646481f6f9956caeeab6732ef7c" 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-18e0102e976f1646481f6f9956caeeab6732ef7c -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=18e0102e976f1646481f6f9956caeeab6732ef7c -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2022-09-15T16:17:03Z -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 1752 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 773 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 244 lines ...
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: setting up the pod
I0915 16:24:25.678533   15445 resource_setup.go:63] adding PV (pvc-9fc175b7-0f4a-4225-96d2-2cb4917f8f04) to pod ()
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Sep 15 16:24:25.736: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-wlwvt" in namespace "azuredisk-6742" to be "Succeeded or Failed"
Sep 15 16:24:25.792: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Pending", Reason="", readiness=false. Elapsed: 55.62806ms
Sep 15 16:24:27.850: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Pending", Reason="", readiness=false. Elapsed: 2.113517853s
Sep 15 16:24:29.906: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.170319584s
Sep 15 16:24:31.963: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.227231871s
Sep 15 16:24:34.020: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.284213645s
Sep 15 16:24:36.078: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Pending", Reason="", readiness=false. Elapsed: 10.341721371s
... skipping 27 lines ...
Sep 15 16:25:33.713: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Running", Reason="", readiness=true. Elapsed: 1m7.976862444s
Sep 15 16:25:35.773: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Running", Reason="", readiness=true. Elapsed: 1m10.036512859s
Sep 15 16:25:37.831: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Running", Reason="", readiness=false. Elapsed: 1m12.095391632s
Sep 15 16:25:39.890: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Running", Reason="", readiness=false. Elapsed: 1m14.154102722s
Sep 15 16:25:41.949: INFO: Pod "azuredisk-volume-tester-wlwvt": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m16.213285159s
STEP: Saw pod success
Sep 15 16:25:41.949: INFO: Pod "azuredisk-volume-tester-wlwvt" satisfied condition "Succeeded or Failed"
Sep 15 16:25:41.949: INFO: deleting Pod "azuredisk-6742"/"azuredisk-volume-tester-wlwvt"
Sep 15 16:25:42.042: INFO: Pod azuredisk-volume-tester-wlwvt has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-wlwvt in namespace azuredisk-6742
Sep 15 16:25:42.107: INFO: deleting PVC "azuredisk-6742"/"pvc-nvtcr"
Sep 15 16:25:42.107: INFO: Deleting PersistentVolumeClaim "pvc-nvtcr"
... skipping 46 lines ...
Sep 15 16:26:26.343: INFO: PersistentVolumeClaim pvc-9wb4n found but phase is Pending instead of Bound.
Sep 15 16:26:28.402: INFO: PersistentVolumeClaim pvc-9wb4n found and phase=Bound (4.178805915s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 16:26:28.578: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-qw8g5" in namespace "azuredisk-4554" to be "Succeeded or Failed"
Sep 15 16:26:28.635: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Pending", Reason="", readiness=false. Elapsed: 56.217458ms
Sep 15 16:26:30.693: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.114361847s
Sep 15 16:26:32.750: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.171849564s
Sep 15 16:26:34.808: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.229297946s
Sep 15 16:26:36.866: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.287769831s
Sep 15 16:26:38.925: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Pending", Reason="", readiness=false. Elapsed: 10.346790921s
... skipping 28 lines ...
Sep 15 16:27:38.621: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Running", Reason="", readiness=true. Elapsed: 1m10.0420789s
Sep 15 16:27:40.679: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Running", Reason="", readiness=true. Elapsed: 1m12.100481462s
Sep 15 16:27:42.737: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Running", Reason="", readiness=false. Elapsed: 1m14.158742943s
Sep 15 16:27:44.798: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Running", Reason="", readiness=false. Elapsed: 1m16.219086429s
Sep 15 16:27:46.856: INFO: Pod "azuredisk-volume-tester-qw8g5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m18.277792952s
STEP: Saw pod success
Sep 15 16:27:46.856: INFO: Pod "azuredisk-volume-tester-qw8g5" satisfied condition "Succeeded or Failed"
Sep 15 16:27:46.856: INFO: deleting Pod "azuredisk-4554"/"azuredisk-volume-tester-qw8g5"
Sep 15 16:27:46.950: INFO: Pod azuredisk-volume-tester-qw8g5 has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-qw8g5 in namespace azuredisk-4554
Sep 15 16:27:47.015: INFO: deleting PVC "azuredisk-4554"/"pvc-9wb4n"
Sep 15 16:27:47.015: INFO: Deleting PersistentVolumeClaim "pvc-9wb4n"
... 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 no error
Sep 15 16:28:29.169: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-j5v7r" in namespace "azuredisk-1917" to be "Succeeded or Failed"
Sep 15 16:28:29.226: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 56.487471ms
Sep 15 16:28:31.284: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 2.114471206s
Sep 15 16:28:33.343: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 4.173568645s
Sep 15 16:28:35.401: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 6.23137128s
Sep 15 16:28:37.460: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 8.290911789s
Sep 15 16:28:39.518: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 10.348171514s
... skipping 12 lines ...
Sep 15 16:29:06.283: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 37.113367916s
Sep 15 16:29:08.341: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Pending", Reason="", readiness=false. Elapsed: 39.171612484s
Sep 15 16:29:10.399: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Running", Reason="", readiness=true. Elapsed: 41.229430882s
Sep 15 16:29:12.458: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Running", Reason="", readiness=false. Elapsed: 43.288183122s
Sep 15 16:29:14.516: INFO: Pod "azuredisk-volume-tester-j5v7r": Phase="Succeeded", Reason="", readiness=false. Elapsed: 45.346166273s
STEP: Saw pod success
Sep 15 16:29:14.516: INFO: Pod "azuredisk-volume-tester-j5v7r" satisfied condition "Succeeded or Failed"
Sep 15 16:29:14.516: INFO: deleting Pod "azuredisk-1917"/"azuredisk-volume-tester-j5v7r"
Sep 15 16:29:14.581: INFO: Pod azuredisk-volume-tester-j5v7r has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-j5v7r in namespace azuredisk-1917
STEP: validating provisioned PV
STEP: checking the PV
... skipping 41 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Sep 15 16:29:56.951: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-8vjrl" in namespace "azuredisk-7313" to be "Succeeded or Failed"
Sep 15 16:29:57.007: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Pending", Reason="", readiness=false. Elapsed: 56.469842ms
Sep 15 16:29:59.066: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.115164965s
Sep 15 16:30:01.125: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.173948515s
Sep 15 16:30:03.182: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.231037666s
Sep 15 16:30:05.241: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.290274783s
Sep 15 16:30:07.300: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.349001343s
... skipping 21 lines ...
Sep 15 16:30:52.603: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Running", Reason="", readiness=true. Elapsed: 55.652025825s
Sep 15 16:30:54.660: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Running", Reason="", readiness=true. Elapsed: 57.709287916s
Sep 15 16:30:56.717: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Running", Reason="", readiness=true. Elapsed: 59.766678755s
Sep 15 16:30:58.775: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Running", Reason="", readiness=false. Elapsed: 1m1.824443558s
Sep 15 16:31:00.833: INFO: Pod "azuredisk-volume-tester-8vjrl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m3.881948795s
STEP: Saw pod success
Sep 15 16:31:00.833: INFO: Pod "azuredisk-volume-tester-8vjrl" satisfied condition "Succeeded or Failed"
Sep 15 16:31:00.833: INFO: deleting Pod "azuredisk-7313"/"azuredisk-volume-tester-8vjrl"
Sep 15 16:31:00.923: INFO: Pod azuredisk-volume-tester-8vjrl has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-8vjrl in namespace azuredisk-7313
STEP: validating provisioned PV
STEP: checking the PV
... skipping 108 lines ...
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: setting up the pod
I0915 16:31:50.044193   15445 resource_setup.go:63] adding PV (pvc-f2a76788-8ba1-442d-a072-06067860fe01) to pod ()
STEP: deploying the pod
STEP: checking that the pod's command exits with an error
Sep 15 16:31:50.108: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-vw6r9" in namespace "azuredisk-8455" to be "Error status code"
Sep 15 16:31:50.168: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 60.450336ms
Sep 15 16:31:52.227: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 2.119442794s
Sep 15 16:31:54.286: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 4.177677406s
Sep 15 16:31:56.344: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 6.235750604s
Sep 15 16:31:58.403: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.295314176s
Sep 15 16:32:00.461: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 10.353200876s
... skipping 22 lines ...
Sep 15 16:32:47.812: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 57.703755068s
Sep 15 16:32:49.870: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Pending", Reason="", readiness=false. Elapsed: 59.762495279s
Sep 15 16:32:51.929: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Running", Reason="", readiness=true. Elapsed: 1m1.820786805s
Sep 15 16:32:53.987: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Running", Reason="", readiness=true. Elapsed: 1m3.879229027s
Sep 15 16:32:56.047: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Running", Reason="", readiness=false. Elapsed: 1m5.939350444s
Sep 15 16:32:58.105: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Running", Reason="", readiness=false. Elapsed: 1m7.996956894s
Sep 15 16:33:00.163: INFO: Pod "azuredisk-volume-tester-vw6r9": Phase="Failed", Reason="", readiness=false. Elapsed: 1m10.05469115s
STEP: Saw pod failure
Sep 15 16:33:00.163: INFO: Pod "azuredisk-volume-tester-vw6r9" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep 15 16:33:05.249: INFO: deleting Pod "azuredisk-8455"/"azuredisk-volume-tester-vw6r9"
Sep 15 16:33:05.312: INFO: Pod azuredisk-volume-tester-vw6r9 has the following logs: out-file : Access to the path 'C:\mnt\test-1\data' is denied.
At line:1 char:1
+ echo $null >> C:\mnt\test-1\data
+ ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
... skipping 298 lines ...