This job view page is being replaced by Spyglass soon. Check out the new job view.
PRhumblec: make the formatting errors based on go 1.19
Resultsuccess
Tests 0 failed / 62 succeeded
Started2022-09-06 10:25
Elapsed40m5s
Revision
Refs 758
uploadercrier
uploadercrier

No Test Failures!


Show 62 Passed Tests

Show 248 Skipped Tests

Error lines from build-log.txt

... skipping 49 lines ...
non alpha feature gates for latest Kubernetes: CSI_PROW_E2E_GATES_LATEST=
non alpha E2E feature gates: CSI_PROW_E2E_GATES=
external-snapshotter version tag: CSI_SNAPSHOTTER_VERSION=v4.0.0
tests that need to be skipped: CSI_PROW_E2E_SKIP=Disruptive
work directory: CSI_PROW_WORK=/home/prow/go/pkg/csiprow.K7KPDGOE2l
artifacts: ARTIFACTS=/logs/artifacts
Tue Sep  6 10:26:01 UTC 2022 go1.19 $ curl --fail --location https://dl.google.com/go/go1.18.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
  1  135M    1 2000k    0     0  8620k      0  0:00:16 --:--:--  0:00:16 8583k
 18  135M   18 25.6M    0     0  21.4M      0  0:00:06  0:00:01  0:00:05 21.3M
 35  135M   35 48.5M    0     0  21.7M      0  0:00:06  0:00:02  0:00:04 21.7M
 55  135M   55 74.5M    0     0  23.2M      0  0:00:05  0:00:03  0:00:02 23.2M
 84  135M   84  114M    0     0  27.0M      0  0:00:04  0:00:04 --:--:-- 27.0M
 97  135M   97  131M    0     0  25.2M      0  0:00:05  0:00:05 --:--:-- 26.0M
100  135M  100  135M    0     0  24.0M      0  0:00:05  0:00:05 --:--:-- 24.8M
Tue Sep  6 10:26:06 UTC 2022 go1.18 $ make all GOFLAGS_VENDOR=-mod=vendor BUILD_PLATFORMS=linux amd64 amd64; linux ppc64le ppc64le -ppc64le; linux s390x s390x -s390x; linux arm arm -arm; linux arm64 arm64 -arm64; linux arm arm/v7 -armv7; windows amd64 amd64 .exe nanoserver:1809 servercore:ltsc2019; windows amd64 amd64 .exe nanoserver:20H2 servercore:20H2; windows amd64 amd64 .exe nanoserver:ltsc2022 servercore:ltsc2022
./release-tools/verify-go-version.sh "go"
mkdir -p bin
... skipping 4 lines ...
export os_arch_seen="" && echo 'linux amd64 amd64; linux ppc64le ppc64le -ppc64le; linux s390x s390x -s390x; linux arm arm -arm; linux arm64 arm64 -arm64; linux arm arm/v7 -armv7; windows amd64 amd64 .exe nanoserver:1809 servercore:ltsc2019; windows amd64 amd64 .exe nanoserver:20H2 servercore:20H2; windows amd64 amd64 .exe nanoserver:ltsc2022 servercore:ltsc2022' | tr ';' '\n' | while read -r os arch buildx_platform suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch-$buildx_platform*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/snapshot-controller && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v6.0.0-rc1-88-g053d03bc -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/bin/snapshot-controller$suffix" .); then \
		echo "Building snapshot-controller for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch-$buildx_platform"; \
done
+ cd ./cmd/snapshot-controller
+ CGO_ENABLED=0
... skipping 38 lines ...
export os_arch_seen="" && echo 'linux amd64 amd64; linux ppc64le ppc64le -ppc64le; linux s390x s390x -s390x; linux arm arm -arm; linux arm64 arm64 -arm64; linux arm arm/v7 -armv7; windows amd64 amd64 .exe nanoserver:1809 servercore:ltsc2019; windows amd64 amd64 .exe nanoserver:20H2 servercore:20H2; windows amd64 amd64 .exe nanoserver:ltsc2022 servercore:ltsc2022' | tr ';' '\n' | while read -r os arch buildx_platform suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch-$buildx_platform*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/csi-snapshotter && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v6.0.0-rc1-88-g053d03bc -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/bin/csi-snapshotter$suffix" .); then \
		echo "Building csi-snapshotter for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch-$buildx_platform"; \
done
+ cd ./cmd/csi-snapshotter
+ CGO_ENABLED=0
... skipping 38 lines ...
export os_arch_seen="" && echo 'linux amd64 amd64; linux ppc64le ppc64le -ppc64le; linux s390x s390x -s390x; linux arm arm -arm; linux arm64 arm64 -arm64; linux arm arm/v7 -armv7; windows amd64 amd64 .exe nanoserver:1809 servercore:ltsc2019; windows amd64 amd64 .exe nanoserver:20H2 servercore:20H2; windows amd64 amd64 .exe nanoserver:ltsc2022 servercore:ltsc2022' | tr ';' '\n' | while read -r os arch buildx_platform suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch-$buildx_platform*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/snapshot-validation-webhook && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v6.0.0-rc1-88-g053d03bc -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/bin/snapshot-validation-webhook$suffix" .); then \
		echo "Building snapshot-validation-webhook for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch-$buildx_platform"; \
done
+ cd ./cmd/snapshot-validation-webhook
+ CGO_ENABLED=0
... skipping 40 lines ...
export os_arch_seen="" && echo '' | tr ';' '\n' | while read -r os arch buildx_platform suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch-$buildx_platform*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/snapshot-controller && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v6.0.0-rc1-88-g053d03bc -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/bin/snapshot-controller$suffix" .); then \
		echo "Building snapshot-controller for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch-$buildx_platform"; \
done
+ cd ./cmd/snapshot-controller
+ CGO_ENABLED=0
... skipping 42 lines ...
export os_arch_seen="" && echo '' | tr ';' '\n' | while read -r os arch buildx_platform suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch-$buildx_platform*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/csi-snapshotter && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v6.0.0-rc1-88-g053d03bc -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/bin/csi-snapshotter$suffix" .); then \
		echo "Building csi-snapshotter for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch-$buildx_platform"; \
done
+ cd ./cmd/csi-snapshotter
+ CGO_ENABLED=0
... skipping 35 lines ...
export os_arch_seen="" && echo '' | tr ';' '\n' | while read -r os arch buildx_platform suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch-$buildx_platform*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/snapshot-validation-webhook && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v6.0.0-rc1-88-g053d03bc -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/bin/snapshot-validation-webhook$suffix" .); then \
		echo "Building snapshot-validation-webhook for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch-$buildx_platform"; \
done
+ cd ./cmd/snapshot-validation-webhook
+ CGO_ENABLED=0
... skipping 24 lines ...
Step 7/7 : LABEL revision=v6.0.0-rc1-88-g053d03bc
 ---> Running in 77684446817d
Removing intermediate container 77684446817d
 ---> 4d6aeb0e7646
Successfully built 4d6aeb0e7646
Successfully tagged snapshot-validation-webhook:latest
Tue Sep  6 10:45:38 UTC 2022 go1.19 $ curl --fail --location -o /home/prow/go/pkg/csiprow.K7KPDGOE2l/bin/kind https://github.com/kubernetes-sigs/kind/releases/download/v0.14.0/kind-linux-amd64
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0

100 6502k  100 6502k    0     0  16.8M      0 --:--:-- --:--:-- --:--:-- 16.8M
No kind clusters found.
INFO: kind-config.yaml:
... skipping 212 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 4ce5a895 Release commit for Kubernetes v1.24.0
Tue Sep  6 10:48:17 UTC 2022 go1.19 /home/prow/go/src/k8s.io/kubernetes$ git clean -fdx
Tue Sep  6 10:48:17 UTC 2022 go1.19 $ curl --fail --location https://dl.google.com/go/go1.18.1.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
 14  135M   14 19.6M    0     0  29.7M      0  0:00:04 --:--:--  0:00:04 29.6M
 32  135M   32 43.2M    0     0  26.1M      0  0:00:05  0:00:01  0:00:04 26.1M
 51  135M   51 69.3M    0     0  25.9M      0  0:00:05  0:00:02  0:00:03 25.9M
 84  135M   84  113M    0     0  31.0M      0  0:00:04  0:00:03  0:00:01 31.0M
100  135M  100  135M    0     0  28.6M      0  0:00:04  0:00:04 --:--:-- 28.6M
100  135M  100  135M    0     0  28.6M      0  0:00:04  0:00:04 --:--:-- 28.4M
Tue Sep  6 10:48:22 UTC 2022 go1.18.1 $ 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 83 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:258

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 210 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
    test/e2e/storage/testsuites/volumemode.go:299

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 206 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:242

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 42 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:280

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 155 lines ...

    test/e2e/storage/external/external.go:262
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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]
  test/e2e/storage/testsuites/subpath.go:280

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:55:21.396: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
W0906 10:55:21.507752  175785 warnings.go:70] policy/v1beta1 PodSecurityPolicy is deprecated in v1.21+, unavailable in v1.25+
Sep  6 10:55:21.507: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:280
Sep  6 10:55:21.534: INFO: Creating resource for dynamic PV
Sep  6 10:55:21.534: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-4264-e2e-scsvncl
STEP: creating a claim
Sep  6 10:55:21.559: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:55:21.583: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iofhzdm] to have phase Bound
Sep  6 10:55:21.597: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofhzdm found but phase is Pending instead of Bound.
Sep  6 10:55:23.931: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofhzdm found and phase=Bound (2.347931232s)
STEP: Creating pod pod-subpath-test-dynamicpv-kjhv
STEP: Checking for subpath error in container status
Sep  6 10:55:42.198: INFO: Deleting pod "pod-subpath-test-dynamicpv-kjhv" in namespace "provisioning-4264"
Sep  6 10:55:42.208: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-kjhv" to be fully deleted
STEP: Deleting pod
Sep  6 10:55:46.226: INFO: Deleting pod "pod-subpath-test-dynamicpv-kjhv" in namespace "provisioning-4264"
STEP: Deleting pvc
Sep  6 10:55:46.232: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iofhzdm"
... skipping 9 lines ...

• [SLOW TEST:29.886 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:280
------------------------------
{"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":252,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (xfs)][Slow] volumes
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:55:51.430: INFO: Driver hostpath.csi.k8s.io doesn't support xfs -- skipping
... skipping 52 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read/write inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:196
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral should create read/write inline ephemeral volume","total":-1,"completed":1,"skipped":31,"failed":0}

SSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumeIO 
  should write files of various sizes, verify size, validate content [Slow]
  test/e2e/storage/testsuites/volume_io.go:149
... skipping 44 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volumeIO
  test/e2e/storage/framework/testsuite.go:50
    should write files of various sizes, verify size, validate content [Slow]
    test/e2e/storage/testsuites/volume_io.go:149
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumeIO should write files of various sizes, verify size, validate content [Slow]","total":-1,"completed":1,"skipped":159,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should verify container cannot write to subpath readonly volumes [Slow]
  test/e2e/storage/testsuites/subpath.go:425
... skipping 15 lines ...
STEP: creating a claim
Sep  6 10:55:22.573: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:55:22.586: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ionv2qd] to have phase Bound
Sep  6 10:55:22.591: INFO: PersistentVolumeClaim hostpath.csi.k8s.ionv2qd found but phase is Pending instead of Bound.
Sep  6 10:55:24.603: INFO: PersistentVolumeClaim hostpath.csi.k8s.ionv2qd found and phase=Bound (2.016269083s)
STEP: Creating pod to format volume volume-prep-provisioning-9277
Sep  6 10:55:24.639: INFO: Waiting up to 5m0s for pod "volume-prep-provisioning-9277" in namespace "provisioning-9277" to be "Succeeded or Failed"
Sep  6 10:55:24.649: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 10.185081ms
Sep  6 10:55:26.661: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 2.021769484s
Sep  6 10:55:28.668: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 4.028947573s
Sep  6 10:55:30.675: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 6.03562434s
Sep  6 10:55:32.681: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 8.042285358s
Sep  6 10:55:34.691: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 10.051496778s
... skipping 2 lines ...
Sep  6 10:55:40.712: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 16.073363183s
Sep  6 10:55:42.720: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 18.080559808s
Sep  6 10:55:44.728: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 20.08916451s
Sep  6 10:55:46.732: INFO: Pod "volume-prep-provisioning-9277": Phase="Pending", Reason="", readiness=false. Elapsed: 22.093412193s
Sep  6 10:55:48.741: INFO: Pod "volume-prep-provisioning-9277": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.101812638s
STEP: Saw pod success
Sep  6 10:55:48.741: INFO: Pod "volume-prep-provisioning-9277" satisfied condition "Succeeded or Failed"
Sep  6 10:55:48.741: INFO: Deleting pod "volume-prep-provisioning-9277" in namespace "provisioning-9277"
Sep  6 10:55:48.755: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-9277" to be fully deleted
STEP: Creating pod pod-subpath-test-dynamicpv-899q
STEP: Checking for subpath error in container status
Sep  6 10:56:06.784: INFO: Deleting pod "pod-subpath-test-dynamicpv-899q" in namespace "provisioning-9277"
Sep  6 10:56:06.800: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-899q" to be fully deleted
STEP: Deleting pod
Sep  6 10:56:06.805: INFO: Deleting pod "pod-subpath-test-dynamicpv-899q" in namespace "provisioning-9277"
STEP: Deleting pvc
Sep  6 10:56:06.810: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ionv2qd"
... skipping 12 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should verify container cannot write to subpath readonly volumes [Slow]
    test/e2e/storage/testsuites/subpath.go:425
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should verify container cannot write to subpath readonly volumes [Slow]","total":-1,"completed":1,"skipped":462,"failed":0}

SSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (xfs)][Slow] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:56:11.890: INFO: Driver hostpath.csi.k8s.io doesn't support xfs -- skipping
... skipping 326 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should access to two volumes with different volume mode and retain data across pod recreation on the same node
    test/e2e/storage/testsuites/multivolume.go:209
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with different volume mode and retain data across pod recreation on the same node","total":-1,"completed":1,"skipped":559,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:56:20.610: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
    test/e2e/storage/testsuites/volumemode.go:299

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 132 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should concurrently access the single read-only volume from pods on the same node
    test/e2e/storage/testsuites/multivolume.go:423
------------------------------
{"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":2,"skipped":201,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volume-expand 
  should not allow expansion of pvcs without AllowVolumeExpansion property
  test/e2e/storage/testsuites/volume_expand.go:159
... skipping 15 lines ...
STEP: creating a claim
Sep  6 10:56:12.677: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokbhjz] to have phase Bound
Sep  6 10:56:12.684: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokbhjz found but phase is Pending instead of Bound.
Sep  6 10:56:14.691: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokbhjz found and phase=Bound (2.014054751s)
STEP: Expanding non-expandable pvc
Sep  6 10:56:14.704: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Sep  6 10:56:14.719: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:16.735: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:18.732: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:20.751: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:22.742: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:24.736: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:26.736: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:28.731: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:30.734: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:32.733: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:34.733: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:36.736: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:38.731: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:40.735: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:42.740: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:44.736: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:56:44.752: INFO: Error updating pvc hostpath.csi.k8s.iokbhjz: persistentvolumeclaims "hostpath.csi.k8s.iokbhjz" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Sep  6 10:56:44.752: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iokbhjz"
Sep  6 10:56:44.762: INFO: Waiting up to 5m0s for PersistentVolume pvc-4eef4366-07b8-42c0-beb8-2543283a8ffb to get deleted
Sep  6 10:56:44.772: INFO: PersistentVolume pvc-4eef4366-07b8-42c0-beb8-2543283a8ffb found and phase=Bound (9.422925ms)
Sep  6 10:56:49.778: INFO: PersistentVolume pvc-4eef4366-07b8-42c0-beb8-2543283a8ffb was removed
STEP: Deleting sc
... skipping 8 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volume-expand
  test/e2e/storage/framework/testsuite.go:50
    should not allow expansion of pvcs without AllowVolumeExpansion property
    test/e2e/storage/testsuites/volume_expand.go:159
------------------------------
{"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":2,"skipped":737,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumes
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:56:49.939: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 183 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volumes
  test/e2e/storage/framework/testsuite.go:50
    should store data
    test/e2e/storage/testsuites/volumes.go:161
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes should store data","total":-1,"completed":2,"skipped":659,"failed":0}

SSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ext3)] volumes
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:56:52.366: INFO: Driver hostpath.csi.k8s.io doesn't support ext3 -- skipping
... skipping 58 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support multiple inline ephemeral volumes
    test/e2e/storage/testsuites/ephemeral.go:254
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should support multiple inline ephemeral volumes","total":-1,"completed":2,"skipped":42,"failed":0}

SS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral 
  should support two pods which have the same volume definition
  test/e2e/storage/testsuites/ephemeral.go:216
... skipping 51 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support two pods which have the same volume definition
    test/e2e/storage/testsuites/ephemeral.go:216
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should support two pods which have the same volume definition","total":-1,"completed":1,"skipped":34,"failed":0}

SSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:57:11.453: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:269

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 29 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:269

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 84 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support restarting containers using directory as subpath [Slow]
    test/e2e/storage/testsuites/subpath.go:322
------------------------------
{"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":179,"failed":0}

S
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:57:20.002: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:280

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 34 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read-only inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:175
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral should create read-only inline ephemeral volume","total":-1,"completed":3,"skipped":288,"failed":0}

SS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:57:23.547: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 61 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support two pods which have the same volume definition
    test/e2e/storage/testsuites/ephemeral.go:216
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral should support two pods which have the same volume definition","total":-1,"completed":2,"skipped":364,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:57:27.922: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 133 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read/write inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:196
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should create read/write inline ephemeral volume","total":-1,"completed":3,"skipped":977,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:57:38.893: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 35 lines ...
Sep  6 10:57:38.931: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:57:38.941: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9z2lj] to have phase Bound
Sep  6 10:57:38.946: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9z2lj found but phase is Pending instead of Bound.
Sep  6 10:57:40.953: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9z2lj found and phase=Bound (2.011786412s)
STEP: Creating pod pod-subpath-test-dynamicpv-tzf9
STEP: Creating a pod to test subpath
Sep  6 10:57:40.971: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-tzf9" in namespace "provisioning-7486" to be "Succeeded or Failed"
Sep  6 10:57:40.980: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 9.190142ms
Sep  6 10:57:42.988: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 2.016380641s
Sep  6 10:57:44.995: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 4.023249685s
Sep  6 10:57:47.001: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 6.029495674s
Sep  6 10:57:49.006: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.034974236s
Sep  6 10:57:51.014: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 10.042892302s
Sep  6 10:57:53.021: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Pending", Reason="", readiness=false. Elapsed: 12.050031631s
Sep  6 10:57:55.027: INFO: Pod "pod-subpath-test-dynamicpv-tzf9": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.055416255s
STEP: Saw pod success
Sep  6 10:57:55.027: INFO: Pod "pod-subpath-test-dynamicpv-tzf9" satisfied condition "Succeeded or Failed"
Sep  6 10:57:55.032: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-tzf9 container test-container-subpath-dynamicpv-tzf9: <nil>
STEP: delete the pod
Sep  6 10:57:55.055: INFO: Waiting for pod pod-subpath-test-dynamicpv-tzf9 to disappear
Sep  6 10:57:55.065: INFO: Pod pod-subpath-test-dynamicpv-tzf9 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-tzf9
Sep  6 10:57:55.065: INFO: Deleting pod "pod-subpath-test-dynamicpv-tzf9" in namespace "provisioning-7486"
... skipping 16 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support existing single file [LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:221
------------------------------
{"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":1005,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:58:00.296: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 182 lines ...
Sep  6 10:57:23.630: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:57:23.639: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7fmq7] to have phase Bound
Sep  6 10:57:23.644: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7fmq7 found but phase is Pending instead of Bound.
Sep  6 10:57:25.652: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7fmq7 found and phase=Bound (2.012871351s)
STEP: Creating pod pod-subpath-test-dynamicpv-nprl
STEP: Creating a pod to test atomic-volume-subpath
Sep  6 10:57:25.670: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-nprl" in namespace "provisioning-8512" to be "Succeeded or Failed"
Sep  6 10:57:25.679: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.827007ms
Sep  6 10:57:27.686: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.015868116s
Sep  6 10:57:29.693: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.022520596s
Sep  6 10:57:31.701: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.030778586s
Sep  6 10:57:33.707: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.03697467s
Sep  6 10:57:35.714: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.043275312s
... skipping 5 lines ...
Sep  6 10:57:47.756: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Running", Reason="", readiness=true. Elapsed: 22.085440895s
Sep  6 10:57:49.762: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Running", Reason="", readiness=true. Elapsed: 24.091973682s
Sep  6 10:57:51.770: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Running", Reason="", readiness=true. Elapsed: 26.099922514s
Sep  6 10:57:53.776: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Running", Reason="", readiness=false. Elapsed: 28.105659477s
Sep  6 10:57:55.783: INFO: Pod "pod-subpath-test-dynamicpv-nprl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 30.11317304s
STEP: Saw pod success
Sep  6 10:57:55.784: INFO: Pod "pod-subpath-test-dynamicpv-nprl" satisfied condition "Succeeded or Failed"
Sep  6 10:57:55.788: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-nprl container test-container-subpath-dynamicpv-nprl: <nil>
STEP: delete the pod
Sep  6 10:57:55.820: INFO: Waiting for pod pod-subpath-test-dynamicpv-nprl to disappear
Sep  6 10:57:55.825: INFO: Pod pod-subpath-test-dynamicpv-nprl no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-nprl
Sep  6 10:57:55.825: INFO: Deleting pod "pod-subpath-test-dynamicpv-nprl" in namespace "provisioning-8512"
... skipping 18 lines ...
  test/e2e/storage/framework/testsuite.go:50
    should support file as subpath [LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:232
------------------------------
S
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support file as subpath [LinuxOnly]","total":-1,"completed":4,"skipped":304,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:58:00.941: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 206 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read-only inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:175
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should create read-only inline ephemeral volume","total":-1,"completed":2,"skipped":216,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral 
  should support two pods which have the same volume definition
  test/e2e/storage/testsuites/ephemeral.go:216
... skipping 37 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support two pods which have the same volume definition
    test/e2e/storage/testsuites/ephemeral.go:216
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral should support two pods which have the same volume definition","total":-1,"completed":3,"skipped":44,"failed":0}

SSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (default fs)] fsgroupchangepolicy
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:58:06.751: INFO: Driver "hostpath.csi.k8s.io" does not support FsGroup - skipping
... skipping 76 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:50
    should not mount / map unused volumes in a pod [LinuxOnly]
    test/e2e/storage/testsuites/volumemode.go:354
------------------------------
{"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":5,"skipped":352,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes 
  should allow exec of files on the volume
  test/e2e/storage/testsuites/volumes.go:198
... skipping 16 lines ...
Sep  6 10:58:06.961: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:58:06.978: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io54jkj] to have phase Bound
Sep  6 10:58:06.981: INFO: PersistentVolumeClaim hostpath.csi.k8s.io54jkj found but phase is Pending instead of Bound.
Sep  6 10:58:08.987: INFO: PersistentVolumeClaim hostpath.csi.k8s.io54jkj found and phase=Bound (2.009014858s)
STEP: Creating pod exec-volume-test-dynamicpv-w8q8
STEP: Creating a pod to test exec-volume-test
Sep  6 10:58:09.008: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-w8q8" in namespace "volume-8600" to be "Succeeded or Failed"
Sep  6 10:58:09.015: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.947703ms
Sep  6 10:58:11.023: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.014462618s
Sep  6 10:58:13.032: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 4.023557299s
Sep  6 10:58:15.039: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.030655471s
Sep  6 10:58:17.047: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Running", Reason="", readiness=true. Elapsed: 8.03882146s
Sep  6 10:58:19.054: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Running", Reason="", readiness=false. Elapsed: 10.045513521s
Sep  6 10:58:21.059: INFO: Pod "exec-volume-test-dynamicpv-w8q8": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.050866164s
STEP: Saw pod success
Sep  6 10:58:21.060: INFO: Pod "exec-volume-test-dynamicpv-w8q8" satisfied condition "Succeeded or Failed"
Sep  6 10:58:21.064: INFO: Trying to get logs from node csi-prow-worker2 pod exec-volume-test-dynamicpv-w8q8 container exec-container-dynamicpv-w8q8: <nil>
STEP: delete the pod
Sep  6 10:58:21.089: INFO: Waiting for pod exec-volume-test-dynamicpv-w8q8 to disappear
Sep  6 10:58:21.093: INFO: Pod exec-volume-test-dynamicpv-w8q8 no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-w8q8
Sep  6 10:58:21.094: INFO: Deleting pod "exec-volume-test-dynamicpv-w8q8" in namespace "volume-8600"
... skipping 14 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volumes
  test/e2e/storage/framework/testsuite.go:50
    should allow exec of files on the volume
    test/e2e/storage/testsuites/volumes.go:198
------------------------------
{"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":4,"skipped":121,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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
  test/e2e/storage/testsuites/volume_expand.go:248
... skipping 99 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] provisioning
  test/e2e/storage/framework/testsuite.go:50
    should mount multiple PV pointing to the same storage on the same node
    test/e2e/storage/testsuites/provisioning.go:518
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] provisioning should mount multiple PV pointing to the same storage on the same node","total":-1,"completed":3,"skipped":241,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:58:39.881: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:242

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 84 lines ...
Sep  6 10:58:26.289: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:58:26.297: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iost6nf] to have phase Bound
Sep  6 10:58:26.305: INFO: PersistentVolumeClaim hostpath.csi.k8s.iost6nf found but phase is Pending instead of Bound.
Sep  6 10:58:28.311: INFO: PersistentVolumeClaim hostpath.csi.k8s.iost6nf found and phase=Bound (2.013600583s)
STEP: Creating pod pod-subpath-test-dynamicpv-v9vj
STEP: Creating a pod to test subpath
Sep  6 10:58:28.326: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-v9vj" in namespace "provisioning-49" to be "Succeeded or Failed"
Sep  6 10:58:28.334: INFO: Pod "pod-subpath-test-dynamicpv-v9vj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.988879ms
Sep  6 10:58:30.339: INFO: Pod "pod-subpath-test-dynamicpv-v9vj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.011807172s
Sep  6 10:58:32.343: INFO: Pod "pod-subpath-test-dynamicpv-v9vj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.016757995s
Sep  6 10:58:34.349: INFO: Pod "pod-subpath-test-dynamicpv-v9vj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.022486597s
Sep  6 10:58:36.355: INFO: Pod "pod-subpath-test-dynamicpv-v9vj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.027900435s
Sep  6 10:58:38.360: INFO: Pod "pod-subpath-test-dynamicpv-v9vj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.033547354s
STEP: Saw pod success
Sep  6 10:58:38.360: INFO: Pod "pod-subpath-test-dynamicpv-v9vj" satisfied condition "Succeeded or Failed"
Sep  6 10:58:38.365: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-v9vj container test-container-subpath-dynamicpv-v9vj: <nil>
STEP: delete the pod
Sep  6 10:58:38.381: INFO: Waiting for pod pod-subpath-test-dynamicpv-v9vj to disappear
Sep  6 10:58:38.385: INFO: Pod pod-subpath-test-dynamicpv-v9vj no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-v9vj
Sep  6 10:58:38.385: INFO: Deleting pod "pod-subpath-test-dynamicpv-v9vj" in namespace "provisioning-49"
... skipping 16 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support readOnly file specified in the volumeMount [LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:382
------------------------------
{"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":5,"skipped":203,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:58:43.515: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 121 lines ...
Sep  6 10:58:18.868: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:58:18.883: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9rh7k] to have phase Bound
Sep  6 10:58:18.888: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9rh7k found but phase is Pending instead of Bound.
Sep  6 10:58:20.893: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9rh7k found and phase=Bound (2.010330436s)
STEP: Creating pod pod-subpath-test-dynamicpv-bnkw
STEP: Creating a pod to test subpath
Sep  6 10:58:20.908: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-bnkw" in namespace "provisioning-1843" to be "Succeeded or Failed"
Sep  6 10:58:20.911: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 3.043142ms
Sep  6 10:58:22.918: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009787428s
Sep  6 10:58:24.922: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.01452007s
Sep  6 10:58:26.927: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01900486s
Sep  6 10:58:28.933: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.02516846s
Sep  6 10:58:30.939: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.030812083s
Sep  6 10:58:32.944: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 12.035611002s
Sep  6 10:58:34.949: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 14.040726722s
Sep  6 10:58:36.954: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Pending", Reason="", readiness=false. Elapsed: 16.045813876s
Sep  6 10:58:38.958: INFO: Pod "pod-subpath-test-dynamicpv-bnkw": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.050327894s
STEP: Saw pod success
Sep  6 10:58:38.958: INFO: Pod "pod-subpath-test-dynamicpv-bnkw" satisfied condition "Succeeded or Failed"
Sep  6 10:58:38.962: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-bnkw container test-container-volume-dynamicpv-bnkw: <nil>
STEP: delete the pod
Sep  6 10:58:38.978: INFO: Waiting for pod pod-subpath-test-dynamicpv-bnkw to disappear
Sep  6 10:58:38.981: INFO: Pod pod-subpath-test-dynamicpv-bnkw no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-bnkw
Sep  6 10:58:38.981: INFO: Deleting pod "pod-subpath-test-dynamicpv-bnkw" in namespace "provisioning-1843"
... skipping 16 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support existing directory
    test/e2e/storage/testsuites/subpath.go:207
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing directory","total":-1,"completed":6,"skipped":378,"failed":0}

SSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (immediate binding)] topology
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (immediate binding)] topology
... skipping 102 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read-only inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:175
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should create read-only inline ephemeral volume","total":-1,"completed":5,"skipped":1611,"failed":0}

SSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ext4)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:58:49.884: INFO: Driver hostpath.csi.k8s.io doesn't support ext4 -- skipping
... skipping 13 lines ...

    test/e2e/storage/framework/testsuite.go:121
------------------------------
SS
------------------------------
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]
  test/e2e/storage/testsuites/volumemode.go:299

[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:58:44.406: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  test/e2e/storage/testsuites/volumemode.go:299
Sep  6 10:58:44.430: INFO: Creating resource for dynamic PV
Sep  6 10:58:44.430: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-5436-e2e-scnnjnn
STEP: creating a claim
Sep  6 10:58:44.441: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7lpf7] to have phase Bound
Sep  6 10:58:44.444: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7lpf7 found but phase is Pending instead of Bound.
Sep  6 10:58:46.449: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7lpf7 found and phase=Bound (2.007782434s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Sep  6 10:58:48.475: INFO: Deleting pod "pod-36a69ca2-1d5a-4dc3-aed8-1c37aad0fdbc" in namespace "volumemode-5436"
Sep  6 10:58:48.479: INFO: Wait up to 5m0s for pod "pod-36a69ca2-1d5a-4dc3-aed8-1c37aad0fdbc" to be fully deleted
STEP: Deleting pvc
Sep  6 10:58:50.489: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io7lpf7"
Sep  6 10:58:50.494: INFO: Waiting up to 5m0s for PersistentVolume pvc-aa96e52e-bd66-49b6-a1a6-c5817a66a0a7 to get deleted
Sep  6 10:58:50.497: INFO: PersistentVolume pvc-aa96e52e-bd66-49b6-a1a6-c5817a66a0a7 found and phase=Bound (2.640062ms)
... skipping 7 lines ...

• [SLOW TEST:11.127 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow]
    test/e2e/storage/testsuites/volumemode.go:299
------------------------------
{"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":7,"skipped":571,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support restarting containers using file as subpath [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:333
... skipping 58 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support restarting containers using file as subpath [Slow][LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:333
------------------------------
{"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":196,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] provisioning
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] provisioning
... skipping 69 lines ...
Sep  6 10:58:43.832: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:58:43.840: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9jsnq] to have phase Bound
Sep  6 10:58:43.844: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9jsnq found but phase is Pending instead of Bound.
Sep  6 10:58:45.850: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9jsnq found and phase=Bound (2.009490592s)
STEP: Creating pod pod-subpath-test-dynamicpv-rb4s
STEP: Creating a pod to test multi_subpath
Sep  6 10:58:45.863: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-rb4s" in namespace "provisioning-3518" to be "Succeeded or Failed"
Sep  6 10:58:45.866: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 2.91211ms
Sep  6 10:58:47.871: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007845898s
Sep  6 10:58:49.875: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 4.012558526s
Sep  6 10:58:51.878: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01578834s
Sep  6 10:58:53.884: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 8.021284051s
Sep  6 10:58:55.890: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 10.027606371s
Sep  6 10:58:57.893: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 12.030793647s
Sep  6 10:58:59.898: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Pending", Reason="", readiness=false. Elapsed: 14.03519468s
Sep  6 10:59:01.902: INFO: Pod "pod-subpath-test-dynamicpv-rb4s": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.039318886s
STEP: Saw pod success
Sep  6 10:59:01.902: INFO: Pod "pod-subpath-test-dynamicpv-rb4s" satisfied condition "Succeeded or Failed"
Sep  6 10:59:01.905: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-rb4s container test-container-subpath-dynamicpv-rb4s: <nil>
STEP: delete the pod
Sep  6 10:59:01.924: INFO: Waiting for pod pod-subpath-test-dynamicpv-rb4s to disappear
Sep  6 10:59:01.928: INFO: Pod pod-subpath-test-dynamicpv-rb4s no longer exists
STEP: Deleting pod
Sep  6 10:59:01.928: INFO: Deleting pod "pod-subpath-test-dynamicpv-rb4s" in namespace "provisioning-3518"
... skipping 14 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support creating multiple subpath from same volumes [Slow]
    test/e2e/storage/testsuites/subpath.go:296
------------------------------
{"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":6,"skipped":462,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:59:07.068: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 13 lines ...

    test/e2e/storage/external/external.go:262
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:242

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:58:40.226: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:242
Sep  6 10:58:40.292: INFO: Creating resource for dynamic PV
Sep  6 10:58:40.292: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-1299-e2e-scc8xcb
STEP: creating a claim
Sep  6 10:58:40.298: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:58:40.305: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iothlqz] to have phase Bound
Sep  6 10:58:40.309: INFO: PersistentVolumeClaim hostpath.csi.k8s.iothlqz found but phase is Pending instead of Bound.
Sep  6 10:58:42.313: INFO: PersistentVolumeClaim hostpath.csi.k8s.iothlqz found and phase=Bound (2.007893761s)
STEP: Creating pod pod-subpath-test-dynamicpv-h7zd
STEP: Checking for subpath error in container status
Sep  6 10:59:02.339: INFO: Deleting pod "pod-subpath-test-dynamicpv-h7zd" in namespace "provisioning-1299"
Sep  6 10:59:02.346: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-h7zd" to be fully deleted
STEP: Deleting pod
Sep  6 10:59:06.356: INFO: Deleting pod "pod-subpath-test-dynamicpv-h7zd" in namespace "provisioning-1299"
STEP: Deleting pvc
Sep  6 10:59:06.359: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iothlqz"
... skipping 9 lines ...

• [SLOW TEST:31.160 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:242
------------------------------
{"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":511,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumes 
  should store data
  test/e2e/storage/testsuites/volumes.go:161

{"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":665,"failed":0}
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumes
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumes
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:58:31.561: INFO: >>> kubeConfig: /root/.kube/config
... skipping 86 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volumes
  test/e2e/storage/framework/testsuite.go:50
    should store data
    test/e2e/storage/testsuites/volumes.go:161
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumes should store data","total":-1,"completed":4,"skipped":665,"failed":0}

SSSSSSSSSSSSSSSSSS
------------------------------
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
  test/e2e/storage/testsuites/volume_expand.go:248
... skipping 43 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand
  test/e2e/storage/framework/testsuite.go:50
    should resize volume when PVC is edited while pod is using it
    test/e2e/storage/testsuites/volume_expand.go:248
------------------------------
{"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":3,"skipped":607,"failed":0}

SSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode 
  should not mount / map unused volumes in a pod [LinuxOnly]
  test/e2e/storage/testsuites/volumemode.go:354
... skipping 55 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:50
    should not mount / map unused volumes in a pod [LinuxOnly]
    test/e2e/storage/testsuites/volumemode.go:354
------------------------------
{"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":8,"skipped":620,"failed":0}

SSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:59:25.087: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 55 lines ...

    test/e2e/storage/external/external.go:262
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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]
  test/e2e/storage/testsuites/volumemode.go:299

[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:59:11.485: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  test/e2e/storage/testsuites/volumemode.go:299
Sep  6 10:59:11.514: INFO: Creating resource for dynamic PV
Sep  6 10:59:11.514: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-5221-e2e-scwg47v
STEP: creating a claim
Sep  6 10:59:11.525: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioxx6f2] to have phase Bound
Sep  6 10:59:11.529: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxx6f2 found but phase is Pending instead of Bound.
Sep  6 10:59:13.534: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxx6f2 found and phase=Bound (2.009005384s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Sep  6 10:59:17.560: INFO: Deleting pod "pod-7d08febe-287a-479b-992b-c748c803aa50" in namespace "volumemode-5221"
Sep  6 10:59:17.565: INFO: Wait up to 5m0s for pod "pod-7d08febe-287a-479b-992b-c748c803aa50" to be fully deleted
STEP: Deleting pvc
Sep  6 10:59:21.575: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioxx6f2"
Sep  6 10:59:21.580: INFO: Waiting up to 5m0s for PersistentVolume pvc-541d8ac4-67e9-4533-bfa7-494542b6990f to get deleted
Sep  6 10:59:21.583: INFO: PersistentVolume pvc-541d8ac4-67e9-4533-bfa7-494542b6990f found and phase=Bound (3.057409ms)
... skipping 7 lines ...

• [SLOW TEST:15.119 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow]
    test/e2e/storage/testsuites/volumemode.go:299
------------------------------
{"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":5,"skipped":571,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath file is outside the volume [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:258

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:59:07.136: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:258
Sep  6 10:59:07.158: INFO: Creating resource for dynamic PV
Sep  6 10:59:07.158: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-9647-e2e-sc9z9hp
STEP: creating a claim
Sep  6 10:59:07.162: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:59:07.171: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iovz6ft] to have phase Bound
Sep  6 10:59:07.176: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovz6ft found but phase is Pending instead of Bound.
Sep  6 10:59:09.180: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovz6ft found and phase=Bound (2.009297056s)
STEP: Creating pod pod-subpath-test-dynamicpv-j75h
STEP: Checking for subpath error in container status
Sep  6 10:59:23.200: INFO: Deleting pod "pod-subpath-test-dynamicpv-j75h" in namespace "provisioning-9647"
Sep  6 10:59:23.209: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-j75h" to be fully deleted
STEP: Deleting pod
Sep  6 10:59:25.218: INFO: Deleting pod "pod-subpath-test-dynamicpv-j75h" in namespace "provisioning-9647"
STEP: Deleting pvc
Sep  6 10:59:25.224: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iovz6ft"
... skipping 9 lines ...

• [SLOW TEST:23.119 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath file is outside the volume [Slow][LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:258
------------------------------
{"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":584,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] volumes
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:59:30.384: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 202 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should concurrently access the single volume from pods on the same node
    test/e2e/storage/testsuites/multivolume.go:298
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node","total":-1,"completed":6,"skipped":1621,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (default fs)] fsgroupchangepolicy
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:59:34.925: INFO: Driver "hostpath.csi.k8s.io" does not support FsGroup - skipping
... skipping 58 lines ...
Sep  6 10:59:14.091: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:59:14.099: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io4tmgk] to have phase Bound
Sep  6 10:59:14.102: INFO: PersistentVolumeClaim hostpath.csi.k8s.io4tmgk found but phase is Pending instead of Bound.
Sep  6 10:59:16.107: INFO: PersistentVolumeClaim hostpath.csi.k8s.io4tmgk found and phase=Bound (2.007894315s)
STEP: Expanding non-expandable pvc
Sep  6 10:59:16.115: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Sep  6 10:59:16.124: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:18.133: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:20.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:22.135: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:24.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:26.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:28.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:30.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:32.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:34.135: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:36.133: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:38.135: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:40.132: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:42.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:44.137: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:46.134: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep  6 10:59:46.142: INFO: Error updating pvc hostpath.csi.k8s.io4tmgk: persistentvolumeclaims "hostpath.csi.k8s.io4tmgk" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Sep  6 10:59:46.142: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io4tmgk"
Sep  6 10:59:46.148: INFO: Waiting up to 5m0s for PersistentVolume pvc-7451b101-bfd0-4f96-9583-157c5aab4019 to get deleted
Sep  6 10:59:46.151: INFO: PersistentVolume pvc-7451b101-bfd0-4f96-9583-157c5aab4019 found and phase=Bound (2.867796ms)
Sep  6 10:59:51.157: INFO: PersistentVolume pvc-7451b101-bfd0-4f96-9583-157c5aab4019 was removed
STEP: Deleting sc
... skipping 8 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volume-expand
  test/e2e/storage/framework/testsuite.go:50
    should not allow expansion of pvcs without AllowVolumeExpansion property
    test/e2e/storage/testsuites/volume_expand.go:159
------------------------------
{"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":5,"skipped":683,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support non-existent path
  test/e2e/storage/testsuites/subpath.go:196
... skipping 16 lines ...
Sep  6 10:59:30.486: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:59:30.494: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iognzz8] to have phase Bound
Sep  6 10:59:30.497: INFO: PersistentVolumeClaim hostpath.csi.k8s.iognzz8 found but phase is Pending instead of Bound.
Sep  6 10:59:32.502: INFO: PersistentVolumeClaim hostpath.csi.k8s.iognzz8 found and phase=Bound (2.008707872s)
STEP: Creating pod pod-subpath-test-dynamicpv-bmtj
STEP: Creating a pod to test subpath
Sep  6 10:59:32.513: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-bmtj" in namespace "provisioning-9070" to be "Succeeded or Failed"
Sep  6 10:59:32.518: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.027378ms
Sep  6 10:59:34.523: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008879s
Sep  6 10:59:36.528: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.014008747s
Sep  6 10:59:38.532: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.018007571s
Sep  6 10:59:40.537: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022598401s
Sep  6 10:59:42.543: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.028362645s
Sep  6 10:59:44.547: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.032489412s
Sep  6 10:59:46.552: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 14.037710223s
Sep  6 10:59:48.557: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 16.042421248s
Sep  6 10:59:50.561: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 18.046952137s
Sep  6 10:59:52.567: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Pending", Reason="", readiness=false. Elapsed: 20.052263543s
Sep  6 10:59:54.571: INFO: Pod "pod-subpath-test-dynamicpv-bmtj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.056938922s
STEP: Saw pod success
Sep  6 10:59:54.571: INFO: Pod "pod-subpath-test-dynamicpv-bmtj" satisfied condition "Succeeded or Failed"
Sep  6 10:59:54.575: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-bmtj container test-container-volume-dynamicpv-bmtj: <nil>
STEP: delete the pod
Sep  6 10:59:54.596: INFO: Waiting for pod pod-subpath-test-dynamicpv-bmtj to disappear
Sep  6 10:59:54.598: INFO: Pod pod-subpath-test-dynamicpv-bmtj no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-bmtj
Sep  6 10:59:54.598: INFO: Deleting pod "pod-subpath-test-dynamicpv-bmtj" in namespace "provisioning-9070"
... skipping 16 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support non-existent path
    test/e2e/storage/testsuites/subpath.go:196
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support non-existent path","total":-1,"completed":8,"skipped":728,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] volumeIO
  test/e2e/storage/framework/testsuite.go:51
Sep  6 10:59:59.779: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 185 lines ...
Sep  6 10:59:26.657: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:59:26.669: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioknbh4] to have phase Bound
Sep  6 10:59:26.672: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioknbh4 found but phase is Pending instead of Bound.
Sep  6 10:59:28.676: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioknbh4 found and phase=Bound (2.007668883s)
STEP: Creating pod pod-subpath-test-dynamicpv-t75q
STEP: Creating a pod to test subpath
Sep  6 10:59:28.692: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-t75q" in namespace "provisioning-4737" to be "Succeeded or Failed"
Sep  6 10:59:28.696: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 3.618112ms
Sep  6 10:59:30.700: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007724689s
Sep  6 10:59:32.705: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 4.012685304s
Sep  6 10:59:34.710: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 6.017083283s
Sep  6 10:59:36.715: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022413456s
Sep  6 10:59:38.720: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 10.02736193s
Sep  6 10:59:40.726: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 12.032866968s
Sep  6 10:59:42.730: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 14.036997885s
Sep  6 10:59:44.735: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 16.042015419s
Sep  6 10:59:46.740: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 18.047578101s
Sep  6 10:59:48.745: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 20.051956989s
Sep  6 10:59:50.749: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.056762417s
STEP: Saw pod success
Sep  6 10:59:50.750: INFO: Pod "pod-subpath-test-dynamicpv-t75q" satisfied condition "Succeeded or Failed"
Sep  6 10:59:50.753: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-t75q container test-container-subpath-dynamicpv-t75q: <nil>
STEP: delete the pod
Sep  6 10:59:50.771: INFO: Waiting for pod pod-subpath-test-dynamicpv-t75q to disappear
Sep  6 10:59:50.775: INFO: Pod pod-subpath-test-dynamicpv-t75q no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-t75q
Sep  6 10:59:50.775: INFO: Deleting pod "pod-subpath-test-dynamicpv-t75q" in namespace "provisioning-4737"
STEP: Creating pod pod-subpath-test-dynamicpv-t75q
STEP: Creating a pod to test subpath
Sep  6 10:59:50.785: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-t75q" in namespace "provisioning-4737" to be "Succeeded or Failed"
Sep  6 10:59:50.789: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 4.139439ms
Sep  6 10:59:52.795: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 2.010165635s
Sep  6 10:59:54.801: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 4.016150103s
Sep  6 10:59:56.805: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 6.020034973s
Sep  6 10:59:58.811: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Pending", Reason="", readiness=false. Elapsed: 8.025755851s
Sep  6 11:00:00.815: INFO: Pod "pod-subpath-test-dynamicpv-t75q": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.029870853s
STEP: Saw pod success
Sep  6 11:00:00.815: INFO: Pod "pod-subpath-test-dynamicpv-t75q" satisfied condition "Succeeded or Failed"
Sep  6 11:00:00.818: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-t75q container test-container-subpath-dynamicpv-t75q: <nil>
STEP: delete the pod
Sep  6 11:00:00.833: INFO: Waiting for pod pod-subpath-test-dynamicpv-t75q to disappear
Sep  6 11:00:00.836: INFO: Pod pod-subpath-test-dynamicpv-t75q no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-t75q
Sep  6 11:00:00.836: INFO: Deleting pod "pod-subpath-test-dynamicpv-t75q" in namespace "provisioning-4737"
... skipping 16 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support existing directories when readOnly specified in the volumeSource
    test/e2e/storage/testsuites/subpath.go:397
------------------------------
{"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":6,"skipped":588,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
... skipping 210 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node
    test/e2e/storage/testsuites/multivolume.go:138
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node","total":-1,"completed":3,"skipped":246,"failed":0}

SSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral 
  should create read/write inline ephemeral volume
  test/e2e/storage/testsuites/ephemeral.go:196
... skipping 38 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read/write inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:196
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral should create read/write inline ephemeral volume","total":-1,"completed":9,"skipped":719,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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]
  test/e2e/storage/testsuites/subpath.go:269

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 10:59:51.329: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  test/e2e/storage/testsuites/subpath.go:269
Sep  6 10:59:51.355: INFO: Creating resource for dynamic PV
Sep  6 10:59:51.355: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-1696-e2e-sclvszb
STEP: creating a claim
Sep  6 10:59:51.360: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 10:59:51.367: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9cb5r] to have phase Bound
Sep  6 10:59:51.372: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9cb5r found but phase is Pending instead of Bound.
Sep  6 10:59:53.377: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9cb5r found and phase=Bound (2.009675842s)
STEP: Creating pod pod-subpath-test-dynamicpv-dvh4
STEP: Checking for subpath error in container status
Sep  6 11:00:17.402: INFO: Deleting pod "pod-subpath-test-dynamicpv-dvh4" in namespace "provisioning-1696"
Sep  6 11:00:17.408: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-dvh4" to be fully deleted
STEP: Deleting pod
Sep  6 11:00:21.423: INFO: Deleting pod "pod-subpath-test-dynamicpv-dvh4" in namespace "provisioning-1696"
STEP: Deleting pvc
Sep  6 11:00:21.427: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io9cb5r"
... skipping 9 lines ...

• [SLOW TEST:35.130 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:269
------------------------------
{"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":6,"skipped":774,"failed":0}

SSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:51
Sep  6 11:00:26.475: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 3 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    test/e2e/storage/testsuites/subpath.go:258

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

    test/e2e/storage/external/external.go:262
------------------------------
... skipping 257 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node
    test/e2e/storage/testsuites/multivolume.go:138
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node","total":-1,"completed":4,"skipped":629,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeLimits
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeLimits
... skipping 68 lines ...
Sep  6 11:00:17.589: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 11:00:17.597: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iofl8f9] to have phase Bound
Sep  6 11:00:17.601: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofl8f9 found but phase is Pending instead of Bound.
Sep  6 11:00:19.605: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofl8f9 found and phase=Bound (2.00836001s)
STEP: Creating pod pod-subpath-test-dynamicpv-88xq
STEP: Creating a pod to test subpath
Sep  6 11:00:19.618: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-88xq" in namespace "provisioning-6165" to be "Succeeded or Failed"
Sep  6 11:00:19.622: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.038425ms
Sep  6 11:00:21.627: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009525848s
Sep  6 11:00:23.632: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.014563966s
Sep  6 11:00:25.639: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 6.021069995s
Sep  6 11:00:27.645: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 8.027089985s
Sep  6 11:00:29.651: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 10.033069325s
Sep  6 11:00:31.656: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Pending", Reason="", readiness=false. Elapsed: 12.03817682s
Sep  6 11:00:33.661: INFO: Pod "pod-subpath-test-dynamicpv-88xq": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.043090114s
STEP: Saw pod success
Sep  6 11:00:33.661: INFO: Pod "pod-subpath-test-dynamicpv-88xq" satisfied condition "Succeeded or Failed"
Sep  6 11:00:33.664: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-88xq container test-container-subpath-dynamicpv-88xq: <nil>
STEP: delete the pod
Sep  6 11:00:33.679: INFO: Waiting for pod pod-subpath-test-dynamicpv-88xq to disappear
Sep  6 11:00:33.682: INFO: Pod pod-subpath-test-dynamicpv-88xq no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-88xq
Sep  6 11:00:33.682: INFO: Deleting pod "pod-subpath-test-dynamicpv-88xq" in namespace "provisioning-6165"
... skipping 16 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should support readOnly directory specified in the volumeMount
    test/e2e/storage/testsuites/subpath.go:367
------------------------------
{"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":10,"skipped":800,"failed":0}

SSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (ext3)] volumes
  test/e2e/storage/framework/testsuite.go:51
Sep  6 11:00:38.747: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 93 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand
  test/e2e/storage/framework/testsuite.go:50
    Verify if offline PVC expansion works
    test/e2e/storage/testsuites/volume_expand.go:176
------------------------------
{"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":9,"skipped":1379,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
... skipping 32 lines ...
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 11:00:41.876: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  test/e2e/storage/testsuites/topology.go:194
Sep  6 11:00:41.898: INFO: Driver didn't provide topology keys -- skipping
[AfterEach] [Testpattern: Dynamic PV (immediate binding)] topology
  test/e2e/framework/framework.go:188
Sep  6 11:00:41.899: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-6187" for this suite.


S [SKIPPING] [0.034 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (immediate binding)] topology
  test/e2e/storage/framework/testsuite.go:50
    should fail to schedule a pod which has topologies that conflict with AllowedTopologies [It]
    test/e2e/storage/testsuites/topology.go:194

    Driver didn't provide topology keys -- skipping

    test/e2e/storage/testsuites/topology.go:126
------------------------------
... skipping 79 lines ...
  test/e2e/framework/framework.go:187
STEP: Creating a kubernetes client
Sep  6 11:00:42.021: 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
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  test/e2e/storage/testsuites/topology.go:194
Sep  6 11:00:42.039: INFO: Driver didn't provide topology keys -- skipping
[AfterEach] [Testpattern: Dynamic PV (delayed binding)] topology
  test/e2e/framework/framework.go:188
Sep  6 11:00:42.039: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-154" for this suite.


S [SKIPPING] [0.027 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (delayed binding)] topology
  test/e2e/storage/framework/testsuite.go:50
    should fail to schedule a pod which has topologies that conflict with AllowedTopologies [It]
    test/e2e/storage/testsuites/topology.go:194

    Driver didn't provide topology keys -- skipping

    test/e2e/storage/testsuites/topology.go:126
------------------------------
... skipping 148 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should concurrently access the single volume from pods on the same node
    test/e2e/storage/testsuites/multivolume.go:298
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node","total":-1,"completed":7,"skipped":705,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral 
  should support multiple inline ephemeral volumes
  test/e2e/storage/testsuites/ephemeral.go:254
... skipping 25 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support multiple inline ephemeral volumes
    test/e2e/storage/testsuites/ephemeral.go:254
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral should support multiple inline ephemeral volumes","total":-1,"completed":4,"skipped":249,"failed":0}

SSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (xfs)][Slow] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:51
Sep  6 11:00:50.304: INFO: Driver hostpath.csi.k8s.io doesn't support xfs -- skipping
... skipping 74 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  test/e2e/storage/framework/testsuite.go:50
    should be able to unmount after the subpath directory is deleted [LinuxOnly]
    test/e2e/storage/testsuites/subpath.go:447
------------------------------
{"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":5,"skipped":809,"failed":0}
Sep  6 11:00:57.987: INFO: Running AfterSuite actions on all nodes
Sep  6 11:00:57.987: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:00:57.987: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:00:57.987: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:00:57.987: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:00:57.987: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 45 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should create read/write inline ephemeral volume
    test/e2e/storage/testsuites/ephemeral.go:196
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should create read/write inline ephemeral volume","total":-1,"completed":7,"skipped":1111,"failed":0}
Sep  6 11:01:11.291: INFO: Running AfterSuite actions on all nodes
Sep  6 11:01:11.291: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:01:11.291: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:01:11.291: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:01:11.291: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:01:11.291: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 138 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support two pods which have the same volume definition
    test/e2e/storage/testsuites/ephemeral.go:216
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should support two pods which have the same volume definition","total":-1,"completed":7,"skipped":1795,"failed":0}
Sep  6 11:01:20.089: INFO: Running AfterSuite actions on all nodes
Sep  6 11:01:20.089: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:01:20.089: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:01:20.089: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:01:20.089: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:01:20.089: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 57 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  test/e2e/storage/framework/testsuite.go:50
    Verify if offline PVC expansion works
    test/e2e/storage/testsuites/volume_expand.go:176
------------------------------
{"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":10,"skipped":1537,"failed":0}
Sep  6 11:01:25.227: INFO: Running AfterSuite actions on all nodes
Sep  6 11:01:25.227: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:01:25.227: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:01:25.227: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:01:25.227: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:01:25.227: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 44 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:50
    should support multiple inline ephemeral volumes
    test/e2e/storage/testsuites/ephemeral.go:254
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (block volmode) (late-binding)] ephemeral should support multiple inline ephemeral volumes","total":-1,"completed":8,"skipped":722,"failed":0}
Sep  6 11:01:33.739: INFO: Running AfterSuite actions on all nodes
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Sep  6 11:01:33.739: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


{"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":11,"skipped":908,"failed":0}
Sep  6 11:01:16.480: INFO: Running AfterSuite actions on all nodes
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Sep  6 11:01:16.480: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3
Sep  6 11:01:33.784: INFO: Running AfterSuite actions on node 1
Sep  6 11:01:33.784: INFO: Dumping logs locally to: /logs/artifacts
Sep  6 11:01:33.785: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory


Ran 52 of 7227 Specs in 373.711 seconds
SUCCESS! -- 52 Passed | 0 Failed | 0 Pending | 7175 Skipped


Ginkgo ran 1 suite in 6m20.843447374s
Test Suite Passed
Tue Sep  6 11:01:33 UTC 2022 go1.18 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/release-tools/filter-junit.go -t=External.Storage|CSI.mock.volume -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 Sep  6 11:01:35 UTC 2022 go1.19 /home/prow/go/src/k8s.io/kubernetes$ env KUBECONFIG=/root/.kube/config KUBE_TEST_REPO_LIST= ginkgo -v -p -focus=External.Storage.*(\[Feature:VolumeSnapshotDataSource\]) -skip=\[Serial\]|\[Disruptive\] /home/prow/go/pkg/csiprow.K7KPDGOE2l/e2e.test -- -report-dir /logs/artifacts -storage.testdriver=/home/prow/go/pkg/csiprow.K7KPDGOE2l/test-driver.yaml
... skipping 733 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] provisioning
  test/e2e/storage/framework/testsuite.go:50
    should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
    test/e2e/storage/testsuites/provisioning.go:208
------------------------------
{"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":405,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] 
  should concurrently access the volume and restored snapshot from pods on the same node [LinuxOnly][Feature:VolumeSnapshotDataSource][Feature:VolumeSourceXFS]
  test/e2e/storage/testsuites/multivolume.go:323
... skipping 99 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  test/e2e/storage/framework/testsuite.go:50
    should concurrently access the volume and restored snapshot from pods on the same node [LinuxOnly][Feature:VolumeSnapshotDataSource][Feature:VolumeSourceXFS]
    test/e2e/storage/testsuites/multivolume.go:323
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the volume and restored snapshot from pods on the same node [LinuxOnly][Feature:VolumeSnapshotDataSource][Feature:VolumeSourceXFS]","total":-1,"completed":1,"skipped":4295,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Ephemeral Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works, check deletion (ephemeral)
  test/e2e/storage/testsuites/snapshottable.go:177
... skipping 12 lines ...
[It] should check snapshot fields, check restore correctly works, check deletion (ephemeral)
  test/e2e/storage/testsuites/snapshottable.go:177
Sep  6 11:01:40.454: INFO: Creating resource for dynamic PV
Sep  6 11:01:40.454: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass snapshotting-7122-e2e-sc9gtt7
STEP: [init] starting a pod to use the claim
Sep  6 11:01:40.471: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-6mgzj" in namespace "snapshotting-7122" to be "Succeeded or Failed"
Sep  6 11:01:40.476: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.884245ms
Sep  6 11:01:42.480: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009109995s
Sep  6 11:01:44.484: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013365155s
Sep  6 11:01:46.489: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.017855834s
Sep  6 11:01:48.493: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022332573s
Sep  6 11:01:50.503: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.032065799s
Sep  6 11:01:52.508: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.03706556s
Sep  6 11:01:54.512: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Pending", Reason="", readiness=false. Elapsed: 14.041082995s
Sep  6 11:01:56.518: INFO: Pod "pvc-snapshottable-tester-6mgzj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.047071436s
STEP: Saw pod success
Sep  6 11:01:56.518: INFO: Pod "pvc-snapshottable-tester-6mgzj" satisfied condition "Succeeded or Failed"
STEP: [init] checking the claim
STEP: creating a SnapshotClass
STEP: creating a dynamic VolumeSnapshot
Sep  6 11:01:56.541: INFO: Waiting up to 5m0s for VolumeSnapshot snapshot-br69n to become ready
Sep  6 11:01:56.546: INFO: VolumeSnapshot snapshot-br69n found but is not ready.
Sep  6 11:01:58.552: INFO: VolumeSnapshot snapshot-br69n found but is not ready.
... skipping 48 lines ...
    test/e2e/storage/testsuites/snapshottable.go:113
      
      test/e2e/storage/testsuites/snapshottable.go:176
        should check snapshot fields, check restore correctly works, check deletion (ephemeral)
        test/e2e/storage/testsuites/snapshottable.go:177
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Ephemeral Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works, check deletion (ephemeral)","total":-1,"completed":1,"skipped":178,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
Sep  6 11:02:57.311: INFO: Running AfterSuite actions on all nodes
Sep  6 11:02:57.311: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:02:57.311: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
... skipping 28 lines ...
Sep  6 11:01:40.041: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 11:01:40.052: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ionzwjk] to have phase Bound
Sep  6 11:01:40.067: INFO: PersistentVolumeClaim hostpath.csi.k8s.ionzwjk found but phase is Pending instead of Bound.
Sep  6 11:01:42.072: INFO: PersistentVolumeClaim hostpath.csi.k8s.ionzwjk found and phase=Bound (2.019941364s)
STEP: [init] starting a pod to use the claim
STEP: [init] check pod success
Sep  6 11:01:42.092: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-qm9g6" in namespace "snapshotting-1483" to be "Succeeded or Failed"
Sep  6 11:01:42.097: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.257162ms
Sep  6 11:01:44.102: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009221689s
Sep  6 11:01:46.107: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.014905148s
Sep  6 11:01:48.113: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.020576407s
Sep  6 11:01:50.117: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.02463241s
Sep  6 11:01:52.122: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.02982913s
Sep  6 11:01:54.128: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Pending", Reason="", readiness=false. Elapsed: 12.035333046s
Sep  6 11:01:56.133: INFO: Pod "pvc-snapshottable-tester-qm9g6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.04083244s
STEP: Saw pod success
Sep  6 11:01:56.133: INFO: Pod "pvc-snapshottable-tester-qm9g6" satisfied condition "Succeeded or Failed"
STEP: [init] checking the claim
Sep  6 11:01:56.137: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ionzwjk] to have phase Bound
Sep  6 11:01:56.141: INFO: PersistentVolumeClaim hostpath.csi.k8s.ionzwjk found and phase=Bound (4.129226ms)
STEP: [init] checking the PV
STEP: [init] deleting the pod
Sep  6 11:01:56.162: INFO: Pod pvc-snapshottable-tester-qm9g6 has the following logs: 
... skipping 33 lines ...
Sep  6 11:02:02.285: INFO: WaitUntil finished successfully after 2.009907463s
STEP: getting the snapshot and snapshot content
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep  6 11:02:02.308: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-gmbcl" in namespace "snapshotting-1483" to be "Succeeded or Failed"
Sep  6 11:02:02.315: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 7.094037ms
Sep  6 11:02:04.320: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.01228944s
Sep  6 11:02:06.325: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.017442624s
Sep  6 11:02:08.329: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.021526896s
Sep  6 11:02:10.335: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.027838716s
Sep  6 11:02:12.342: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.033949209s
Sep  6 11:02:14.347: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 12.039290178s
Sep  6 11:02:16.351: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.043728993s
Sep  6 11:02:18.357: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.04901274s
Sep  6 11:02:20.361: INFO: Pod "pvc-snapshottable-data-tester-gmbcl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.053469804s
STEP: Saw pod success
Sep  6 11:02:20.361: INFO: Pod "pvc-snapshottable-data-tester-gmbcl" satisfied condition "Succeeded or Failed"
Sep  6 11:02:20.370: INFO: Pod pvc-snapshottable-data-tester-gmbcl has the following logs: 
Sep  6 11:02:20.371: INFO: Deleting pod "pvc-snapshottable-data-tester-gmbcl" in namespace "snapshotting-1483"
Sep  6 11:02:20.379: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-gmbcl" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the snapshot
Sep  6 11:02:34.414: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:46515 --kubeconfig=/root/.kube/config --namespace=snapshotting-1483 exec restored-pvc-tester-vsvfj --namespace=snapshotting-1483 -- cat /mnt/test/data'
... skipping 69 lines ...
Sep  6 11:01:41.220: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 11:01:41.226: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iob9kjx] to have phase Bound
Sep  6 11:01:41.229: INFO: PersistentVolumeClaim hostpath.csi.k8s.iob9kjx found but phase is Pending instead of Bound.
Sep  6 11:01:43.233: INFO: PersistentVolumeClaim hostpath.csi.k8s.iob9kjx found and phase=Bound (2.006926274s)
STEP: [init] starting a pod to use the claim
STEP: [init] check pod success
Sep  6 11:01:43.253: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-z8vvt" in namespace "snapshotting-7788" to be "Succeeded or Failed"
Sep  6 11:01:43.258: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.497475ms
Sep  6 11:01:45.262: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009043739s
Sep  6 11:01:47.268: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.014285095s
Sep  6 11:01:49.272: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.018327351s
Sep  6 11:01:51.276: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022794752s
Sep  6 11:01:53.280: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 10.026507579s
... skipping 3 lines ...
Sep  6 11:02:01.300: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 18.047037826s
Sep  6 11:02:03.305: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 20.051660739s
Sep  6 11:02:05.310: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 22.056400349s
Sep  6 11:02:07.315: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Pending", Reason="", readiness=false. Elapsed: 24.061237412s
Sep  6 11:02:09.319: INFO: Pod "pvc-snapshottable-tester-z8vvt": Phase="Succeeded", Reason="", readiness=false. Elapsed: 26.065177315s
STEP: Saw pod success
Sep  6 11:02:09.319: INFO: Pod "pvc-snapshottable-tester-z8vvt" satisfied condition "Succeeded or Failed"
STEP: [init] checking the claim
Sep  6 11:02:09.321: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iob9kjx] to have phase Bound
Sep  6 11:02:09.324: INFO: PersistentVolumeClaim hostpath.csi.k8s.iob9kjx found and phase=Bound (2.714012ms)
STEP: [init] checking the PV
STEP: [init] deleting the pod
Sep  6 11:02:09.335: INFO: Pod pvc-snapshottable-tester-z8vvt has the following logs: 
... skipping 11 lines ...
Sep  6 11:02:11.383: INFO: received snapshotStatus map[boundVolumeSnapshotContentName:snapcontent-0b5fc32e-e809-4a9c-ba36-ee87a87f3845 creationTime:2022-09-06T11:02:09Z readyToUse:true restoreSize:1Mi]
Sep  6 11:02:11.383: INFO: snapshotContentName snapcontent-0b5fc32e-e809-4a9c-ba36-ee87a87f3845
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep  6 11:02:11.408: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-qmghj" in namespace "snapshotting-7788" to be "Succeeded or Failed"
Sep  6 11:02:11.412: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.198798ms
Sep  6 11:02:13.417: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009337357s
Sep  6 11:02:15.422: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.014203794s
Sep  6 11:02:17.428: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.019681207s
Sep  6 11:02:19.433: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.024796485s
Sep  6 11:02:21.437: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.029084154s
Sep  6 11:02:23.443: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.034413813s
Sep  6 11:02:25.448: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 14.039819819s
Sep  6 11:02:27.453: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Pending", Reason="", readiness=false. Elapsed: 16.044728509s
Sep  6 11:02:29.458: INFO: Pod "pvc-snapshottable-data-tester-qmghj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.050170917s
STEP: Saw pod success
Sep  6 11:02:29.458: INFO: Pod "pvc-snapshottable-data-tester-qmghj" satisfied condition "Succeeded or Failed"
Sep  6 11:02:29.471: INFO: Pod pvc-snapshottable-data-tester-qmghj has the following logs: 
Sep  6 11:02:29.471: INFO: Deleting pod "pvc-snapshottable-data-tester-qmghj" in namespace "snapshotting-7788"
Sep  6 11:02:29.481: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-qmghj" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the snapshot
Sep  6 11:02:43.512: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:46515 --kubeconfig=/root/.kube/config --namespace=snapshotting-7788 exec restored-pvc-tester-wqdll --namespace=snapshotting-7788 -- cat /mnt/test/data'
... skipping 44 lines ...
    test/e2e/storage/testsuites/snapshottable.go:113
      
      test/e2e/storage/testsuites/snapshottable.go:176
        should check snapshot fields, check restore correctly works after modifying source data, check deletion (persistent)
        test/e2e/storage/testsuites/snapshottable.go:278
------------------------------
{"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 (persistent)","total":-1,"completed":1,"skipped":632,"failed":0}
Sep  6 11:03:20.822: INFO: Running AfterSuite actions on all nodes
Sep  6 11:03:20.822: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:03:20.822: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:03:20.822: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:03:20.822: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:03:20.822: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 25 lines ...
Sep  6 11:01:40.137: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 11:01:40.155: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iortxc4] to have phase Bound
Sep  6 11:01:40.186: INFO: PersistentVolumeClaim hostpath.csi.k8s.iortxc4 found but phase is Pending instead of Bound.
Sep  6 11:01:42.192: INFO: PersistentVolumeClaim hostpath.csi.k8s.iortxc4 found and phase=Bound (2.037128672s)
STEP: [init] starting a pod to use the claim
STEP: [init] check pod success
Sep  6 11:01:42.209: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-spzhz" in namespace "snapshotting-2012" to be "Succeeded or Failed"
Sep  6 11:01:42.215: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.145232ms
Sep  6 11:01:44.221: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.011940663s
Sep  6 11:01:46.225: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.01648348s
Sep  6 11:01:48.231: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.022155515s
Sep  6 11:01:50.235: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 8.026323341s
Sep  6 11:01:52.240: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 10.031006914s
Sep  6 11:01:54.245: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Pending", Reason="", readiness=false. Elapsed: 12.036562649s
Sep  6 11:01:56.253: INFO: Pod "pvc-snapshottable-tester-spzhz": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.044214728s
STEP: Saw pod success
Sep  6 11:01:56.253: INFO: Pod "pvc-snapshottable-tester-spzhz" satisfied condition "Succeeded or Failed"
STEP: [init] checking the claim
Sep  6 11:01:56.257: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iortxc4] to have phase Bound
Sep  6 11:01:56.266: INFO: PersistentVolumeClaim hostpath.csi.k8s.iortxc4 found and phase=Bound (8.317267ms)
STEP: [init] checking the PV
STEP: [init] deleting the pod
Sep  6 11:01:56.293: INFO: Pod pvc-snapshottable-tester-spzhz has the following logs: 
... skipping 35 lines ...
Sep  6 11:02:06.434: INFO: WaitUntil finished successfully after 2.008914712s
STEP: getting the snapshot and snapshot content
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep  6 11:02:06.456: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-h57zb" in namespace "snapshotting-2012" to be "Succeeded or Failed"
Sep  6 11:02:06.460: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 3.911806ms
Sep  6 11:02:08.464: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008454894s
Sep  6 11:02:10.469: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013308854s
Sep  6 11:02:12.474: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01832198s
Sep  6 11:02:14.479: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.022686604s
Sep  6 11:02:16.484: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 10.028273089s
Sep  6 11:02:18.489: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 12.033292478s
Sep  6 11:02:20.494: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 14.038548088s
Sep  6 11:02:22.509: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 16.053507109s
Sep  6 11:02:24.514: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Pending", Reason="", readiness=false. Elapsed: 18.057938295s
Sep  6 11:02:26.518: INFO: Pod "pvc-snapshottable-data-tester-h57zb": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.062469283s
STEP: Saw pod success
Sep  6 11:02:26.518: INFO: Pod "pvc-snapshottable-data-tester-h57zb" satisfied condition "Succeeded or Failed"
Sep  6 11:02:26.530: INFO: Pod pvc-snapshottable-data-tester-h57zb has the following logs: 
Sep  6 11:02:26.530: INFO: Deleting pod "pvc-snapshottable-data-tester-h57zb" in namespace "snapshotting-2012"
Sep  6 11:02:26.541: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-h57zb" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the snapshot
Sep  6 11:02:42.578: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:46515 --kubeconfig=/root/.kube/config --namespace=snapshotting-2012 exec restored-pvc-tester-cxnzq --namespace=snapshotting-2012 -- cat /mnt/test/data'
... skipping 35 lines ...
Sep  6 11:03:06.942: INFO: volumesnapshotcontents pre-provisioned-snapcontent-61cb5187-8ec8-4429-bea1-2208f1d96128 has been found and is not deleted
Sep  6 11:03:07.947: INFO: volumesnapshotcontents pre-provisioned-snapcontent-61cb5187-8ec8-4429-bea1-2208f1d96128 has been found and is not deleted
Sep  6 11:03:08.953: INFO: volumesnapshotcontents pre-provisioned-snapcontent-61cb5187-8ec8-4429-bea1-2208f1d96128 has been found and is not deleted
Sep  6 11:03:09.958: INFO: volumesnapshotcontents pre-provisioned-snapcontent-61cb5187-8ec8-4429-bea1-2208f1d96128 has been found and is not deleted
Sep  6 11:03:10.964: INFO: volumesnapshotcontents pre-provisioned-snapcontent-61cb5187-8ec8-4429-bea1-2208f1d96128 has been found and is not deleted
Sep  6 11:03:11.968: INFO: volumesnapshotcontents pre-provisioned-snapcontent-61cb5187-8ec8-4429-bea1-2208f1d96128 has been found and is not deleted
Sep  6 11:03:12.969: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  test/e2e/storage/testsuites/snapshottable.go:172
Sep  6 11:03:12.980: INFO: Pod restored-pvc-tester-cxnzq has the following logs: 
Sep  6 11:03:12.980: INFO: Deleting pod "restored-pvc-tester-cxnzq" in namespace "snapshotting-2012"
Sep  6 11:03:12.984: INFO: Wait up to 5m0s for pod "restored-pvc-tester-cxnzq" to be fully deleted
Sep  6 11:03:16.992: INFO: deleting claim "snapshotting-2012"/"pvc-tkdv6"
... skipping 28 lines ...
    test/e2e/storage/testsuites/snapshottable.go:113
      
      test/e2e/storage/testsuites/snapshottable.go:176
        should check snapshot fields, check restore correctly works after modifying source data, check deletion (persistent)
        test/e2e/storage/testsuites/snapshottable.go:278
------------------------------
{"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 (persistent)","total":-1,"completed":1,"skipped":16,"failed":0}
Sep  6 11:03:24.067: INFO: Running AfterSuite actions on all nodes
Sep  6 11:03:24.067: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:03:24.067: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:03:24.067: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:03:24.067: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:03:24.067: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 23 lines ...
Sep  6 11:01:41.346: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep  6 11:01:41.361: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7zd7x] to have phase Bound
Sep  6 11:01:41.367: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7zd7x found but phase is Pending instead of Bound.
Sep  6 11:01:43.372: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7zd7x found and phase=Bound (2.010707137s)
STEP: [init] starting a pod to use the claim
STEP: [init] check pod success
Sep  6 11:01:43.388: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-gslgw" in namespace "snapshotting-3756" to be "Succeeded or Failed"
Sep  6 11:01:43.397: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.434054ms
Sep  6 11:01:45.401: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.012811963s
Sep  6 11:01:47.407: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.018420466s
Sep  6 11:01:49.411: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.023023571s
Sep  6 11:01:51.416: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.027880622s
Sep  6 11:01:53.422: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.034015071s
Sep  6 11:01:55.428: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 12.039336973s
Sep  6 11:01:57.433: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 14.044975369s
Sep  6 11:01:59.439: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 16.050472353s
Sep  6 11:02:01.446: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 18.057422914s
Sep  6 11:02:03.451: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Pending", Reason="", readiness=false. Elapsed: 20.062336317s
Sep  6 11:02:05.455: INFO: Pod "pvc-snapshottable-tester-gslgw": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.066744885s
STEP: Saw pod success
Sep  6 11:02:05.455: INFO: Pod "pvc-snapshottable-tester-gslgw" satisfied condition "Succeeded or Failed"
STEP: [init] checking the claim
Sep  6 11:02:05.458: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7zd7x] to have phase Bound
Sep  6 11:02:05.461: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7zd7x found and phase=Bound (3.106559ms)
STEP: [init] checking the PV
STEP: [init] deleting the pod
Sep  6 11:02:05.477: INFO: Pod pvc-snapshottable-tester-gslgw has the following logs: 
... skipping 11 lines ...
Sep  6 11:02:07.530: INFO: received snapshotStatus map[boundVolumeSnapshotContentName:snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 creationTime:2022-09-06T11:02:05Z readyToUse:true restoreSize:1Mi]
Sep  6 11:02:07.530: INFO: snapshotContentName snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep  6 11:02:07.549: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-nnqbd" in namespace "snapshotting-3756" to be "Succeeded or Failed"
Sep  6 11:02:07.555: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 3.885651ms
Sep  6 11:02:09.559: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008718569s
Sep  6 11:02:11.564: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013406036s
Sep  6 11:02:13.568: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01777811s
Sep  6 11:02:15.574: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.023325254s
Sep  6 11:02:17.580: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.028978494s
Sep  6 11:02:19.585: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 12.03387313s
Sep  6 11:02:21.589: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 14.038749327s
Sep  6 11:02:23.596: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 16.045028068s
Sep  6 11:02:25.601: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Pending", Reason="", readiness=false. Elapsed: 18.050074622s
Sep  6 11:02:27.607: INFO: Pod "pvc-snapshottable-data-tester-nnqbd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.056561825s
STEP: Saw pod success
Sep  6 11:02:27.607: INFO: Pod "pvc-snapshottable-data-tester-nnqbd" satisfied condition "Succeeded or Failed"
Sep  6 11:02:27.618: INFO: Pod pvc-snapshottable-data-tester-nnqbd has the following logs: 
Sep  6 11:02:27.618: INFO: Deleting pod "pvc-snapshottable-data-tester-nnqbd" in namespace "snapshotting-3756"
Sep  6 11:02:27.637: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-nnqbd" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the snapshot
Sep  6 11:02:45.683: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:46515 --kubeconfig=/root/.kube/config --namespace=snapshotting-3756 exec restored-pvc-tester-w92nt --namespace=snapshotting-3756 -- cat /mnt/test/data'
... skipping 35 lines ...
Sep  6 11:03:10.060: INFO: volumesnapshotcontents snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 has been found and is not deleted
Sep  6 11:03:11.067: INFO: volumesnapshotcontents snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 has been found and is not deleted
Sep  6 11:03:12.071: INFO: volumesnapshotcontents snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 has been found and is not deleted
Sep  6 11:03:13.077: INFO: volumesnapshotcontents snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 has been found and is not deleted
Sep  6 11:03:14.082: INFO: volumesnapshotcontents snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 has been found and is not deleted
Sep  6 11:03:15.087: INFO: volumesnapshotcontents snapcontent-ec9fb63a-5725-47ce-885e-c157abe92128 has been found and is not deleted
Sep  6 11:03:16.087: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  test/e2e/storage/testsuites/snapshottable.go:172
Sep  6 11:03:16.095: INFO: Pod restored-pvc-tester-w92nt has the following logs: 
Sep  6 11:03:16.095: INFO: Deleting pod "restored-pvc-tester-w92nt" in namespace "snapshotting-3756"
Sep  6 11:03:16.101: INFO: Wait up to 5m0s for pod "restored-pvc-tester-w92nt" to be fully deleted
Sep  6 11:03:18.110: INFO: deleting claim "snapshotting-3756"/"pvc-k8px8"
... skipping 28 lines ...
    test/e2e/storage/testsuites/snapshottable.go:113
      
      test/e2e/storage/testsuites/snapshottable.go:176
        should check snapshot fields, check restore correctly works after modifying source data, check deletion (persistent)
        test/e2e/storage/testsuites/snapshottable.go:278
------------------------------
{"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 (persistent)","total":-1,"completed":1,"skipped":691,"failed":0}
Sep  6 11:03:25.197: INFO: Running AfterSuite actions on all nodes
Sep  6 11:03:25.197: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:03:25.197: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:03:25.197: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:03:25.197: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:03:25.197: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 121 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] provisioning
  test/e2e/storage/framework/testsuite.go:50
    should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
    test/e2e/storage/testsuites/provisioning.go:208
------------------------------
{"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":2,"skipped":4583,"failed":0}
Sep  6 11:03:33.207: INFO: Running AfterSuite actions on all nodes
Sep  6 11:03:33.208: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:03:33.208: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:03:33.208: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:03:33.208: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:03:33.208: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 17 lines ...
[It] should check snapshot fields, check restore correctly works, check deletion (ephemeral)
  test/e2e/storage/testsuites/snapshottable.go:177
Sep  6 11:02:50.188: INFO: Creating resource for dynamic PV
Sep  6 11:02:50.188: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass snapshotting-8171-e2e-scgtv9k
STEP: [init] starting a pod to use the claim
Sep  6 11:02:50.206: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-r4wk6" in namespace "snapshotting-8171" to be "Succeeded or Failed"
Sep  6 11:02:50.217: INFO: Pod "pvc-snapshottable-tester-r4wk6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.733682ms
Sep  6 11:02:52.221: INFO: Pod "pvc-snapshottable-tester-r4wk6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.014523065s
Sep  6 11:02:54.225: INFO: Pod "pvc-snapshottable-tester-r4wk6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.018989893s
Sep  6 11:02:56.229: INFO: Pod "pvc-snapshottable-tester-r4wk6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.022926549s
Sep  6 11:02:58.234: INFO: Pod "pvc-snapshottable-tester-r4wk6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.027698049s
Sep  6 11:03:00.237: INFO: Pod "pvc-snapshottable-tester-r4wk6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.031191617s
STEP: Saw pod success
Sep  6 11:03:00.238: INFO: Pod "pvc-snapshottable-tester-r4wk6" satisfied condition "Succeeded or Failed"
STEP: [init] checking the claim
STEP: creating a SnapshotClass
STEP: creating a dynamic VolumeSnapshot
Sep  6 11:03:00.256: INFO: Waiting up to 5m0s for VolumeSnapshot snapshot-tzdfz to become ready
Sep  6 11:03:00.260: INFO: VolumeSnapshot snapshot-tzdfz found but is not ready.
Sep  6 11:03:02.265: INFO: VolumeSnapshot snapshot-tzdfz found and is ready
... skipping 41 lines ...
Sep  6 11:03:32.452: INFO: volumesnapshotcontents snapcontent-79a26507-40c4-4e9a-aff7-3f61a97aa52d has been found and is not deleted
Sep  6 11:03:33.457: INFO: volumesnapshotcontents snapcontent-79a26507-40c4-4e9a-aff7-3f61a97aa52d has been found and is not deleted
Sep  6 11:03:34.462: INFO: volumesnapshotcontents snapcontent-79a26507-40c4-4e9a-aff7-3f61a97aa52d has been found and is not deleted
Sep  6 11:03:35.467: INFO: volumesnapshotcontents snapcontent-79a26507-40c4-4e9a-aff7-3f61a97aa52d has been found and is not deleted
Sep  6 11:03:36.471: INFO: volumesnapshotcontents snapcontent-79a26507-40c4-4e9a-aff7-3f61a97aa52d has been found and is not deleted
Sep  6 11:03:37.476: INFO: volumesnapshotcontents snapcontent-79a26507-40c4-4e9a-aff7-3f61a97aa52d has been found and is not deleted
Sep  6 11:03:38.477: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  test/e2e/storage/testsuites/snapshottable.go:172
Sep  6 11:03:38.485: INFO: Pod restored-pvc-tester-k8twq has the following logs: 
Sep  6 11:03:38.485: INFO: Deleting pod "restored-pvc-tester-k8twq" in namespace "snapshotting-8171"
Sep  6 11:03:38.489: INFO: Wait up to 5m0s for pod "restored-pvc-tester-k8twq" to be fully deleted
Sep  6 11:04:10.499: INFO: deleting snapshot "snapshotting-8171"/"snapshot-tzdfz"
... skipping 24 lines ...
    test/e2e/storage/testsuites/snapshottable.go:113
      
      test/e2e/storage/testsuites/snapshottable.go:176
        should check snapshot fields, check restore correctly works, check deletion (ephemeral)
        test/e2e/storage/testsuites/snapshottable.go:177
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Ephemeral Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works, check deletion (ephemeral)","total":-1,"completed":2,"skipped":781,"failed":0}
Sep  6 11:04:12.578: INFO: Running AfterSuite actions on all nodes
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Sep  6 11:04:12.578: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


{"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 (persistent)","total":-1,"completed":1,"skipped":15,"failed":0}
Sep  6 11:03:11.709: INFO: Running AfterSuite actions on all nodes
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func19.2
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func9.2
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Sep  6 11:03:11.709: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3
Sep  6 11:04:12.633: INFO: Running AfterSuite actions on node 1
Sep  6 11:04:12.633: INFO: Dumping logs locally to: /logs/artifacts
Sep  6 11:04:12.633: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory


Ran 9 of 7227 Specs in 152.905 seconds
SUCCESS! -- 9 Passed | 0 Failed | 0 Pending | 7218 Skipped


Ginkgo ran 1 suite in 2m37.261980739s
Test Suite Passed
Tue Sep  6 11:04:12 UTC 2022 go1.18 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/release-tools/filter-junit.go -t=External.Storage|CSI.mock.volume -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 Sep  6 11:04:13 UTC 2022 go1.19 /home/prow/go/src/k8s.io/kubernetes$ env KUBECONFIG=/root/.kube/config KUBE_TEST_REPO_LIST= ginkgo -v -focus=External.Storage.*(\[Serial\]|\[Disruptive\]) -skip=\[Feature:|Disruptive /home/prow/go/pkg/csiprow.K7KPDGOE2l/e2e.test -- -report-dir /logs/artifacts -storage.testdriver=/home/prow/go/pkg/csiprow.K7KPDGOE2l/test-driver.yaml
Sep  6 11:04:13.965: INFO: Driver loaded from path [/home/prow/go/pkg/csiprow.K7KPDGOE2l/test-driver.yaml]: &{DriverInfo:{Name:hostpath.csi.k8s.io InTreePluginName: FeatureTag: MaxFileSize:0 SupportedSizeRange:{Max: Min:1Mi} SupportedFsType:map[:{}] SupportedMountOption:map[] RequiredMountOption:map[] Capabilities:map[block:true controllerExpansion:true exec:true multipods:true nodeExpansion:true onlineExpansion:true persistence:true singleNodeVolume:true snapshotDataSource:true topology:true] RequiredAccessModes:[] TopologyKeys:[] NumAllowedTopologies:0 StressTestOptions:<nil> VolumeSnapshotStressTestOptions:<nil> PerformanceTestOptions:<nil>} StorageClass:{FromName:true FromFile: FromExistingClassName:} SnapshotClass:{FromName:true FromFile: FromExistingClassName:} InlineVolumes:[{Attributes:map[] Shared:false ReadOnly:false}] ClientNodeName:csi-prow-worker2 Timeouts:map[]}
Sep  6 11:04:13.965: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used.
I0906 11:04:13.965777  207101 e2e.go:129] Starting e2e run "d34e4a00-fbdf-4945-8614-3a1757bc5613" on Ginkgo node 1
{"msg":"Test Suite starting","total":7,"completed":0,"skipped":0,"failed":0}
Running Suite: Kubernetes e2e suite
===================================
Random Seed: 1662462253 - Will randomize all specs
Will run 7 of 7227 specs

Sep  6 11:04:16.205: INFO: >>> kubeConfig: /root/.kube/config
... skipping 242 lines ...
test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] provisioning
  test/e2e/storage/framework/testsuite.go:50
    should provision storage with any volume data source [Serial]
    test/e2e/storage/testsuites/provisioning.go:246
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] provisioning should provision storage with any volume data source [Serial]","total":7,"completed":1,"skipped":5144,"failed":0}
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volume-stress 
  multiple pods should access different volumes repeatedly [Slow] [Serial]
  test/e2e/storage/testsuites/volume_stress.go:205
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volume-stress
... skipping 46 lines ...
Sep  6 11:05:14.518: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Sep  6 11:05:14.518: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Sep  6 11:05:14.518: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Sep  6 11:05:14.518: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3
Sep  6 11:05:14.518: INFO: Running AfterSuite actions on node 1
Sep  6 11:05:14.518: INFO: Dumping logs locally to: /logs/artifacts
Sep  6 11:05:14.519: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory

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

Ran 1 of 7227 Specs in 58.317 seconds
SUCCESS! -- 1 Passed | 0 Failed | 0 Pending | 7226 Skipped
PASS

Ginkgo ran 1 suite in 1m0.846422558s
Test Suite Passed
Tue Sep  6 11:05:14 UTC 2022 go1.18 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/external-snapshotter/release-tools/filter-junit.go -t=External.Storage|CSI.mock.volume -o /logs/artifacts/junit_serial.xml /logs/artifacts/junit_01.xml
Tue Sep  6 11:05:15 UTC 2022 go1.19 $ kind export logs --name=csi-prow /logs/artifacts/cluster-logs/non-alpha
... skipping 14 lines ...