This job view page is being replaced by Spyglass soon. Check out the new job view.
PRboddumanohar: use blobfuse-proxy agent that runs on nodes to maintain blobfuse mounts
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2021-03-04 11:27
Elapsed13m36s
Revisiondf933d67771e1d96013221682eeff7f26d3451a6
Refs 349

No Test Failures!


Error lines from build-log.txt

... skipping 67 lines ...
Preparing to install helm into /usr/local/bin
helm installed into /usr/local/bin/helm
kubectl apply -f ./deploy/blobfuse-proxy/blobfuse-proxy.yaml
daemonset.apps/csi-blobfuse-proxy created
# Only build and push the image if it does not exist in the registry
docker pull k8sprow.azurecr.io/blob-csi:e2e-4345bf71e2410b66c37de3868f86a8df80c66a8e || make blob-container push
Error response from daemon: manifest for k8sprow.azurecr.io/blob-csi:e2e-4345bf71e2410b66c37de3868f86a8df80c66a8e not found: manifest unknown: manifest tagged by "e2e-4345bf71e2410b66c37de3868f86a8df80c66a8e" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/blob-csi-driver'
docker buildx rm container-builder || true
error: no builder "container-builder" found
docker buildx create --use --name=container-builder
container-builder
docker buildx build --no-cache --build-arg LDFLAGS="-X sigs.k8s.io/blob-csi-driver/pkg/blob.driverVersion=e2e-4345bf71e2410b66c37de3868f86a8df80c66a8e -X sigs.k8s.io/blob-csi-driver/pkg/blob.gitCommit=4345bf71e2410b66c37de3868f86a8df80c66a8e -X sigs.k8s.io/blob-csi-driver/pkg/blob.buildDate=2021-03-04T11:34:05Z -s -w -extldflags '-static'" -t k8sprow.azurecr.io/blob-csi:e2e-4345bf71e2410b66c37de3868f86a8df80c66a8e -f ./pkg/blobplugin/Dockerfile --platform="linux/amd64" --push .
#1 [internal] booting buildkit
#1 sha256:2dc98604d816d9d48a0047b2be4cbdf34c00e01bf6c2633115edadf8cf29ec58
#1 pulling image moby/buildkit:buildx-stable-1
... skipping 97 lines ...
#13 5.530 Reading state information...
#13 5.698 The following additional packages will be installed:
#13 5.698   cmake-data libarchive13 libblkid1 libc-dev-bin libc6-dev libcom-err2
#13 5.698   libcurl3-gnutls libcurl4 libdpkg-perl libevent-2.1-6 libexpat1 libfuse2
#13 5.698   libgdbm-compat4 libgdbm6 libglib2.0-0 libgmp-dev libgmpxx4ldbl
#13 5.698   libgnutls-dane0 libgnutls-openssl27 libgnutls30 libgnutlsxx28
#13 5.698   libgpg-error-dev libgssapi-krb5-2 libicu63 libidn2-dev libjsoncpp1
#13 5.698   libk5crypto3 libkeyutils1 libkrb5-3 libkrb5support0 libldap-2.4-2
#13 5.698   libldap-common libmount1 libncurses6 libncursesw6 libnghttp2-14
#13 5.698   libp11-kit-dev libp11-kit0 libpcre16-3 libpcre2-8-0 libpcre3-dev libpcre32-3
#13 5.699   libpcrecpp0v5 libperl5.28 libprocps7 libpsl5 librhash0 librtmp1 libsasl2-2
#13 5.699   libsasl2-modules-db libselinux1-dev libsepol1-dev libssh2-1 libssl1.1
#13 5.699   libtasn1-6-dev libtinfo6 libunbound8 libuuid1 libuv1 libxml2 linux-libc-dev
... skipping 14 lines ...
#13 6.323 The following NEW packages will be installed:
#13 6.323   ca-certificates cmake cmake-data libarchive13 libblkid1 libc-dev-bin
#13 6.323   libc6-dev libcom-err2 libcurl3-gnutls libcurl4 libcurl4-gnutls-dev
#13 6.323   libdpkg-perl libevent-2.1-6 libexpat1 libfuse-dev libfuse2 libgcrypt20-dev
#13 6.323   libgdbm-compat4 libgdbm6 libglib2.0-0 libgmp-dev libgmpxx4ldbl
#13 6.323   libgnutls-dane0 libgnutls-openssl27 libgnutls28-dev libgnutlsxx28
#13 6.324   libgpg-error-dev libgssapi-krb5-2 libicu63 libidn2-dev libjsoncpp1
#13 6.324   libk5crypto3 libkeyutils1 libkrb5-3 libkrb5support0 libldap-2.4-2
#13 6.324   libldap-common libmount1 libncurses6 libncursesw6 libnghttp2-14
#13 6.324   libp11-kit-dev libpcre16-3 libpcre2-8-0 libpcre3-dev libpcre32-3
#13 6.324   libpcrecpp0v5 libperl5.28 libprocps7 libpsl5 librhash0 librtmp1 libsasl2-2
#13 6.324   libsasl2-modules-db libselinux1-dev libsepol1-dev libssh2-1 libssl1.1
#13 6.324   libtasn1-6-dev libtinfo6 libunbound8 libuuid1 libuv1 libxml2 linux-libc-dev
... skipping 60 lines ...
#13 7.072 Get:54 http://deb.debian.org/debian buster/main amd64 libpcre16-3 amd64 2:8.39-12 [259 kB]
#13 7.076 Get:55 http://deb.debian.org/debian buster/main amd64 libpcre32-3 amd64 2:8.39-12 [250 kB]
#13 7.079 Get:56 http://deb.debian.org/debian buster/main amd64 libpcrecpp0v5 amd64 2:8.39-12 [152 kB]
#13 7.081 Get:57 http://deb.debian.org/debian buster/main amd64 libpcre3-dev amd64 2:8.39-12 [650 kB]
#13 7.089 Get:58 http://deb.debian.org/debian buster/main amd64 libselinux1-dev amd64 2.8-1+b1 [169 kB]
#13 7.092 Get:59 http://deb.debian.org/debian buster/main amd64 libfuse-dev amd64 2.9.9-1+deb10u1 [152 kB]
#13 7.093 Get:60 http://deb.debian.org/debian buster/main amd64 libgpg-error-dev amd64 1.35-1 [124 kB]
#13 7.095 Get:61 http://deb.debian.org/debian buster/main amd64 libgcrypt20-dev amd64 1.8.4-5 [608 kB]
#13 7.102 Get:62 http://deb.debian.org/debian buster/main amd64 libmount1 amd64 2.33.1-0.1 [207 kB]
#13 7.105 Get:63 http://deb.debian.org/debian buster/main amd64 libglib2.0-0 amd64 2.58.3-2+deb10u2 [1258 kB]
#13 7.118 Get:64 http://deb.debian.org/debian buster/main amd64 libgmpxx4ldbl amd64 2:6.1.2+dfsg-4 [22.9 kB]
#13 7.119 Get:65 http://deb.debian.org/debian buster/main amd64 libgmp-dev amd64 2:6.1.2+dfsg-4 [628 kB]
#13 7.127 Get:66 http://deb.debian.org/debian buster/main amd64 libunbound8 amd64 1.9.0-2+deb10u2 [462 kB]
... skipping 186 lines ...
#13 15.85 Selecting previously unselected package libselinux1-dev:amd64.
#13 15.85 Preparing to unpack .../50-libselinux1-dev_2.8-1+b1_amd64.deb ...
#13 15.85 Unpacking libselinux1-dev:amd64 (2.8-1+b1) ...
#13 15.92 Selecting previously unselected package libfuse-dev.
#13 15.92 Preparing to unpack .../51-libfuse-dev_2.9.9-1+deb10u1_amd64.deb ...
#13 15.92 Unpacking libfuse-dev (2.9.9-1+deb10u1) ...
#13 15.98 Selecting previously unselected package libgpg-error-dev.

#13 15.98 Preparing to unpack .../52-libgpg-error-dev_1.35-1_amd64.deb ...

#13 15.98 Unpacking libgpg-error-dev (1.35-1) ...

#13 16.04 Selecting previously unselected package libgcrypt20-dev.
#13 16.04 Preparing to unpack .../53-libgcrypt20-dev_1.8.4-5_amd64.deb ...
#13 16.05 Unpacking libgcrypt20-dev (1.8.4-5) ...
#13 16.17 Selecting previously unselected package libmount1:amd64.
#13 16.17 Preparing to unpack .../54-libmount1_2.33.1-0.1_amd64.deb ...
#13 16.17 Unpacking libmount1:amd64 (2.33.1-0.1) ...
... skipping 60 lines ...
#13 17.80 Setting up libldap-common (2.4.47+dfsg-3+deb10u6) ...
#13 17.82 Setting up libicu63:amd64 (63.1-6+deb10u1) ...
#13 17.83 Setting up libkrb5support0:amd64 (1.17-3+deb10u1) ...
#13 17.85 Setting up libsasl2-modules-db:amd64 (2.1.27+dfsg-1+deb10u1) ...
#13 17.86 Setting up libgmpxx4ldbl:amd64 (2:6.1.2+dfsg-4) ...
#13 17.87 Setting up libuv1:amd64 (1.24.1-1) ...
#13 17.88 Setting up libgpg-error-dev (1.35-1) ...

#13 17.90 Setting up librtmp1:amd64 (2.4+20151223.gitfa8646d.1-2) ...
#13 17.91 Setting up libpcre32-3:amd64 (2:8.39-12) ...
#13 17.92 Setting up libuuid1:amd64 (2.33.1-0.1) ...
#13 17.93 Setting up libgnutlsxx28:amd64 (3.6.7-4+deb10u6) ...
#13 17.95 Setting up libpcre2-8-0:amd64 (10.32-5) ...
#13 17.96 Setting up libk5crypto3:amd64 (1.17-3+deb10u1) ...
... skipping 395 lines ...
#16 9.174 Setting up libpython-stdlib:amd64 (2.7.16-1) ...
#16 9.187 Setting up python (2.7.16-1) ...
#16 9.210 Setting up libdevmapper1.02.1:amd64 (2:1.02.155-3) ...
#16 9.222 Setting up dmsetup (2:1.02.155-3) ...
#16 9.237 Setting up nfs-common (1:1.3.4-2.5+deb10u1) ...
#16 9.260 /var/lib/dpkg/info/nfs-common.postinst: 7: /var/lib/dpkg/info/nfs-common.postinst: ucf: not found
#16 9.260 dpkg: error processing package nfs-common (--configure):

#16 9.260  installed nfs-common package post-installation script subprocess returned error exit status 127

#16 9.261 dpkg: dependency problems prevent configuration of nfs-kernel-server:
#16 9.261  nfs-kernel-server depends on nfs-common (= 1:1.3.4-2.5+deb10u1); however:
#16 9.261   Package nfs-common is not configured yet.
#16 9.261 
#16 9.261 dpkg: error processing package nfs-kernel-server (--configure):

#16 9.261  dependency problems - leaving unconfigured
#16 9.261 Processing triggers for libc-bin (2.28-10) ...
#16 9.296 Errors were encountered while processing:
#16 9.296  nfs-common
#16 9.296  nfs-kernel-server
#16 9.323 E: Sub-process /usr/bin/dpkg returned an error code (1)
#16 DONE 9.4s

#17 exporting to image
#17 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00
#17 exporting layers
#17 exporting layers 12.0s done
... skipping 697 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=false'
          env:
            - name: ADDRESS
              value: /csi/csi.sock
          imagePullPolicy: IfNotPresent
          volumeMounts:
            - name: socket-dir
... skipping 54 lines ...
Git Commit: N/A
Go Version: go1.16
Platform: linux/amd64

Streaming logs below:
STEP: Building a namespace api object, basename blob
W0304 11:36:43.779219   13062 azure_config.go:51] Failed to get cloud-config from secret: failed to get secret azure-cloud-provider: secrets "azure-cloud-provider" not found, skip initializing from secret
I0304 11:36:43.781484   13062 driver.go:80] Enabling controller service capability: CREATE_DELETE_VOLUME
I0304 11:36:43.781509   13062 driver.go:80] Enabling controller service capability: EXPAND_VOLUME
I0304 11:36:43.781516   13062 driver.go:99] Enabling volume access mode: SINGLE_NODE_WRITER
I0304 11:36:43.781521   13062 driver.go:99] Enabling volume access mode: SINGLE_NODE_READER_ONLY
I0304 11:36:43.781526   13062 driver.go:99] Enabling volume access mode: MULTI_NODE_READER_ONLY
I0304 11:36:43.781531   13062 driver.go:99] Enabling volume access mode: MULTI_NODE_SINGLE_WRITER
... skipping 19 lines ...
Mar  4 11:37:04.157: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-mbwv5] to have phase Bound
Mar  4 11:37:04.190: INFO: PersistentVolumeClaim pvc-mbwv5 found and phase=Bound (33.009043ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Mar  4 11:37:04.291: INFO: Waiting up to 15m0s for pod "blob-volume-tester-fjqrp" in namespace "blob-8081" to be "Error status code"
Mar  4 11:37:04.324: INFO: Pod "blob-volume-tester-fjqrp": Phase="Pending", Reason="", readiness=false. Elapsed: 32.482713ms
Mar  4 11:37:06.359: INFO: Pod "blob-volume-tester-fjqrp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.067889496s
Mar  4 11:37:08.394: INFO: Pod "blob-volume-tester-fjqrp": Phase="Running", Reason="", readiness=true. Elapsed: 4.103075594s
Mar  4 11:37:10.429: INFO: Pod "blob-volume-tester-fjqrp": Phase="Failed", Reason="", readiness=false. Elapsed: 6.137766226s
STEP: Saw pod failure
Mar  4 11:37:10.429: INFO: Pod "blob-volume-tester-fjqrp" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Mar  4 11:37:10.491: INFO: deleting Pod "blob-8081"/"blob-volume-tester-fjqrp"
Mar  4 11:37:10.527: INFO: Pod blob-volume-tester-fjqrp has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system

STEP: Deleting pod blob-volume-tester-fjqrp in namespace blob-8081
Mar  4 11:37:10.565: INFO: deleting PVC "blob-8081"/"pvc-mbwv5"
... skipping 79 lines ...
Mar  4 11:37:13.455: INFO: PersistentVolumeClaim pvc-fspp2 found but phase is Pending instead of Bound.
Mar  4 11:37:15.490: INFO: PersistentVolumeClaim pvc-fspp2 found and phase=Bound (2.067189582s)
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
Mar  4 11:37:15.591: INFO: Waiting up to 15m0s for pod "blob-volume-tester-p64k4" in namespace "blob-694" to be "Succeeded or Failed"
Mar  4 11:37:15.624: INFO: Pod "blob-volume-tester-p64k4": Phase="Pending", Reason="", readiness=false. Elapsed: 32.675659ms
Mar  4 11:37:17.659: INFO: Pod "blob-volume-tester-p64k4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.067431058s
STEP: Saw pod success
Mar  4 11:37:17.659: INFO: Pod "blob-volume-tester-p64k4" satisfied condition "Succeeded or Failed"
Mar  4 11:37:17.659: INFO: deleting Pod "blob-694"/"blob-volume-tester-p64k4"
Mar  4 11:37:17.694: INFO: Pod blob-volume-tester-p64k4 has the following logs: hello world

STEP: Deleting pod blob-volume-tester-p64k4 in namespace blob-694
Mar  4 11:37:17.733: INFO: deleting PVC "blob-694"/"pvc-fspp2"
Mar  4 11:37:17.733: INFO: Deleting PersistentVolumeClaim "pvc-fspp2"
... skipping 33 lines ...
Mar  4 11:37:19.252: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-lm2df] to have phase Bound
Mar  4 11:37:19.285: INFO: PersistentVolumeClaim pvc-lm2df found and phase=Bound (33.490379ms)
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
Mar  4 11:37:19.389: INFO: Waiting up to 15m0s for pod "blob-volume-tester-f6cxf" in namespace "blob-3274" to be "Succeeded or Failed"
Mar  4 11:37:19.422: INFO: Pod "blob-volume-tester-f6cxf": Phase="Pending", Reason="", readiness=false. Elapsed: 32.946448ms
Mar  4 11:37:21.455: INFO: Pod "blob-volume-tester-f6cxf": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.066072882s
STEP: Saw pod success
Mar  4 11:37:21.455: INFO: Pod "blob-volume-tester-f6cxf" satisfied condition "Succeeded or Failed"
Mar  4 11:37:21.455: INFO: deleting Pod "blob-3274"/"blob-volume-tester-f6cxf"
Mar  4 11:37:21.490: INFO: Pod blob-volume-tester-f6cxf has the following logs: hello world

STEP: Deleting pod blob-volume-tester-f6cxf in namespace blob-3274
Mar  4 11:37:21.530: INFO: deleting PVC "blob-3274"/"pvc-lm2df"
Mar  4 11:37:21.530: INFO: Deleting PersistentVolumeClaim "pvc-lm2df"
... skipping 31 lines ...
Mar  4 11:37:22.684: INFO: PersistentVolumeClaim pvc-c6sf4 found but phase is Pending instead of Bound.
Mar  4 11:37:24.718: INFO: PersistentVolumeClaim pvc-c6sf4 found and phase=Bound (2.07232096s)
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
Mar  4 11:37:24.818: INFO: Waiting up to 15m0s for pod "blob-volume-tester-wl8q2" in namespace "blob-495" to be "Succeeded or Failed"
Mar  4 11:37:24.851: INFO: Pod "blob-volume-tester-wl8q2": Phase="Pending", Reason="", readiness=false. Elapsed: 33.21154ms
Mar  4 11:37:26.886: INFO: Pod "blob-volume-tester-wl8q2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.06778294s
STEP: Saw pod success
Mar  4 11:37:26.886: INFO: Pod "blob-volume-tester-wl8q2" satisfied condition "Succeeded or Failed"
Mar  4 11:37:26.886: INFO: deleting Pod "blob-495"/"blob-volume-tester-wl8q2"
Mar  4 11:37:26.923: INFO: Pod blob-volume-tester-wl8q2 has the following logs: hello world

STEP: Deleting pod blob-volume-tester-wl8q2 in namespace blob-495
Mar  4 11:37:26.962: INFO: deleting PVC "blob-495"/"pvc-c6sf4"
Mar  4 11:37:26.962: INFO: Deleting PersistentVolumeClaim "pvc-c6sf4"
... skipping 114 lines ...
Mar  4 11:39:21.611: INFO: PersistentVolumeClaim pvc-mhpcp found but phase is Pending instead of Bound.
Mar  4 11:39:23.646: INFO: PersistentVolumeClaim pvc-mhpcp found and phase=Bound (18.341519348s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Mar  4 11:39:23.746: INFO: Waiting up to 15m0s for pod "blob-volume-tester-7vwcl" in namespace "blob-408" to be "Error status code"
Mar  4 11:39:23.779: INFO: Pod "blob-volume-tester-7vwcl": Phase="Pending", Reason="", readiness=false. Elapsed: 32.938388ms
Mar  4 11:39:25.813: INFO: Pod "blob-volume-tester-7vwcl": Phase="Failed", Reason="", readiness=false. Elapsed: 2.067188036s
STEP: Saw pod failure
Mar  4 11:39:25.813: INFO: Pod "blob-volume-tester-7vwcl" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Mar  4 11:39:25.853: INFO: deleting Pod "blob-408"/"blob-volume-tester-7vwcl"
Mar  4 11:39:25.888: INFO: Pod blob-volume-tester-7vwcl has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod blob-volume-tester-7vwcl in namespace blob-408
Mar  4 11:39:25.931: INFO: deleting PVC "blob-408"/"pvc-mhpcp"
... skipping 87 lines ...
Mar  4 11:40:37.304: INFO: Deleting PersistentVolumeClaim "pvc-cnfqv"
STEP: waiting for claim's PV "pvc-aa86e67e-19ea-4e42-947b-44defced5056" to be deleted
Mar  4 11:40:37.338: INFO: Waiting up to 10m0s for PersistentVolume pvc-aa86e67e-19ea-4e42-947b-44defced5056 to get deleted
Mar  4 11:40:37.371: INFO: PersistentVolume pvc-aa86e67e-19ea-4e42-947b-44defced5056 found and phase=Bound (32.731107ms)
Mar  4 11:40:42.406: INFO: PersistentVolume pvc-aa86e67e-19ea-4e42-947b-44defced5056 found and phase=Bound (5.067153924s)
Mar  4 11:40:47.439: INFO: PersistentVolume pvc-aa86e67e-19ea-4e42-947b-44defced5056 found and phase=Bound (10.10087078s)
{"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-03-04T11:40:48Z"}