This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: fix: support create snapshot in parallel
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2021-04-06 08:29
Elapsed28m50s
Revisiona2dd442e705df62b6182901d1da23e8e208d91da
Refs 777

No Test Failures!


Error lines from build-log.txt

... skipping 64 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11248  100 11248    0     0   192k      0 --:--:-- --:--:-- --:--:--  192k
Downloading https://get.helm.sh/helm-v3.5.3-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.2.0-3e50c36c0d5a6d7278c97e2e5437f3140f000571 || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azuredisk-csi:v1.2.0-3e50c36c0d5a6d7278c97e2e5437f3140f000571 not found: manifest unknown: manifest tagged by "v1.2.0-3e50c36c0d5a6d7278c97e2e5437f3140f000571" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver'
CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.driverVersion=v1.2.0-3e50c36c0d5a6d7278c97e2e5437f3140f000571 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=3e50c36c0d5a6d7278c97e2e5437f3140f000571 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-04-06T08:37:28Z -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static""  -mod vendor -o _output/azurediskplugin ./pkg/azurediskplugin
CGO_ENABLED=0 GOOS=windows go build -a -ldflags "-X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.driverVersion=v1.2.0-3e50c36c0d5a6d7278c97e2e5437f3140f000571 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=3e50c36c0d5a6d7278c97e2e5437f3140f000571 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2021-04-06T08:38:23Z -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static""  -mod vendor -o _output/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
make container-linux
make[3]: Entering directory '/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver'
docker buildx build --pull --output=type=registry --platform="linux/amd64" --build-arg PLUGIN_NAME=azurediskplugin \
	-t k8sprow.azurecr.io/azuredisk-csi:v1.2.0-3e50c36c0d5a6d7278c97e2e5437f3140f000571-linux-amd64 -f ./pkg/azurediskplugin/Dockerfile .
... skipping 1534 lines ...
        - name: csi-resizer
          image: "mcr.microsoft.com/oss/kubernetes-csi/csi-resizer:v1.1.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 117 lines ...
Go Version: go1.16.1
Platform: linux/amd64
Topology Key: N/A

Streaming logs below:
STEP: Building a namespace api object, basename azuredisk
W0406 08:42:37.925688   13113 azure_config.go:46] Failed to get cloud-config from secret: failed to get secret azure-cloud-provider: secrets "azure-cloud-provider" not found, skip initializing from secret
I0406 08:42:37.929356   13113 azuredisk.go:147] disable UseInstanceMetadata for controller
I0406 08:42:37.929829   13113 driver.go:80] Enabling controller service capability: CREATE_DELETE_VOLUME
I0406 08:42:37.929859   13113 driver.go:80] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0406 08:42:37.929865   13113 driver.go:80] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0406 08:42:37.929870   13113 driver.go:80] Enabling controller service capability: LIST_SNAPSHOTS
I0406 08:42:37.929875   13113 driver.go:80] Enabling controller service capability: CLONE_VOLUME
... skipping 65 lines ...

    test case is only available for CSI drivers

    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/suite_test.go:236
------------------------------
Pre-Provisioned [single-az] 
  should fail when maxShares is invalid [disk.csi.azure.com][windows]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/pre_provisioning_test.go:158
STEP: Creating a kubernetes client
Apr  6 08:42:38.684: INFO: >>> kubeConfig: /root/tmp187906426/kubeconfig/kubeconfig.eastus2.json
STEP: Building a namespace api object, basename azuredisk
STEP: Waiting for a default service account to be provisioned in namespace
I0406 08:42:38.850790   13113 azuredisk_driver.go:57] Using azure disk driver: kubernetes.io/azure-disk
... skipping 2 lines ...

S [SKIPPING] [0.234 seconds]
Pre-Provisioned
/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/pre_provisioning_test.go:37
  [single-az]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/pre_provisioning_test.go:67
    should fail when maxShares is invalid [disk.csi.azure.com][windows] [It]
    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/pre_provisioning_test.go:158

    test case is only available for CSI drivers

    /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/suite_test.go:236
------------------------------
... skipping 66 lines ...
Apr  6 08:42:44.459: INFO: PersistentVolumeClaim pvc-nlcb4 found and phase=Bound (4.104456635s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Apr  6 08:42:44.558: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-g8kkh" in namespace "azuredisk-5541" to be "Succeeded or Failed"
Apr  6 08:42:44.590: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 31.958337ms
Apr  6 08:42:46.625: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.066959633s
Apr  6 08:42:48.660: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.102004619s
Apr  6 08:42:50.696: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.13786427s
Apr  6 08:42:52.729: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.171333485s
Apr  6 08:42:54.763: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.204578409s
... skipping 21 lines ...
Apr  6 08:43:39.514: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 54.956020855s
Apr  6 08:43:41.547: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 56.989222759s
Apr  6 08:43:43.583: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Pending", Reason="", readiness=false. Elapsed: 59.025142514s
Apr  6 08:43:45.617: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Running", Reason="", readiness=true. Elapsed: 1m1.058607933s
Apr  6 08:43:47.652: INFO: Pod "azuredisk-volume-tester-g8kkh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m3.094043051s
STEP: Saw pod success
Apr  6 08:43:47.652: INFO: Pod "azuredisk-volume-tester-g8kkh" satisfied condition "Succeeded or Failed"
Apr  6 08:43:47.652: INFO: deleting Pod "azuredisk-5541"/"azuredisk-volume-tester-g8kkh"
Apr  6 08:43:47.748: INFO: Pod azuredisk-volume-tester-g8kkh has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-g8kkh in namespace azuredisk-5541
Apr  6 08:43:47.790: INFO: deleting PVC "azuredisk-5541"/"pvc-nlcb4"
Apr  6 08:43:47.790: INFO: Deleting PersistentVolumeClaim "pvc-nlcb4"
... skipping 99 lines ...
Apr  6 08:44:35.378: INFO: PersistentVolumeClaim pvc-g9642 found and phase=Bound (4.101726916s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with an error
Apr  6 08:44:35.475: INFO: Waiting up to 10m0s for pod "azuredisk-volume-tester-jp9ch" in namespace "azuredisk-4147" to be "Error status code"
Apr  6 08:44:35.507: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 31.774876ms
Apr  6 08:44:37.542: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 2.067204377s
Apr  6 08:44:39.576: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 4.101187144s
Apr  6 08:44:41.609: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 6.134142648s
Apr  6 08:44:43.643: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 8.168121311s
Apr  6 08:44:45.678: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 10.202587971s
... skipping 12 lines ...
Apr  6 08:45:12.130: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 36.655050477s
Apr  6 08:45:14.170: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 38.69509837s
Apr  6 08:45:16.205: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 40.730222712s
Apr  6 08:45:18.241: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 42.765951161s
Apr  6 08:45:20.276: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 44.800822689s
Apr  6 08:45:22.309: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Pending", Reason="", readiness=false. Elapsed: 46.833830082s
Apr  6 08:45:24.343: INFO: Pod "azuredisk-volume-tester-jp9ch": Phase="Failed", Reason="", readiness=false. Elapsed: 48.867705599s
STEP: Saw pod failure
Apr  6 08:45:24.343: INFO: Pod "azuredisk-volume-tester-jp9ch" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Apr  6 08:45:24.438: INFO: deleting Pod "azuredisk-4147"/"azuredisk-volume-tester-jp9ch"
Apr  6 08:45:24.479: INFO: Pod azuredisk-volume-tester-jp9ch 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 425 lines ...
Apr  6 08:53:11.258: INFO: PersistentVolumeClaim pvc-pc2fb found and phase=Bound (4.101331048s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Apr  6 08:53:11.357: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-v5fpw" in namespace "azuredisk-953" to be "Succeeded or Failed"
Apr  6 08:53:11.389: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 32.322728ms
Apr  6 08:53:13.424: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.06679504s
Apr  6 08:53:15.458: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.100830417s
Apr  6 08:53:17.492: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.1353891s
Apr  6 08:53:19.527: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.170561422s
Apr  6 08:53:21.562: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.205498786s
... skipping 27 lines ...
Apr  6 08:54:18.528: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 1m7.170843424s
Apr  6 08:54:20.562: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 1m9.204655583s
Apr  6 08:54:22.596: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 1m11.239459767s
Apr  6 08:54:24.631: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Pending", Reason="", readiness=false. Elapsed: 1m13.274174592s
Apr  6 08:54:26.667: INFO: Pod "azuredisk-volume-tester-v5fpw": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m15.309828943s
STEP: Saw pod success
Apr  6 08:54:26.667: INFO: Pod "azuredisk-volume-tester-v5fpw" satisfied condition "Succeeded or Failed"
Apr  6 08:54:26.667: INFO: deleting Pod "azuredisk-953"/"azuredisk-volume-tester-v5fpw"
Apr  6 08:54:26.801: INFO: Pod azuredisk-volume-tester-v5fpw has the following logs: hello world
hello world
hello world

STEP: Deleting pod azuredisk-volume-tester-v5fpw in namespace azuredisk-953
... skipping 171 lines ...
Apr  6 08:55:42.141: INFO: PersistentVolumeClaim pvc-jd4rj found but phase is Pending instead of Bound.
Apr  6 08:55:44.175: INFO: PersistentVolumeClaim pvc-jd4rj found and phase=Bound (4.098503264s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Apr  6 08:55:44.274: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-g422w" in namespace "azuredisk-1351" to be "Succeeded or Failed"
Apr  6 08:55:44.306: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 31.789182ms
Apr  6 08:55:46.340: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2.065972356s
Apr  6 08:55:48.375: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 4.100559457s
Apr  6 08:55:50.408: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 6.134142661s
Apr  6 08:55:52.442: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 8.167768955s
Apr  6 08:55:54.477: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 10.203127877s
... skipping 59 lines ...
Apr  6 08:57:56.552: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2m12.278170884s
Apr  6 08:57:58.587: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2m14.313378561s
Apr  6 08:58:00.620: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2m16.345964012s
Apr  6 08:58:02.654: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2m18.379661671s
Apr  6 08:58:04.687: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2m20.413206616s
Apr  6 08:58:06.721: INFO: Pod "azuredisk-volume-tester-g422w": Phase="Pending", Reason="", readiness=false. Elapsed: 2m22.446588556s
{"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-04-06T08:58:08Z"}