This job view page is being replaced by Spyglass soon. Check out the new job view.
PRpohly: Kubernetes 1.21
ResultFAILURE
Tests 1 failed / 48 succeeded
Started2021-05-04 12:24
Elapsed21m45s
Revision510fb0f9c957bb8b131ea3cf5e524ecde51ce0dc
Refs 146

Test Failures


test-go 0.00s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=test\-go$'
go test -mod=vendor `go list -mod=vendor ./... | grep -v -e 'vendor' -e '/test/e2e$' ` 
?   	github.com/kubernetes-csi/external-provisioner/cmd/csi-provisioner	[no test files]
I0504 12:29:39.389146   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.390041   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.390157   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.390319   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.390320   36397 capacity_test.go:1163] modifying topology
I0504 12:29:39.390357   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.390494   36397 capacity_test.go:1144] modifying objects
I0504 12:29:39.390537   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.390548   36397 capacity_test.go:1163] modifying topology
I0504 12:29:39.390568   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:39.491571   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:40.390960   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:40.390987   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:40.391012   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:50.493689   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:50.493867   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:50.495550   36397 capacity_test.go:1155] modifying capacity
I0504 12:29:50.495573   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:50.495825   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:50.595431   36397 capacity_test.go:1144] modifying objects
I0504 12:29:50.595489   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:51.494766   36397 capacity_test.go:1144] modifying objects
I0504 12:29:51.494827   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:51.494929   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:51.495812   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:51.497259   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:51.596856   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:29:52.496004   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:01.598530   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:01.599431   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:01.599586   36397 capacity_test.go:1163] modifying topology
I0504 12:30:01.599626   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:02.599380   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:02.599701   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:02.599786   36397 capacity_test.go:1163] modifying topology
I0504 12:30:02.599848   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:02.600674   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:02.701710   36397 capacity_test.go:1144] modifying objects
I0504 12:30:02.701883   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:03.600049   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:03.601148   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:03.702190   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:12.702066   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:12.702094   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:13.703243   36397 capacity_test.go:1264] waiting for controller to catch up
--- FAIL: TestCapacityController (0.00s)
    --- FAIL: TestCapacityController/reuse_one_capacity_object,_keep_owner (0.10s)
        capacity_test.go:1129: metrics after prepare: expected goal/current/obsolete object numbers 1/1/0: 
            metric output does not match expectation; want:
            
            # HELP csistoragecapacities_desired_current [ALPHA] Number of CSIStorageCapacity objects that exist and are supposed to be managed automatically.
            # TYPE csistoragecapacities_desired_current gauge
            csistoragecapacities_desired_current 1
            # HELP csistoragecapacities_desired_goal [ALPHA] Number of CSIStorageCapacity objects that are supposed to be managed automatically.
            # TYPE csistoragecapacities_desired_goal gauge
            csistoragecapacities_desired_goal 1
            # HELP csistoragecapacities_obsolete [ALPHA] Number of CSIStorageCapacity objects that exist and will be deleted automatically. Objects that exist and may need an update are not considered obsolete and therefore not included in this value.
            # TYPE csistoragecapacities_obsolete gauge
            csistoragecapacities_obsolete 0
            
            got:
            
            # HELP csistoragecapacities_desired_current [ALPHA] Number of CSIStorageCapacity objects that exist and are supposed to be managed automatically.
            # TYPE csistoragecapacities_desired_current gauge
            csistoragecapacities_desired_current 0
            # HELP csistoragecapacities_desired_goal [ALPHA] Number of CSIStorageCapacity objects that are supposed to be managed automatically.
            # TYPE csistoragecapacities_desired_goal gauge
            csistoragecapacities_desired_goal 1
            # HELP csistoragecapacities_obsolete [ALPHA] Number of CSIStorageCapacity objects that exist and will be deleted automatically. Objects that exist and may need an update are not considered obsolete and therefore not included in this value.
            # TYPE csistoragecapacities_obsolete gauge
            csistoragecapacities_obsolete 0
FAIL
FAIL	github.com/kubernetes-csi/external-provisioner/pkg/capacity	45.557s
ok  	github.com/kubernetes-csi/external-provisioner/pkg/capacity/topology	0.092s
ok  	github.com/kubernetes-csi/external-provisioner/pkg/controller	17.860s
?   	github.com/kubernetes-csi/external-provisioner/pkg/features	[no test files]
ok  	github.com/kubernetes-csi/external-provisioner/pkg/owner	0.022s
?   	github.com/kubernetes-csi/external-provisioner/release-tools	[no test files]
FAIL
    
				
				Click to see stdout/stderrfrom junit_make_test.xml

Filter through log files | View test history on testgrid


Show 48 Passed Tests

Show 363 Skipped Tests

Error lines from build-log.txt

... skipping 91 lines ...
artifacts: ARTIFACTS=/logs/artifacts
Tue May  4 12:24:37 UTC 2021 go1.16.3 $ 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.2.0-2-gf1b8acbba  -extldflags "-static"' -o "./bin/csi-provisioner$suffix" ./cmd/csi-provisioner); then \
		echo "Building csi-provisioner 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.2.0-2-gf1b8acbba  -extldflags "-static" -o ./bin/csi-provisioner ./cmd/csi-provisioner
+ CGO_ENABLED=0 GOOS=windows GOARCH=amd64 go build -mod=vendor -a -ldflags  -X main.version=v2.2.0-2-gf1b8acbba  -extldflags "-static" -o ./bin/csi-provisioner.exe ./cmd/csi-provisioner
+ CGO_ENABLED=0 GOOS=linux GOARCH=ppc64le go build -mod=vendor -a -ldflags  -X main.version=v2.2.0-2-gf1b8acbba  -extldflags "-static" -o ./bin/csi-provisioner-ppc64le ./cmd/csi-provisioner
... skipping 47 lines ...
I0504 12:30:03.600049   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:03.601148   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:03.702190   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:12.702066   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:12.702094   36397 capacity_test.go:1264] waiting for controller to catch up
I0504 12:30:13.703243   36397 capacity_test.go:1264] waiting for controller to catch up
--- FAIL: TestCapacityController (0.00s)
    --- FAIL: TestCapacityController/reuse_one_capacity_object,_keep_owner (0.10s)
        capacity_test.go:1129: metrics after prepare: expected goal/current/obsolete object numbers 1/1/0: 
            metric output does not match expectation; want:
            
            # HELP csistoragecapacities_desired_current [ALPHA] Number of CSIStorageCapacity objects that exist and are supposed to be managed automatically.
            # TYPE csistoragecapacities_desired_current gauge
            csistoragecapacities_desired_current 1
... skipping 12 lines ...
            # HELP csistoragecapacities_desired_goal [ALPHA] Number of CSIStorageCapacity objects that are supposed to be managed automatically.
            # TYPE csistoragecapacities_desired_goal gauge
            csistoragecapacities_desired_goal 1
            # HELP csistoragecapacities_obsolete [ALPHA] Number of CSIStorageCapacity objects that exist and will be deleted automatically. Objects that exist and may need an update are not considered obsolete and therefore not included in this value.
            # TYPE csistoragecapacities_obsolete gauge
            csistoragecapacities_obsolete 0
FAIL
FAIL	github.com/kubernetes-csi/external-provisioner/pkg/capacity	45.557s
ok  	github.com/kubernetes-csi/external-provisioner/pkg/capacity/topology	0.092s
ok  	github.com/kubernetes-csi/external-provisioner/pkg/controller	17.860s
?   	github.com/kubernetes-csi/external-provisioner/pkg/features	[no test files]
ok  	github.com/kubernetes-csi/external-provisioner/pkg/owner	0.022s
?   	github.com/kubernetes-csi/external-provisioner/release-tools	[no test files]
FAIL
make: *** [release-tools/build.make:194: test-go] Error 1

### test-vet:
go vet -mod=vendor `go list -mod=vendor ./... | grep -v vendor `

### test-fmt:
files=$(find . -name '*.go' | grep -v './vendor' ); \
... skipping 96 lines ...
/usr/bin/docker

/home/prow/go/src/github.com/kubernetes-csi/external-provisioner/release-tools:
Using shellcheck 0.6.0 docker image.
Congratulations! All shell files are passing lint.
make: Target 'test' not remade because of errors.
ERROR: 'make test' failed
WARNING: 'make test' failed, proceeding anyway
Tue May  4 12:31:04 UTC 2021 go1.16.3 $ 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.2.0-2-gf1b8acbba  -extldflags "-static"' -o "./bin/csi-provisioner$suffix" ./cmd/csi-provisioner); then \
		echo "Building csi-provisioner 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.2.0-2-gf1b8acbba  -extldflags "-static" -o ./bin/csi-provisioner ./cmd/csi-provisioner
docker build -t csi-provisioner:latest -f Dockerfile --label revision=v2.2.0-2-gf1b8acbba .
Sending build context to Docker daemon  400.4MB

... skipping 26 lines ...
Step 7/7 : LABEL revision=v2.2.0-2-gf1b8acbba
 ---> Running in 464124fff40a
Removing intermediate container 464124fff40a
 ---> 01745f8113c7
Successfully built 01745f8113c7
Successfully tagged csi-provisioner:latest
Tue May  4 12:31:52 UTC 2021 go1.16.3 $ curl --fail --location -o /home/prow/go/pkg/csiprow.BRXkqsfErX/bin/kind https://github.com/kubernetes-sigs/kind/releases/download/v0.10.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
100   624  100   624    0     0   3606      0 --:--:-- --:--:-- --:--:--  3606

100 7277k  100 7277k    0     0  12.7M      0 --:--:-- --:--:-- --:--:-- 12.7M
No kind clusters found.
INFO: kind-config.yaml:
... skipping 187 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 af46c47c Merge pull request #97075 from adtac/apfe2e-5
Tue May  4 12:34:43 UTC 2021 go1.16.3 /home/prow/go/src/k8s.io/kubernetes$ git clean -fdx
Tue May  4 12:34:43 UTC 2021 go1.16.3 $ curl --fail --location https://dl.google.com/go/go1.15.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
 12  115M   12 14.2M    0     0  32.7M      0  0:00:03 --:--:--  0:00:03 32.6M
 33  115M   33 39.2M    0     0  27.3M      0  0:00:04  0:00:01  0:00:03 27.3M
 63  115M   63 73.7M    0     0  30.7M      0  0:00:03  0:00:02  0:00:01 30.7M
 93  115M   93  108M    0     0  31.8M      0  0:00:03  0:00:03 --:--:-- 31.8M
100  115M  100  115M    0     0  30.2M      0  0:00:03  0:00:03 --:--:-- 30.2M
Tue May  4 12:34:47 UTC 2021 go1.15 $ 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 193 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: 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:129
    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:833
------------------------------
... skipping 176 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: 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:129
    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:833
------------------------------
... skipping 50 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:129
    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:292

    Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:247
------------------------------
... skipping 230 lines ...
May  4 12:38:33.953: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:38:33.967: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ios4hpg] to have phase Bound
May  4 12:38:33.976: INFO: PersistentVolumeClaim hostpath.csi.k8s.ios4hpg found but phase is Pending instead of Bound.
May  4 12:38:35.980: INFO: PersistentVolumeClaim hostpath.csi.k8s.ios4hpg found and phase=Bound (2.012710725s)
STEP: Creating pod pod-subpath-test-dynamicpv-6sg6
STEP: Creating a pod to test multi_subpath
May  4 12:38:35.989: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-6sg6" in namespace "provisioning-7796" to be "Succeeded or Failed"
May  4 12:38:35.994: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 3.886421ms
May  4 12:38:37.998: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008374927s
May  4 12:38:40.002: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.011751254s
May  4 12:38:42.005: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.015091601s
May  4 12:38:44.012: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022611182s
May  4 12:38:46.018: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.028546551s
May  4 12:38:48.027: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 12.036705397s
May  4 12:38:50.034: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 14.043945509s
May  4 12:38:52.125: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Pending", Reason="", readiness=false. Elapsed: 16.135446719s
May  4 12:38:54.129: INFO: Pod "pod-subpath-test-dynamicpv-6sg6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.139140586s
STEP: Saw pod success
May  4 12:38:54.129: INFO: Pod "pod-subpath-test-dynamicpv-6sg6" satisfied condition "Succeeded or Failed"
May  4 12:38:54.132: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-6sg6 container test-container-subpath-dynamicpv-6sg6: <nil>
STEP: delete the pod
May  4 12:38:54.157: INFO: Waiting for pod pod-subpath-test-dynamicpv-6sg6 to disappear
May  4 12:38:54.160: INFO: Pod pod-subpath-test-dynamicpv-6sg6 no longer exists
STEP: Deleting pod
May  4 12:38:54.160: INFO: Deleting pod "pod-subpath-test-dynamicpv-6sg6" in namespace "provisioning-7796"
... 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:129
    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":1,"skipped":36,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] provisioning
... skipping 70 lines ...
May  4 12:38:34.296: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:38:34.303: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iowwz48] to have phase Bound
May  4 12:38:34.308: INFO: PersistentVolumeClaim hostpath.csi.k8s.iowwz48 found but phase is Pending instead of Bound.
May  4 12:38:36.311: INFO: PersistentVolumeClaim hostpath.csi.k8s.iowwz48 found and phase=Bound (2.007477032s)
STEP: Creating pod pod-subpath-test-dynamicpv-5vvl
STEP: Creating a pod to test subpath
May  4 12:38:36.321: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-5vvl" in namespace "provisioning-254" to be "Succeeded or Failed"
May  4 12:38:36.324: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.531691ms
May  4 12:38:38.328: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00637976s
May  4 12:38:40.331: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.009833807s
May  4 12:38:42.337: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.016011973s
May  4 12:38:44.343: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022005793s
May  4 12:38:46.348: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.026983323s
May  4 12:38:48.357: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 12.035720891s
May  4 12:38:50.363: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.041925588s
May  4 12:38:52.369: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.047534355s
May  4 12:38:54.373: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Pending", Reason="", readiness=false. Elapsed: 18.051602712s
May  4 12:38:56.377: INFO: Pod "pod-subpath-test-dynamicpv-5vvl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.055678106s
STEP: Saw pod success
May  4 12:38:56.377: INFO: Pod "pod-subpath-test-dynamicpv-5vvl" satisfied condition "Succeeded or Failed"
May  4 12:38:56.380: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-5vvl container test-container-volume-dynamicpv-5vvl: <nil>
STEP: delete the pod
May  4 12:38:56.395: INFO: Waiting for pod pod-subpath-test-dynamicpv-5vvl to disappear
May  4 12:38:56.398: INFO: Pod pod-subpath-test-dynamicpv-5vvl no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-5vvl
May  4 12:38:56.398: INFO: Deleting pod "pod-subpath-test-dynamicpv-5vvl" in namespace "provisioning-254"
... 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:129
    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":1,"skipped":229,"failed":0}

SSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (ntfs)][sig-windows] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
May  4 12:39:01.443: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 38 lines ...
STEP: creating a claim
May  4 12:38:33.797: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iosbx7b] to have phase Bound
May  4 12:38:33.805: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosbx7b found but phase is Pending instead of Bound.
May  4 12:38:35.808: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosbx7b found and phase=Bound (2.011029842s)
STEP: Expanding non-expandable pvc
May  4 12:38:35.813: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
May  4 12:38:35.818: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:37.826: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:39.825: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:41.826: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:43.837: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:45.832: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:47.847: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:49.836: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:52.132: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:53.827: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:55.826: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:57.827: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:38:59.826: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:39:01.835: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:39:03.827: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:39:05.827: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:39:05.833: INFO: Error updating pvc hostpath.csi.k8s.iosbx7b: persistentvolumeclaims "hostpath.csi.k8s.iosbx7b" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
May  4 12:39:05.833: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iosbx7b"
May  4 12:39:05.837: INFO: Waiting up to 5m0s for PersistentVolume pvc-22984abb-2067-4c36-bcbd-55e6a92c3f17 to get deleted
May  4 12:39:05.839: INFO: PersistentVolume pvc-22984abb-2067-4c36-bcbd-55e6a92c3f17 found and phase=Bound (2.203814ms)
May  4 12:39:10.843: INFO: PersistentVolume pvc-22984abb-2067-4c36-bcbd-55e6a92c3f17 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:129
    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:154
------------------------------
{"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":1,"skipped":3,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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:130
[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
May  4 12:38:34.392: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
May  4 12:38:34.429: 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 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
May  4 12:38:34.432: INFO: Creating resource for dynamic PV
May  4 12:38:34.432: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-9409-e2e-sclqcfw
STEP: creating a claim
May  4 12:38:34.437: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:38:34.443: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iosbmnl] to have phase Bound
May  4 12:38:34.446: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosbmnl found but phase is Pending instead of Bound.
May  4 12:38:36.450: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosbmnl found and phase=Bound (2.007053208s)
STEP: Creating pod pod-subpath-test-dynamicpv-2fql
STEP: Checking for subpath error in container status
May  4 12:39:00.477: INFO: Deleting pod "pod-subpath-test-dynamicpv-2fql" in namespace "provisioning-9409"
May  4 12:39:00.484: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-2fql" to be fully deleted
STEP: Deleting pod
May  4 12:39:06.493: INFO: Deleting pod "pod-subpath-test-dynamicpv-2fql" in namespace "provisioning-9409"
STEP: Deleting pvc
May  4 12:39:06.496: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iosbmnl"
... skipping 9 lines ...

• [SLOW TEST:37.127 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:129
    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
------------------------------
{"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":1,"skipped":352,"failed":0}
[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:130
May  4 12:39:11.522: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
[AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175

... skipping 60 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:129
    should write files of various sizes, verify size, validate content [Slow][LinuxOnly]
    /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][LinuxOnly]","total":-1,"completed":1,"skipped":221,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:39:24.214: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV 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: 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:129
    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:833
------------------------------
... skipping 92 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:129
    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:833
------------------------------
... skipping 107 lines ...
May  4 12:39:10.940: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:39:10.945: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iojtfc9] to have phase Bound
May  4 12:39:10.948: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojtfc9 found but phase is Pending instead of Bound.
May  4 12:39:12.951: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojtfc9 found and phase=Bound (2.006119311s)
STEP: Creating pod pod-subpath-test-dynamicpv-52zd
STEP: Creating a pod to test subpath
May  4 12:39:12.960: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-52zd" in namespace "provisioning-8926" to be "Succeeded or Failed"
May  4 12:39:12.964: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.038415ms
May  4 12:39:14.971: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.010975133s
May  4 12:39:16.975: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.014540785s
May  4 12:39:18.979: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.018521018s
May  4 12:39:20.987: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.026573084s
May  4 12:39:22.991: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.031171307s
May  4 12:39:25.002: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 12.041509603s
May  4 12:39:27.006: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Pending", Reason="", readiness=false. Elapsed: 14.045646706s
May  4 12:39:29.014: INFO: Pod "pod-subpath-test-dynamicpv-52zd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.053790287s
STEP: Saw pod success
May  4 12:39:29.014: INFO: Pod "pod-subpath-test-dynamicpv-52zd" satisfied condition "Succeeded or Failed"
May  4 12:39:29.020: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-52zd container test-container-subpath-dynamicpv-52zd: <nil>
STEP: delete the pod
May  4 12:39:29.047: INFO: Waiting for pod pod-subpath-test-dynamicpv-52zd to disappear
May  4 12:39:29.052: INFO: Pod pod-subpath-test-dynamicpv-52zd no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-52zd
May  4 12:39:29.052: INFO: Deleting pod "pod-subpath-test-dynamicpv-52zd" in namespace "provisioning-8926"
... 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:129
    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":2,"skipped":36,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:39:34.256: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... skipping 165 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:129
    should not mount / map unused volumes in a pod [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:347
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode should not mount / map unused volumes in a pod [LinuxOnly]","total":-1,"completed":2,"skipped":529,"failed":0}

SSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
May  4 12:39:52.466: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 89 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:129
    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:178
------------------------------
... skipping 71 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:129
    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:178
------------------------------
... skipping 149 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:129
    should access to two volumes with different volume mode and retain data across pod recreation on the same node [LinuxOnly]
    /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 [LinuxOnly]","total":-1,"completed":2,"skipped":123,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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 [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
... skipping 96 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:129
    should concurrently access the single volume from pods on the same node [LinuxOnly]
    /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 [LinuxOnly]","total":-1,"completed":3,"skipped":246,"failed":0}

SSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (default fs)] fsgroupchangepolicy
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] fsgroupchangepolicy
... skipping 38 lines ...
May  4 12:39:53.419: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:39:53.424: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io54trm] to have phase Bound
May  4 12:39:53.426: INFO: PersistentVolumeClaim hostpath.csi.k8s.io54trm found but phase is Pending instead of Bound.
May  4 12:39:55.430: INFO: PersistentVolumeClaim hostpath.csi.k8s.io54trm found and phase=Bound (2.005869638s)
STEP: Creating pod exec-volume-test-dynamicpv-6p5v
STEP: Creating a pod to test exec-volume-test
May  4 12:39:55.439: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-6p5v" in namespace "volume-4008" to be "Succeeded or Failed"
May  4 12:39:55.442: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Pending", Reason="", readiness=false. Elapsed: 2.702376ms
May  4 12:39:57.445: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006308065s
May  4 12:39:59.449: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010076078s
May  4 12:40:01.452: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Pending", Reason="", readiness=false. Elapsed: 6.013480254s
May  4 12:40:03.456: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017293226s
May  4 12:40:05.460: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Pending", Reason="", readiness=false. Elapsed: 10.021116791s
May  4 12:40:07.464: INFO: Pod "exec-volume-test-dynamicpv-6p5v": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.024847721s
STEP: Saw pod success
May  4 12:40:07.464: INFO: Pod "exec-volume-test-dynamicpv-6p5v" satisfied condition "Succeeded or Failed"
May  4 12:40:07.466: INFO: Trying to get logs from node csi-prow-worker2 pod exec-volume-test-dynamicpv-6p5v container exec-container-dynamicpv-6p5v: <nil>
STEP: delete the pod
May  4 12:40:07.484: INFO: Waiting for pod exec-volume-test-dynamicpv-6p5v to disappear
May  4 12:40:07.487: INFO: Pod exec-volume-test-dynamicpv-6p5v no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-6p5v
May  4 12:40:07.487: INFO: Deleting pod "exec-volume-test-dynamicpv-6p5v" in namespace "volume-4008"
... 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:129
    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":3,"skipped":1282,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:40:12.544: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... 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 (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:129
    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:241
------------------------------
{"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":1,"skipped":380,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:40:21.743: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- skipping
... skipping 69 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:129
    should not mount / map unused volumes in a pod [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:347
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode should not mount / map unused volumes in a pod [LinuxOnly]","total":-1,"completed":2,"skipped":423,"failed":0}

SSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:40:33.226: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 238 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:129
    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":3,"skipped":211,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:40:42.405: 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/external/external.go:247
------------------------------
SSSSSSSSSSSSSSSSSSS
------------------------------
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:130
[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
May  4 12:40:11.334: 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 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
May  4 12:40:11.363: INFO: Creating resource for dynamic PV
May  4 12:40:11.363: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-1622-e2e-scq8pzr
STEP: creating a claim
May  4 12:40:11.366: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:40:11.372: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io6vdtq] to have phase Bound
May  4 12:40:11.374: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6vdtq found but phase is Pending instead of Bound.
May  4 12:40:13.378: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6vdtq found and phase=Bound (2.006509078s)
STEP: Creating pod pod-subpath-test-dynamicpv-sghg
STEP: Checking for subpath error in container status
May  4 12:40:25.396: INFO: Deleting pod "pod-subpath-test-dynamicpv-sghg" in namespace "provisioning-1622"
May  4 12:40:25.401: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-sghg" to be fully deleted
STEP: Deleting pod
May  4 12:40:37.407: INFO: Deleting pod "pod-subpath-test-dynamicpv-sghg" in namespace "provisioning-1622"
STEP: Deleting pvc
May  4 12:40:37.409: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io6vdtq"
... skipping 9 lines ...

• [SLOW TEST:31.104 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:129
    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
------------------------------
S
------------------------------
{"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":4,"skipped":271,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:40:42.475: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
... skipping 282 lines ...
May  4 12:40:21.870: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:40:21.876: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokbnk6] to have phase Bound
May  4 12:40:21.884: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokbnk6 found but phase is Pending instead of Bound.
May  4 12:40:23.887: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokbnk6 found and phase=Bound (2.011116771s)
STEP: Creating pod pod-subpath-test-dynamicpv-rjj5
STEP: Creating a pod to test subpath
May  4 12:40:23.899: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-rjj5" in namespace "provisioning-4406" to be "Succeeded or Failed"
May  4 12:40:23.903: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 3.551734ms
May  4 12:40:25.906: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007093101s
May  4 12:40:27.916: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.016766538s
May  4 12:40:29.919: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.020288449s
May  4 12:40:31.923: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.023892752s
May  4 12:40:33.929: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 10.029504698s
May  4 12:40:35.933: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Pending", Reason="", readiness=false. Elapsed: 12.033323267s
May  4 12:40:37.936: INFO: Pod "pod-subpath-test-dynamicpv-rjj5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.037012245s
STEP: Saw pod success
May  4 12:40:37.936: INFO: Pod "pod-subpath-test-dynamicpv-rjj5" satisfied condition "Succeeded or Failed"
May  4 12:40:37.939: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-rjj5 container test-container-subpath-dynamicpv-rjj5: <nil>
STEP: delete the pod
May  4 12:40:37.960: INFO: Waiting for pod pod-subpath-test-dynamicpv-rjj5 to disappear
May  4 12:40:37.964: INFO: Pod pod-subpath-test-dynamicpv-rjj5 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-rjj5
May  4 12:40:37.964: INFO: Deleting pod "pod-subpath-test-dynamicpv-rjj5" in namespace "provisioning-4406"
... 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:129
    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":504,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (ext4)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
May  4 12:40:43.056: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 22 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
May  4 12:40:43.080: 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:192
May  4 12:40:43.110: 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
May  4 12:40:43.110: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-6460" for this suite.


S [SKIPPING] [0.038 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:129
    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:192

    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:124
------------------------------
... skipping 60 lines ...

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:247
------------------------------
S
------------------------------
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:292

[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:130
[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
May  4 12:40:42.906: 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:292
May  4 12:40:42.934: INFO: Creating resource for dynamic PV
May  4 12:40:42.934: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-8495-e2e-scjlxct
STEP: creating a claim
May  4 12:40:42.947: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iowb5g9] to have phase Bound
May  4 12:40:42.950: INFO: PersistentVolumeClaim hostpath.csi.k8s.iowb5g9 found but phase is Pending instead of Bound.
May  4 12:40:44.954: INFO: PersistentVolumeClaim hostpath.csi.k8s.iowb5g9 found and phase=Bound (2.006988682s)
STEP: Creating pod
STEP: Waiting for the pod to fail
May  4 12:40:46.973: INFO: Deleting pod "pod-76c31735-cdfc-4e45-bfef-df8290a38660" in namespace "volumemode-8495"
May  4 12:40:46.977: INFO: Wait up to 5m0s for pod "pod-76c31735-cdfc-4e45-bfef-df8290a38660" to be fully deleted
STEP: Deleting pvc
May  4 12:40:56.983: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iowb5g9"
May  4 12:40:56.987: INFO: Waiting up to 5m0s for PersistentVolume pvc-17ab93a5-9ef8-4e8b-8bea-bf37521992f6 to get deleted
May  4 12:40:56.995: INFO: PersistentVolume pvc-17ab93a5-9ef8-4e8b-8bea-bf37521992f6 found and phase=Bound (6.392813ms)
... skipping 7 lines ...

• [SLOW TEST:19.105 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:129
    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:292
------------------------------
{"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":538,"failed":0}

SSSSSSSSSSSSSSSSSS
------------------------------
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:129
    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":2,"skipped":254,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] provisioning
... skipping 178 lines ...
May  4 12:40:33.638: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:40:33.643: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iojghqn] to have phase Bound
May  4 12:40:33.649: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojghqn found but phase is Pending instead of Bound.
May  4 12:40:35.653: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojghqn found and phase=Bound (2.009855661s)
STEP: Expanding non-expandable pvc
May  4 12:40:35.659: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
May  4 12:40:35.666: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:37.674: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:39.674: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:41.674: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:43.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:45.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:47.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:49.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:51.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:53.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:55.674: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:57.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:40:59.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:41:01.673: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:41:03.674: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:41:05.675: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
May  4 12:41:05.680: INFO: Error updating pvc hostpath.csi.k8s.iojghqn: persistentvolumeclaims "hostpath.csi.k8s.iojghqn" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
May  4 12:41:05.681: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iojghqn"
May  4 12:41:05.684: INFO: Waiting up to 5m0s for PersistentVolume pvc-4f4585ed-b7f3-48b2-8570-fd020d504e57 to get deleted
May  4 12:41:05.687: INFO: PersistentVolume pvc-4f4585ed-b7f3-48b2-8570-fd020d504e57 found and phase=Bound (2.745611ms)
May  4 12:41:10.689: INFO: PersistentVolume pvc-4f4585ed-b7f3-48b2-8570-fd020d504e57 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:129
    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:154
------------------------------
{"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":3,"skipped":723,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
... skipping 62 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:129
    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 (filesystem volmode)] multiVolume [Slow] should concurrently access the single read-only volume from pods on the same node","total":-1,"completed":4,"skipped":1381,"failed":0}

SSSSSSSSSSSSS
------------------------------
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:169
... 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:129
    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:169
------------------------------
{"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":5,"skipped":399,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:41:23.869: 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:129
    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:833
------------------------------
... skipping 29 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:129
    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:292

    Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:247
------------------------------
... skipping 62 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:129
    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
------------------------------
{"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":1,"skipped":267,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:41:24.243: 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:129
    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:178
------------------------------
... skipping 19 lines ...
STEP: creating a claim
May  4 12:41:02.068: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:41:02.072: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iommhgx] to have phase Bound
May  4 12:41:02.086: INFO: PersistentVolumeClaim hostpath.csi.k8s.iommhgx found but phase is Pending instead of Bound.
May  4 12:41:04.090: INFO: PersistentVolumeClaim hostpath.csi.k8s.iommhgx found and phase=Bound (2.017895555s)
STEP: Creating pod to format volume volume-prep-provisioning-3771
May  4 12:41:04.101: INFO: Waiting up to 5m0s for pod "volume-prep-provisioning-3771" in namespace "provisioning-3771" to be "Succeeded or Failed"
May  4 12:41:04.105: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 4.448371ms
May  4 12:41:06.109: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008127828s
May  4 12:41:08.112: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 4.011422029s
May  4 12:41:10.116: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014985167s
May  4 12:41:12.119: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 8.018783424s
May  4 12:41:14.123: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 10.022583486s
May  4 12:41:16.127: INFO: Pod "volume-prep-provisioning-3771": Phase="Pending", Reason="", readiness=false. Elapsed: 12.026124044s
May  4 12:41:18.130: INFO: Pod "volume-prep-provisioning-3771": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.029541096s
STEP: Saw pod success
May  4 12:41:18.130: INFO: Pod "volume-prep-provisioning-3771" satisfied condition "Succeeded or Failed"
May  4 12:41:18.130: INFO: Deleting pod "volume-prep-provisioning-3771" in namespace "provisioning-3771"
May  4 12:41:18.139: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-3771" to be fully deleted
STEP: Creating pod pod-subpath-test-dynamicpv-9qzj
STEP: Checking for subpath error in container status
May  4 12:41:24.151: INFO: Deleting pod "pod-subpath-test-dynamicpv-9qzj" in namespace "provisioning-3771"
May  4 12:41:24.160: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-9qzj" to be fully deleted
STEP: Deleting pod
May  4 12:41:24.166: INFO: Deleting pod "pod-subpath-test-dynamicpv-9qzj" in namespace "provisioning-3771"
STEP: Deleting pvc
May  4 12:41:24.170: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iommhgx"
... skipping 12 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:129
    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
------------------------------
{"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":5,"skipped":556,"failed":0}

SSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ext3)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
May  4 12:41:29.204: INFO: Driver hostpath.csi.k8s.io doesn't support ext3 -- skipping
... skipping 36 lines ...
May  4 12:41:10.779: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:41:10.786: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioblsxq] to have phase Bound
May  4 12:41:10.793: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioblsxq found but phase is Pending instead of Bound.
May  4 12:41:12.798: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioblsxq found and phase=Bound (2.012313038s)
STEP: Creating pod pod-subpath-test-dynamicpv-m9bp
STEP: Creating a pod to test subpath
May  4 12:41:12.806: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-m9bp" in namespace "provisioning-265" to be "Succeeded or Failed"
May  4 12:41:12.810: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 3.138526ms
May  4 12:41:14.813: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006623947s
May  4 12:41:16.816: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.009798334s
May  4 12:41:18.821: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014075461s
May  4 12:41:20.824: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017750597s
May  4 12:41:22.828: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 10.02141567s
May  4 12:41:24.831: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Pending", Reason="", readiness=false. Elapsed: 12.024609569s
May  4 12:41:26.840: INFO: Pod "pod-subpath-test-dynamicpv-m9bp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.033798749s
STEP: Saw pod success
May  4 12:41:26.840: INFO: Pod "pod-subpath-test-dynamicpv-m9bp" satisfied condition "Succeeded or Failed"
May  4 12:41:26.843: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-m9bp container test-container-subpath-dynamicpv-m9bp: <nil>
STEP: delete the pod
May  4 12:41:26.856: INFO: Waiting for pod pod-subpath-test-dynamicpv-m9bp to disappear
May  4 12:41:26.858: INFO: Pod pod-subpath-test-dynamicpv-m9bp no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-m9bp
May  4 12:41:26.858: INFO: Deleting pod "pod-subpath-test-dynamicpv-m9bp" in namespace "provisioning-265"
... 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:129
    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":4,"skipped":756,"failed":0}

SS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)(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:169
... skipping 52 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:129
    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:169
------------------------------
{"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":3,"skipped":644,"failed":0}

SSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  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
... skipping 15 lines ...
May  4 12:41:12.360: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:41:12.365: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioqkjz8] to have phase Bound
May  4 12:41:12.368: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqkjz8 found but phase is Pending instead of Bound.
May  4 12:41:14.371: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqkjz8 found and phase=Bound (2.006131841s)
STEP: Creating pod pod-subpath-test-dynamicpv-ssrk
STEP: Creating a pod to test subpath
May  4 12:41:14.382: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-ssrk" in namespace "provisioning-6913" to be "Succeeded or Failed"
May  4 12:41:14.387: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 5.724322ms
May  4 12:41:16.391: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009807424s
May  4 12:41:18.395: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013741002s
May  4 12:41:20.399: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.017300387s
May  4 12:41:22.402: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.020918731s
May  4 12:41:24.406: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.024845278s
May  4 12:41:26.410: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 12.027993214s
May  4 12:41:28.413: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 14.031190434s
May  4 12:41:30.416: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.034767039s
STEP: Saw pod success
May  4 12:41:30.416: INFO: Pod "pod-subpath-test-dynamicpv-ssrk" satisfied condition "Succeeded or Failed"
May  4 12:41:30.419: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-ssrk container test-container-subpath-dynamicpv-ssrk: <nil>
STEP: delete the pod
May  4 12:41:30.436: INFO: Waiting for pod pod-subpath-test-dynamicpv-ssrk to disappear
May  4 12:41:30.439: INFO: Pod pod-subpath-test-dynamicpv-ssrk no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-ssrk
May  4 12:41:30.439: INFO: Deleting pod "pod-subpath-test-dynamicpv-ssrk" in namespace "provisioning-6913"
STEP: Creating pod pod-subpath-test-dynamicpv-ssrk
STEP: Creating a pod to test subpath
May  4 12:41:30.445: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-ssrk" in namespace "provisioning-6913" to be "Succeeded or Failed"
May  4 12:41:30.447: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.231325ms
May  4 12:41:32.450: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.005339993s
May  4 12:41:34.453: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.008632914s
May  4 12:41:36.457: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.012383306s
May  4 12:41:38.460: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.015664673s
May  4 12:41:40.464: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.018712176s
May  4 12:41:42.467: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Pending", Reason="", readiness=false. Elapsed: 12.0223687s
May  4 12:41:44.472: INFO: Pod "pod-subpath-test-dynamicpv-ssrk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.027323989s
STEP: Saw pod success
May  4 12:41:44.472: INFO: Pod "pod-subpath-test-dynamicpv-ssrk" satisfied condition "Succeeded or Failed"
May  4 12:41:44.474: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-ssrk container test-container-subpath-dynamicpv-ssrk: <nil>
STEP: delete the pod
May  4 12:41:44.485: INFO: Waiting for pod pod-subpath-test-dynamicpv-ssrk to disappear
May  4 12:41:44.488: INFO: Pod pod-subpath-test-dynamicpv-ssrk no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-ssrk
May  4 12:41:44.488: INFO: Deleting pod "pod-subpath-test-dynamicpv-ssrk" in namespace "provisioning-6913"
... 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:129
    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":5,"skipped":1394,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:41:49.555: 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: 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:129
    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:833
------------------------------
... skipping 91 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:129
    should be able to unmount after the subpath directory is deleted [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:441
------------------------------
{"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 [LinuxOnly]","total":-1,"completed":6,"skipped":488,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (xfs)][Slow] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
May  4 12:41:51.314: INFO: Driver hostpath.csi.k8s.io doesn't support xfs -- skipping
... skipping 43 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
May  4 12:41:51.409: 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:192
May  4 12:41:51.442: 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
May  4 12:41:51.443: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-7270" for this suite.


S [SKIPPING] [0.041 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:129
    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:192

    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:124
------------------------------
... skipping 29 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:129
    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:178
------------------------------
... skipping 41 lines ...
May  4 12:41:49.781: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:41:49.787: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7rtbs] to have phase Bound
May  4 12:41:49.789: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7rtbs found but phase is Pending instead of Bound.
May  4 12:41:51.793: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7rtbs found and phase=Bound (2.005716562s)
STEP: Creating pod pod-subpath-test-dynamicpv-pfp6
STEP: Creating a pod to test subpath
May  4 12:41:51.802: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-pfp6" in namespace "provisioning-7958" to be "Succeeded or Failed"
May  4 12:41:51.805: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 3.433045ms
May  4 12:41:53.809: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006838315s
May  4 12:41:55.812: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010310564s
May  4 12:41:57.815: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01362866s
May  4 12:41:59.819: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017004784s
May  4 12:42:01.822: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.020460274s
May  4 12:42:03.826: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 12.024230799s
May  4 12:42:05.829: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Pending", Reason="", readiness=false. Elapsed: 14.027456403s
May  4 12:42:07.833: INFO: Pod "pod-subpath-test-dynamicpv-pfp6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.030951924s
STEP: Saw pod success
May  4 12:42:07.833: INFO: Pod "pod-subpath-test-dynamicpv-pfp6" satisfied condition "Succeeded or Failed"
May  4 12:42:07.835: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-pfp6 container test-container-volume-dynamicpv-pfp6: <nil>
STEP: delete the pod
May  4 12:42:07.855: INFO: Waiting for pod pod-subpath-test-dynamicpv-pfp6 to disappear
May  4 12:42:07.857: INFO: Pod pod-subpath-test-dynamicpv-pfp6 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-pfp6
May  4 12:42:07.857: INFO: Deleting pod "pod-subpath-test-dynamicpv-pfp6" in namespace "provisioning-7958"
... 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:129
    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":6,"skipped":1573,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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:130
May  4 12:42:12.923: INFO: Driver hostpath.csi.k8s.io doesn't support ntfs -- 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: 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:129
    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:833
------------------------------
... skipping 71 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:129
    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:833
------------------------------
... skipping 85 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:129
    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":2,"skipped":401,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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 [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
... skipping 96 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:129
    should concurrently access the single volume from pods on the same node [LinuxOnly]
    /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 [LinuxOnly]","total":-1,"completed":6,"skipped":602,"failed":0}

SSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (default fs)] fsgroupchangepolicy
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] fsgroupchangepolicy
... skipping 15 lines ...

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/fsgroupchangepolicy.go:84
------------------------------
SSSSSSS
------------------------------
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:130
[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
May  4 12:42:14.410: 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
May  4 12:42:14.439: INFO: Creating resource for dynamic PV
May  4 12:42:14.439: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-314-e2e-sc5sd48
STEP: creating a claim
May  4 12:42:14.442: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:42:14.447: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioqds2v] to have phase Bound
May  4 12:42:14.450: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqds2v found but phase is Pending instead of Bound.
May  4 12:42:16.454: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqds2v found and phase=Bound (2.006680586s)
STEP: Creating pod pod-subpath-test-dynamicpv-nfd7
STEP: Checking for subpath error in container status
May  4 12:42:32.469: INFO: Deleting pod "pod-subpath-test-dynamicpv-nfd7" in namespace "provisioning-314"
May  4 12:42:32.474: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-nfd7" to be fully deleted
STEP: Deleting pod
May  4 12:42:38.481: INFO: Deleting pod "pod-subpath-test-dynamicpv-nfd7" in namespace "provisioning-314"
STEP: Deleting pvc
May  4 12:42:38.483: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioqds2v"
... skipping 9 lines ...

• [SLOW TEST:29.098 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:129
    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":3,"skipped":424,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:241
... skipping 43 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:129
    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:241
------------------------------
{"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":3,"skipped":542,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ext4)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
May  4 12:42:44.728: INFO: Driver hostpath.csi.k8s.io doesn't support ext4 -- skipping
... skipping 168 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:129
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node [LinuxOnly]
    /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 [LinuxOnly]","total":-1,"completed":7,"skipped":715,"failed":0}
May  4 12:42:46.093: INFO: Running AfterSuite actions on all nodes


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 [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:130
... skipping 123 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:129
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node [LinuxOnly]
    /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 [LinuxOnly]","total":-1,"completed":5,"skipped":758,"failed":0}
May  4 12:42:46.333: INFO: Running AfterSuite actions on all nodes


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:130
[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
May  4 12:42:13.278: 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
May  4 12:42:13.310: INFO: Creating resource for dynamic PV
May  4 12:42:13.310: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-2346-e2e-sc6q4tr
STEP: creating a claim
May  4 12:42:13.313: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:42:13.327: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iodv9gn] to have phase Bound
May  4 12:42:13.331: INFO: PersistentVolumeClaim hostpath.csi.k8s.iodv9gn found but phase is Pending instead of Bound.
May  4 12:42:15.335: INFO: PersistentVolumeClaim hostpath.csi.k8s.iodv9gn found and phase=Bound (2.007967321s)
STEP: Creating pod pod-subpath-test-dynamicpv-hzvw
STEP: Checking for subpath error in container status
May  4 12:42:33.351: INFO: Deleting pod "pod-subpath-test-dynamicpv-hzvw" in namespace "provisioning-2346"
May  4 12:42:33.356: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-hzvw" to be fully deleted
STEP: Deleting pod
May  4 12:42:41.364: INFO: Deleting pod "pod-subpath-test-dynamicpv-hzvw" in namespace "provisioning-2346"
STEP: Deleting pvc
May  4 12:42:41.367: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iodv9gn"
... skipping 9 lines ...

• [SLOW TEST:33.117 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:129
    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
------------------------------
{"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":7,"skipped":1860,"failed":0}
May  4 12:42:46.397: INFO: Running AfterSuite actions on all nodes


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:292

[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:130
[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
May  4 12:42:43.613: 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:292
May  4 12:42:43.642: INFO: Creating resource for dynamic PV
May  4 12:42:43.642: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-5045-e2e-scqc7r9
STEP: creating a claim
May  4 12:42:43.650: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io49qmg] to have phase Bound
May  4 12:42:43.653: INFO: PersistentVolumeClaim hostpath.csi.k8s.io49qmg found but phase is Pending instead of Bound.
May  4 12:42:45.657: INFO: PersistentVolumeClaim hostpath.csi.k8s.io49qmg found and phase=Bound (2.006358715s)
STEP: Creating pod
STEP: Waiting for the pod to fail
May  4 12:42:47.680: INFO: Deleting pod "pod-b9901a06-7e82-4169-bdc2-1084f38306ea" in namespace "volumemode-5045"
May  4 12:42:47.684: INFO: Wait up to 5m0s for pod "pod-b9901a06-7e82-4169-bdc2-1084f38306ea" to be fully deleted
STEP: Deleting pvc
May  4 12:42:57.692: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io49qmg"
May  4 12:42:57.697: INFO: Waiting up to 5m0s for PersistentVolume pvc-bfc3c909-1966-4354-95ae-d2847f98ac4c to get deleted
May  4 12:42:57.700: INFO: PersistentVolume pvc-bfc3c909-1966-4354-95ae-d2847f98ac4c found and phase=Bound (2.6043ms)
... skipping 7 lines ...

• [SLOW TEST:19.103 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:129
    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:292
------------------------------
{"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":4,"skipped":511,"failed":0}
May  4 12:43:02.718: INFO: Running AfterSuite actions on all nodes


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 ...
May  4 12:42:18.315: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:42:18.320: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iovsgf5] to have phase Bound
May  4 12:42:18.322: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovsgf5 found but phase is Pending instead of Bound.
May  4 12:42:20.326: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovsgf5 found and phase=Bound (2.005818367s)
STEP: Creating pod pod-subpath-test-dynamicpv-vd5x
STEP: Creating a pod to test atomic-volume-subpath
May  4 12:42:20.335: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-vd5x" in namespace "provisioning-6851" to be "Succeeded or Failed"
May  4 12:42:20.339: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Pending", Reason="", readiness=false. Elapsed: 3.483017ms
May  4 12:42:22.343: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007384881s
May  4 12:42:24.347: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010973506s
May  4 12:42:26.351: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014887952s
May  4 12:42:28.354: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Pending", Reason="", readiness=false. Elapsed: 8.018455291s
May  4 12:42:30.358: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Pending", Reason="", readiness=false. Elapsed: 10.021957253s
... skipping 9 lines ...
May  4 12:42:50.408: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Running", Reason="", readiness=true. Elapsed: 30.071697655s
May  4 12:42:52.411: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Running", Reason="", readiness=true. Elapsed: 32.075164233s
May  4 12:42:54.414: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Running", Reason="", readiness=true. Elapsed: 34.078588614s
May  4 12:42:56.418: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Running", Reason="", readiness=true. Elapsed: 36.08196329s
May  4 12:42:58.422: INFO: Pod "pod-subpath-test-dynamicpv-vd5x": Phase="Succeeded", Reason="", readiness=false. Elapsed: 38.085715022s
STEP: Saw pod success
May  4 12:42:58.422: INFO: Pod "pod-subpath-test-dynamicpv-vd5x" satisfied condition "Succeeded or Failed"
May  4 12:42:58.424: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-vd5x container test-container-subpath-dynamicpv-vd5x: <nil>
STEP: delete the pod
May  4 12:42:58.439: INFO: Waiting for pod pod-subpath-test-dynamicpv-vd5x to disappear
May  4 12:42:58.442: INFO: Pod pod-subpath-test-dynamicpv-vd5x no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-vd5x
May  4 12:42:58.442: INFO: Deleting pod "pod-subpath-test-dynamicpv-vd5x" in namespace "provisioning-6851"
... 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 (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    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":4,"skipped":649,"failed":0}
May  4 12:43:32.016: INFO: Running AfterSuite actions on all nodes


{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support file as subpath [LinuxOnly]","total":-1,"completed":7,"skipped":621,"failed":0}
May  4 12:43:03.478: INFO: Running AfterSuite actions on all nodes
May  4 12:43:32.072: INFO: Running AfterSuite actions on node 1
May  4 12:43:32.072: INFO: Dumping logs locally to: /logs/artifacts
May  4 12:43:32.072: 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 5870 Specs in 298.585 seconds
SUCCESS! -- 37 Passed | 0 Failed | 0 Pending | 5833 Skipped


Ginkgo ran 1 suite in 5m1.688337074s
Test Suite Passed
Tue May  4 12:43:32 UTC 2021 go1.16.3 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/external-provisioner/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
Tue May  4 12:43:32 UTC 2021 go1.16.3 /home/prow/go/src/github.com/onsi/ginkgo$ git fetch --depth=1 https://github.com/onsi/ginkgo v1.7.0
... skipping 321 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:129
    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":269,"failed":0}
May  4 12:44:54.503: 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 check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
May  4 12:43:41.259: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:43:41.266: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io4pk97] to have phase Bound
May  4 12:43:41.270: INFO: PersistentVolumeClaim hostpath.csi.k8s.io4pk97 found but phase is Pending instead of Bound.
May  4 12:43:43.274: INFO: PersistentVolumeClaim hostpath.csi.k8s.io4pk97 found and phase=Bound (2.007772053s)
STEP: starting a pod to use the claim
May  4 12:43:43.283: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-2pqh8" in namespace "snapshotting-3652" to be "Succeeded or Failed"
May  4 12:43:43.286: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.513483ms
May  4 12:43:45.289: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.005809019s
May  4 12:43:47.292: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 4.009106974s
May  4 12:43:49.296: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.012767045s
May  4 12:43:51.299: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 8.016190278s
May  4 12:43:53.303: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 10.019425643s
May  4 12:43:55.307: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 12.023555462s
May  4 12:43:57.310: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Pending", Reason="", readiness=false. Elapsed: 14.026672938s
May  4 12:43:59.313: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Running", Reason="", readiness=true. Elapsed: 16.029815926s
May  4 12:44:01.317: INFO: Pod "pvc-snapshottable-tester-2pqh8": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.033466178s
STEP: Saw pod success
May  4 12:44:01.317: INFO: Pod "pvc-snapshottable-tester-2pqh8" satisfied condition "Succeeded or Failed"
May  4 12:44:01.329: INFO: Pod pvc-snapshottable-tester-2pqh8 has the following logs: 
May  4 12:44:01.329: INFO: Deleting pod "pvc-snapshottable-tester-2pqh8" in namespace "snapshotting-3652"
May  4 12:44:01.339: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-2pqh8" to be fully deleted
May  4 12:44:01.342: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io4pk97] to have phase Bound
May  4 12:44:01.346: INFO: PersistentVolumeClaim hostpath.csi.k8s.io4pk97 found and phase=Bound (4.333153ms)
STEP: checking the claim
... skipping 11 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
May  4 12:44:03.392: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-r8zpv" in namespace "snapshotting-3652" to be "Succeeded or Failed"
May  4 12:44:03.396: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.077812ms
May  4 12:44:05.400: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007488658s
May  4 12:44:07.403: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010968433s
May  4 12:44:09.407: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014883309s
May  4 12:44:11.411: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Pending", Reason="", readiness=false. Elapsed: 8.018517289s
May  4 12:44:13.414: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Pending", Reason="", readiness=false. Elapsed: 10.022077251s
May  4 12:44:15.418: INFO: Pod "pvc-snapshottable-data-tester-r8zpv": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.025781054s
STEP: Saw pod success
May  4 12:44:15.418: INFO: Pod "pvc-snapshottable-data-tester-r8zpv" satisfied condition "Succeeded or Failed"
May  4 12:44:15.423: INFO: Pod pvc-snapshottable-data-tester-r8zpv has the following logs: 
May  4 12:44:15.424: INFO: Deleting pod "pvc-snapshottable-data-tester-r8zpv" in namespace "snapshotting-3652"
May  4 12:44:15.438: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-r8zpv" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
May  4 12:44:23.489: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:38747 --kubeconfig=/root/.kube/config --namespace=snapshotting-3652 exec restored-pvc-tester-p8ct9 --namespace=snapshotting-3652 -- cat /mnt/test/data'
... skipping 43 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":1520,"failed":0}
May  4 12:45:12.758: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
May  4 12:43:40.069: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:43:40.076: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io59x65] to have phase Bound
May  4 12:43:40.080: INFO: PersistentVolumeClaim hostpath.csi.k8s.io59x65 found but phase is Pending instead of Bound.
May  4 12:43:42.084: INFO: PersistentVolumeClaim hostpath.csi.k8s.io59x65 found and phase=Bound (2.007937617s)
STEP: starting a pod to use the claim
May  4 12:43:42.095: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-pnbp7" in namespace "snapshotting-2811" to be "Succeeded or Failed"
May  4 12:43:42.105: INFO: Pod "pvc-snapshottable-tester-pnbp7": Phase="Pending", Reason="", readiness=false. Elapsed: 10.404342ms
May  4 12:43:44.109: INFO: Pod "pvc-snapshottable-tester-pnbp7": Phase="Pending", Reason="", readiness=false. Elapsed: 2.013607074s
May  4 12:43:46.113: INFO: Pod "pvc-snapshottable-tester-pnbp7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.018246319s
May  4 12:43:48.118: INFO: Pod "pvc-snapshottable-tester-pnbp7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.022623619s
May  4 12:43:50.123: INFO: Pod "pvc-snapshottable-tester-pnbp7": Phase="Pending", Reason="", readiness=false. Elapsed: 8.027712287s
May  4 12:43:52.127: INFO: Pod "pvc-snapshottable-tester-pnbp7": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.031837296s
STEP: Saw pod success
May  4 12:43:52.127: INFO: Pod "pvc-snapshottable-tester-pnbp7" satisfied condition "Succeeded or Failed"
May  4 12:43:52.134: INFO: Pod pvc-snapshottable-tester-pnbp7 has the following logs: 
May  4 12:43:52.134: INFO: Deleting pod "pvc-snapshottable-tester-pnbp7" in namespace "snapshotting-2811"
May  4 12:43:52.153: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-pnbp7" to be fully deleted
May  4 12:43:52.156: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io59x65] to have phase Bound
May  4 12:43:52.158: INFO: PersistentVolumeClaim hostpath.csi.k8s.io59x65 found and phase=Bound (2.595845ms)
STEP: checking the claim
... skipping 31 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
May  4 12:43:58.288: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-ndlvl" in namespace "snapshotting-2811" to be "Succeeded or Failed"
May  4 12:43:58.294: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 5.486692ms
May  4 12:44:00.297: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00897935s
May  4 12:44:02.301: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.012318963s
May  4 12:44:04.304: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.016028945s
May  4 12:44:06.308: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.019643132s
May  4 12:44:08.312: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.023592824s
May  4 12:44:10.323: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 12.034155387s
May  4 12:44:12.326: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.037410854s
May  4 12:44:14.329: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.040762321s
May  4 12:44:16.333: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 18.044642476s
May  4 12:44:18.336: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Pending", Reason="", readiness=false. Elapsed: 20.047908483s
May  4 12:44:20.340: INFO: Pod "pvc-snapshottable-data-tester-ndlvl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.05112132s
STEP: Saw pod success
May  4 12:44:20.340: INFO: Pod "pvc-snapshottable-data-tester-ndlvl" satisfied condition "Succeeded or Failed"
May  4 12:44:20.344: INFO: Pod pvc-snapshottable-data-tester-ndlvl has the following logs: 
May  4 12:44:20.344: INFO: Deleting pod "pvc-snapshottable-data-tester-ndlvl" in namespace "snapshotting-2811"
May  4 12:44:20.355: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-ndlvl" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
May  4 12:44:36.384: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:38747 --kubeconfig=/root/.kube/config --namespace=snapshotting-2811 exec restored-pvc-tester-446fd --namespace=snapshotting-2811 -- cat /mnt/test/data'
... skipping 43 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":677,"failed":0}
May  4 12:45:21.651: 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 check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
May  4 12:43:39.997: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:43:40.006: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokd7sq] to have phase Bound
May  4 12:43:40.016: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokd7sq found but phase is Pending instead of Bound.
May  4 12:43:42.020: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokd7sq found and phase=Bound (2.014387878s)
STEP: starting a pod to use the claim
May  4 12:43:42.031: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-rbsqh" in namespace "snapshotting-3797" to be "Succeeded or Failed"
May  4 12:43:42.035: INFO: Pod "pvc-snapshottable-tester-rbsqh": Phase="Pending", Reason="", readiness=false. Elapsed: 3.841288ms
May  4 12:43:44.039: INFO: Pod "pvc-snapshottable-tester-rbsqh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00844363s
May  4 12:43:46.043: INFO: Pod "pvc-snapshottable-tester-rbsqh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.012197164s
May  4 12:43:48.047: INFO: Pod "pvc-snapshottable-tester-rbsqh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.016296722s
May  4 12:43:50.051: INFO: Pod "pvc-snapshottable-tester-rbsqh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.020494921s
May  4 12:43:52.055: INFO: Pod "pvc-snapshottable-tester-rbsqh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.024141274s
STEP: Saw pod success
May  4 12:43:52.055: INFO: Pod "pvc-snapshottable-tester-rbsqh" satisfied condition "Succeeded or Failed"
May  4 12:43:52.068: INFO: Pod pvc-snapshottable-tester-rbsqh has the following logs: 
May  4 12:43:52.068: INFO: Deleting pod "pvc-snapshottable-tester-rbsqh" in namespace "snapshotting-3797"
May  4 12:43:52.080: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-rbsqh" to be fully deleted
May  4 12:43:52.082: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokd7sq] to have phase Bound
May  4 12:43:52.085: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokd7sq found and phase=Bound (2.583439ms)
STEP: checking the claim
... skipping 11 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
May  4 12:43:54.134: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-wkndt" in namespace "snapshotting-3797" to be "Succeeded or Failed"
May  4 12:43:54.140: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.106737ms
May  4 12:43:56.143: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009586614s
May  4 12:43:58.147: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013005723s
May  4 12:44:00.150: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01651875s
May  4 12:44:02.154: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.019988763s
May  4 12:44:04.157: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 10.023708609s
... skipping 2 lines ...
May  4 12:44:10.169: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 16.035884478s
May  4 12:44:12.173: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 18.039449863s
May  4 12:44:14.177: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 20.043228374s
May  4 12:44:16.181: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Pending", Reason="", readiness=false. Elapsed: 22.047019584s
May  4 12:44:18.185: INFO: Pod "pvc-snapshottable-data-tester-wkndt": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.051057368s
STEP: Saw pod success
May  4 12:44:18.185: INFO: Pod "pvc-snapshottable-data-tester-wkndt" satisfied condition "Succeeded or Failed"
May  4 12:44:18.192: INFO: Pod pvc-snapshottable-data-tester-wkndt has the following logs: 
May  4 12:44:18.192: INFO: Deleting pod "pvc-snapshottable-data-tester-wkndt" in namespace "snapshotting-3797"
May  4 12:44:18.203: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-wkndt" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
May  4 12:44:32.234: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:38747 --kubeconfig=/root/.kube/config --namespace=snapshotting-3797 exec restored-pvc-tester-6898x --namespace=snapshotting-3797 -- cat /mnt/test/data'
... skipping 35 lines ...
May  4 12:44:58.532: INFO: volumesnapshotcontents snapcontent-570611c8-b640-4716-a2ca-c411256b331a has been found and is not deleted
May  4 12:44:59.535: INFO: volumesnapshotcontents snapcontent-570611c8-b640-4716-a2ca-c411256b331a has been found and is not deleted
May  4 12:45:00.540: INFO: volumesnapshotcontents snapcontent-570611c8-b640-4716-a2ca-c411256b331a has been found and is not deleted
May  4 12:45:01.544: INFO: volumesnapshotcontents snapcontent-570611c8-b640-4716-a2ca-c411256b331a has been found and is not deleted
May  4 12:45:02.549: INFO: volumesnapshotcontents snapcontent-570611c8-b640-4716-a2ca-c411256b331a has been found and is not deleted
May  4 12:45:03.553: INFO: volumesnapshotcontents snapcontent-570611c8-b640-4716-a2ca-c411256b331a has been found and is not deleted
May  4 12:45:04.553: 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:192
May  4 12:45:04.558: INFO: Pod restored-pvc-tester-6898x has the following logs: 
May  4 12:45:04.558: INFO: Deleting pod "restored-pvc-tester-6898x" in namespace "snapshotting-3797"
May  4 12:45:04.563: INFO: Wait up to 5m0s for pod "restored-pvc-tester-6898x" to be fully deleted
May  4 12:45:46.571: INFO: deleting claim "snapshotting-3797"/"pvc-bnnbt"
... skipping 28 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":648,"failed":0}
May  4 12:45:53.637: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
May  4 12:43:38.984: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
May  4 12:43:38.994: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iocnlpw] to have phase Bound
May  4 12:43:39.002: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocnlpw found but phase is Pending instead of Bound.
May  4 12:43:41.006: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocnlpw found and phase=Bound (2.011918119s)
STEP: starting a pod to use the claim
May  4 12:43:41.031: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-z64d2" in namespace "snapshotting-8329" to be "Succeeded or Failed"
May  4 12:43:41.036: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.371997ms
May  4 12:43:43.040: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00841511s
May  4 12:43:45.043: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.011482243s
May  4 12:43:47.047: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.015298991s
May  4 12:43:49.050: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.0186674s
May  4 12:43:51.057: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.025807521s
May  4 12:43:53.061: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 12.029250678s
May  4 12:43:55.068: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 14.036606672s
May  4 12:43:57.072: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 16.040233151s
May  4 12:43:59.076: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 18.043979596s
May  4 12:44:01.080: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Pending", Reason="", readiness=false. Elapsed: 20.048251031s
May  4 12:44:03.084: INFO: Pod "pvc-snapshottable-tester-z64d2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.051862297s
STEP: Saw pod success
May  4 12:44:03.084: INFO: Pod "pvc-snapshottable-tester-z64d2" satisfied condition "Succeeded or Failed"
May  4 12:44:03.094: INFO: Pod pvc-snapshottable-tester-z64d2 has the following logs: 
May  4 12:44:03.094: INFO: Deleting pod "pvc-snapshottable-tester-z64d2" in namespace "snapshotting-8329"
May  4 12:44:03.104: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-z64d2" to be fully deleted
May  4 12:44:03.107: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iocnlpw] to have phase Bound
May  4 12:44:03.109: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocnlpw found and phase=Bound (2.10977ms)
STEP: checking the claim
... skipping 33 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
May  4 12:44:13.232: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-8cs5j" in namespace "snapshotting-8329" to be "Succeeded or Failed"
May  4 12:44:13.239: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Pending", Reason="", readiness=false. Elapsed: 6.343086ms
May  4 12:44:15.243: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009884387s
May  4 12:44:17.248: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Pending", Reason="", readiness=false. Elapsed: 4.015017349s
May  4 12:44:19.251: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Pending", Reason="", readiness=false. Elapsed: 6.018252087s
May  4 12:44:21.255: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Pending", Reason="", readiness=false. Elapsed: 8.021490807s
May  4 12:44:23.259: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Pending", Reason="", readiness=false. Elapsed: 10.026047087s
May  4 12:44:25.265: INFO: Pod "pvc-snapshottable-data-tester-8cs5j": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.032174449s
STEP: Saw pod success
May  4 12:44:25.265: INFO: Pod "pvc-snapshottable-data-tester-8cs5j" satisfied condition "Succeeded or Failed"
May  4 12:44:25.276: INFO: Pod pvc-snapshottable-data-tester-8cs5j has the following logs: 
May  4 12:44:25.276: INFO: Deleting pod "pvc-snapshottable-data-tester-8cs5j" in namespace "snapshotting-8329"
May  4 12:44:25.295: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-8cs5j" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
May  4 12:44:33.332: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:38747 --kubeconfig=/root/.kube/config --namespace=snapshotting-8329 exec restored-pvc-tester-vdk2j --namespace=snapshotting-8329 -- cat /mnt/test/data'
... skipping 35 lines ...
May  4 12:44:59.619: INFO: volumesnapshotcontents pre-provisioned-snapcontent-296abc8e-2c29-4ed0-a833-a73657dd9188 has been found and is not deleted
May  4 12:45:00.623: INFO: volumesnapshotcontents pre-provisioned-snapcontent-296abc8e-2c29-4ed0-a833-a73657dd9188 has been found and is not deleted
May  4 12:45:01.627: INFO: volumesnapshotcontents pre-provisioned-snapcontent-296abc8e-2c29-4ed0-a833-a73657dd9188 has been found and is not deleted
May  4 12:45:02.631: INFO: volumesnapshotcontents pre-provisioned-snapcontent-296abc8e-2c29-4ed0-a833-a73657dd9188 has been found and is not deleted
May  4 12:45:03.635: INFO: volumesnapshotcontents pre-provisioned-snapcontent-296abc8e-2c29-4ed0-a833-a73657dd9188 has been found and is not deleted
May  4 12:45:04.639: INFO: volumesnapshotcontents pre-provisioned-snapcontent-296abc8e-2c29-4ed0-a833-a73657dd9188 has been found and is not deleted
May  4 12:45:05.639: 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:192
May  4 12:45:05.645: INFO: Pod restored-pvc-tester-vdk2j has the following logs: 
May  4 12:45:05.645: INFO: Deleting pod "restored-pvc-tester-vdk2j" in namespace "snapshotting-8329"
May  4 12:45:05.650: INFO: Wait up to 5m0s for pod "restored-pvc-tester-vdk2j" to be fully deleted
May  4 12:45:47.658: INFO: deleting claim "snapshotting-8329"/"pvc-9k687"
... skipping 28 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":57,"failed":0}
May  4 12:45:54.724: INFO: Running AfterSuite actions on all nodes


{"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":174,"failed":0}
May  4 12:44:34.114: INFO: Running AfterSuite actions on all nodes
May  4 12:45:54.759: INFO: Running AfterSuite actions on node 1
May  4 12:45:54.759: INFO: Dumping logs locally to: /logs/artifacts
May  4 12:45:54.759: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory


Ran 6 of 5870 Specs in 136.241 seconds
SUCCESS! -- 6 Passed | 0 Failed | 0 Pending | 5864 Skipped


Ginkgo ran 1 suite in 2m19.388011578s
Test Suite Passed
Tue May  4 12:45:54 UTC 2021 go1.16.3 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/external-provisioner/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
Tue May  4 12:45:55 UTC 2021 go1.16.3 $ kind delete cluster --name=csi-prow
... skipping 17 lines ...