This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: feat: switch to csi-proxy GA v1
ResultABORTED
Tests 0 failed / 0 succeeded
Started2021-07-25 08:52
Elapsed1h0m
Revision19e6c6f05dd477ae4fb11a1b030262f14063820d
Refs 728

No Test Failures!


Error lines from build-log.txt

... skipping 71 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11248  100 11248    0     0   168k      0 --:--:-- --:--:-- --:--:--  168k
Downloading https://get.helm.sh/helm-v3.6.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/azurefile-csi:e2e-063af937344ef8d041eb5cf4628e4758a8e0d1fc || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azurefile-csi:e2e-063af937344ef8d041eb5cf4628e4758a8e0d1fc not found: manifest unknown: manifest tagged by "e2e-063af937344ef8d041eb5cf4628e4758a8e0d1fc" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/azurefile-csi-driver'
CGO_ENABLED=0 GOOS=windows go build -a -ldflags "-X sigs.k8s.io/azurefile-csi-driver/pkg/azurefile.driverVersion=e2e-063af937344ef8d041eb5cf4628e4758a8e0d1fc -X sigs.k8s.io/azurefile-csi-driver/pkg/azurefile.gitCommit=063af937344ef8d041eb5cf4628e4758a8e0d1fc -X sigs.k8s.io/azurefile-csi-driver/pkg/azurefile.buildDate=2021-07-25T08:58:15Z -s -w -extldflags '-static'" -mod vendor -o _output/amd64/azurefileplugin.exe ./pkg/azurefileplugin
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 670 lines ...
#9 9.212 debconf: (Can't locate Term/ReadLine.pm in @INC (you may need to install the Term::ReadLine module) (@INC contains: /etc/perl /usr/local/lib/x86_64-linux-gnu/perl/5.28.1 /usr/local/share/perl/5.28.1 /usr/lib/x86_64-linux-gnu/perl5/5.28 /usr/share/perl5 /usr/lib/x86_64-linux-gnu/perl/5.28 /usr/share/perl/5.28 /usr/local/lib/site_perl /usr/lib/x86_64-linux-gnu/perl-base) at /usr/share/perl5/Debconf/FrontEnd/Readline.pm line 7.)
#9 9.212 debconf: falling back to frontend: Teletype
#9 9.243 Setting up libnfsidmap2:amd64 (0.25-5.1) ...
#9 9.254 Setting up python (2.7.16-1) ...
#9 9.275 Setting up nfs-common (1:1.3.4-2.5+deb10u1) ...
#9 9.296 /var/lib/dpkg/info/nfs-common.postinst: 7: /var/lib/dpkg/info/nfs-common.postinst: ucf: not found
#9 9.297 dpkg: error processing package nfs-common (--configure):

#9 9.297  installed nfs-common package post-installation script subprocess returned error exit status 127

#9 9.297 Processing triggers for libc-bin (2.28-10) ...
#9 9.315 Processing triggers for mime-support (3.62) ...
#9 9.346 Errors were encountered while processing:
#9 9.346  nfs-common
#9 9.366 E: Sub-process /usr/bin/dpkg returned an error code (1)
#9 DONE 9.5s

#10 exporting to image
#10 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00
#10 exporting layers
#10 exporting layers 5.9s done
... skipping 582 lines ...
#8 59.86 debconf: (Can't locate Term/ReadLine.pm in @INC (you may need to install the Term::ReadLine module) (@INC contains: /etc/perl /usr/local/lib/aarch64-linux-gnu/perl/5.28.1 /usr/local/share/perl/5.28.1 /usr/lib/aarch64-linux-gnu/perl5/5.28 /usr/share/perl5 /usr/lib/aarch64-linux-gnu/perl/5.28 /usr/share/perl/5.28 /usr/local/lib/site_perl /usr/lib/aarch64-linux-gnu/perl-base) at /usr/share/perl5/Debconf/FrontEnd/Readline.pm line 7.)
#8 59.86 debconf: falling back to frontend: Teletype
#8 60.23 Setting up libnfsidmap2:arm64 (0.25-5.1) ...
#8 60.24 Setting up python (2.7.16-1) ...
#8 60.52 Setting up nfs-common (1:1.3.4-2.5+deb10u1) ...
#8 60.59 /var/lib/dpkg/info/nfs-common.postinst: 7: /var/lib/dpkg/info/nfs-common.postinst: ucf: not found
#8 60.60 dpkg: error processing package nfs-common (--configure):

#8 60.60  installed nfs-common package post-installation script subprocess returned error exit status 127

#8 60.60 Processing triggers for libc-bin (2.28-10) ...
#8 60.71 Processing triggers for mime-support (3.62) ...
#8 61.13 Errors were encountered while processing:
#8 61.13  nfs-common
#8 61.25 E: Sub-process /usr/bin/dpkg returned an error code (1)
#8 DONE 61.3s

#9 exporting to image
#9 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00
#9 exporting layers
#9 exporting layers 5.6s done
... skipping 1220 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 134 lines ...
Driver Version: N/A
Git Commit: N/A
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
E0725 09:08:51.816345   14083 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret /: resource name may not be empty
STEP: Building a namespace api object, basename azurefile
I0725 09:08:51.817462   14083 driver.go:93] Enabling controller service capability: CREATE_DELETE_VOLUME
I0725 09:08:51.817484   14083 driver.go:93] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0725 09:08:51.817489   14083 driver.go:93] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0725 09:08:51.817493   14083 driver.go:93] Enabling controller service capability: EXPAND_VOLUME
I0725 09:08:51.817499   14083 driver.go:112] Enabling volume access mode: SINGLE_NODE_WRITER
... skipping 120 lines ...
Jul 25 09:10:54.481: INFO: PersistentVolumeClaim pvc-qlpfm found but phase is Pending instead of Bound.
Jul 25 09:10:56.537: INFO: PersistentVolumeClaim pvc-qlpfm found and phase=Bound (1m38.756286287s)
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
Jul 25 09:10:56.706: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-mwcxv" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 25 09:10:56.769: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 63.014412ms
Jul 25 09:10:58.824: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.118759502s
Jul 25 09:11:00.881: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.175354347s
Jul 25 09:11:02.938: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 6.231929751s
Jul 25 09:11:04.994: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 8.288800346s
Jul 25 09:11:07.051: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 10.345426359s
... skipping 427 lines ...
Jul 25 09:25:47.371: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 14m50.665286923s
Jul 25 09:25:49.426: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 14m52.720106462s
Jul 25 09:25:51.482: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 14m54.776583795s
Jul 25 09:25:53.536: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 14m56.830432615s
Jul 25 09:25:55.592: INFO: Pod "azurefile-volume-tester-mwcxv": Phase="Pending", Reason="", readiness=false. Elapsed: 14m58.885921114s
Jul 25 09:25:57.592: INFO: deleting Pod "azurefile-1318"/"azurefile-volume-tester-mwcxv"
Jul 25 09:25:57.712: INFO: Error getting logs for pod azurefile-volume-tester-mwcxv: the server rejected our request for an unknown reason (get pods azurefile-volume-tester-mwcxv)
STEP: Deleting pod azurefile-volume-tester-mwcxv in namespace azurefile-1318
Jul 25 09:25:57.775: INFO: deleting PVC "azurefile-1318"/"pvc-qlpfm"
Jul 25 09:25:57.775: INFO: Deleting PersistentVolumeClaim "pvc-qlpfm"
STEP: waiting for claim's PV "pvc-0f1da517-3413-4eaf-9f96-2dc01ee5ea35" to be deleted
Jul 25 09:25:57.940: INFO: Waiting up to 10m0s for PersistentVolume pvc-0f1da517-3413-4eaf-9f96-2dc01ee5ea35 to get deleted
Jul 25 09:25:57.995: INFO: PersistentVolume pvc-0f1da517-3413-4eaf-9f96-2dc01ee5ea35 found and phase=Bound (55.031299ms)
... skipping 5 lines ...
STEP: Collecting events from namespace "azurefile-1318".
STEP: Found 7 events.
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:09:17 +0000 UTC - event for pvc-qlpfm: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "file.csi.azure.com" or manually created by system administrator
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:09:17 +0000 UTC - event for pvc-qlpfm: {file.csi.azure.com_k8s-master-38735262-0_dd9920bf-3c35-449a-9acb-7755e5405cdc } Provisioning: External provisioner is provisioning volume for claim "azurefile-1318/pvc-qlpfm"
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:10:54 +0000 UTC - event for pvc-qlpfm: {file.csi.azure.com_k8s-master-38735262-0_dd9920bf-3c35-449a-9acb-7755e5405cdc } ProvisioningSucceeded: Successfully provisioned volume pvc-0f1da517-3413-4eaf-9f96-2dc01ee5ea35
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:10:56 +0000 UTC - event for azurefile-volume-tester-mwcxv: {default-scheduler } Scheduled: Successfully assigned azurefile-1318/azurefile-volume-tester-mwcxv to 3873k8s001
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:10:57 +0000 UTC - event for azurefile-volume-tester-mwcxv: {kubelet 3873k8s001} FailedMount: MountVolume.MountDevice failed for volume "pvc-0f1da517-3413-4eaf-9f96-2dc01ee5ea35" : rpc error: code = Unknown desc = prepare stage path failed for \var\lib\kubelet\plugins\kubernetes.io\csi\pv\pvc-0f1da517-3413-4eaf-9f96-2dc01ee5ea35\globalmount with error: could not cast to csi proxy class
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:12:59 +0000 UTC - event for azurefile-volume-tester-mwcxv: {kubelet 3873k8s001} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume-1], unattached volumes=[default-token-h6vtz test-volume-1]: timed out waiting for the condition
Jul 25 09:26:08.269: INFO: At 2021-07-25 09:17:33 +0000 UTC - event for azurefile-volume-tester-mwcxv: {kubelet 3873k8s001} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume-1], unattached volumes=[test-volume-1 default-token-h6vtz]: timed out waiting for the condition
Jul 25 09:26:08.323: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Jul 25 09:26:08.323: INFO: 
Jul 25 09:26:08.377: INFO: 
Logging node info for node 3873k8s000
... skipping 66 lines ...
• Failure [1013.632 seconds]
Dynamic Provisioning
/home/prow/go/src/sigs.k8s.io/azurefile-csi-driver/test/e2e/dynamic_provisioning_test.go:42
  should create a volume on demand with mount options [kubernetes.io/azure-file] [file.csi.azure.com] [Windows] [It]
  /home/prow/go/src/sigs.k8s.io/azurefile-csi-driver/test/e2e/dynamic_provisioning_test.go:103

  Unexpected error:
      <*errors.errorString | 0xc000302e50>: {
          s: "Gave up after waiting 15m0s for pod \"azurefile-volume-tester-mwcxv\" to be \"Succeeded or Failed\"",
      }
      Gave up after waiting 15m0s for pod "azurefile-volume-tester-mwcxv" to be "Succeeded or Failed"
  occurred

  /home/prow/go/src/sigs.k8s.io/azurefile-csi-driver/test/e2e/testsuites/testsuites.go:690
------------------------------
Dynamic Provisioning 
  should create a pod with volume mount subpath [file.csi.azure.com] [Windows]
... skipping 27 lines ...
Jul 25 09:26:32.325: INFO: PersistentVolumeClaim pvc-n8dch found but phase is Pending instead of Bound.
Jul 25 09:26:34.380: INFO: PersistentVolumeClaim pvc-n8dch found and phase=Bound (22.705213006s)
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
Jul 25 09:26:34.543: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-pqcdd" in namespace "azurefile-694" to be "Succeeded or Failed"
Jul 25 09:26:34.597: INFO: Pod "azurefile-volume-tester-pqcdd": Phase="Pending", Reason="", readiness=false. Elapsed: 53.433206ms
Jul 25 09:26:36.652: INFO: Pod "azurefile-volume-tester-pqcdd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108605961s
Jul 25 09:26:38.709: INFO: Pod "azurefile-volume-tester-pqcdd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.16609291s
Jul 25 09:26:40.764: INFO: Pod "azurefile-volume-tester-pqcdd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.220774143s
Jul 25 09:26:42.819: INFO: Pod "azurefile-volume-tester-pqcdd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.275742435s
Jul 25 09:26:44.876: INFO: Pod "azurefile-volume-tester-pqcdd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.332418024s
... skipping 330 lines ...