This job view page is being replaced by Spyglass soon. Check out the new job view.
PRpohly: master: update release-tools
ResultFAILURE
Tests 1 failed / 44 succeeded
Started2020-08-12 08:15
Elapsed30m8s
Revisiond1f49a137a94f828c69a8214b4fa6e95e86d1c65
Refs 75

Test Failures


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller should create snapshot objects correctly 5m26s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=External\sStorage\s\[Driver\:\shostpath\.csi\.k8s\.io\]\s\[Testpattern\:\sDynamic\sSnapshot\s\(retain\spolicy\)\]\ssnapshottable\[Feature\:VolumeSnapshotDataSource\]\svolume\ssnapshot\scontroller\s\sshould\screate\ssnapshot\sobjects\scorrectly$'
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:187
Aug 12 08:44:29.658: Unexpected error:
    <*errors.errorString | 0xc001feec90>: {
        s: "volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da is not deleted within 5m0s",
    }
    volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da is not deleted within 5m0s
occurred
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:446
				
				Click to see stdout/stderrfrom junit_final.xml

Filter through log files | View test history on testgrid


Show 44 Passed Tests

Show 149 Skipped Tests

Error lines from build-log.txt

... skipping 56 lines ...
artifacts: ARTIFACTS=/logs/artifacts
Wed Aug 12 08:15:47 UTC 2020 go1.15rc2 $ make all GOFLAGS_VENDOR=-mod=vendor BUILD_PLATFORMS=linux amd64; windows amd64 .exe; linux ppc64le -ppc64le; linux s390x -s390x; linux arm64 -arm64
./release-tools/verify-go-version.sh "go"
mkdir -p bin
echo 'linux amd64; windows amd64 .exe; linux ppc64le -ppc64le; linux s390x -s390x; linux arm64 -arm64' | tr ';' '\n' | while read -r os arch suffix; do \
	if ! (set -x; CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags '-X main.version=v2.0.0-68-g897d590 -extldflags "-static"' -o "./bin/livenessprobe$suffix" ./cmd/livenessprobe); then \
		echo "Building livenessprobe for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
done
+ CGO_ENABLED=0 GOOS=linux GOARCH=amd64 go build -mod=vendor -a -ldflags -X main.version=v2.0.0-68-g897d590 -extldflags "-static" -o ./bin/livenessprobe ./cmd/livenessprobe
+ CGO_ENABLED=0 GOOS=windows GOARCH=amd64 go build -mod=vendor -a -ldflags -X main.version=v2.0.0-68-g897d590 -extldflags "-static" -o ./bin/livenessprobe.exe ./cmd/livenessprobe
+ CGO_ENABLED=0 GOOS=linux GOARCH=ppc64le go build -mod=vendor -a -ldflags -X main.version=v2.0.0-68-g897d590 -extldflags "-static" -o ./bin/livenessprobe-ppc64le ./cmd/livenessprobe
+ CGO_ENABLED=0 GOOS=linux GOARCH=s390x go build -mod=vendor -a -ldflags -X main.version=v2.0.0-68-g897d590 -extldflags "-static" -o ./bin/livenessprobe-s390x ./cmd/livenessprobe
+ CGO_ENABLED=0 GOOS=linux GOARCH=arm64 go build -mod=vendor -a -ldflags -X main.version=v2.0.0-68-g897d590 -extldflags "-static" -o ./bin/livenessprobe-arm64 ./cmd/livenessprobe
Wed Aug 12 08:18:16 UTC 2020 go1.15rc2 $ make container GOFLAGS_VENDOR=-mod=vendor
./release-tools/verify-go-version.sh "go"
mkdir -p bin
echo '' | tr ';' '\n' | while read -r os arch suffix; do \
	if ! (set -x; CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags '-X main.version=v2.0.0-68-g897d590 -extldflags "-static"' -o "./bin/livenessprobe$suffix" ./cmd/livenessprobe); then \
		echo "Building livenessprobe for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
done
+ CGO_ENABLED=0 GOOS= GOARCH= go build -mod=vendor -a -ldflags -X main.version=v2.0.0-68-g897d590 -extldflags "-static" -o ./bin/livenessprobe ./cmd/livenessprobe
docker build -t livenessprobe:latest -f Dockerfile --label revision=v2.0.0-68-g897d590 .
Sending build context to Docker daemon  87.16MB

... skipping 27 lines ...
Step 7/7 : LABEL revision=v2.0.0-68-g897d590
 ---> Running in 4978cc81274d
Removing intermediate container 4978cc81274d
 ---> b8bedfce891c
Successfully built b8bedfce891c
Successfully tagged livenessprobe:latest
Wed Aug 12 08:18:59 UTC 2020 go1.15rc2 $ curl --fail --location -o /home/prow/go/pkg/csiprow.2eQljO2pve/bin/kind https://github.com/kubernetes-sigs/kind/releases/download/v0.6.0/kind-linux-amd64
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100   629  100   629    0     0   2364      0 --:--:-- --:--:-- --:--:--  2355

100 9521k  100 9521k    0     0  11.9M      0 --:--:-- --:--:-- --:--:-- 11.9M
INFO: kind-config.yaml:
kind: Cluster
... skipping 182 lines ...
do so (now or later) by using -b with the checkout command again. Example:

  git checkout -b <new-branch-name>

HEAD is now at fa13dc11 Merge pull request #93907 from robscott/endpointslice-e2e-test-fix
Wed Aug 12 08:24:30 UTC 2020 go1.15rc2 /home/prow/go/src/k8s.io/kubernetes$ git clean -fdx
Wed Aug 12 08:24:30 UTC 2020 go1.15rc2 $ curl --fail --location https://dl.google.com/go/go1.14.4.linux-amd64.tar.gz
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
  0  117M    0  484k    0     0  1046k      0  0:01:55 --:--:--  0:01:55 1044k
  7  117M    7 8687k    0     0  5693k      0  0:00:21  0:00:01  0:00:20 5689k
 13  117M   13 15.9M    0     0  6427k      0  0:00:18  0:00:02  0:00:16 6425k
 19  117M   19 23.3M    0     0  6535k      0  0:00:18  0:00:03  0:00:15 6535k
 24  117M   24 29.2M    0     0  6523k      0  0:00:18  0:00:04  0:00:14 6523k
 29  117M   29 35.2M    0     0  6513k      0  0:00:18  0:00:05  0:00:13 7013k
 34  117M   34 41.1M    0     0  6521k      0  0:00:18  0:00:06  0:00:12 6777k
 41  117M   41 48.5M    0     0  6573k      0  0:00:18  0:00:07  0:00:11 6648k
 48  117M   48 57.4M    0     0  6802k      0  0:00:17  0:00:08  0:00:09 6998k
 56  117M   56 66.4M    0     0  7107k      0  0:00:16  0:00:09  0:00:07 7646k
 63  117M   63 75.3M    0     0  7280k      0  0:00:16  0:00:10  0:00:06 8120k
 70  117M   70 82.7M    0     0  7392k      0  0:00:16  0:00:11  0:00:05 8518k
 77  117M   77 91.6M    0     0  7513k      0  0:00:16  0:00:12  0:00:04 8954k
 85  117M   85  100M    0     0  7639k      0  0:00:15  0:00:13  0:00:02 9137k
 89  117M   89  105M    0     0  7367k      0  0:00:16  0:00:14  0:00:02 7861k
 92  117M   92  109M    0     0  7148k      0  0:00:16  0:00:15  0:00:01 6873k
 97  117M   97  115M    0     0  7037k      0  0:00:17  0:00:16  0:00:01 6275k
100  117M  100  117M    0     0  6817k      0  0:00:17  0:00:17 --:--:-- 5156k
100  117M  100  117M    0     0  6817k      0  0:00:17  0:00:17 --:--:-- 4205k
Wed Aug 12 08:24:48 UTC 2020 go1.14.4 $ make WHAT=test/e2e/e2e.test -C/home/prow/go/src/k8s.io/kubernetes
make: Entering directory '/home/prow/go/src/k8s.io/kubernetes'
make[1]: Entering directory '/home/prow/go/src/k8s.io/kubernetes'
... skipping 120 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237

    Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:185
------------------------------
... skipping 103 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264

    Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:185
------------------------------
... skipping 42 lines ...
Aug 12 08:34:14.521: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:34:14.554: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iobrcjq] to have phase Bound
Aug 12 08:34:14.569: INFO: PersistentVolumeClaim hostpath.csi.k8s.iobrcjq found but phase is Pending instead of Bound.
Aug 12 08:34:16.579: INFO: PersistentVolumeClaim hostpath.csi.k8s.iobrcjq found and phase=Bound (2.025057855s)
STEP: Creating pod pod-subpath-test-dynamicpv-hjbl
STEP: Creating a pod to test subpath
Aug 12 08:34:16.602: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-hjbl" in namespace "provisioning-19" to be "Succeeded or Failed"
Aug 12 08:34:16.624: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 21.826117ms
Aug 12 08:34:18.639: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.037205566s
Aug 12 08:34:20.659: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.056778189s
Aug 12 08:34:22.669: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.06654749s
Aug 12 08:34:24.679: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.076593653s
Aug 12 08:34:26.699: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.096897216s
Aug 12 08:34:28.707: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 12.104792169s
Aug 12 08:34:30.747: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.144638931s
Aug 12 08:34:32.756: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.153927351s
Aug 12 08:34:34.764: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Pending", Reason="", readiness=false. Elapsed: 18.161800006s
Aug 12 08:34:36.768: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Running", Reason="", readiness=false. Elapsed: 20.165942643s
Aug 12 08:34:38.793: INFO: Pod "pod-subpath-test-dynamicpv-hjbl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.190272738s
STEP: Saw pod success
Aug 12 08:34:38.793: INFO: Pod "pod-subpath-test-dynamicpv-hjbl" satisfied condition "Succeeded or Failed"
Aug 12 08:34:38.808: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-hjbl container test-container-volume-dynamicpv-hjbl: <nil>
STEP: delete the pod
Aug 12 08:34:38.855: INFO: Waiting for pod pod-subpath-test-dynamicpv-hjbl to disappear
Aug 12 08:34:38.867: INFO: Pod pod-subpath-test-dynamicpv-hjbl no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-hjbl
Aug 12 08:34:38.867: INFO: Deleting pod "pod-subpath-test-dynamicpv-hjbl" in namespace "provisioning-19"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support non-existent path
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:191
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support non-existent path","total":-1,"completed":1,"skipped":142,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:34:13.420: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
Aug 12 08:34:13.564: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237
Aug 12 08:34:13.597: INFO: Creating resource for dynamic PV
Aug 12 08:34:13.597: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-9607-e2e-schqgss
STEP: creating a claim
Aug 12 08:34:13.608: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:34:13.679: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioxlgxx] to have phase Bound
Aug 12 08:34:13.708: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlgxx found but phase is Pending instead of Bound.
Aug 12 08:34:15.727: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlgxx found and phase=Bound (2.047935499s)
STEP: Creating pod pod-subpath-test-dynamicpv-dtgw
STEP: Checking for subpath error in container status
Aug 12 08:34:29.790: INFO: Deleting pod "pod-subpath-test-dynamicpv-dtgw" in namespace "provisioning-9607"
Aug 12 08:34:29.808: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-dtgw" to be fully deleted
STEP: Deleting pod
Aug 12 08:34:39.838: INFO: Deleting pod "pod-subpath-test-dynamicpv-dtgw" in namespace "provisioning-9607"
STEP: Deleting pvc
Aug 12 08:34:39.851: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioxlgxx"
... skipping 9 lines ...

• [SLOW TEST:31.511 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath directory is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath directory is outside the volume [Slow][LinuxOnly]","total":-1,"completed":1,"skipped":1,"failed":0}

SSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:34:44.969: INFO: Driver hostpath.csi.k8s.io doesn't support PreprovisionedPV -- skipping
... skipping 71 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should not mount / map unused volumes in a pod
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:345
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode should not mount / map unused volumes in a pod","total":-1,"completed":1,"skipped":11,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:34:51.212: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275

    Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:185
------------------------------
... skipping 20 lines ...
STEP: creating a claim
Aug 12 08:34:14.090: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:34:14.149: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io74nqs] to have phase Bound
Aug 12 08:34:14.170: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nqs found but phase is Pending instead of Bound.
Aug 12 08:34:16.180: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nqs found and phase=Bound (2.03139105s)
STEP: Creating pod to format volume volume-prep-provisioning-1681
Aug 12 08:34:16.198: INFO: Waiting up to 5m0s for pod "volume-prep-provisioning-1681" in namespace "provisioning-1681" to be "Succeeded or Failed"
Aug 12 08:34:16.203: INFO: Pod "volume-prep-provisioning-1681": Phase="Pending", Reason="", readiness=false. Elapsed: 5.13004ms
Aug 12 08:34:18.210: INFO: Pod "volume-prep-provisioning-1681": Phase="Pending", Reason="", readiness=false. Elapsed: 2.01241731s
Aug 12 08:34:20.230: INFO: Pod "volume-prep-provisioning-1681": Phase="Pending", Reason="", readiness=false. Elapsed: 4.032283761s
Aug 12 08:34:22.243: INFO: Pod "volume-prep-provisioning-1681": Phase="Pending", Reason="", readiness=false. Elapsed: 6.044741425s
Aug 12 08:34:24.251: INFO: Pod "volume-prep-provisioning-1681": Phase="Pending", Reason="", readiness=false. Elapsed: 8.053308739s
Aug 12 08:34:26.256: INFO: Pod "volume-prep-provisioning-1681": Phase="Pending", Reason="", readiness=false. Elapsed: 10.058097101s
Aug 12 08:34:28.261: INFO: Pod "volume-prep-provisioning-1681": Phase="Running", Reason="", readiness=true. Elapsed: 12.062639295s
Aug 12 08:34:30.266: INFO: Pod "volume-prep-provisioning-1681": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.068300662s
STEP: Saw pod success
Aug 12 08:34:30.266: INFO: Pod "volume-prep-provisioning-1681" satisfied condition "Succeeded or Failed"
Aug 12 08:34:30.266: INFO: Deleting pod "volume-prep-provisioning-1681" in namespace "provisioning-1681"
Aug 12 08:34:30.300: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-1681" to be fully deleted
STEP: Creating pod pod-subpath-test-dynamicpv-q4mk
STEP: Checking for subpath error in container status
Aug 12 08:34:46.339: INFO: Deleting pod "pod-subpath-test-dynamicpv-q4mk" in namespace "provisioning-1681"
Aug 12 08:34:46.362: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-q4mk" to be fully deleted
STEP: Deleting pod
Aug 12 08:34:46.371: INFO: Deleting pod "pod-subpath-test-dynamicpv-q4mk" in namespace "provisioning-1681"
STEP: Deleting pvc
Aug 12 08:34:46.385: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io74nqs"
... skipping 14 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should verify container cannot write to subpath readonly volumes [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:419
------------------------------
SS
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should verify container cannot write to subpath readonly volumes [Slow]","total":-1,"completed":1,"skipped":46,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:34:52.060: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 84 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should be able to unmount after the subpath directory is deleted
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:439
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should be able to unmount after the subpath directory is deleted","total":-1,"completed":1,"skipped":161,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] volumeIO
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:35:00.977: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 153 lines ...
Aug 12 08:34:44.278: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:34:44.300: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io2lhnc] to have phase Bound
Aug 12 08:34:44.325: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2lhnc found but phase is Pending instead of Bound.
Aug 12 08:34:46.335: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2lhnc found and phase=Bound (2.034021999s)
STEP: Creating pod pod-subpath-test-dynamicpv-fhhx
STEP: Creating a pod to test subpath
Aug 12 08:34:46.369: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-fhhx" in namespace "provisioning-7807" to be "Succeeded or Failed"
Aug 12 08:34:46.378: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.362806ms
Aug 12 08:34:48.404: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.034232194s
Aug 12 08:34:50.425: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.055206325s
Aug 12 08:34:52.438: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.068483688s
Aug 12 08:34:54.460: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.090475114s
Aug 12 08:34:56.468: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 10.098314836s
Aug 12 08:34:58.486: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Running", Reason="", readiness=true. Elapsed: 12.116607127s
Aug 12 08:35:00.495: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Running", Reason="", readiness=true. Elapsed: 14.12557398s
Aug 12 08:35:02.503: INFO: Pod "pod-subpath-test-dynamicpv-fhhx": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.133409782s
STEP: Saw pod success
Aug 12 08:35:02.503: INFO: Pod "pod-subpath-test-dynamicpv-fhhx" satisfied condition "Succeeded or Failed"
Aug 12 08:35:02.521: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-fhhx container test-container-subpath-dynamicpv-fhhx: <nil>
STEP: delete the pod
Aug 12 08:35:02.572: INFO: Waiting for pod pod-subpath-test-dynamicpv-fhhx to disappear
Aug 12 08:35:02.579: INFO: Pod pod-subpath-test-dynamicpv-fhhx no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-fhhx
Aug 12 08:35:02.579: INFO: Deleting pod "pod-subpath-test-dynamicpv-fhhx" in namespace "provisioning-7807"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support readOnly directory specified in the volumeMount
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:361
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support readOnly directory specified in the volumeMount","total":-1,"completed":2,"skipped":166,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode 
  should not mount / map unused volumes in a pod
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:345
... skipping 48 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should not mount / map unused volumes in a pod
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:345
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode should not mount / map unused volumes in a pod","total":-1,"completed":2,"skipped":15,"failed":0}

SSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (xfs)][Slow] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:35:09.750: INFO: Driver hostpath.csi.k8s.io doesn't support InlineVolume -- skipping
... skipping 24 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 108 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should concurrently access the single read-only volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the single read-only volume from pods on the same node","total":-1,"completed":1,"skipped":27,"failed":0}

SSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:35:19.408: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... skipping 87 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291

    Driver hostpath.csi.k8s.io doesn't support PreprovisionedPV -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:180
------------------------------
... skipping 69 lines ...
[BeforeEach] [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:35:21.567: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename topology
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:191
Aug 12 08:35:21.662: INFO: Driver didn't provide topology keys -- skipping
[AfterEach] [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Aug 12 08:35:21.663: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-9047" for this suite.


S [SKIPPING] [0.134 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail to schedule a pod which has topologies that conflict with AllowedTopologies [It]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:191

    Driver didn't provide topology keys -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:123
------------------------------
... skipping 50 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.008 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 43 lines ...
Aug 12 08:35:01.776: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:35:01.787: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioqhkd4] to have phase Bound
Aug 12 08:35:01.794: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqhkd4 found but phase is Pending instead of Bound.
Aug 12 08:35:03.803: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqhkd4 found and phase=Bound (2.016089758s)
STEP: Expanding non-expandable pvc
Aug 12 08:35:03.820: INFO: currentPvcSize {{1048576 0} {<nil>} 1Mi BinarySI}, newSize {{1074790400 0} {<nil>}  BinarySI}
Aug 12 08:35:03.835: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:05.851: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:07.871: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:09.873: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:11.856: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:13.855: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:15.853: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:17.847: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:19.856: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:21.859: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:23.860: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:25.866: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:27.860: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:29.852: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:31.860: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:33.846: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:35:33.855: INFO: Error updating pvc hostpath.csi.k8s.ioqhkd4: persistentvolumeclaims "hostpath.csi.k8s.ioqhkd4" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Aug 12 08:35:33.855: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioqhkd4"
Aug 12 08:35:33.863: INFO: Waiting up to 5m0s for PersistentVolume pvc-cd1826c2-c1a0-4fc2-8d91-1fde1e96214d to get deleted
Aug 12 08:35:33.868: INFO: PersistentVolume pvc-cd1826c2-c1a0-4fc2-8d91-1fde1e96214d found and phase=Bound (4.30526ms)
Aug 12 08:35:38.875: INFO: PersistentVolume pvc-cd1826c2-c1a0-4fc2-8d91-1fde1e96214d was removed
STEP: Deleting sc
... skipping 8 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should not allow expansion of pvcs without AllowVolumeExpansion property
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:147
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volume-expand should not allow expansion of pvcs without AllowVolumeExpansion property","total":-1,"completed":2,"skipped":455,"failed":0}

SSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:35:39.008: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 151 lines ...

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:180
------------------------------
SSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:35:11.184: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275
Aug 12 08:35:11.224: INFO: Creating resource for dynamic PV
Aug 12 08:35:11.224: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-7046-e2e-scrrrbg
STEP: creating a claim
Aug 12 08:35:11.230: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:35:11.240: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io8rqqc] to have phase Bound
Aug 12 08:35:11.244: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8rqqc found but phase is Pending instead of Bound.
Aug 12 08:35:13.255: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8rqqc found and phase=Bound (2.014734651s)
STEP: Creating pod pod-subpath-test-dynamicpv-rb4s
STEP: Checking for subpath error in container status
Aug 12 08:35:27.303: INFO: Deleting pod "pod-subpath-test-dynamicpv-rb4s" in namespace "provisioning-7046"
Aug 12 08:35:27.320: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-rb4s" to be fully deleted
STEP: Deleting pod
Aug 12 08:35:35.340: INFO: Deleting pod "pod-subpath-test-dynamicpv-rb4s" in namespace "provisioning-7046"
STEP: Deleting pvc
Aug 12 08:35:35.345: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io8rqqc"
... skipping 9 lines ...

• [SLOW TEST:29.217 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275
------------------------------
S
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]","total":-1,"completed":3,"skipped":345,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] 
  should access to two volumes with the same volume mode and retain data across pod recreation on the same node
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:130
... skipping 125 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:130
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node","total":-1,"completed":1,"skipped":8,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support file as subpath [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:227
... skipping 15 lines ...
Aug 12 08:35:07.950: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:35:07.975: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iof8hrw] to have phase Bound
Aug 12 08:35:07.988: INFO: PersistentVolumeClaim hostpath.csi.k8s.iof8hrw found but phase is Pending instead of Bound.
Aug 12 08:35:09.998: INFO: PersistentVolumeClaim hostpath.csi.k8s.iof8hrw found and phase=Bound (2.023328416s)
STEP: Creating pod pod-subpath-test-dynamicpv-jpl5
STEP: Creating a pod to test atomic-volume-subpath
Aug 12 08:35:10.041: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-jpl5" in namespace "provisioning-4458" to be "Succeeded or Failed"
Aug 12 08:35:10.084: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Pending", Reason="", readiness=false. Elapsed: 43.364343ms
Aug 12 08:35:12.096: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.055354552s
Aug 12 08:35:14.103: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.062599458s
Aug 12 08:35:16.123: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.082029912s
Aug 12 08:35:18.134: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.09372811s
Aug 12 08:35:20.156: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Pending", Reason="", readiness=false. Elapsed: 10.114856028s
... skipping 11 lines ...
Aug 12 08:35:44.316: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Running", Reason="", readiness=true. Elapsed: 34.275770727s
Aug 12 08:35:46.328: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Running", Reason="", readiness=true. Elapsed: 36.287416472s
Aug 12 08:35:48.342: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Running", Reason="", readiness=true. Elapsed: 38.301340315s
Aug 12 08:35:50.352: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Running", Reason="", readiness=true. Elapsed: 40.311843424s
Aug 12 08:35:52.366: INFO: Pod "pod-subpath-test-dynamicpv-jpl5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 42.325259577s
STEP: Saw pod success
Aug 12 08:35:52.366: INFO: Pod "pod-subpath-test-dynamicpv-jpl5" satisfied condition "Succeeded or Failed"
Aug 12 08:35:52.392: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-jpl5 container test-container-subpath-dynamicpv-jpl5: <nil>
STEP: delete the pod
Aug 12 08:35:52.504: INFO: Waiting for pod pod-subpath-test-dynamicpv-jpl5 to disappear
Aug 12 08:35:52.523: INFO: Pod pod-subpath-test-dynamicpv-jpl5 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-jpl5
Aug 12 08:35:52.523: INFO: Deleting pod "pod-subpath-test-dynamicpv-jpl5" in namespace "provisioning-4458"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support file as subpath [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:227
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support file as subpath [LinuxOnly]","total":-1,"completed":3,"skipped":205,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:35:57.799: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "CSIInlineVolume" - skipping
... skipping 45 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253

    Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:185
------------------------------
... skipping 83 lines ...
Aug 12 08:35:40.591: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:35:40.608: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iosg5j9] to have phase Bound
Aug 12 08:35:40.620: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosg5j9 found but phase is Pending instead of Bound.
Aug 12 08:35:42.631: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosg5j9 found and phase=Bound (2.022471314s)
STEP: Creating pod pod-subpath-test-dynamicpv-5gqk
STEP: Creating a pod to test subpath
Aug 12 08:35:42.684: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-5gqk" in namespace "provisioning-4721" to be "Succeeded or Failed"
Aug 12 08:35:42.696: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 11.556533ms
Aug 12 08:35:44.712: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.027413301s
Aug 12 08:35:46.730: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.045393989s
Aug 12 08:35:48.742: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.05810879s
Aug 12 08:35:50.757: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.07227863s
Aug 12 08:35:52.766: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.081516534s
Aug 12 08:35:54.775: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Pending", Reason="", readiness=false. Elapsed: 12.090525752s
Aug 12 08:35:56.789: INFO: Pod "pod-subpath-test-dynamicpv-5gqk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.105175562s
STEP: Saw pod success
Aug 12 08:35:56.790: INFO: Pod "pod-subpath-test-dynamicpv-5gqk" satisfied condition "Succeeded or Failed"
Aug 12 08:35:56.803: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-5gqk container test-container-subpath-dynamicpv-5gqk: <nil>
STEP: delete the pod
Aug 12 08:35:56.828: INFO: Waiting for pod pod-subpath-test-dynamicpv-5gqk to disappear
Aug 12 08:35:56.844: INFO: Pod pod-subpath-test-dynamicpv-5gqk no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-5gqk
Aug 12 08:35:56.844: INFO: Deleting pod "pod-subpath-test-dynamicpv-5gqk" in namespace "provisioning-4721"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support existing single file [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing single file [LinuxOnly]","total":-1,"completed":3,"skipped":723,"failed":0}

SSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] provisioning
... skipping 198 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:130
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node","total":-1,"completed":2,"skipped":202,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:36:05.706: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... skipping 34 lines ...

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
SS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode 
  should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291

[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:35:58.685: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291
Aug 12 08:35:58.852: INFO: Creating resource for dynamic PV
Aug 12 08:35:58.852: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-5116-e2e-scnf4xk
STEP: creating a claim
Aug 12 08:35:58.892: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iof4d54] to have phase Bound
Aug 12 08:35:58.903: INFO: PersistentVolumeClaim hostpath.csi.k8s.iof4d54 found but phase is Pending instead of Bound.
Aug 12 08:36:00.921: INFO: PersistentVolumeClaim hostpath.csi.k8s.iof4d54 found and phase=Bound (2.028861203s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Aug 12 08:36:03.015: INFO: Deleting pod "pod-783472f1-645f-4092-b4e8-2d957636e8ad" in namespace "volumemode-5116"
Aug 12 08:36:03.020: INFO: Wait up to 5m0s for pod "pod-783472f1-645f-4092-b4e8-2d957636e8ad" to be fully deleted
STEP: Deleting pvc
Aug 12 08:36:15.036: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iof4d54"
Aug 12 08:36:15.046: INFO: Waiting up to 5m0s for PersistentVolume pvc-80b4ded2-c8f6-4171-9f40-3a3d68fce649 to get deleted
Aug 12 08:36:15.053: INFO: PersistentVolume pvc-80b4ded2-c8f6-4171-9f40-3a3d68fce649 found and phase=Bound (6.401002ms)
... skipping 7 lines ...

• [SLOW TEST:21.439 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail to use a volume in a pod with mismatched mode [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode should fail to use a volume in a pod with mismatched mode [Slow]","total":-1,"completed":4,"skipped":395,"failed":0}

S
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:35:40.768: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253
Aug 12 08:35:40.830: INFO: Creating resource for dynamic PV
Aug 12 08:35:40.831: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-1212-e2e-scxcfnl
STEP: creating a claim
Aug 12 08:35:40.841: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:35:40.875: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iow4ggh] to have phase Bound
Aug 12 08:35:40.889: INFO: PersistentVolumeClaim hostpath.csi.k8s.iow4ggh found but phase is Pending instead of Bound.
Aug 12 08:35:42.899: INFO: PersistentVolumeClaim hostpath.csi.k8s.iow4ggh found and phase=Bound (2.024558096s)
STEP: Creating pod pod-subpath-test-dynamicpv-k45m
STEP: Checking for subpath error in container status
Aug 12 08:36:10.976: INFO: Deleting pod "pod-subpath-test-dynamicpv-k45m" in namespace "provisioning-1212"
Aug 12 08:36:10.992: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-k45m" to be fully deleted
STEP: Deleting pod
Aug 12 08:36:15.015: INFO: Deleting pod "pod-subpath-test-dynamicpv-k45m" in namespace "provisioning-1212"
STEP: Deleting pvc
Aug 12 08:36:15.020: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iow4ggh"
... skipping 9 lines ...

• [SLOW TEST:39.362 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath file is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253
------------------------------
SSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
... skipping 208 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should access to two volumes with different volume mode and retain data across pod recreation on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:210
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with different volume mode and retain data across pod recreation on the same node","total":-1,"completed":2,"skipped":661,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:36:36.935: INFO: Driver hostpath.csi.k8s.io doesn't support PreprovisionedPV -- skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291

    Driver hostpath.csi.k8s.io doesn't support PreprovisionedPV -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:180
------------------------------
... skipping 48 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should resize volume when PVC is edited while pod is using it
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:232
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it","total":-1,"completed":4,"skipped":852,"failed":0}

SSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:36:39.955: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 154 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support restarting containers using directory as subpath [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:317
------------------------------
S
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support restarting containers using directory as subpath [Slow]","total":-1,"completed":2,"skipped":115,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:36:40.985: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... skipping 70 lines ...
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support creating multiple subpath from same volumes [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:291

{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath file is outside the volume [Slow][LinuxOnly]","total":-1,"completed":4,"skipped":417,"failed":0}
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:36:20.135: INFO: >>> kubeConfig: /root/.kube/config
... skipping 8 lines ...
Aug 12 08:36:20.293: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:36:20.332: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io5pwvj] to have phase Bound
Aug 12 08:36:20.342: INFO: PersistentVolumeClaim hostpath.csi.k8s.io5pwvj found but phase is Pending instead of Bound.
Aug 12 08:36:22.348: INFO: PersistentVolumeClaim hostpath.csi.k8s.io5pwvj found and phase=Bound (2.01578354s)
STEP: Creating pod pod-subpath-test-dynamicpv-8tdj
STEP: Creating a pod to test multi_subpath
Aug 12 08:36:22.368: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-8tdj" in namespace "provisioning-5295" to be "Succeeded or Failed"
Aug 12 08:36:22.375: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.846254ms
Aug 12 08:36:24.404: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.035025772s
Aug 12 08:36:26.408: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.039605137s
Aug 12 08:36:28.416: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.047470672s
Aug 12 08:36:30.435: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.066715747s
Aug 12 08:36:32.447: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.078123578s
Aug 12 08:36:34.461: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.091913796s
Aug 12 08:36:36.482: INFO: Pod "pod-subpath-test-dynamicpv-8tdj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.113517682s
STEP: Saw pod success
Aug 12 08:36:36.482: INFO: Pod "pod-subpath-test-dynamicpv-8tdj" satisfied condition "Succeeded or Failed"
Aug 12 08:36:36.495: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-8tdj container test-container-subpath-dynamicpv-8tdj: <nil>
STEP: delete the pod
Aug 12 08:36:36.549: INFO: Waiting for pod pod-subpath-test-dynamicpv-8tdj to disappear
Aug 12 08:36:36.559: INFO: Pod pod-subpath-test-dynamicpv-8tdj no longer exists
STEP: Deleting pod
Aug 12 08:36:36.559: INFO: Deleting pod "pod-subpath-test-dynamicpv-8tdj" in namespace "provisioning-5295"
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support creating multiple subpath from same volumes [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:291
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support creating multiple subpath from same volumes [Slow]","total":-1,"completed":5,"skipped":417,"failed":0}

SSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (default fs)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (default fs)] provisioning
... skipping 257 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should resize volume when PVC is edited while pod is using it
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:232
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it","total":-1,"completed":5,"skipped":491,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:36:50.203: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "CSIInlineVolume" - skipping
... skipping 162 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 102 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should store data
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumes should store data","total":-1,"completed":2,"skipped":43,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:37:00.588: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 36 lines ...
Aug 12 08:36:37.141: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:36:37.159: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iohvd5k] to have phase Bound
Aug 12 08:36:37.171: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohvd5k found but phase is Pending instead of Bound.
Aug 12 08:36:39.184: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohvd5k found and phase=Bound (2.02458399s)
STEP: Creating pod pod-subpath-test-dynamicpv-55xk
STEP: Creating a pod to test subpath
Aug 12 08:36:39.213: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-55xk" in namespace "provisioning-5753" to be "Succeeded or Failed"
Aug 12 08:36:39.222: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 7.2293ms
Aug 12 08:36:41.236: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.021540124s
Aug 12 08:36:43.250: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.035683343s
Aug 12 08:36:45.261: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.046648993s
Aug 12 08:36:47.288: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.072733697s
Aug 12 08:36:49.295: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.080199776s
... skipping 5 lines ...
Aug 12 08:37:01.377: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 22.162240884s
Aug 12 08:37:03.391: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 24.176033849s
Aug 12 08:37:05.404: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 26.189657838s
Aug 12 08:37:07.417: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Pending", Reason="", readiness=false. Elapsed: 28.20184978s
Aug 12 08:37:09.431: INFO: Pod "pod-subpath-test-dynamicpv-55xk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 30.216071689s
STEP: Saw pod success
Aug 12 08:37:09.431: INFO: Pod "pod-subpath-test-dynamicpv-55xk" satisfied condition "Succeeded or Failed"
Aug 12 08:37:09.440: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-55xk container test-container-subpath-dynamicpv-55xk: <nil>
STEP: delete the pod
Aug 12 08:37:09.511: INFO: Waiting for pod pod-subpath-test-dynamicpv-55xk to disappear
Aug 12 08:37:09.516: INFO: Pod pod-subpath-test-dynamicpv-55xk no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-55xk
Aug 12 08:37:09.516: INFO: Deleting pod "pod-subpath-test-dynamicpv-55xk" in namespace "provisioning-5753"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support readOnly file specified in the volumeMount [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:376
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support readOnly file specified in the volumeMount [LinuxOnly]","total":-1,"completed":3,"skipped":725,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand 
  Verify if offline PVC expansion works
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:162
... skipping 51 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    Verify if offline PVC expansion works
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:162
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand Verify if offline PVC expansion works","total":-1,"completed":3,"skipped":440,"failed":0}

SSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:37:15.359: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 45 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253

    Driver supports dynamic provisioning, skipping InlineVolume pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 8 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275

    Driver supports dynamic provisioning, skipping InlineVolume pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 41 lines ...
Aug 12 08:36:41.959: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:36:41.968: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioh69zl] to have phase Bound
Aug 12 08:36:41.975: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioh69zl found but phase is Pending instead of Bound.
Aug 12 08:36:43.980: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioh69zl found and phase=Bound (2.011956884s)
STEP: Creating pod pod-subpath-test-dynamicpv-pv49
STEP: Creating a pod to test subpath
Aug 12 08:36:44.024: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-pv49" in namespace "provisioning-2029" to be "Succeeded or Failed"
Aug 12 08:36:44.048: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 24.498346ms
Aug 12 08:36:46.059: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 2.035043203s
Aug 12 08:36:48.093: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 4.069739566s
Aug 12 08:36:50.103: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 6.079404737s
Aug 12 08:36:52.109: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 8.084829618s
Aug 12 08:36:54.125: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 10.101549592s
Aug 12 08:36:56.138: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 12.114072647s
Aug 12 08:36:58.144: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 14.119913806s
Aug 12 08:37:00.160: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 16.136041s
Aug 12 08:37:02.171: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.147420386s
STEP: Saw pod success
Aug 12 08:37:02.171: INFO: Pod "pod-subpath-test-dynamicpv-pv49" satisfied condition "Succeeded or Failed"
Aug 12 08:37:02.191: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-pv49 container test-container-subpath-dynamicpv-pv49: <nil>
STEP: delete the pod
Aug 12 08:37:02.237: INFO: Waiting for pod pod-subpath-test-dynamicpv-pv49 to disappear
Aug 12 08:37:02.259: INFO: Pod pod-subpath-test-dynamicpv-pv49 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-pv49
Aug 12 08:37:02.259: INFO: Deleting pod "pod-subpath-test-dynamicpv-pv49" in namespace "provisioning-2029"
STEP: Creating pod pod-subpath-test-dynamicpv-pv49
STEP: Creating a pod to test subpath
Aug 12 08:37:02.288: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-pv49" in namespace "provisioning-2029" to be "Succeeded or Failed"
Aug 12 08:37:02.292: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 4.70505ms
Aug 12 08:37:04.300: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 2.01263291s
Aug 12 08:37:06.324: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 4.036365317s
Aug 12 08:37:08.332: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 6.043837136s
Aug 12 08:37:10.341: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 8.052975685s
Aug 12 08:37:12.352: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 10.064480342s
Aug 12 08:37:14.367: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 12.079288416s
Aug 12 08:37:16.378: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Pending", Reason="", readiness=false. Elapsed: 14.089902087s
Aug 12 08:37:18.393: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Running", Reason="", readiness=true. Elapsed: 16.104916041s
Aug 12 08:37:20.406: INFO: Pod "pod-subpath-test-dynamicpv-pv49": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.118174094s
STEP: Saw pod success
Aug 12 08:37:20.406: INFO: Pod "pod-subpath-test-dynamicpv-pv49" satisfied condition "Succeeded or Failed"
Aug 12 08:37:20.427: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-pv49 container test-container-subpath-dynamicpv-pv49: <nil>
STEP: delete the pod
Aug 12 08:37:20.470: INFO: Waiting for pod pod-subpath-test-dynamicpv-pv49 to disappear
Aug 12 08:37:20.484: INFO: Pod pod-subpath-test-dynamicpv-pv49 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-pv49
Aug 12 08:37:20.484: INFO: Deleting pod "pod-subpath-test-dynamicpv-pv49" in namespace "provisioning-2029"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support existing directories when readOnly specified in the volumeSource
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:391
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing directories when readOnly specified in the volumeSource","total":-1,"completed":3,"skipped":293,"failed":0}

SSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:37:25.740: INFO: Driver supports dynamic provisioning, skipping InlineVolume pattern
... skipping 24 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 109 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264

    Driver supports dynamic provisioning, skipping InlineVolume pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 174 lines ...
[BeforeEach] [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:37:27.378: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename topology
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:191
Aug 12 08:37:27.436: INFO: Driver didn't provide topology keys -- skipping
[AfterEach] [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Aug 12 08:37:27.438: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-5806" for this suite.


S [SKIPPING] [0.074 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail to schedule a pod which has topologies that conflict with AllowedTopologies [It]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:191

    Driver didn't provide topology keys -- skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:123
------------------------------
... skipping 122 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should concurrently access the single volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node","total":-1,"completed":6,"skipped":705,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:37:41.128: INFO: Driver supports dynamic provisioning, skipping InlineVolume pattern
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237

    Driver supports dynamic provisioning, skipping InlineVolume pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:793
------------------------------
... skipping 122 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should concurrently access the single volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node","total":-1,"completed":6,"skipped":907,"failed":0}

SS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volume-expand 
  should not allow expansion of pvcs without AllowVolumeExpansion property
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:147
... skipping 16 lines ...
STEP: creating a claim
Aug 12 08:37:15.035: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iobkbh9] to have phase Bound
Aug 12 08:37:15.043: INFO: PersistentVolumeClaim hostpath.csi.k8s.iobkbh9 found but phase is Pending instead of Bound.
Aug 12 08:37:17.048: INFO: PersistentVolumeClaim hostpath.csi.k8s.iobkbh9 found and phase=Bound (2.012816538s)
STEP: Expanding non-expandable pvc
Aug 12 08:37:17.056: INFO: currentPvcSize {{1048576 0} {<nil>} 1Mi BinarySI}, newSize {{1074790400 0} {<nil>}  BinarySI}
Aug 12 08:37:17.068: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:19.091: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:21.094: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:23.085: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:25.079: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:27.083: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:29.088: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:31.078: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:33.083: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:35.088: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:37.085: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:39.095: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:41.099: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:43.092: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:45.110: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:47.088: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug 12 08:37:47.135: INFO: Error updating pvc hostpath.csi.k8s.iobkbh9: persistentvolumeclaims "hostpath.csi.k8s.iobkbh9" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Aug 12 08:37:47.135: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iobkbh9"
Aug 12 08:37:47.152: INFO: Waiting up to 5m0s for PersistentVolume pvc-f8fcf28d-07c7-455c-9e4a-88ff1e3e67a9 to get deleted
Aug 12 08:37:47.161: INFO: PersistentVolume pvc-f8fcf28d-07c7-455c-9e4a-88ff1e3e67a9 found and phase=Bound (8.827401ms)
Aug 12 08:37:52.171: INFO: PersistentVolume pvc-f8fcf28d-07c7-455c-9e4a-88ff1e3e67a9 was removed
STEP: Deleting sc
... skipping 8 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should not allow expansion of pvcs without AllowVolumeExpansion property
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:147
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volume-expand should not allow expansion of pvcs without AllowVolumeExpansion property","total":-1,"completed":4,"skipped":798,"failed":0}

SSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support existing directory
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:202
... skipping 15 lines ...
Aug 12 08:37:28.168: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:37:28.188: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iox2kjs] to have phase Bound
Aug 12 08:37:28.208: INFO: PersistentVolumeClaim hostpath.csi.k8s.iox2kjs found but phase is Pending instead of Bound.
Aug 12 08:37:30.218: INFO: PersistentVolumeClaim hostpath.csi.k8s.iox2kjs found and phase=Bound (2.030520012s)
STEP: Creating pod pod-subpath-test-dynamicpv-vkzl
STEP: Creating a pod to test subpath
Aug 12 08:37:30.252: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-vkzl" in namespace "provisioning-2794" to be "Succeeded or Failed"
Aug 12 08:37:30.270: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 17.379324ms
Aug 12 08:37:32.274: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.022125004s
Aug 12 08:37:34.294: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.041739938s
Aug 12 08:37:36.314: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.061563417s
Aug 12 08:37:38.338: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.085318358s
Aug 12 08:37:40.351: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.099129704s
... skipping 2 lines ...
Aug 12 08:37:46.373: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.120383223s
Aug 12 08:37:48.379: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 18.126594111s
Aug 12 08:37:50.388: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 20.136081904s
Aug 12 08:37:52.399: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Pending", Reason="", readiness=false. Elapsed: 22.146608512s
Aug 12 08:37:54.405: INFO: Pod "pod-subpath-test-dynamicpv-vkzl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.152258195s
STEP: Saw pod success
Aug 12 08:37:54.405: INFO: Pod "pod-subpath-test-dynamicpv-vkzl" satisfied condition "Succeeded or Failed"
Aug 12 08:37:54.411: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-vkzl container test-container-volume-dynamicpv-vkzl: <nil>
STEP: delete the pod
Aug 12 08:37:54.486: INFO: Waiting for pod pod-subpath-test-dynamicpv-vkzl to disappear
Aug 12 08:37:54.498: INFO: Pod pod-subpath-test-dynamicpv-vkzl no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-vkzl
Aug 12 08:37:54.498: INFO: Deleting pod "pod-subpath-test-dynamicpv-vkzl" in namespace "provisioning-2794"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support existing directory
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:202
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing directory","total":-1,"completed":4,"skipped":803,"failed":0}

SS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes 
  should allow exec of files on the volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:192
... skipping 15 lines ...
Aug 12 08:37:41.900: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:37:41.919: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9hplw] to have phase Bound
Aug 12 08:37:41.934: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9hplw found but phase is Pending instead of Bound.
Aug 12 08:37:43.942: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9hplw found and phase=Bound (2.023273105s)
STEP: Creating pod exec-volume-test-dynamicpv-j787
STEP: Creating a pod to test exec-volume-test
Aug 12 08:37:43.967: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-j787" in namespace "volume-5816" to be "Succeeded or Failed"
Aug 12 08:37:43.975: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 8.830013ms
Aug 12 08:37:45.987: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 2.020014188s
Aug 12 08:37:47.996: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 4.029104317s
Aug 12 08:37:50.003: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 6.036067263s
Aug 12 08:37:52.014: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 8.047534303s
Aug 12 08:37:54.021: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 10.054054606s
Aug 12 08:37:56.031: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Pending", Reason="", readiness=false. Elapsed: 12.064265571s
Aug 12 08:37:58.039: INFO: Pod "exec-volume-test-dynamicpv-j787": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.072464702s
STEP: Saw pod success
Aug 12 08:37:58.039: INFO: Pod "exec-volume-test-dynamicpv-j787" satisfied condition "Succeeded or Failed"
Aug 12 08:37:58.046: INFO: Trying to get logs from node csi-prow-worker2 pod exec-volume-test-dynamicpv-j787 container exec-container-dynamicpv-j787: <nil>
STEP: delete the pod
Aug 12 08:37:58.099: INFO: Waiting for pod exec-volume-test-dynamicpv-j787 to disappear
Aug 12 08:37:58.108: INFO: Pod exec-volume-test-dynamicpv-j787 no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-j787
Aug 12 08:37:58.108: INFO: Deleting pod "exec-volume-test-dynamicpv-j787" in namespace "volume-5816"
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should allow exec of files on the volume
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:192
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes should allow exec of files on the volume","total":-1,"completed":7,"skipped":919,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode 
  should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291

[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:37:52.251: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291
Aug 12 08:37:52.342: INFO: Creating resource for dynamic PV
Aug 12 08:37:52.342: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-1957-e2e-scddt8s
STEP: creating a claim
Aug 12 08:37:52.364: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iort5tk] to have phase Bound
Aug 12 08:37:52.386: INFO: PersistentVolumeClaim hostpath.csi.k8s.iort5tk found but phase is Pending instead of Bound.
Aug 12 08:37:54.398: INFO: PersistentVolumeClaim hostpath.csi.k8s.iort5tk found and phase=Bound (2.03429098s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Aug 12 08:37:56.448: INFO: Deleting pod "pod-a17b5c44-55b9-43f1-be11-c9ad6f68df11" in namespace "volumemode-1957"
Aug 12 08:37:56.464: INFO: Wait up to 5m0s for pod "pod-a17b5c44-55b9-43f1-be11-c9ad6f68df11" to be fully deleted
STEP: Deleting pvc
Aug 12 08:38:04.483: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iort5tk"
Aug 12 08:38:04.497: INFO: Waiting up to 5m0s for PersistentVolume pvc-730b9bc0-e609-4ebd-91d4-d7efdcc3920d to get deleted
Aug 12 08:38:04.512: INFO: PersistentVolume pvc-730b9bc0-e609-4ebd-91d4-d7efdcc3920d found and phase=Bound (14.950191ms)
... skipping 7 lines ...

• [SLOW TEST:17.325 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail to use a volume in a pod with mismatched mode [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:291
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode should fail to use a volume in a pod with mismatched mode [Slow]","total":-1,"completed":5,"skipped":802,"failed":0}

SSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
Aug 12 08:38:09.604: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 85 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should concurrently access the single read-only volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
------------------------------
S
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should concurrently access the single read-only volume from pods on the same node","total":-1,"completed":3,"skipped":100,"failed":0}

SSS
------------------------------
Aug 12 08:38:09.895: INFO: Running AfterSuite actions on all nodes

S
... skipping 59 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    Verify if offline PVC expansion works
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:162
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand Verify if offline PVC expansion works","total":-1,"completed":4,"skipped":693,"failed":0}
Aug 12 08:38:19.858: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support restarting containers using file as subpath [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:332
... skipping 56 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should support restarting containers using file as subpath [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:332
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support restarting containers using file as subpath [Slow][LinuxOnly]","total":-1,"completed":5,"skipped":1066,"failed":0}
Aug 12 08:38:30.054: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:127
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Aug 12 08:37:59.623: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264
Aug 12 08:37:59.718: INFO: Creating resource for dynamic PV
Aug 12 08:37:59.718: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-3986-e2e-scg5kqc
STEP: creating a claim
Aug 12 08:37:59.724: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:37:59.732: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iox29v4] to have phase Bound
Aug 12 08:37:59.748: INFO: PersistentVolumeClaim hostpath.csi.k8s.iox29v4 found but phase is Pending instead of Bound.
Aug 12 08:38:01.760: INFO: PersistentVolumeClaim hostpath.csi.k8s.iox29v4 found and phase=Bound (2.028646796s)
STEP: Creating pod pod-subpath-test-dynamicpv-hcn6
STEP: Checking for subpath error in container status
Aug 12 08:38:13.857: INFO: Deleting pod "pod-subpath-test-dynamicpv-hcn6" in namespace "provisioning-3986"
Aug 12 08:38:13.871: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-hcn6" to be fully deleted
STEP: Deleting pod
Aug 12 08:38:25.888: INFO: Deleting pod "pod-subpath-test-dynamicpv-hcn6" in namespace "provisioning-3986"
STEP: Deleting pvc
Aug 12 08:38:25.893: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iox29v4"
... skipping 9 lines ...

• [SLOW TEST:31.335 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]","total":-1,"completed":5,"skipped":805,"failed":0}
Aug 12 08:38:30.974: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumeIO 
  should write files of various sizes, verify size, validate content [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_io.go:144
... skipping 41 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volumeIO
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should write files of various sizes, verify size, validate content [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_io.go:144
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumeIO should write files of various sizes, verify size, validate content [Slow]","total":-1,"completed":7,"skipped":909,"failed":0}
Aug 12 08:38:40.370: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes 
  should store data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
... skipping 78 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should store data
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes should store data","total":-1,"completed":8,"skipped":958,"failed":0}
Aug 12 08:38:50.404: INFO: Running AfterSuite actions on all nodes
Aug 12 08:38:50.404: INFO: Running AfterSuite actions on node 1
Aug 12 08:38:50.404: INFO: Dumping logs locally to: /logs/artifacts
Aug 12 08:38:50.406: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory


Ran 37 of 5433 Specs in 277.358 seconds
SUCCESS! -- 37 Passed | 0 Failed | 0 Pending | 5396 Skipped


Ginkgo ran 1 suite in 4m41.769399137s
Test Suite Passed
Wed Aug 12 08:38:50 UTC 2020 go1.15rc2 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/livenessprobe/release-tools/filter-junit.go -t=External Storage -o /logs/artifacts/junit_parallel.xml /logs/artifacts/junit_01.xml /logs/artifacts/junit_02.xml /logs/artifacts/junit_03.xml /logs/artifacts/junit_04.xml /logs/artifacts/junit_05.xml /logs/artifacts/junit_06.xml /logs/artifacts/junit_07.xml
Wed Aug 12 08:38:53 UTC 2020 go1.15rc2 /home/prow/go/src/github.com/onsi/ginkgo$ git fetch --depth=1 https://github.com/onsi/ginkgo v1.7.0
... skipping 99 lines ...
STEP: creating a claim
Aug 12 08:39:05.604: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:39:05.648: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioj5x2g] to have phase Bound
Aug 12 08:39:05.677: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioj5x2g found but phase is Pending instead of Bound.
Aug 12 08:39:07.686: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioj5x2g found and phase=Bound (2.037548093s)
STEP: starting a pod to use the claim
Aug 12 08:39:07.756: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-5pstl" in namespace "snapshotting-2229" to be "Succeeded or Failed"
Aug 12 08:39:07.770: INFO: Pod "pvc-snapshottable-tester-5pstl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.305102ms
Aug 12 08:39:09.778: INFO: Pod "pvc-snapshottable-tester-5pstl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.022171453s
Aug 12 08:39:11.798: INFO: Pod "pvc-snapshottable-tester-5pstl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.041618708s
Aug 12 08:39:13.811: INFO: Pod "pvc-snapshottable-tester-5pstl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.054839904s
Aug 12 08:39:15.819: INFO: Pod "pvc-snapshottable-tester-5pstl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.062891346s
Aug 12 08:39:17.823: INFO: Pod "pvc-snapshottable-tester-5pstl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.067474927s
STEP: Saw pod success
Aug 12 08:39:17.824: INFO: Pod "pvc-snapshottable-tester-5pstl" satisfied condition "Succeeded or Failed"
Aug 12 08:39:17.832: INFO: Pod pvc-snapshottable-tester-5pstl has the following logs: 
Aug 12 08:39:17.832: INFO: Deleting pod "pvc-snapshottable-tester-5pstl" in namespace "snapshotting-2229"
Aug 12 08:39:17.845: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-5pstl" to be fully deleted
Aug 12 08:39:17.852: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioj5x2g] to have phase Bound
Aug 12 08:39:17.858: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioj5x2g found and phase=Bound (6.393274ms)
STEP: checking the claim
... skipping 54 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:116
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:191
        should delete the VolumeSnapshotContent according to its deletion policy
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:208
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should delete the VolumeSnapshotContent according to its deletion policy","total":-1,"completed":1,"skipped":361,"failed":0}

SSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should create snapshot objects correctly
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:223
... skipping 17 lines ...
STEP: creating a claim
Aug 12 08:39:07.330: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:39:07.373: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iohr6p8] to have phase Bound
Aug 12 08:39:07.381: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohr6p8 found but phase is Pending instead of Bound.
Aug 12 08:39:09.386: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohr6p8 found and phase=Bound (2.013225143s)
STEP: starting a pod to use the claim
Aug 12 08:39:09.399: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-4jx5w" in namespace "snapshotting-235" to be "Succeeded or Failed"
Aug 12 08:39:09.402: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 3.370439ms
Aug 12 08:39:11.414: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 2.015221214s
Aug 12 08:39:13.420: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 4.020903297s
Aug 12 08:39:15.444: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 6.044754277s
Aug 12 08:39:17.454: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 8.055176661s
Aug 12 08:39:19.460: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 10.060801495s
Aug 12 08:39:21.471: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 12.072266984s
Aug 12 08:39:23.483: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 14.083923147s
Aug 12 08:39:25.495: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 16.096442599s
Aug 12 08:39:27.514: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Pending", Reason="", readiness=false. Elapsed: 18.115204707s
Aug 12 08:39:29.543: INFO: Pod "pvc-snapshottable-tester-4jx5w": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.144132203s
STEP: Saw pod success
Aug 12 08:39:29.543: INFO: Pod "pvc-snapshottable-tester-4jx5w" satisfied condition "Succeeded or Failed"
Aug 12 08:39:29.580: INFO: Pod pvc-snapshottable-tester-4jx5w has the following logs: 
Aug 12 08:39:29.580: INFO: Deleting pod "pvc-snapshottable-tester-4jx5w" in namespace "snapshotting-235"
Aug 12 08:39:29.606: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-4jx5w" to be fully deleted
Aug 12 08:39:29.621: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iohr6p8] to have phase Bound
Aug 12 08:39:29.635: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohr6p8 found and phase=Bound (13.688463ms)
STEP: checking the claim
... skipping 47 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:116
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:191
        should create snapshot objects correctly
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:223
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should create snapshot objects correctly","total":-1,"completed":1,"skipped":668,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
Aug 12 08:39:41.092: INFO: Running AfterSuite actions on all nodes


... skipping 20 lines ...
STEP: creating a claim
Aug 12 08:39:04.464: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:39:04.496: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iopc9tl] to have phase Bound
Aug 12 08:39:04.499: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopc9tl found but phase is Pending instead of Bound.
Aug 12 08:39:06.528: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopc9tl found and phase=Bound (2.031828856s)
STEP: starting a pod to use the claim
Aug 12 08:39:06.568: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-n6xgt" in namespace "snapshotting-5011" to be "Succeeded or Failed"
Aug 12 08:39:06.578: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Pending", Reason="", readiness=false. Elapsed: 10.124096ms
Aug 12 08:39:08.588: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Pending", Reason="", readiness=false. Elapsed: 2.019547626s
Aug 12 08:39:10.592: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.024293124s
Aug 12 08:39:12.602: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.033697764s
Aug 12 08:39:14.619: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.050812868s
Aug 12 08:39:16.635: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Pending", Reason="", readiness=false. Elapsed: 10.067127408s
Aug 12 08:39:18.643: INFO: Pod "pvc-snapshottable-tester-n6xgt": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.074996248s
STEP: Saw pod success
Aug 12 08:39:18.643: INFO: Pod "pvc-snapshottable-tester-n6xgt" satisfied condition "Succeeded or Failed"
Aug 12 08:39:18.656: INFO: Pod pvc-snapshottable-tester-n6xgt has the following logs: 
Aug 12 08:39:18.656: INFO: Deleting pod "pvc-snapshottable-tester-n6xgt" in namespace "snapshotting-5011"
Aug 12 08:39:18.687: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-n6xgt" to be fully deleted
Aug 12 08:39:18.699: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iopc9tl] to have phase Bound
Aug 12 08:39:18.705: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopc9tl found and phase=Bound (5.35026ms)
STEP: checking the claim
... skipping 45 lines ...
Aug 12 08:39:47.140: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
Aug 12 08:39:48.148: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
Aug 12 08:39:49.156: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
Aug 12 08:39:50.164: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
Aug 12 08:39:51.182: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
Aug 12 08:39:52.191: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
Aug 12 08:39:53.191: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:187
Aug 12 08:39:53.191: INFO: deleting snapshot "snapshotting-5011"/"snapshot-2b4qd"
Aug 12 08:39:53.198: INFO: deleting snapshot content ""/"snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e"
Aug 12 08:39:53.218: INFO: Waiting up to 5m0s for volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e to be deleted
Aug 12 08:39:53.222: INFO: volumesnapshotcontents snapcontent-f812f361-4ef7-4e37-95af-fe0c44e46f0e has been found and is not deleted
... skipping 24 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:116
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:191
        should delete the VolumeSnapshotContent according to its deletion policy
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:208
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should delete the VolumeSnapshotContent according to its deletion policy","total":-1,"completed":1,"skipped":118,"failed":0}
Aug 12 08:40:00.351: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] provisioning 
  should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/provisioning.go:198
... skipping 109 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/provisioning.go:198
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] provisioning should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]","total":-1,"completed":1,"skipped":1246,"failed":0}
Aug 12 08:40:02.839: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] provisioning 
  should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/provisioning.go:198
... skipping 115 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:126
    should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/provisioning.go:198
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] provisioning should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]","total":-1,"completed":1,"skipped":553,"failed":0}
Aug 12 08:40:31.858: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should restore from snapshot with saved data after modifying source data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:244
... skipping 16 lines ...
STEP: creating a claim
Aug 12 08:39:27.411: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:39:27.424: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iopqjkb] to have phase Bound
Aug 12 08:39:27.440: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopqjkb found but phase is Pending instead of Bound.
Aug 12 08:39:29.458: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopqjkb found and phase=Bound (2.03211672s)
STEP: starting a pod to use the claim
Aug 12 08:39:29.487: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-rjb7f" in namespace "snapshotting-4158" to be "Succeeded or Failed"
Aug 12 08:39:29.495: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 7.929835ms
Aug 12 08:39:31.507: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 2.020424946s
Aug 12 08:39:33.526: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 4.038702839s
Aug 12 08:39:35.533: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 6.045619208s
Aug 12 08:39:37.542: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 8.055273859s
Aug 12 08:39:39.548: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 10.060610449s
Aug 12 08:39:41.576: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 12.089509016s
Aug 12 08:39:43.585: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 14.097612789s
Aug 12 08:39:45.593: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 16.105841523s
Aug 12 08:39:47.603: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Pending", Reason="", readiness=false. Elapsed: 18.115681398s
Aug 12 08:39:49.618: INFO: Pod "pvc-snapshottable-tester-rjb7f": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.131565106s
STEP: Saw pod success
Aug 12 08:39:49.619: INFO: Pod "pvc-snapshottable-tester-rjb7f" satisfied condition "Succeeded or Failed"
Aug 12 08:39:49.640: INFO: Pod pvc-snapshottable-tester-rjb7f has the following logs: 
Aug 12 08:39:49.641: INFO: Deleting pod "pvc-snapshottable-tester-rjb7f" in namespace "snapshotting-4158"
Aug 12 08:39:49.675: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-rjb7f" to be fully deleted
Aug 12 08:39:49.687: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iopqjkb] to have phase Bound
Aug 12 08:39:49.694: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopqjkb found and phase=Bound (7.047274ms)
STEP: checking the claim
... skipping 8 lines ...
Aug 12 08:39:51.798: INFO: WaitUntil finished successfully after 2.031293564s
Aug 12 08:39:51.811: INFO: received snapshotStatus map[boundVolumeSnapshotContentName:snapcontent-e8605064-d021-44df-94a6-157381bc83f9 creationTime:2020-08-12T08:39:49Z readyToUse:true restoreSize:1Mi]
Aug 12 08:39:51.811: INFO: snapshotContentName snapcontent-e8605064-d021-44df-94a6-157381bc83f9
[It] should restore from snapshot with saved data after modifying source data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:244
STEP: modifying the data in the source PVC
Aug 12 08:39:51.826: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-tlp2s" in namespace "snapshotting-4158" to be "Succeeded or Failed"
Aug 12 08:39:51.833: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Pending", Reason="", readiness=false. Elapsed: 6.129321ms
Aug 12 08:39:53.844: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Pending", Reason="", readiness=false. Elapsed: 2.01753005s
Aug 12 08:39:55.852: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Pending", Reason="", readiness=false. Elapsed: 4.02556652s
Aug 12 08:39:57.867: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Pending", Reason="", readiness=false. Elapsed: 6.040293826s
Aug 12 08:39:59.878: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Pending", Reason="", readiness=false. Elapsed: 8.051534555s
Aug 12 08:40:01.890: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Pending", Reason="", readiness=false. Elapsed: 10.063749466s
Aug 12 08:40:03.899: INFO: Pod "pvc-snapshottable-data-tester-tlp2s": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.072893538s
STEP: Saw pod success
Aug 12 08:40:03.899: INFO: Pod "pvc-snapshottable-data-tester-tlp2s" satisfied condition "Succeeded or Failed"
Aug 12 08:40:03.926: INFO: Pod pvc-snapshottable-data-tester-tlp2s has the following logs: 
Aug 12 08:40:03.926: INFO: Deleting pod "pvc-snapshottable-data-tester-tlp2s" in namespace "snapshotting-4158"
Aug 12 08:40:03.971: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-tlp2s" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Aug 12 08:40:14.044: INFO: ExecWithOptions {Command:[/bin/sh -c cat /mnt/test/data] Namespace:snapshotting-4158 PodName:restored-pvc-tester-bjn9z ContainerName:volume-tester Stdin:<nil> CaptureStdout:true CaptureStderr:true PreserveWhitespace:false}
... skipping 37 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:116
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:191
        should restore from snapshot with saved data after modifying source data
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:244
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should restore from snapshot with saved data after modifying source data","total":-1,"completed":2,"skipped":370,"failed":0}
Aug 12 08:41:01.742: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should restore from snapshot with saved data after modifying source data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:244
... skipping 17 lines ...
STEP: creating a claim
Aug 12 08:39:06.579: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:39:06.692: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iomdhhk] to have phase Bound
Aug 12 08:39:06.710: INFO: PersistentVolumeClaim hostpath.csi.k8s.iomdhhk found but phase is Pending instead of Bound.
Aug 12 08:39:08.737: INFO: PersistentVolumeClaim hostpath.csi.k8s.iomdhhk found and phase=Bound (2.045281685s)
STEP: starting a pod to use the claim
Aug 12 08:39:08.763: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-4dmgl" in namespace "snapshotting-728" to be "Succeeded or Failed"
Aug 12 08:39:08.778: INFO: Pod "pvc-snapshottable-tester-4dmgl": Phase="Pending", Reason="", readiness=false. Elapsed: 15.275196ms
Aug 12 08:39:10.797: INFO: Pod "pvc-snapshottable-tester-4dmgl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.034089841s
Aug 12 08:39:12.804: INFO: Pod "pvc-snapshottable-tester-4dmgl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.041178529s
Aug 12 08:39:14.827: INFO: Pod "pvc-snapshottable-tester-4dmgl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.06433232s
Aug 12 08:39:16.838: INFO: Pod "pvc-snapshottable-tester-4dmgl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 8.07591423s
STEP: Saw pod success
Aug 12 08:39:16.839: INFO: Pod "pvc-snapshottable-tester-4dmgl" satisfied condition "Succeeded or Failed"
Aug 12 08:39:17.193: INFO: Pod pvc-snapshottable-tester-4dmgl has the following logs: 
Aug 12 08:39:17.194: INFO: Deleting pod "pvc-snapshottable-tester-4dmgl" in namespace "snapshotting-728"
Aug 12 08:39:17.216: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-4dmgl" to be fully deleted
Aug 12 08:39:17.224: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iomdhhk] to have phase Bound
Aug 12 08:39:17.236: INFO: PersistentVolumeClaim hostpath.csi.k8s.iomdhhk found and phase=Bound (11.932851ms)
STEP: checking the claim
... skipping 25 lines ...
Aug 12 08:39:53.532: INFO: WaitUntil finished successfully after 36.249453333s
Aug 12 08:39:53.548: INFO: received snapshotStatus map[boundVolumeSnapshotContentName:snapcontent-4dc9afcf-9aa2-491a-bff2-73abf3601d1d creationTime:2020-08-12T08:39:53Z readyToUse:true restoreSize:1Mi]
Aug 12 08:39:53.548: INFO: snapshotContentName snapcontent-4dc9afcf-9aa2-491a-bff2-73abf3601d1d
[It] should restore from snapshot with saved data after modifying source data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:244
STEP: modifying the data in the source PVC
Aug 12 08:39:53.590: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-gtbwg" in namespace "snapshotting-728" to be "Succeeded or Failed"
Aug 12 08:39:53.600: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.110001ms
Aug 12 08:39:55.606: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.01595664s
Aug 12 08:39:57.611: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.020810562s
Aug 12 08:39:59.619: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Pending", Reason="", readiness=false. Elapsed: 6.029175013s
Aug 12 08:40:01.626: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Pending", Reason="", readiness=false. Elapsed: 8.036560244s
Aug 12 08:40:03.631: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.041145185s
Aug 12 08:40:05.641: INFO: Pod "pvc-snapshottable-data-tester-gtbwg": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.05060904s
STEP: Saw pod success
Aug 12 08:40:05.641: INFO: Pod "pvc-snapshottable-data-tester-gtbwg" satisfied condition "Succeeded or Failed"
Aug 12 08:40:05.663: INFO: Pod pvc-snapshottable-data-tester-gtbwg has the following logs: 
Aug 12 08:40:05.663: INFO: Deleting pod "pvc-snapshottable-data-tester-gtbwg" in namespace "snapshotting-728"
Aug 12 08:40:05.707: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-gtbwg" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Aug 12 08:40:15.763: INFO: ExecWithOptions {Command:[/bin/sh -c cat /mnt/test/data] Namespace:snapshotting-728 PodName:restored-pvc-tester-wbdfp ContainerName:volume-tester Stdin:<nil> CaptureStdout:true CaptureStderr:true PreserveWhitespace:false}
... skipping 37 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:116
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:191
        should restore from snapshot with saved data after modifying source data
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:244
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should restore from snapshot with saved data after modifying source data","total":-1,"completed":1,"skipped":547,"failed":0}
Aug 12 08:41:03.375: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should create snapshot objects correctly
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:223
... skipping 17 lines ...
STEP: creating a claim
Aug 12 08:39:09.555: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug 12 08:39:09.567: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokc2xv] to have phase Bound
Aug 12 08:39:09.579: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokc2xv found but phase is Pending instead of Bound.
Aug 12 08:39:11.591: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokc2xv found and phase=Bound (2.023329543s)
STEP: starting a pod to use the claim
Aug 12 08:39:11.640: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-crvmj" in namespace "snapshotting-6583" to be "Succeeded or Failed"
Aug 12 08:39:11.647: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.449191ms
Aug 12 08:39:13.655: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.014771041s
Aug 12 08:39:15.667: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.026975511s
Aug 12 08:39:17.678: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.037554719s
Aug 12 08:39:19.684: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.043864261s
Aug 12 08:39:21.692: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.051294589s
Aug 12 08:39:23.707: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.06630192s
Aug 12 08:39:25.716: INFO: Pod "pvc-snapshottable-tester-crvmj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.076124053s
STEP: Saw pod success
Aug 12 08:39:25.716: INFO: Pod "pvc-snapshottable-tester-crvmj" satisfied condition "Succeeded or Failed"
Aug 12 08:39:25.740: INFO: Pod pvc-snapshottable-tester-crvmj has the following logs: 
Aug 12 08:39:25.740: INFO: Deleting pod "pvc-snapshottable-tester-crvmj" in namespace "snapshotting-6583"
Aug 12 08:39:25.782: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-crvmj" to be fully deleted
Aug 12 08:39:25.788: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokc2xv] to have phase Bound
Aug 12 08:39:25.795: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokc2xv found and phase=Bound (7.597818ms)
STEP: checking the claim
... skipping 165 lines ...
Aug 12 08:44:17.600: INFO: volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da has been found and is not deleted
Aug 12 08:44:19.604: INFO: volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da has been found and is not deleted
Aug 12 08:44:21.622: INFO: volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da has been found and is not deleted
Aug 12 08:44:23.632: INFO: volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da has been found and is not deleted
Aug 12 08:44:25.639: INFO: volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da has been found and is not deleted
Aug 12 08:44:27.655: INFO: volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da has been found and is not deleted
Aug 12 08:44:29.656: INFO: WaitUntil failed after reaching the timeout 5m0s
Aug 12 08:44:29.658: FAIL: Unexpected error:
    <*errors.errorString | 0xc001feec90>: {
        s: "volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da is not deleted within 5m0s",
    }
    volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da is not deleted within 5m0s
occurred

... skipping 119 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:116
       [AfterEach]
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:191
        should create snapshot objects correctly
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:223

        Aug 12 08:44:29.658: Unexpected error:
            <*errors.errorString | 0xc001feec90>: {
                s: "volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da is not deleted within 5m0s",
            }
            volumesnapshotcontents snapcontent-419ed336-60f6-43a2-9fb0-4cdd2e76f5da is not deleted within 5m0s
        occurred

        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:446
------------------------------
{"msg":"FAILED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should create snapshot objects correctly","total":-1,"completed":0,"skipped":1158,"failed":1,"failures":["External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should create snapshot objects correctly"]}
Aug 12 08:44:35.734: INFO: Running AfterSuite actions on all nodes
Aug 12 08:44:35.738: INFO: Running AfterSuite actions on node 1
Aug 12 08:44:35.738: INFO: Dumping logs locally to: /logs/artifacts
Aug 12 08:44:35.739: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory



Summarizing 1 Failure:

[Fail] External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller [AfterEach]  should create snapshot objects correctly 
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:446

Ran 8 of 5433 Specs in 332.675 seconds
FAIL! -- 7 Passed | 1 Failed | 0 Pending | 5425 Skipped


Ginkgo ran 1 suite in 5m37.073343053s
Test Suite Failed
Wed Aug 12 08:44:36 UTC 2020 go1.15rc2 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/livenessprobe/release-tools/filter-junit.go -t=External Storage -o /logs/artifacts/junit_parallel-features.xml /logs/artifacts/junit_01.xml /logs/artifacts/junit_02.xml /logs/artifacts/junit_03.xml /logs/artifacts/junit_04.xml /logs/artifacts/junit_05.xml /logs/artifacts/junit_06.xml /logs/artifacts/junit_07.xml
WARNING: E2E parallel features failed
Wed Aug 12 08:44:38 UTC 2020 go1.15rc2 /home/prow/go/src/github.com/onsi/ginkgo$ git fetch --depth=1 https://github.com/onsi/ginkgo v1.7.0
From https://github.com/onsi/ginkgo
 * tag               v1.7.0     -> FETCH_HEAD
Wed Aug 12 08:44:39 UTC 2020 go1.15rc2 /home/prow/go/src/github.com/onsi/ginkgo$ git checkout FETCH_HEAD
HEAD is now at 2e1be8f v1.7.0
Wed Aug 12 08:44:39 UTC 2020 go1.15rc2 /home/prow/go/src/github.com/onsi/ginkgo$ git clean -fdx
Wed Aug 12 08:44:39 UTC 2020 go1.15rc2 $ go get github.com/onsi/ginkgo/ginkgo
go: found github.com/onsi/ginkgo/ginkgo in github.com/onsi/ginkgo v1.14.0
Wed Aug 12 08:44:44 UTC 2020 go1.15rc2 /home/prow/go/src/k8s.io/kubernetes$ ginkgo -v -focus=External.Storage.*(\[Serial\]|\[Disruptive\]) -skip=\[Feature:|Disruptive|different\s+node /home/prow/go/pkg/csiprow.2eQljO2pve/e2e.test -- -report-dir /logs/artifacts -storage.testdriver=/home/prow/go/pkg/csiprow.2eQljO2pve/test-driver.yaml
Aug 12 08:44:48.258: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used.
I0812 08:44:48.257972   86268 test_context.go:429] Tolerating taints "node-role.kubernetes.io/master" when considering if nodes are ready
I0812 08:44:48.258363   86268 e2e.go:129] Starting e2e run "d650be1b-4ad0-41f0-945b-8475b6220655" on Ginkgo node 1
{"msg":"Test Suite starting","total":3,"completed":0,"skipped":0,"failed":0}
Running Suite: Kubernetes e2e suite
===================================
Random Seed: 1597221885 - Will randomize all specs
Will run 3 of 5433 specs

Aug 12 08:44:48.382: INFO: >>> kubeConfig: /root/.kube/config
... skipping 91 lines ...

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/stress.go:88
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSAug 12 08:44:48.817: INFO: Running AfterSuite actions on all nodes
Aug 12 08:44:48.817: INFO: Running AfterSuite actions on node 1
Aug 12 08:44:48.817: INFO: Dumping logs locally to: /logs/artifacts
Aug 12 08:44:48.818: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory

JUnit report was created: /logs/artifacts/junit_01.xml
{"msg":"Test Suite completed","total":3,"completed":0,"skipped":5433,"failed":0}

Ran 0 of 5433 Specs in 0.439 seconds
SUCCESS! -- 0 Passed | 0 Failed | 0 Pending | 5433 Skipped
PASS

Ginkgo ran 1 suite in 4.133293129s
Test Suite Passed
Wed Aug 12 08:44:49 UTC 2020 go1.15rc2 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/livenessprobe/release-tools/filter-junit.go -t=External Storage -o /logs/artifacts/junit_serial.xml /logs/artifacts/junit_01.xml
Wed Aug 12 08:44:51 UTC 2020 go1.15rc2 $ kind delete cluster --name=csi-prow
... skipping 11 lines ...