This job view page is being replaced by Spyglass soon. Check out the new job view.
PRverult: Add storage capacity exhausted error fix to 1.7.3 release notes
ResultFAILURE
Tests 1 failed / 113 succeeded
Started2021-09-11 02:03
Elapsed16m45s
Revisionb335f208cfdb133492e67a928e59e862e40fa06a
Refs 335

Test Failures


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support restarting containers using file as subpath [Slow][LinuxOnly] 4m2s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=External\sStorage\s\[Driver\:\shostpath\.csi\.k8s\.io\]\s\[Testpattern\:\sDynamic\sPV\s\(default\sfs\)\]\ssubPath\sshould\ssupport\srestarting\scontainers\susing\sfile\sas\ssubpath\s\[Slow\]\[LinuxOnly\]$'
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:332
Sep 11 02:16:53.113: while waiting for container to stabilize
Unexpected error:
    <*errors.errorString | 0xc00026e1f0>: {
        s: "timed out waiting for the condition",
    }
    timed out waiting for the condition
occurred
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:867
				
				Click to see stdout/stderrfrom junit_parallel.xml

Filter through log files | View test history on testgrid


Show 113 Passed Tests

Show 684 Skipped Tests

Error lines from build-log.txt

... skipping 47 lines ...
alpha feature gates for latest Kubernetes: CSI_PROW_E2E_ALPHA_GATES_LATEST=GenericEphemeralVolume=true,CSIStorageCapacity=true
alpha E2E feature gates: CSI_PROW_E2E_ALPHA_GATES=GenericEphemeralVolume=true,CSIStorageCapacity=true
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.FWoQbi339P
artifacts: ARTIFACTS=/logs/artifacts
Sat Sep 11 02:03:52 UTC 2021 go1.17 $ curl --fail --location https://dl.google.com/go/go1.16.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
 15  123M   15 19.6M    0     0  30.4M      0  0:00:04 --:--:--  0:00:04 30.3M
 43  123M   43 54.0M    0     0  32.7M      0  0:00:03  0:00:01  0:00:02 32.7M
 85  123M   85  105M    0     0  40.1M      0  0:00:03  0:00:02  0:00:01 40.1M
100  123M  100  123M    0     0  36.2M      0  0:00:03  0:00:03 --:--:-- 36.2M
Sat Sep 11 02:03:56 UTC 2021 go1.16 $ make all GOFLAGS_VENDOR=-mod=vendor BUILD_PLATFORMS=linux amd64; linux ppc64le -ppc64le; linux s390x -s390x; linux arm -arm; linux arm64 -arm64; windows amd64 .exe nanoserver:1809 servercore:ltsc2019; windows amd64 .exe nanoserver:1909 servercore:1909; windows amd64 .exe nanoserver:2004 servercore:2004; windows amd64 .exe nanoserver:20H2 servercore:20H2; windows 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; linux ppc64le -ppc64le; linux s390x -s390x; linux arm -arm; linux arm64 -arm64; windows amd64 .exe nanoserver:1809 servercore:ltsc2019; windows amd64 .exe nanoserver:1909 servercore:1909; windows amd64 .exe nanoserver:2004 servercore:2004; windows amd64 .exe nanoserver:20H2 servercore:20H2; windows amd64 .exe nanoserver:ltsc2022 servercore:ltsc2022' | tr ';' '\n' | while read -r os arch suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/hostpathplugin && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v1.7.2-56-gad551552 -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/csi-driver-host-path/bin/hostpathplugin$suffix" .); then \
		echo "Building hostpathplugin for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch"; \
done
+ cd ./cmd/hostpathplugin
+ CGO_ENABLED=0
... skipping 35 lines ...
export os_arch_seen="" && echo '' | tr ';' '\n' | while read -r os arch suffix base_image addon_image; do \
	os_arch_seen_pre=${os_arch_seen%%$os-$arch*}; \
	if ! [ ${#os_arch_seen_pre} = ${#os_arch_seen} ]; then \
		continue; \
	fi; \
	if ! (set -x; cd ./cmd/hostpathplugin && CGO_ENABLED=0 GOOS="$os" GOARCH="$arch" go build -mod=vendor -a -ldflags ' -X main.version=v1.7.2-56-gad551552 -extldflags "-static"' -o "/home/prow/go/src/github.com/kubernetes-csi/csi-driver-host-path/bin/hostpathplugin$suffix" .); then \
		echo "Building hostpathplugin for GOOS=$os GOARCH=$arch failed, see error(s) above."; \
		exit 1; \
	fi; \
	os_arch_seen+=";$os-$arch"; \
done
+ cd ./cmd/hostpathplugin
+ CGO_ENABLED=0
... skipping 75 lines ...
Step 8/8 : LABEL revision=v1.7.2-56-gad551552
 ---> Running in 7a9725e02b1b
Removing intermediate container 7a9725e02b1b
 ---> 66b0641a5c77
Successfully built 66b0641a5c77
Successfully tagged hostpathplugin:latest
Sat Sep 11 02:05:31 UTC 2021 go1.17 $ curl --fail --location -o /home/prow/go/pkg/csiprow.FWoQbi339P/bin/kind https://github.com/kubernetes-sigs/kind/releases/download/v0.11.1/kind-linux-amd64
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100   624  100   624    0     0   6568      0 --:--:-- --:--:-- --:--:--  6568

100 6660k  100 6660k    0     0  28.9M      0 --:--:-- --:--:-- --:--:-- 28.9M
No kind clusters found.
INFO: kind-config.yaml:
... skipping 188 lines ...
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:362
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodePublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:379
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodePublishVolume 
  should fail when no target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:393
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodePublishVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:408
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeUnpublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:427
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeUnpublishVolume 
  should fail when no target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:439
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeUnpublishVolume 
... skipping 9 lines ...
STEP: Checking the target path exists
STEP: Unpublishing the volume
STEP: Checking the target path was removed
•
------------------------------
Node Service NodeStageVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:525
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeStageVolume 
  should fail when no staging target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:544
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeStageVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:563
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a single node writer volume
•
------------------------------
Node Service NodeUnstageVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:614
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeUnstageVolume 
  should fail when no staging target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:628
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:650
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when no volume path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:664
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:678
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when volume does not exist on the specified path
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:693
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a single node writer volume for expansion
STEP: getting a node id
STEP: node staging volume
STEP: publishing the volume on a node
STEP: Get node volume stats
•
------------------------------
Node Service NodeExpandVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:740
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeExpandVolume 
  should fail when no volume path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:755
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a single node writer volume for expansion
•
------------------------------
Node Service NodeExpandVolume 
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:774
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeExpandVolume 
... skipping 51 lines ...
STEP: publishing the volume on a node
STEP: publishing the volume on a node
STEP: Get node volume stats
•
------------------------------
ExpandVolume [Controller Server] 
  should fail if no volume id is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1489
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
ExpandVolume [Controller Server] 
  should fail if no capacity range is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1506
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
ExpandVolume [Controller Server] 
... skipping 75 lines ...
I0911 02:08:20.698240   28159 resources.go:322] deleting snapshot ID 22eb2808-12a5-11ec-bed5-c2995aa68635
I0911 02:08:20.701205   28159 resources.go:322] deleting snapshot ID 22e4a2c0-12a5-11ec-bed5-c2995aa68635
I0911 02:08:20.704618   28159 resources.go:322] deleting snapshot ID 22e408c2-12a5-11ec-bed5-c2995aa68635
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1383
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when no source volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1400
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
CreateSnapshot [Controller Server] 
... skipping 6 lines ...
STEP: creating a snapshot with the same name and source volume ID
I0911 02:08:22.848352   28159 resources.go:322] deleting snapshot ID 243551a2-12a5-11ec-bed5-c2995aa68635
I0911 02:08:22.849697   28159 resources.go:322] deleting snapshot ID 243551a2-12a5-11ec-bed5-c2995aa68635
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when requesting to create a snapshot with already existing name and different source volume ID
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1431
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a snapshot
STEP: creating a new source volume
STEP: creating a snapshot with the same name but different source volume ID
... skipping 9 lines ...
STEP: creating a snapshot
I0911 02:08:24.237611   28159 resources.go:322] deleting snapshot ID 250fdba8-12a5-11ec-bed5-c2995aa68635
I0911 02:08:24.238467   28159 resources.go:322] deleting snapshot ID 250fdba8-12a5-11ec-bed5-c2995aa68635
•
------------------------------
DeleteSnapshot [Controller Server] 
  should fail when no snapshot id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1327
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
DeleteSnapshot [Controller Server] 
... skipping 57 lines ...
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:181
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] ListVolumes 
  should fail when an invalid starting_token is passed
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:193
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] ListVolumes 
... skipping 11 lines ...
  ListVolumes
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:174
    pagination should detect volumes added between pages and accept tokens when the last volume from a page is deleted
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:267
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:375
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when no volume capabilities are provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:390
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] CreateVolume 
... skipping 10 lines ...
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a volume
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should not fail when requesting to create a volume with already existing name and same capacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:459
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a volume
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when requesting to create a volume with already existing name and different capacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:500
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a volume
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should not fail when creating volume with maximum-length name
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:544
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a volume
•
------------------------------
... skipping 5 lines ...
STEP: creating a snapshot
STEP: creating a volume from source snapshot
I0911 02:08:37.238372   28159 resources.go:322] deleting snapshot ID 2cc8a6f1-12a5-11ec-bed5-c2995aa68635
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when the volume source snapshot is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:594
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a volume from source snapshot
•
------------------------------
... skipping 4 lines ...
STEP: creating mount and staging directories
STEP: creating a volume
STEP: creating a volume from source volume
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when the volume source volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:640
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a volume from source snapshot
•
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:670
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] DeleteVolume 
... skipping 10 lines ...
STEP: creating mount and staging directories
STEP: creating a volume
STEP: deleting a volume
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:733
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when no volume capabilities are provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:747
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
STEP: creating a single node writer volume
•
------------------------------
... skipping 4 lines ...
STEP: creating mount and staging directories
STEP: creating a single node writer volume
STEP: validating volume capabilities
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when the requested volume does not exist
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:824
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:851
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.677 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when no volume id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:851

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no node id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:866
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.678 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when no node id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:866

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:882
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.692 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when no volume capability is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:882

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when publishing more volumes than the node max attach limit
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:899
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.676 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when publishing more volumes than the node max attach limit [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:899

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when the volume does not exist
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:939
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.683 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when the volume does not exist [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:939

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when the node does not exist
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:961
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.706 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when the node does not exist [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:961

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when the volume is already published but is incompatible
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1000
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.642 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when the volume is already published but is incompatible [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1000

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:847
------------------------------
... skipping 31 lines ...

    Controller Publish, UnpublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1058
------------------------------
Controller Service [Controller Server] ControllerUnpublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1078
STEP: reusing connection to CSI driver at dns:///172.18.0.4:31822
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.649 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1071
    should fail when no volume id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1078

    ControllerUnpublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1074
------------------------------

JUnit report was created: /logs/artifacts/junit_sanity.xml

Ran 67 of 78 Specs in 54.339 seconds
SUCCESS! -- 67 Passed | 0 Failed | 1 Pending | 10 Skipped
Sat Sep 11 02:08:51 UTC 2021 go1.17 $ git init /home/prow/go/src/k8s.io/kubernetes
Initialized empty Git repository in /home/prow/go/src/k8s.io/kubernetes/.git/
Sat Sep 11 02:08:51 UTC 2021 go1.17 /home/prow/go/src/k8s.io/kubernetes$ git fetch --depth=1 https://github.com/kubernetes/kubernetes v1.20.0
From https://github.com/kubernetes/kubernetes
 * tag                 v1.20.0    -> FETCH_HEAD
Sat Sep 11 02:09:02 UTC 2021 go1.17 /home/prow/go/src/k8s.io/kubernetes$ git checkout FETCH_HEAD
... skipping 7 lines ...
do so (now or later) by using -b with the checkout command again. Example:

  git checkout -b <new-branch-name>

HEAD is now at af46c47c Merge pull request #97075 from adtac/apfe2e-5
Sat Sep 11 02:09:04 UTC 2021 go1.17 /home/prow/go/src/k8s.io/kubernetes$ git clean -fdx
Sat Sep 11 02:09:04 UTC 2021 go1.17 $ curl --fail --location https://dl.google.com/go/go1.15.linux-amd64.tar.gz
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
 28  115M   28 32.6M    0     0  31.7M      0  0:00:03  0:00:01  0:00:02 31.7M
 55  115M   55 63.9M    0     0  32.2M      0  0:00:03  0:00:01  0:00:02 32.2M
 93  115M   93  107M    0     0  35.7M      0  0:00:03  0:00:03 --:--:-- 35.7M
100  115M  100  115M    0     0  33.3M      0  0:00:03  0:00:03 --:--:-- 33.3M
Sat Sep 11 02:09:07 UTC 2021 go1.15 $ make WHAT=test/e2e/e2e.test -C/home/prow/go/src/k8s.io/kubernetes
make: Entering directory '/home/prow/go/src/k8s.io/kubernetes'
make[1]: Entering directory '/home/prow/go/src/k8s.io/kubernetes'
... skipping 420 lines ...

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

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

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

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

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

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:247
------------------------------
... skipping 400 lines ...

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

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

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

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

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

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

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

    Driver supports dynamic provisioning, skipping InlineVolume pattern

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

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

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

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

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

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

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:247
------------------------------
... skipping 71 lines ...

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

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:833
------------------------------
... skipping 133 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:09.730: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename topology
Sep 11 02:13:11.158: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192
Sep 11 02:13:11.167: INFO: Driver didn't provide topology keys -- skipping
[AfterEach] [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 11 02:13:11.168: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-7603" for this suite.


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

    Driver didn't provide topology keys -- skipping

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

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

    Driver supports dynamic provisioning, skipping InlineVolume pattern

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

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

    Driver supports dynamic provisioning, skipping InlineVolume pattern

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

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

    Driver supports dynamic provisioning, skipping PreprovisionedPV pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:833
------------------------------
... skipping 49 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:11.312: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename topology
Sep 11 02:13:14.941: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192
Sep 11 02:13:14.943: INFO: Driver didn't provide topology keys -- skipping
[AfterEach] [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 11 02:13:14.944: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-4555" for this suite.


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

    Driver didn't provide topology keys -- skipping

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

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

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

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

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

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

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

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

    Driver supports dynamic provisioning, skipping InlineVolume pattern

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:833
------------------------------
... skipping 175 lines ...
Sep 11 02:13:11.899: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:11.906: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io57qcs] to have phase Bound
Sep 11 02:13:11.910: INFO: PersistentVolumeClaim hostpath.csi.k8s.io57qcs found but phase is Pending instead of Bound.
Sep 11 02:13:13.916: INFO: PersistentVolumeClaim hostpath.csi.k8s.io57qcs found and phase=Bound (2.009854498s)
STEP: Expanding non-expandable pvc
Sep 11 02:13:13.922: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Sep 11 02:13:13.929: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:15.941: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:17.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:19.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:21.937: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:23.937: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:25.936: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:27.939: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:29.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:31.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:33.937: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:35.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:37.941: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:39.937: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:41.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:43.938: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:43.944: INFO: Error updating pvc hostpath.csi.k8s.io57qcs: persistentvolumeclaims "hostpath.csi.k8s.io57qcs" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Sep 11 02:13:43.944: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io57qcs"
Sep 11 02:13:43.953: INFO: Waiting up to 5m0s for PersistentVolume pvc-3b4503e7-6eca-4d3b-8075-98a0de42b233 to get deleted
Sep 11 02:13:43.957: INFO: PersistentVolume pvc-3b4503e7-6eca-4d3b-8075-98a0de42b233 found and phase=Bound (4.728999ms)
Sep 11 02:13:48.961: INFO: PersistentVolume pvc-3b4503e7-6eca-4d3b-8075-98a0de42b233 was removed
STEP: Deleting sc
... skipping 8 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should not allow expansion of pvcs without AllowVolumeExpansion property
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:154
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volume-expand should not allow expansion of pvcs without AllowVolumeExpansion property","total":-1,"completed":1,"skipped":130,"failed":0}

SSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support creating multiple subpath from same volumes [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:291
... skipping 16 lines ...
Sep 11 02:13:09.506: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:09.673: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io44sxd] to have phase Bound
Sep 11 02:13:09.900: INFO: PersistentVolumeClaim hostpath.csi.k8s.io44sxd found but phase is Pending instead of Bound.
Sep 11 02:13:11.904: INFO: PersistentVolumeClaim hostpath.csi.k8s.io44sxd found and phase=Bound (2.230808852s)
STEP: Creating pod pod-subpath-test-dynamicpv-w8s6
STEP: Creating a pod to test multi_subpath
Sep 11 02:13:11.921: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-w8s6" in namespace "provisioning-3873" to be "Succeeded or Failed"
Sep 11 02:13:11.930: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.791452ms
Sep 11 02:13:13.933: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.012118961s
Sep 11 02:13:15.938: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.017206278s
Sep 11 02:13:17.941: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.019956311s
Sep 11 02:13:19.945: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.023887448s
Sep 11 02:13:21.948: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.027501579s
... skipping 6 lines ...
Sep 11 02:13:35.973: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 24.052286663s
Sep 11 02:13:37.977: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 26.056226327s
Sep 11 02:13:39.981: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 28.059788508s
Sep 11 02:13:41.984: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Pending", Reason="", readiness=false. Elapsed: 30.063104717s
Sep 11 02:13:43.987: INFO: Pod "pod-subpath-test-dynamicpv-w8s6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 32.065889215s
STEP: Saw pod success
Sep 11 02:13:43.987: INFO: Pod "pod-subpath-test-dynamicpv-w8s6" satisfied condition "Succeeded or Failed"
Sep 11 02:13:43.989: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-w8s6 container test-container-subpath-dynamicpv-w8s6: <nil>
STEP: delete the pod
Sep 11 02:13:44.002: INFO: Waiting for pod pod-subpath-test-dynamicpv-w8s6 to disappear
Sep 11 02:13:44.005: INFO: Pod pod-subpath-test-dynamicpv-w8s6 no longer exists
STEP: Deleting pod
Sep 11 02:13:44.005: INFO: Deleting pod "pod-subpath-test-dynamicpv-w8s6" in namespace "provisioning-3873"
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support creating multiple subpath from same volumes [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:291
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support creating multiple subpath from same volumes [Slow]","total":-1,"completed":1,"skipped":20,"failed":0}

SSSSSSSS
------------------------------
Sep 11 02:13:49.050: INFO: Running AfterSuite actions on all nodes


... skipping 20 lines ...
STEP: creating a claim
Sep 11 02:13:13.484: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iohwzcc] to have phase Bound
Sep 11 02:13:13.603: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohwzcc found but phase is Pending instead of Bound.
Sep 11 02:13:15.606: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohwzcc found and phase=Bound (2.12105683s)
STEP: Expanding non-expandable pvc
Sep 11 02:13:15.612: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Sep 11 02:13:15.632: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:17.642: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:19.639: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:21.638: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:23.638: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:25.639: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:27.640: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:29.641: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:31.641: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:33.639: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:35.640: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:37.640: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:39.639: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:41.640: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:43.648: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:45.640: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Sep 11 02:13:45.646: INFO: Error updating pvc hostpath.csi.k8s.iohwzcc: persistentvolumeclaims "hostpath.csi.k8s.iohwzcc" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Sep 11 02:13:45.646: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iohwzcc"
Sep 11 02:13:45.650: INFO: Waiting up to 5m0s for PersistentVolume pvc-bdfe682a-6dda-4e3b-9a8c-1131a6aa23f8 to get deleted
Sep 11 02:13:45.653: INFO: PersistentVolume pvc-bdfe682a-6dda-4e3b-9a8c-1131a6aa23f8 found and phase=Bound (2.613026ms)
Sep 11 02:13:50.656: INFO: PersistentVolume pvc-bdfe682a-6dda-4e3b-9a8c-1131a6aa23f8 was removed
STEP: Deleting sc
... skipping 8 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should not allow expansion of pvcs without AllowVolumeExpansion property
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:154
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volume-expand should not allow expansion of pvcs without AllowVolumeExpansion property","total":-1,"completed":1,"skipped":54,"failed":0}
Sep 11 02:13:50.673: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support readOnly file specified in the volumeMount [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:376
... skipping 16 lines ...
Sep 11 02:13:11.138: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:11.177: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io6xwn2] to have phase Bound
Sep 11 02:13:11.213: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6xwn2 found but phase is Pending instead of Bound.
Sep 11 02:13:13.403: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6xwn2 found and phase=Bound (2.226744869s)
STEP: Creating pod pod-subpath-test-dynamicpv-4rjf
STEP: Creating a pod to test subpath
Sep 11 02:13:13.603: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-4rjf" in namespace "provisioning-2750" to be "Succeeded or Failed"
Sep 11 02:13:13.614: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 11.003093ms
Sep 11 02:13:15.621: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 2.017907291s
Sep 11 02:13:17.624: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 4.020889896s
Sep 11 02:13:19.627: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 6.02332141s
Sep 11 02:13:21.629: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 8.025499306s
Sep 11 02:13:23.631: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 10.027512827s
... skipping 6 lines ...
Sep 11 02:13:37.654: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 24.050491889s
Sep 11 02:13:39.657: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 26.053538731s
Sep 11 02:13:41.660: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 28.057156933s
Sep 11 02:13:43.664: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Pending", Reason="", readiness=false. Elapsed: 30.060829727s
Sep 11 02:13:45.671: INFO: Pod "pod-subpath-test-dynamicpv-4rjf": Phase="Succeeded", Reason="", readiness=false. Elapsed: 32.067487846s
STEP: Saw pod success
Sep 11 02:13:45.671: INFO: Pod "pod-subpath-test-dynamicpv-4rjf" satisfied condition "Succeeded or Failed"
Sep 11 02:13:45.677: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-4rjf container test-container-subpath-dynamicpv-4rjf: <nil>
STEP: delete the pod
Sep 11 02:13:45.704: INFO: Waiting for pod pod-subpath-test-dynamicpv-4rjf to disappear
Sep 11 02:13:45.707: INFO: Pod pod-subpath-test-dynamicpv-4rjf no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-4rjf
Sep 11 02:13:45.707: INFO: Deleting pod "pod-subpath-test-dynamicpv-4rjf" in namespace "provisioning-2750"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support readOnly file specified in the volumeMount [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:376
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support readOnly file specified in the volumeMount [LinuxOnly]","total":-1,"completed":1,"skipped":70,"failed":0}
Sep 11 02:13:50.735: INFO: Running AfterSuite actions on all nodes


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

[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:11.921: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:292
Sep 11 02:13:15.292: INFO: Creating resource for dynamic PV
Sep 11 02:13:15.292: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-8568-e2e-scs75n9
STEP: creating a claim
Sep 11 02:13:15.300: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io77trn] to have phase Bound
Sep 11 02:13:15.303: INFO: PersistentVolumeClaim hostpath.csi.k8s.io77trn found but phase is Pending instead of Bound.
Sep 11 02:13:17.306: INFO: PersistentVolumeClaim hostpath.csi.k8s.io77trn found but phase is Pending instead of Bound.
Sep 11 02:13:19.309: INFO: PersistentVolumeClaim hostpath.csi.k8s.io77trn found and phase=Bound (4.008387237s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Sep 11 02:14:09.331: INFO: Deleting pod "pod-5bd4a1b0-1546-44d1-8a57-7c3e1d5b03f5" in namespace "volumemode-8568"
Sep 11 02:14:09.335: INFO: Wait up to 5m0s for pod "pod-5bd4a1b0-1546-44d1-8a57-7c3e1d5b03f5" to be fully deleted
STEP: Deleting pvc
Sep 11 02:14:25.342: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io77trn"
Sep 11 02:14:25.348: INFO: Waiting up to 5m0s for PersistentVolume pvc-d800a4d1-3f62-4999-82ce-666e7e5b01f1 to get deleted
Sep 11 02:14:25.351: INFO: PersistentVolume pvc-d800a4d1-3f62-4999-82ce-666e7e5b01f1 found and phase=Bound (2.839893ms)
... skipping 7 lines ...

• [SLOW TEST:78.445 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should fail to use a volume in a pod with mismatched mode [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:292
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode should fail to use a volume in a pod with mismatched mode [Slow]","total":-1,"completed":1,"skipped":396,"failed":0}
Sep 11 02:14:30.367: INFO: Running AfterSuite actions on all nodes


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:09.066: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
Sep 11 02:13:09.781: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:264
Sep 11 02:13:09.920: INFO: Creating resource for dynamic PV
Sep 11 02:13:09.920: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-2680-e2e-sc6zww2
STEP: creating a claim
Sep 11 02:13:10.131: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:10.323: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iofbtpd] to have phase Bound
Sep 11 02:13:10.531: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofbtpd found but phase is Pending instead of Bound.
Sep 11 02:13:12.903: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofbtpd found and phase=Bound (2.579428219s)
STEP: Creating pod pod-subpath-test-dynamicpv-z956
STEP: Checking for subpath error in container status
Sep 11 02:14:09.499: INFO: Deleting pod "pod-subpath-test-dynamicpv-z956" in namespace "provisioning-2680"
Sep 11 02:14:09.503: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-z956" to be fully deleted
STEP: Deleting pod
Sep 11 02:14:31.508: INFO: Deleting pod "pod-subpath-test-dynamicpv-z956" in namespace "provisioning-2680"
STEP: Deleting pvc
Sep 11 02:14:31.511: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iofbtpd"
... skipping 9 lines ...

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


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support non-existent path
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:191
... skipping 16 lines ...
Sep 11 02:13:13.409: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:13.484: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7xvpx] to have phase Bound
Sep 11 02:13:13.602: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7xvpx found but phase is Pending instead of Bound.
Sep 11 02:13:15.605: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7xvpx found and phase=Bound (2.121193443s)
STEP: Creating pod pod-subpath-test-dynamicpv-2pjx
STEP: Creating a pod to test subpath
Sep 11 02:13:15.630: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-2pjx" in namespace "provisioning-891" to be "Succeeded or Failed"
Sep 11 02:13:15.633: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 3.646825ms
Sep 11 02:13:17.638: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008087542s
Sep 11 02:13:19.640: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010607942s
Sep 11 02:13:21.643: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.013557667s
Sep 11 02:13:23.647: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.016969955s
Sep 11 02:13:25.650: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 10.020641571s
... skipping 29 lines ...
Sep 11 02:14:25.757: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.127172314s
Sep 11 02:14:27.761: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.130988498s
Sep 11 02:14:29.764: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 1m14.134436609s
Sep 11 02:14:31.769: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Pending", Reason="", readiness=false. Elapsed: 1m16.13907934s
Sep 11 02:14:33.772: INFO: Pod "pod-subpath-test-dynamicpv-2pjx": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m18.142478405s
STEP: Saw pod success
Sep 11 02:14:33.772: INFO: Pod "pod-subpath-test-dynamicpv-2pjx" satisfied condition "Succeeded or Failed"
Sep 11 02:14:33.775: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-2pjx container test-container-volume-dynamicpv-2pjx: <nil>
STEP: delete the pod
Sep 11 02:14:33.797: INFO: Waiting for pod pod-subpath-test-dynamicpv-2pjx to disappear
Sep 11 02:14:33.803: INFO: Pod pod-subpath-test-dynamicpv-2pjx no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-2pjx
Sep 11 02:14:33.803: INFO: Deleting pod "pod-subpath-test-dynamicpv-2pjx" in namespace "provisioning-891"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support non-existent path
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:191
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support non-existent path","total":-1,"completed":1,"skipped":54,"failed":0}
Sep 11 02:14:38.832: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support readOnly directory specified in the volumeMount
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:361
... skipping 16 lines ...
Sep 11 02:13:14.352: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:14.358: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iol6b7w] to have phase Bound
Sep 11 02:13:14.361: INFO: PersistentVolumeClaim hostpath.csi.k8s.iol6b7w found but phase is Pending instead of Bound.
Sep 11 02:13:16.366: INFO: PersistentVolumeClaim hostpath.csi.k8s.iol6b7w found and phase=Bound (2.007915062s)
STEP: Creating pod pod-subpath-test-dynamicpv-jspd
STEP: Creating a pod to test subpath
Sep 11 02:13:16.381: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-jspd" in namespace "provisioning-4508" to be "Succeeded or Failed"
Sep 11 02:13:16.386: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.508473ms
Sep 11 02:13:18.390: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.008337499s
Sep 11 02:13:20.393: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.011944521s
Sep 11 02:13:22.397: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.015266658s
Sep 11 02:13:24.400: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.018730035s
Sep 11 02:13:26.404: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.023053972s
... skipping 29 lines ...
Sep 11 02:14:26.509: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.127875553s
Sep 11 02:14:28.512: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.130799518s
Sep 11 02:14:30.515: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m14.133651532s
Sep 11 02:14:32.518: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m16.136864711s
Sep 11 02:14:34.521: INFO: Pod "pod-subpath-test-dynamicpv-jspd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m18.139993292s
STEP: Saw pod success
Sep 11 02:14:34.521: INFO: Pod "pod-subpath-test-dynamicpv-jspd" satisfied condition "Succeeded or Failed"
Sep 11 02:14:34.524: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-jspd container test-container-subpath-dynamicpv-jspd: <nil>
STEP: delete the pod
Sep 11 02:14:34.539: INFO: Waiting for pod pod-subpath-test-dynamicpv-jspd to disappear
Sep 11 02:14:34.541: INFO: Pod pod-subpath-test-dynamicpv-jspd no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-jspd
Sep 11 02:14:34.541: INFO: Deleting pod "pod-subpath-test-dynamicpv-jspd" in namespace "provisioning-4508"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support readOnly directory specified in the volumeMount
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:361
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support readOnly directory specified in the volumeMount","total":-1,"completed":1,"skipped":328,"failed":0}
Sep 11 02:14:39.570: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes 
  should allow exec of files on the volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:192
... skipping 17 lines ...
Sep 11 02:13:13.603: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ion92wc] to have phase Bound
Sep 11 02:13:13.618: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion92wc found but phase is Pending instead of Bound.
Sep 11 02:13:15.631: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion92wc found but phase is Pending instead of Bound.
Sep 11 02:13:17.634: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion92wc found and phase=Bound (4.030656036s)
STEP: Creating pod exec-volume-test-dynamicpv-kk59
STEP: Creating a pod to test exec-volume-test
Sep 11 02:13:17.645: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-kk59" in namespace "volume-2873" to be "Succeeded or Failed"
Sep 11 02:13:17.647: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 1.972927ms
Sep 11 02:13:19.650: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 2.004936334s
Sep 11 02:13:21.652: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 4.007582474s
Sep 11 02:13:23.655: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 6.010656689s
Sep 11 02:13:25.658: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 8.013545958s
Sep 11 02:13:27.661: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 10.016124694s
... skipping 29 lines ...
Sep 11 02:14:27.776: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.131823025s
Sep 11 02:14:29.779: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.134094893s
Sep 11 02:14:31.782: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 1m14.137418275s
Sep 11 02:14:33.791: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Pending", Reason="", readiness=false. Elapsed: 1m16.146316664s
Sep 11 02:14:35.795: INFO: Pod "exec-volume-test-dynamicpv-kk59": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m18.149856921s
STEP: Saw pod success
Sep 11 02:14:35.797: INFO: Pod "exec-volume-test-dynamicpv-kk59" satisfied condition "Succeeded or Failed"
Sep 11 02:14:35.800: INFO: Trying to get logs from node csi-prow-worker pod exec-volume-test-dynamicpv-kk59 container exec-container-dynamicpv-kk59: <nil>
STEP: delete the pod
Sep 11 02:14:35.815: INFO: Waiting for pod exec-volume-test-dynamicpv-kk59 to disappear
Sep 11 02:14:35.817: INFO: Pod exec-volume-test-dynamicpv-kk59 no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-kk59
Sep 11 02:14:35.817: INFO: Deleting pod "exec-volume-test-dynamicpv-kk59" in namespace "volume-2873"
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should allow exec of files on the volume
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:192
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes should allow exec of files on the volume","total":-1,"completed":1,"skipped":40,"failed":0}
Sep 11 02:14:40.847: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support existing single file [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
... skipping 17 lines ...
Sep 11 02:13:11.357: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iojff4b] to have phase Bound
Sep 11 02:13:11.365: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojff4b found but phase is Pending instead of Bound.
Sep 11 02:13:13.419: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojff4b found but phase is Pending instead of Bound.
Sep 11 02:13:15.423: INFO: PersistentVolumeClaim hostpath.csi.k8s.iojff4b found and phase=Bound (4.065975598s)
STEP: Creating pod pod-subpath-test-dynamicpv-mtkr
STEP: Creating a pod to test subpath
Sep 11 02:13:15.436: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-mtkr" in namespace "provisioning-6981" to be "Succeeded or Failed"
Sep 11 02:13:15.439: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.977914ms
Sep 11 02:13:17.443: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00689672s
Sep 11 02:13:19.446: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010172539s
Sep 11 02:13:21.450: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 6.013681811s
Sep 11 02:13:23.453: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017153278s
Sep 11 02:13:25.457: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 10.021003028s
... skipping 35 lines ...
Sep 11 02:14:37.582: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 1m22.146194102s
Sep 11 02:14:39.586: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 1m24.149844257s
Sep 11 02:14:41.588: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 1m26.152544966s
Sep 11 02:14:43.593: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Pending", Reason="", readiness=false. Elapsed: 1m28.157538344s
Sep 11 02:14:45.598: INFO: Pod "pod-subpath-test-dynamicpv-mtkr": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m30.162324871s
STEP: Saw pod success
Sep 11 02:14:45.598: INFO: Pod "pod-subpath-test-dynamicpv-mtkr" satisfied condition "Succeeded or Failed"
Sep 11 02:14:45.601: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-mtkr container test-container-subpath-dynamicpv-mtkr: <nil>
STEP: delete the pod
Sep 11 02:14:45.617: INFO: Waiting for pod pod-subpath-test-dynamicpv-mtkr to disappear
Sep 11 02:14:45.619: INFO: Pod pod-subpath-test-dynamicpv-mtkr no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-mtkr
Sep 11 02:14:45.619: INFO: Deleting pod "pod-subpath-test-dynamicpv-mtkr" in namespace "provisioning-6981"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support existing single file [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing single file [LinuxOnly]","total":-1,"completed":1,"skipped":20,"failed":0}
Sep 11 02:14:50.651: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral 
  should support multiple inline ephemeral volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:217
... skipping 29 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support multiple inline ephemeral volumes
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:217
------------------------------
{"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":1,"skipped":65,"failed":0}
Sep 11 02:14:52.506: INFO: Running AfterSuite actions on all nodes


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:14.336: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237
Sep 11 02:13:16.344: INFO: Creating resource for dynamic PV
Sep 11 02:13:16.344: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-8546-e2e-schnxf5
STEP: creating a claim
Sep 11 02:13:16.347: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:16.353: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iosvhks] to have phase Bound
Sep 11 02:13:16.356: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosvhks found but phase is Pending instead of Bound.
Sep 11 02:13:18.359: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosvhks found but phase is Pending instead of Bound.
Sep 11 02:13:20.362: INFO: PersistentVolumeClaim hostpath.csi.k8s.iosvhks found and phase=Bound (4.008839385s)
STEP: Creating pod pod-subpath-test-dynamicpv-88dl
STEP: Checking for subpath error in container status
Sep 11 02:14:56.380: INFO: Deleting pod "pod-subpath-test-dynamicpv-88dl" in namespace "provisioning-8546"
Sep 11 02:14:56.387: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-88dl" to be fully deleted
STEP: Deleting pod
Sep 11 02:15:04.395: INFO: Deleting pod "pod-subpath-test-dynamicpv-88dl" in namespace "provisioning-8546"
STEP: Deleting pvc
Sep 11 02:15:04.397: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iosvhks"
... skipping 9 lines ...

• [SLOW TEST:115.086 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should fail if subpath directory is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:237
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath directory is outside the volume [Slow][LinuxOnly]","total":-1,"completed":1,"skipped":336,"failed":0}
Sep 11 02:15:09.424: INFO: Running AfterSuite actions on all nodes


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

[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:48.982: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:292
Sep 11 02:13:49.014: INFO: Creating resource for dynamic PV
Sep 11 02:13:49.014: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-279-e2e-scwqqbr
STEP: creating a claim
Sep 11 02:13:49.023: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9jvsx] to have phase Bound
Sep 11 02:13:49.027: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9jvsx found but phase is Pending instead of Bound.
Sep 11 02:13:51.030: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9jvsx found and phase=Bound (2.007381379s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Sep 11 02:14:23.049: INFO: Deleting pod "pod-ccb31b7e-c78c-4683-a168-8ecae05b7fcf" in namespace "volumemode-279"
Sep 11 02:14:23.053: INFO: Wait up to 5m0s for pod "pod-ccb31b7e-c78c-4683-a168-8ecae05b7fcf" to be fully deleted
STEP: Deleting pvc
Sep 11 02:15:05.058: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io9jvsx"
Sep 11 02:15:05.062: INFO: Waiting up to 5m0s for PersistentVolume pvc-8b15b817-fda0-4442-9292-81785b3ea65b to get deleted
Sep 11 02:15:05.064: INFO: PersistentVolume pvc-8b15b817-fda0-4442-9292-81785b3ea65b found and phase=Bound (2.174398ms)
... skipping 7 lines ...

• [SLOW TEST:81.104 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should fail to use a volume in a pod with mismatched mode [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:292
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode should fail to use a volume in a pod with mismatched mode [Slow]","total":-1,"completed":2,"skipped":133,"failed":0}
Sep 11 02:15:10.087: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral 
  should create read/write inline ephemeral volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:167
... skipping 30 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should create read/write inline ephemeral volume
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:167
------------------------------
{"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":215,"failed":0}
Sep 11 02:15:10.328: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support file as subpath [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:227
... skipping 16 lines ...
Sep 11 02:13:14.549: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokzskg] to have phase Bound
Sep 11 02:13:14.552: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokzskg found but phase is Pending instead of Bound.
Sep 11 02:13:16.554: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokzskg found but phase is Pending instead of Bound.
Sep 11 02:13:18.558: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokzskg found and phase=Bound (4.0090323s)
STEP: Creating pod pod-subpath-test-dynamicpv-c8d2
STEP: Creating a pod to test atomic-volume-subpath
Sep 11 02:13:18.570: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-c8d2" in namespace "provisioning-4624" to be "Succeeded or Failed"
Sep 11 02:13:18.573: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.930753ms
Sep 11 02:13:20.576: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00667535s
Sep 11 02:13:22.580: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010490987s
Sep 11 02:13:24.584: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014108786s
Sep 11 02:13:26.588: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.018163867s
Sep 11 02:13:28.591: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.021567595s
... skipping 44 lines ...
Sep 11 02:14:58.941: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Running", Reason="", readiness=true. Elapsed: 1m40.370935985s
Sep 11 02:15:00.944: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Running", Reason="", readiness=true. Elapsed: 1m42.374141061s
Sep 11 02:15:02.947: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Running", Reason="", readiness=true. Elapsed: 1m44.377678762s
Sep 11 02:15:04.951: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Running", Reason="", readiness=true. Elapsed: 1m46.381160374s
Sep 11 02:15:06.954: INFO: Pod "pod-subpath-test-dynamicpv-c8d2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m48.384450133s
STEP: Saw pod success
Sep 11 02:15:06.954: INFO: Pod "pod-subpath-test-dynamicpv-c8d2" satisfied condition "Succeeded or Failed"
Sep 11 02:15:06.957: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-c8d2 container test-container-subpath-dynamicpv-c8d2: <nil>
STEP: delete the pod
Sep 11 02:15:06.973: INFO: Waiting for pod pod-subpath-test-dynamicpv-c8d2 to disappear
Sep 11 02:15:06.976: INFO: Pod pod-subpath-test-dynamicpv-c8d2 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-c8d2
Sep 11 02:15:06.977: INFO: Deleting pod "pod-subpath-test-dynamicpv-c8d2" in namespace "provisioning-4624"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support file as subpath [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:227
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support file as subpath [LinuxOnly]","total":-1,"completed":1,"skipped":31,"failed":0}
Sep 11 02:15:12.010: INFO: Running AfterSuite actions on all nodes


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:11.425: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275
Sep 11 02:13:15.091: INFO: Creating resource for dynamic PV
Sep 11 02:13:15.091: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-6515-e2e-sc4v92h
STEP: creating a claim
Sep 11 02:13:15.095: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:15.100: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioc4czt] to have phase Bound
Sep 11 02:13:15.103: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc4czt found but phase is Pending instead of Bound.
Sep 11 02:13:17.106: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc4czt found but phase is Pending instead of Bound.
Sep 11 02:13:19.114: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc4czt found and phase=Bound (4.013325162s)
STEP: Creating pod pod-subpath-test-dynamicpv-rcxw
STEP: Checking for subpath error in container status
Sep 11 02:15:01.129: INFO: Deleting pod "pod-subpath-test-dynamicpv-rcxw" in namespace "provisioning-6515"
Sep 11 02:15:01.135: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-rcxw" to be fully deleted
STEP: Deleting pod
Sep 11 02:15:07.141: INFO: Deleting pod "pod-subpath-test-dynamicpv-rcxw" in namespace "provisioning-6515"
STEP: Deleting pvc
Sep 11 02:15:07.144: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioc4czt"
... skipping 9 lines ...

• [SLOW TEST:120.744 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:275
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]","total":-1,"completed":1,"skipped":57,"failed":0}
Sep 11 02:15:12.170: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should verify container cannot write to subpath readonly volumes [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:419
... skipping 16 lines ...
Sep 11 02:13:13.412: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:13.484: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iorr4tx] to have phase Bound
Sep 11 02:13:13.603: INFO: PersistentVolumeClaim hostpath.csi.k8s.iorr4tx found but phase is Pending instead of Bound.
Sep 11 02:13:15.605: INFO: PersistentVolumeClaim hostpath.csi.k8s.iorr4tx found but phase is Pending instead of Bound.
Sep 11 02:13:17.609: INFO: PersistentVolumeClaim hostpath.csi.k8s.iorr4tx found and phase=Bound (4.124364162s)
STEP: Creating pod to format volume volume-prep-provisioning-4870
Sep 11 02:13:17.618: INFO: Waiting up to 5m0s for pod "volume-prep-provisioning-4870" in namespace "provisioning-4870" to be "Succeeded or Failed"
Sep 11 02:13:17.620: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 2.58072ms
Sep 11 02:13:19.624: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006297585s
Sep 11 02:13:21.626: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 4.00856575s
Sep 11 02:13:23.629: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 6.011046408s
Sep 11 02:13:25.632: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 8.014170488s
Sep 11 02:13:27.635: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 10.017203544s
... skipping 31 lines ...
Sep 11 02:14:31.734: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 1m14.11653628s
Sep 11 02:14:33.738: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 1m16.120030253s
Sep 11 02:14:35.742: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 1m18.123867772s
Sep 11 02:14:37.750: INFO: Pod "volume-prep-provisioning-4870": Phase="Pending", Reason="", readiness=false. Elapsed: 1m20.131838156s
Sep 11 02:14:39.753: INFO: Pod "volume-prep-provisioning-4870": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m22.135469096s
STEP: Saw pod success
Sep 11 02:14:39.753: INFO: Pod "volume-prep-provisioning-4870" satisfied condition "Succeeded or Failed"
Sep 11 02:14:39.753: INFO: Deleting pod "volume-prep-provisioning-4870" in namespace "provisioning-4870"
Sep 11 02:14:39.762: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-4870" to be fully deleted
STEP: Creating pod pod-subpath-test-dynamicpv-t7w5
STEP: Checking for subpath error in container status
Sep 11 02:15:07.781: INFO: Deleting pod "pod-subpath-test-dynamicpv-t7w5" in namespace "provisioning-4870"
Sep 11 02:15:07.790: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-t7w5" to be fully deleted
STEP: Deleting pod
Sep 11 02:15:07.793: INFO: Deleting pod "pod-subpath-test-dynamicpv-t7w5" in namespace "provisioning-4870"
STEP: Deleting pvc
Sep 11 02:15:07.796: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iorr4tx"
... skipping 12 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should verify container cannot write to subpath readonly volumes [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:419
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should verify container cannot write to subpath readonly volumes [Slow]","total":-1,"completed":1,"skipped":58,"failed":0}
Sep 11 02:15:12.824: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support existing directory
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:202
... skipping 16 lines ...
Sep 11 02:13:17.152: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iohcfwq] to have phase Bound
Sep 11 02:13:17.155: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohcfwq found but phase is Pending instead of Bound.
Sep 11 02:13:19.157: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohcfwq found but phase is Pending instead of Bound.
Sep 11 02:13:21.160: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohcfwq found and phase=Bound (4.007141385s)
STEP: Creating pod pod-subpath-test-dynamicpv-qwkz
STEP: Creating a pod to test subpath
Sep 11 02:13:21.169: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-qwkz" in namespace "provisioning-6516" to be "Succeeded or Failed"
Sep 11 02:13:21.171: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117471ms
Sep 11 02:13:23.175: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006068565s
Sep 11 02:13:25.178: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.009781152s
Sep 11 02:13:27.182: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.013584369s
Sep 11 02:13:29.186: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 8.01723657s
Sep 11 02:13:31.190: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 10.02122503s
... skipping 48 lines ...
Sep 11 02:15:09.573: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 1m48.404651514s
Sep 11 02:15:11.592: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 1m50.423055063s
Sep 11 02:15:13.600: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 1m52.431854324s
Sep 11 02:15:15.605: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Pending", Reason="", readiness=false. Elapsed: 1m54.435979129s
Sep 11 02:15:17.608: INFO: Pod "pod-subpath-test-dynamicpv-qwkz": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m56.439456683s
STEP: Saw pod success
Sep 11 02:15:17.608: INFO: Pod "pod-subpath-test-dynamicpv-qwkz" satisfied condition "Succeeded or Failed"
Sep 11 02:15:17.611: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-qwkz container test-container-volume-dynamicpv-qwkz: <nil>
STEP: delete the pod
Sep 11 02:15:17.627: INFO: Waiting for pod pod-subpath-test-dynamicpv-qwkz to disappear
Sep 11 02:15:17.630: INFO: Pod pod-subpath-test-dynamicpv-qwkz no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-qwkz
Sep 11 02:15:17.630: INFO: Deleting pod "pod-subpath-test-dynamicpv-qwkz" in namespace "provisioning-6516"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support existing directory
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:202
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing directory","total":-1,"completed":1,"skipped":282,"failed":0}
Sep 11 02:15:22.660: INFO: Running AfterSuite actions on all nodes


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]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:441
... skipping 43 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should be able to unmount after the subpath directory is deleted [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:441
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should be able to unmount after the subpath directory is deleted [LinuxOnly]","total":-1,"completed":1,"skipped":56,"failed":0}
Sep 11 02:15:32.966: INFO: Running AfterSuite actions on all nodes


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:130
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 11 02:13:10.514: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
Sep 11 02:13:12.046: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253
Sep 11 02:13:12.050: INFO: Creating resource for dynamic PV
Sep 11 02:13:12.050: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-6219-e2e-scfsxbh
STEP: creating a claim
Sep 11 02:13:12.053: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:12.059: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioh56q6] to have phase Bound
Sep 11 02:13:12.065: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioh56q6 found but phase is Pending instead of Bound.
Sep 11 02:13:14.067: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioh56q6 found but phase is Pending instead of Bound.
Sep 11 02:13:16.072: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioh56q6 found and phase=Bound (4.012980859s)
STEP: Creating pod pod-subpath-test-dynamicpv-8vhq
STEP: Checking for subpath error in container status
Sep 11 02:14:44.091: INFO: Deleting pod "pod-subpath-test-dynamicpv-8vhq" in namespace "provisioning-6219"
Sep 11 02:14:44.100: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-8vhq" to be fully deleted
STEP: Deleting pod
Sep 11 02:15:30.106: INFO: Deleting pod "pod-subpath-test-dynamicpv-8vhq" in namespace "provisioning-6219"
STEP: Deleting pvc
Sep 11 02:15:30.108: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioh56q6"
... skipping 9 lines ...

• [SLOW TEST:144.615 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should fail if subpath file is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:253
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath file is outside the volume [Slow][LinuxOnly]","total":-1,"completed":1,"skipped":36,"failed":0}
Sep 11 02:15:35.131: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode 
  should not mount / map unused volumes in a pod [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:347
... skipping 50 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should not mount / map unused volumes in a pod [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:347
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumeMode should not mount / map unused volumes in a pod [LinuxOnly]","total":-1,"completed":1,"skipped":128,"failed":0}
Sep 11 02:15:39.142: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode 
  should not mount / map unused volumes in a pod [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:347
... skipping 49 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should not mount / map unused volumes in a pod [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:347
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode should not mount / map unused volumes in a pod [LinuxOnly]","total":-1,"completed":1,"skipped":128,"failed":0}
Sep 11 02:15:42.420: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral 
  should create read-only inline ephemeral volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:155
... skipping 29 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should create read-only inline ephemeral volume
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:155
------------------------------
{"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":1,"skipped":286,"failed":0}
Sep 11 02:15:42.885: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support existing directories when readOnly specified in the volumeSource
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:391
... skipping 16 lines ...
Sep 11 02:13:11.263: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:13:11.278: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io7r7nx] to have phase Bound
Sep 11 02:13:11.293: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7r7nx found but phase is Pending instead of Bound.
Sep 11 02:13:13.417: INFO: PersistentVolumeClaim hostpath.csi.k8s.io7r7nx found and phase=Bound (2.139119127s)
STEP: Creating pod pod-subpath-test-dynamicpv-kmfn
STEP: Creating a pod to test subpath
Sep 11 02:13:13.624: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-kmfn" in namespace "provisioning-4693" to be "Succeeded or Failed"
Sep 11 02:13:13.671: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 46.991496ms
Sep 11 02:13:15.679: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.054472203s
Sep 11 02:13:17.682: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.058065521s
Sep 11 02:13:19.692: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.068022794s
Sep 11 02:13:21.695: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.070604194s
Sep 11 02:13:23.698: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 10.073481503s
... skipping 36 lines ...
Sep 11 02:14:37.840: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 1m24.216109522s
Sep 11 02:14:39.844: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 1m26.220008514s
Sep 11 02:14:41.848: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 1m28.223415633s
Sep 11 02:14:43.852: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 1m30.227394136s
Sep 11 02:14:45.856: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m32.231883149s
STEP: Saw pod success
Sep 11 02:14:45.856: INFO: Pod "pod-subpath-test-dynamicpv-kmfn" satisfied condition "Succeeded or Failed"
Sep 11 02:14:45.858: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-kmfn container test-container-subpath-dynamicpv-kmfn: <nil>
STEP: delete the pod
Sep 11 02:14:45.877: INFO: Waiting for pod pod-subpath-test-dynamicpv-kmfn to disappear
Sep 11 02:14:45.880: INFO: Pod pod-subpath-test-dynamicpv-kmfn no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-kmfn
Sep 11 02:14:45.880: INFO: Deleting pod "pod-subpath-test-dynamicpv-kmfn" in namespace "provisioning-4693"
STEP: Creating pod pod-subpath-test-dynamicpv-kmfn
STEP: Creating a pod to test subpath
Sep 11 02:14:45.893: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-kmfn" in namespace "provisioning-4693" to be "Succeeded or Failed"
Sep 11 02:14:45.896: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.919154ms
Sep 11 02:14:47.901: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007204823s
Sep 11 02:14:49.904: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010738814s
Sep 11 02:14:51.908: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014151797s
Sep 11 02:14:53.911: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017741876s
Sep 11 02:14:55.916: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 10.022139595s
... skipping 17 lines ...
Sep 11 02:15:31.979: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 46.085214741s
Sep 11 02:15:33.982: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 48.088843941s
Sep 11 02:15:35.986: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 50.092216001s
Sep 11 02:15:37.989: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Pending", Reason="", readiness=false. Elapsed: 52.095659599s
Sep 11 02:15:39.993: INFO: Pod "pod-subpath-test-dynamicpv-kmfn": Phase="Succeeded", Reason="", readiness=false. Elapsed: 54.099397539s
STEP: Saw pod success
Sep 11 02:15:39.993: INFO: Pod "pod-subpath-test-dynamicpv-kmfn" satisfied condition "Succeeded or Failed"
Sep 11 02:15:39.995: INFO: Trying to get logs from node csi-prow-worker pod pod-subpath-test-dynamicpv-kmfn container test-container-subpath-dynamicpv-kmfn: <nil>
STEP: delete the pod
Sep 11 02:15:40.011: INFO: Waiting for pod pod-subpath-test-dynamicpv-kmfn to disappear
Sep 11 02:15:40.014: INFO: Pod pod-subpath-test-dynamicpv-kmfn no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-kmfn
Sep 11 02:15:40.014: INFO: Deleting pod "pod-subpath-test-dynamicpv-kmfn" in namespace "provisioning-4693"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support existing directories when readOnly specified in the volumeSource
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:391
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing directories when readOnly specified in the volumeSource","total":-1,"completed":1,"skipped":54,"failed":0}
Sep 11 02:15:45.044: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand 
  should resize volume when PVC is edited while pod is using it
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:241
... skipping 45 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should resize volume when PVC is edited while pod is using it
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:241
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it","total":-1,"completed":1,"skipped":8,"failed":0}
Sep 11 02:15:56.535: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] 
  should concurrently access the single volume from pods on the same node [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
... skipping 97 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should concurrently access the single volume from pods on the same node [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node [LinuxOnly]","total":-1,"completed":1,"skipped":211,"failed":0}
Sep 11 02:16:04.680: INFO: Running AfterSuite actions on all nodes


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
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:241
... skipping 45 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should resize volume when PVC is edited while pod is using it
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:241
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it","total":-1,"completed":1,"skipped":470,"failed":0}
Sep 11 02:16:07.518: INFO: Running AfterSuite actions on all nodes


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
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
... skipping 61 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should concurrently access the single read-only volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the single read-only volume from pods on the same node","total":-1,"completed":1,"skipped":271,"failed":0}
Sep 11 02:16:07.880: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand 
  Verify if offline PVC expansion works
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:169
... skipping 53 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    Verify if offline PVC expansion works
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:169
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)(allowExpansion)] volume-expand Verify if offline PVC expansion works","total":-1,"completed":1,"skipped":35,"failed":0}
Sep 11 02:16:08.590: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] 
  should concurrently access the single volume from pods on the same node [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
... skipping 98 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should concurrently access the single volume from pods on the same node [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:308
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node [LinuxOnly]","total":-1,"completed":1,"skipped":165,"failed":0}
Sep 11 02:16:12.525: INFO: Running AfterSuite actions on all nodes


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


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes 
  should store data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
... skipping 117 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should store data
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes should store data","total":-1,"completed":1,"skipped":441,"failed":0}
Sep 11 02:16:14.866: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumes 
  should store data
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
... skipping 130 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should store data
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:151
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumes should store data","total":-1,"completed":1,"skipped":52,"failed":0}
Sep 11 02:16:15.603: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] 
  should access to two volumes with different volume mode and retain data across pod recreation on the same node [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:210
... skipping 126 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should access to two volumes with different volume mode and retain data across pod recreation on the same node [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:210
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with different volume mode and retain data across pod recreation on the same node [LinuxOnly]","total":-1,"completed":1,"skipped":100,"failed":0}
Sep 11 02:16:16.767: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand 
  Verify if offline PVC expansion works
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:169
... skipping 53 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    Verify if offline PVC expansion works
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:169
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand Verify if offline PVC expansion works","total":-1,"completed":1,"skipped":27,"failed":0}
Sep 11 02:16:16.818: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumeIO 
  should write files of various sizes, verify size, validate content [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_io.go:144
... skipping 42 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] volumeIO
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should write files of various sizes, verify size, validate content [Slow][LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_io.go:144
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumeIO should write files of various sizes, verify size, validate content [Slow][LinuxOnly]","total":-1,"completed":1,"skipped":34,"failed":0}
Sep 11 02:16:18.816: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] 
  should access to two volumes with the same volume mode and retain data across pod recreation on the same node [LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:130
... skipping 127 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node [LinuxOnly]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:130
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node [LinuxOnly]","total":-1,"completed":1,"skipped":2,"failed":0}
Sep 11 02:16:20.058: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] 
  should concurrently access the single read-only volume from pods on the same node
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
... skipping 64 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should concurrently access the single read-only volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:333
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should concurrently access the single read-only volume from pods on the same node","total":-1,"completed":1,"skipped":336,"failed":0}
Sep 11 02:16:26.520: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral 
  should support two pods which share the same volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:179
... skipping 35 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support two pods which share the same volume
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/ephemeral.go:179
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral should support two pods which share the same volume","total":-1,"completed":1,"skipped":614,"failed":0}
Sep 11 02:16:37.285: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support restarting containers using file as subpath [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:332
... skipping 38 lines ...
Sep 11 02:14:53.102: INFO: stderr: ""
Sep 11 02:14:53.102: INFO: stdout: ""
Sep 11 02:14:53.102: INFO: Pod exec output: 
STEP: Waiting for container to stop restarting
Sep 11 02:15:33.109: INFO: Container has restart count: 4
Sep 11 02:15:59.110: INFO: Container has restart count: 5
Sep 11 02:16:53.113: FAIL: while waiting for container to stabilize
Unexpected error:
    <*errors.errorString | 0xc00026e1f0>: {
        s: "timed out waiting for the condition",
    }
    timed out waiting for the condition
occurred

... skipping 37 lines ...
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:26 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Pulled: Container image "docker.io/library/busybox:1.29" already present on machine
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:27 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Created: Created container test-container-subpath-dynamicpv-wvwk
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:27 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Started: Started container test-container-subpath-dynamicpv-wvwk
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:27 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Pulled: Container image "docker.io/library/busybox:1.29" already present on machine
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:27 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Created: Created container test-container-volume-dynamicpv-wvwk
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:27 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Started: Started container test-container-volume-dynamicpv-wvwk
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:53 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Unhealthy: Liveness probe failed: cat: can't open '/probe-volume/probe-file': No such file or directory

Sep 11 02:17:12.150: INFO: At 2021-09-11 02:13:53 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} Killing: Container test-container-subpath-dynamicpv-wvwk failed liveness probe, will be restarted
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:14:05 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {kubelet csi-prow-worker} BackOff: Back-off restarting failed container
Sep 11 02:17:12.150: INFO: At 2021-09-11 02:14:11 +0000 UTC - event for pod-subpath-test-dynamicpv-wvwk: {csi-pv-monitor-agent-hostpath.csi.k8s.io } VolumeConditionAbnormal: The volume isn't mounted
Sep 11 02:17:12.153: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Sep 11 02:17:12.153: INFO: 
Sep 11 02:17:12.156: INFO: 
Logging node info for node csi-prow-control-plane
Sep 11 02:17:12.160: INFO: Node Info: &Node{ObjectMeta:{csi-prow-control-plane    bd91ede1-b39a-4ec3-8bcf-38f12cc0b6d7 1492 0 2021-09-11 02:06:27 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:csi-prow-control-plane kubernetes.io/os:linux node-role.kubernetes.io/control-plane: node-role.kubernetes.io/master:] map[kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  [{kubelet Update v1 2021-09-11 02:06:27 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}},"f:status":{"f:addresses":{".":{},"k:{\"type\":\"Hostname\"}":{".":{},"f:address":{},"f:type":{}},"k:{\"type\":\"InternalIP\"}":{".":{},"f:address":{},"f:type":{}}},"f:allocatable":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:hugepages-1Gi":{},"f:hugepages-2Mi":{},"f:memory":{},"f:pods":{}},"f:capacity":{".":{},"f:cpu":{},"f:ephemeral-storage":{},"f:hugepages-1Gi":{},"f:hugepages-2Mi":{},"f:memory":{},"f:pods":{}},"f:conditions":{".":{},"k:{\"type\":\"DiskPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"MemoryPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"PIDPressure\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:daemonEndpoints":{"f:kubeletEndpoint":{"f:Port":{}}},"f:images":{},"f:nodeInfo":{"f:architecture":{},"f:bootID":{},"f:containerRuntimeVersion":{},"f:kernelVersion":{},"f:kubeProxyVersion":{},"f:kubeletVersion":{},"f:machineID":{},"f:operatingSystem":{},"f:osImage":{},"f:systemUUID":{}}}}} {kubeadm Update v1 2021-09-11 02:06:30 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}},"f:labels":{"f:node-role.kubernetes.io/control-plane":{},"f:node-role.kubernetes.io/master":{}}}}} {kube-controller-manager Update v1 2021-09-11 02:06:45 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.0.0/24\"":{}},"f:taints":{}}}}]},Spec:NodeSpec{PodCIDR:10.244.0.0/24,DoNotUseExternalID:,ProviderID:kind://docker/csi-prow/csi-prow-control-plane,Unschedulable:false,Taints:[]Taint{Taint{Key:node-role.kubernetes.io/master,Value:,Effect:NoSchedule,TimeAdded:<nil>,},},ConfigSource:nil,PodCIDRs:[10.244.0.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{259975987200 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{67446181888 0} {<nil>} 65865412Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{259975987200 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{67446181888 0} {<nil>} 65865412Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2021-09-11 02:12:36 +0000 UTC,LastTransitionTime:2021-09-11 02:06:26 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2021-09-11 02:12:36 +0000 UTC,LastTransitionTime:2021-09-11 02:06:26 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2021-09-11 02:12:36 +0000 UTC,LastTransitionTime:2021-09-11 02:06:26 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2021-09-11 02:12:36 +0000 UTC,LastTransitionTime:2021-09-11 02:06:55 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.4,},NodeAddress{Type:Hostname,Address:csi-prow-control-plane,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:48a3167467e146708a796d3162ef86f8,SystemUUID:885d0d47-bb30-4425-acf5-ff18c45220bc,BootID:27785bc6-ee58-4b80-93a5-e11f6f148fea,KernelVersion:5.4.0-1046-gke,OSImage:Ubuntu 21.04,ContainerRuntimeVersion:containerd://1.5.2,KubeletVersion:v1.20.7,KubeProxyVersion:v1.20.7,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[k8s.gcr.io/kube-apiserver:v1.20.7],SizeBytes:122987857,},ContainerImage{Names:[k8s.gcr.io/kube-proxy:v1.20.7],SizeBytes:120339943,},ContainerImage{Names:[k8s.gcr.io/kube-controller-manager:v1.20.7],SizeBytes:117523811,},ContainerImage{Names:[k8s.gcr.io/etcd:3.4.13-0],SizeBytes:86742272,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20210326-1e038dc5],SizeBytes:53960776,},ContainerImage{Names:[k8s.gcr.io/kube-scheduler:v1.20.7],SizeBytes:48502096,},ContainerImage{Names:[docker.io/library/hostpathplugin:csiprow],SizeBytes:30889498,},ContainerImage{Names:[k8s.gcr.io/build-image/debian-base:v2.1.0],SizeBytes:21086532,},ContainerImage{Names:[k8s.gcr.io/coredns:1.7.0],SizeBytes:13982350,},ContainerImage{Names:[docker.io/rancher/local-path-provisioner:v0.0.14],SizeBytes:13367922,},ContainerImage{Names:[k8s.gcr.io/pause:3.5],SizeBytes:301416,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 78 lines ...
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support restarting containers using file as subpath [Slow][LinuxOnly] [It]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:332

    Sep 11 02:16:53.113: while waiting for container to stabilize
    Unexpected error:
        <*errors.errorString | 0xc00026e1f0>: {
            s: "timed out waiting for the condition",
        }
        timed out waiting for the condition
    occurred

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:867
------------------------------
{"msg":"FAILED 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":0,"skipped":54,"failed":1,"failures":["External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support restarting containers using file as subpath [Slow][LinuxOnly]"]}
Sep 11 02:17:12.463: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath 
  should support restarting containers using directory as subpath [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:317
... skipping 60 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should support restarting containers using directory as subpath [Slow]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:317
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support restarting containers using directory as subpath [Slow]","total":-1,"completed":1,"skipped":81,"failed":0}
Sep 11 02:17:27.011: INFO: Running AfterSuite actions on all nodes
Sep 11 02:17:27.012: INFO: Running AfterSuite actions on node 1
Sep 11 02:17:27.012: INFO: Dumping logs locally to: /logs/artifacts
Sep 11 02:17:27.012: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory



Summarizing 1 Failure:

[Fail] External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath [It] should support restarting containers using file as subpath [Slow][LinuxOnly] 
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:867

Ran 41 of 5870 Specs in 258.758 seconds
FAIL! -- 40 Passed | 1 Failed | 0 Pending | 5829 Skipped


Ginkgo ran 1 suite in 4m35.274983259s
Test Suite Failed
Sat Sep 11 02:17:27 UTC 2021 go1.16 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/csi-driver-host-path/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 /logs/artifacts/junit_08.xml /logs/artifacts/junit_09.xml /logs/artifacts/junit_10.xml /logs/artifacts/junit_11.xml /logs/artifacts/junit_12.xml /logs/artifacts/junit_13.xml /logs/artifacts/junit_14.xml /logs/artifacts/junit_15.xml /logs/artifacts/junit_16.xml /logs/artifacts/junit_17.xml /logs/artifacts/junit_18.xml /logs/artifacts/junit_19.xml /logs/artifacts/junit_20.xml /logs/artifacts/junit_21.xml /logs/artifacts/junit_22.xml /logs/artifacts/junit_23.xml /logs/artifacts/junit_24.xml /logs/artifacts/junit_25.xml /logs/artifacts/junit_26.xml /logs/artifacts/junit_27.xml /logs/artifacts/junit_28.xml /logs/artifacts/junit_29.xml /logs/artifacts/junit_30.xml /logs/artifacts/junit_31.xml /logs/artifacts/junit_32.xml /logs/artifacts/junit_33.xml /logs/artifacts/junit_34.xml /logs/artifacts/junit_35.xml /logs/artifacts/junit_36.xml /logs/artifacts/junit_37.xml /logs/artifacts/junit_38.xml /logs/artifacts/junit_39.xml /logs/artifacts/junit_40.xml
WARNING: E2E parallel failed
Sat Sep 11 02:17:27 UTC 2021 go1.17 /home/prow/go/src/github.com/onsi/ginkgo$ git fetch --depth=1 https://github.com/onsi/ginkgo v1.7.0
From https://github.com/onsi/ginkgo
 * tag               v1.7.0     -> FETCH_HEAD
Sat Sep 11 02:17:28 UTC 2021 go1.17 /home/prow/go/src/github.com/onsi/ginkgo$ git checkout FETCH_HEAD
HEAD is now at 2e1be8f v1.7.0
Sat Sep 11 02:17:28 UTC 2021 go1.17 /home/prow/go/src/github.com/onsi/ginkgo$ git clean -fdx
... skipping 434 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:169
  [Testpattern: Dynamic PV (block volmode)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:129
    should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/provisioning.go:198
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] provisioning should provision storage with snapshot data source [Feature:VolumeSnapshotDataSource]","total":-1,"completed":1,"skipped":46,"failed":0}
Sep 11 02:18:39.903: INFO: Running AfterSuite actions on all nodes


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


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
Sep 11 02:17:47.431: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:17:47.458: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iopvzzq] to have phase Bound
Sep 11 02:17:47.476: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopvzzq found but phase is Pending instead of Bound.
Sep 11 02:17:49.480: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopvzzq found and phase=Bound (2.021999688s)
STEP: starting a pod to use the claim
Sep 11 02:17:49.492: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-7nmz2" in namespace "snapshotting-6195" to be "Succeeded or Failed"
Sep 11 02:17:49.496: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Pending", Reason="", readiness=false. Elapsed: 3.683778ms
Sep 11 02:17:51.499: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.0069703s
Sep 11 02:17:53.502: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010418115s
Sep 11 02:17:55.506: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.01429197s
Sep 11 02:17:57.513: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.020860447s
Sep 11 02:17:59.515: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.0235921s
Sep 11 02:18:01.520: INFO: Pod "pvc-snapshottable-tester-7nmz2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.02805523s
STEP: Saw pod success
Sep 11 02:18:01.520: INFO: Pod "pvc-snapshottable-tester-7nmz2" satisfied condition "Succeeded or Failed"
Sep 11 02:18:01.555: INFO: Pod pvc-snapshottable-tester-7nmz2 has the following logs: 
Sep 11 02:18:01.555: INFO: Deleting pod "pvc-snapshottable-tester-7nmz2" in namespace "snapshotting-6195"
Sep 11 02:18:01.564: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-7nmz2" to be fully deleted
Sep 11 02:18:01.567: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iopvzzq] to have phase Bound
Sep 11 02:18:01.569: INFO: PersistentVolumeClaim hostpath.csi.k8s.iopvzzq found and phase=Bound (2.287478ms)
STEP: checking the claim
... skipping 11 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep 11 02:18:03.614: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-n8mrh" in namespace "snapshotting-6195" to be "Succeeded or Failed"
Sep 11 02:18:03.621: INFO: Pod "pvc-snapshottable-data-tester-n8mrh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.539873ms
Sep 11 02:18:05.625: INFO: Pod "pvc-snapshottable-data-tester-n8mrh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.010322826s
Sep 11 02:18:07.629: INFO: Pod "pvc-snapshottable-data-tester-n8mrh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013899981s
Sep 11 02:18:09.633: INFO: Pod "pvc-snapshottable-data-tester-n8mrh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.0180475s
Sep 11 02:18:11.637: INFO: Pod "pvc-snapshottable-data-tester-n8mrh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 8.022030687s
STEP: Saw pod success
Sep 11 02:18:11.637: INFO: Pod "pvc-snapshottable-data-tester-n8mrh" satisfied condition "Succeeded or Failed"
Sep 11 02:18:11.642: INFO: Pod pvc-snapshottable-data-tester-n8mrh has the following logs: 
Sep 11 02:18:11.642: INFO: Deleting pod "pvc-snapshottable-data-tester-n8mrh" in namespace "snapshotting-6195"
Sep 11 02:18:11.651: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-n8mrh" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Sep 11 02:18:23.673: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:44801 --kubeconfig=/root/.kube/config --namespace=snapshotting-6195 exec restored-pvc-tester-kfvtx --namespace=snapshotting-6195 -- cat /mnt/test/data'
... skipping 43 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":33,"failed":0}
Sep 11 02:19:10.894: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
Sep 11 02:17:48.842: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:17:48.854: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io5jvtk] to have phase Bound
Sep 11 02:17:48.873: INFO: PersistentVolumeClaim hostpath.csi.k8s.io5jvtk found but phase is Pending instead of Bound.
Sep 11 02:17:50.877: INFO: PersistentVolumeClaim hostpath.csi.k8s.io5jvtk found and phase=Bound (2.022926033s)
STEP: starting a pod to use the claim
Sep 11 02:17:50.885: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-6nl9v" in namespace "snapshotting-2978" to be "Succeeded or Failed"
Sep 11 02:17:50.888: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 2.179081ms
Sep 11 02:17:52.892: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 2.005619303s
Sep 11 02:17:54.895: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 4.00900663s
Sep 11 02:17:56.898: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 6.012304209s
Sep 11 02:17:58.902: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 8.015757784s
Sep 11 02:18:00.906: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 10.019473198s
Sep 11 02:18:02.910: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 12.023468559s
Sep 11 02:18:04.913: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Pending", Reason="", readiness=false. Elapsed: 14.02683771s
Sep 11 02:18:06.916: INFO: Pod "pvc-snapshottable-tester-6nl9v": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.030353329s
STEP: Saw pod success
Sep 11 02:18:06.916: INFO: Pod "pvc-snapshottable-tester-6nl9v" satisfied condition "Succeeded or Failed"
Sep 11 02:18:06.922: INFO: Pod pvc-snapshottable-tester-6nl9v has the following logs: 
Sep 11 02:18:06.922: INFO: Deleting pod "pvc-snapshottable-tester-6nl9v" in namespace "snapshotting-2978"
Sep 11 02:18:06.930: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-6nl9v" to be fully deleted
Sep 11 02:18:06.933: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io5jvtk] to have phase Bound
Sep 11 02:18:06.936: INFO: PersistentVolumeClaim hostpath.csi.k8s.io5jvtk found and phase=Bound (2.691235ms)
STEP: checking the claim
... skipping 34 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep 11 02:18:19.044: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-ft26g" in namespace "snapshotting-2978" to be "Succeeded or Failed"
Sep 11 02:18:19.051: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 6.98314ms
Sep 11 02:18:21.054: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 2.010482859s
Sep 11 02:18:23.057: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 4.013709214s
Sep 11 02:18:25.061: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 6.017102517s
Sep 11 02:18:27.064: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 8.020557085s
Sep 11 02:18:29.068: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 10.024131894s
Sep 11 02:18:31.074: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Pending", Reason="", readiness=false. Elapsed: 12.030639816s
Sep 11 02:18:33.077: INFO: Pod "pvc-snapshottable-data-tester-ft26g": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.033636214s
STEP: Saw pod success
Sep 11 02:18:33.077: INFO: Pod "pvc-snapshottable-data-tester-ft26g" satisfied condition "Succeeded or Failed"
Sep 11 02:18:33.082: INFO: Pod pvc-snapshottable-data-tester-ft26g has the following logs: 
Sep 11 02:18:33.082: INFO: Deleting pod "pvc-snapshottable-data-tester-ft26g" in namespace "snapshotting-2978"
Sep 11 02:18:33.090: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-ft26g" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Sep 11 02:18:39.107: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:44801 --kubeconfig=/root/.kube/config --namespace=snapshotting-2978 exec restored-pvc-tester-t9z5t --namespace=snapshotting-2978 -- cat /mnt/test/data'
... skipping 43 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (delete policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":148,"failed":0}
Sep 11 02:19:22.330: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
Sep 11 02:17:48.668: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:17:48.705: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioxlxxh] to have phase Bound
Sep 11 02:17:48.728: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlxxh found but phase is Pending instead of Bound.
Sep 11 02:17:50.732: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlxxh found and phase=Bound (2.026534382s)
STEP: starting a pod to use the claim
Sep 11 02:17:50.740: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-sfpng" in namespace "snapshotting-5622" to be "Succeeded or Failed"
Sep 11 02:17:50.743: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 2.352819ms
Sep 11 02:17:52.746: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00604783s
Sep 11 02:17:54.750: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 4.009368623s
Sep 11 02:17:56.753: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 6.012879957s
Sep 11 02:17:58.758: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017869132s
Sep 11 02:18:00.761: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 10.02125465s
Sep 11 02:18:02.765: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 12.024884461s
Sep 11 02:18:04.769: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Pending", Reason="", readiness=false. Elapsed: 14.028879217s
Sep 11 02:18:06.772: INFO: Pod "pvc-snapshottable-tester-sfpng": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.032093913s
STEP: Saw pod success
Sep 11 02:18:06.772: INFO: Pod "pvc-snapshottable-tester-sfpng" satisfied condition "Succeeded or Failed"
Sep 11 02:18:06.777: INFO: Pod pvc-snapshottable-tester-sfpng has the following logs: 
Sep 11 02:18:06.777: INFO: Deleting pod "pvc-snapshottable-tester-sfpng" in namespace "snapshotting-5622"
Sep 11 02:18:06.785: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-sfpng" to be fully deleted
Sep 11 02:18:06.788: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.ioxlxxh] to have phase Bound
Sep 11 02:18:06.791: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlxxh found and phase=Bound (2.936783ms)
STEP: checking the claim
... skipping 14 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep 11 02:18:14.840: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-nff5p" in namespace "snapshotting-5622" to be "Succeeded or Failed"
Sep 11 02:18:14.843: INFO: Pod "pvc-snapshottable-data-tester-nff5p": Phase="Pending", Reason="", readiness=false. Elapsed: 2.372833ms
Sep 11 02:18:16.847: INFO: Pod "pvc-snapshottable-data-tester-nff5p": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006485744s
Sep 11 02:18:18.851: INFO: Pod "pvc-snapshottable-data-tester-nff5p": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.009693121s
STEP: Saw pod success
Sep 11 02:18:18.851: INFO: Pod "pvc-snapshottable-data-tester-nff5p" satisfied condition "Succeeded or Failed"
Sep 11 02:18:18.855: INFO: Pod pvc-snapshottable-data-tester-nff5p has the following logs: 
Sep 11 02:18:18.855: INFO: Deleting pod "pvc-snapshottable-data-tester-nff5p" in namespace "snapshotting-5622"
Sep 11 02:18:18.865: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-nff5p" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Sep 11 02:18:32.889: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:44801 --kubeconfig=/root/.kube/config --namespace=snapshotting-5622 exec restored-pvc-tester-579g9 --namespace=snapshotting-5622 -- cat /mnt/test/data'
... skipping 35 lines ...
Sep 11 02:18:59.169: INFO: volumesnapshotcontents snapcontent-8bdff796-25e8-4e1d-be77-86002259810a has been found and is not deleted
Sep 11 02:19:00.173: INFO: volumesnapshotcontents snapcontent-8bdff796-25e8-4e1d-be77-86002259810a has been found and is not deleted
Sep 11 02:19:01.177: INFO: volumesnapshotcontents snapcontent-8bdff796-25e8-4e1d-be77-86002259810a has been found and is not deleted
Sep 11 02:19:02.182: INFO: volumesnapshotcontents snapcontent-8bdff796-25e8-4e1d-be77-86002259810a has been found and is not deleted
Sep 11 02:19:03.186: INFO: volumesnapshotcontents snapcontent-8bdff796-25e8-4e1d-be77-86002259810a has been found and is not deleted
Sep 11 02:19:04.190: INFO: volumesnapshotcontents snapcontent-8bdff796-25e8-4e1d-be77-86002259810a has been found and is not deleted
Sep 11 02:19:05.190: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:192
Sep 11 02:19:05.195: INFO: Pod restored-pvc-tester-579g9 has the following logs: 
Sep 11 02:19:05.195: INFO: Deleting pod "restored-pvc-tester-579g9" in namespace "snapshotting-5622"
Sep 11 02:19:05.200: INFO: Wait up to 5m0s for pod "restored-pvc-tester-579g9" to be fully deleted
Sep 11 02:19:47.213: INFO: deleting claim "snapshotting-5622"/"pvc-nr8l9"
... skipping 28 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":127,"failed":0}
Sep 11 02:19:54.282: INFO: Running AfterSuite actions on all nodes


External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  
  should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
... skipping 17 lines ...
STEP: creating a claim
Sep 11 02:17:48.314: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Sep 11 02:17:48.333: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io586g8] to have phase Bound
Sep 11 02:17:48.363: INFO: PersistentVolumeClaim hostpath.csi.k8s.io586g8 found but phase is Pending instead of Bound.
Sep 11 02:17:50.367: INFO: PersistentVolumeClaim hostpath.csi.k8s.io586g8 found and phase=Bound (2.033879865s)
STEP: starting a pod to use the claim
Sep 11 02:17:50.374: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-dmfzp" in namespace "snapshotting-6954" to be "Succeeded or Failed"
Sep 11 02:17:50.379: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 3.764928ms
Sep 11 02:17:52.382: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007251869s
Sep 11 02:17:54.386: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010901443s
Sep 11 02:17:56.389: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.014441979s
Sep 11 02:17:58.393: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.017963611s
Sep 11 02:18:00.397: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 10.021917001s
Sep 11 02:18:02.400: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Pending", Reason="", readiness=false. Elapsed: 12.025417531s
Sep 11 02:18:04.404: INFO: Pod "pvc-snapshottable-tester-dmfzp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.029419654s
STEP: Saw pod success
Sep 11 02:18:04.404: INFO: Pod "pvc-snapshottable-tester-dmfzp" satisfied condition "Succeeded or Failed"
Sep 11 02:18:04.410: INFO: Pod pvc-snapshottable-tester-dmfzp has the following logs: 
Sep 11 02:18:04.410: INFO: Deleting pod "pvc-snapshottable-tester-dmfzp" in namespace "snapshotting-6954"
Sep 11 02:18:04.421: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-dmfzp" to be fully deleted
Sep 11 02:18:04.423: INFO: Waiting up to 5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io586g8] to have phase Bound
Sep 11 02:18:04.426: INFO: PersistentVolumeClaim hostpath.csi.k8s.io586g8 found and phase=Bound (2.670759ms)
STEP: checking the claim
... skipping 33 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Sep 11 02:18:14.565: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-xwjzk" in namespace "snapshotting-6954" to be "Succeeded or Failed"
Sep 11 02:18:14.568: INFO: Pod "pvc-snapshottable-data-tester-xwjzk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.554909ms
Sep 11 02:18:16.571: INFO: Pod "pvc-snapshottable-data-tester-xwjzk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.005761778s
Sep 11 02:18:18.575: INFO: Pod "pvc-snapshottable-data-tester-xwjzk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.009343524s
Sep 11 02:18:20.578: INFO: Pod "pvc-snapshottable-data-tester-xwjzk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.012867321s
Sep 11 02:18:22.582: INFO: Pod "pvc-snapshottable-data-tester-xwjzk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.016301331s
Sep 11 02:18:24.585: INFO: Pod "pvc-snapshottable-data-tester-xwjzk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.019557513s
STEP: Saw pod success
Sep 11 02:18:24.585: INFO: Pod "pvc-snapshottable-data-tester-xwjzk" satisfied condition "Succeeded or Failed"
Sep 11 02:18:24.916: INFO: Pod pvc-snapshottable-data-tester-xwjzk has the following logs: 
Sep 11 02:18:24.916: INFO: Deleting pod "pvc-snapshottable-data-tester-xwjzk" in namespace "snapshotting-6954"
Sep 11 02:18:24.924: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-xwjzk" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Sep 11 02:18:44.954: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:44801 --kubeconfig=/root/.kube/config --namespace=snapshotting-6954 exec restored-pvc-tester-xq2mc --namespace=snapshotting-6954 -- cat /mnt/test/data'
... skipping 35 lines ...
Sep 11 02:19:11.266: INFO: volumesnapshotcontents pre-provisioned-snapcontent-d03ed868-b392-4a6b-8cd0-d36682bbb680 has been found and is not deleted
Sep 11 02:19:12.270: INFO: volumesnapshotcontents pre-provisioned-snapcontent-d03ed868-b392-4a6b-8cd0-d36682bbb680 has been found and is not deleted
Sep 11 02:19:13.274: INFO: volumesnapshotcontents pre-provisioned-snapcontent-d03ed868-b392-4a6b-8cd0-d36682bbb680 has been found and is not deleted
Sep 11 02:19:14.279: INFO: volumesnapshotcontents pre-provisioned-snapcontent-d03ed868-b392-4a6b-8cd0-d36682bbb680 has been found and is not deleted
Sep 11 02:19:15.283: INFO: volumesnapshotcontents pre-provisioned-snapcontent-d03ed868-b392-4a6b-8cd0-d36682bbb680 has been found and is not deleted
Sep 11 02:19:16.287: INFO: volumesnapshotcontents pre-provisioned-snapcontent-d03ed868-b392-4a6b-8cd0-d36682bbb680 has been found and is not deleted
Sep 11 02:19:17.287: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:192
Sep 11 02:19:17.293: INFO: Pod restored-pvc-tester-xq2mc has the following logs: 
Sep 11 02:19:17.293: INFO: Deleting pod "restored-pvc-tester-xq2mc" in namespace "snapshotting-6954"
Sep 11 02:19:17.296: INFO: Wait up to 5m0s for pod "restored-pvc-tester-xq2mc" to be fully deleted
Sep 11 02:19:57.302: INFO: deleting claim "snapshotting-6954"/"pvc-l2gl7"
... skipping 28 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:121
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:196
        should check snapshot fields, check restore correctly works after modifying source data, check deletion
        /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:213
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Pre-provisioned Snapshot (retain policy)] snapshottable[Feature:VolumeSnapshotDataSource] volume snapshot controller  should check snapshot fields, check restore correctly works after modifying source data, check deletion","total":-1,"completed":1,"skipped":74,"failed":0}
Sep 11 02:20:04.372: INFO: Running AfterSuite actions on all nodes


Sep 11 02:17:48.716: INFO: Running AfterSuite actions on all nodes
Sep 11 02:20:04.389: INFO: Running AfterSuite actions on node 1
Sep 11 02:20:04.389: INFO: Dumping logs locally to: /logs/artifacts
Sep 11 02:20:04.389: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory


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


Ginkgo ran 1 suite in 2m34.572594349s
Test Suite Passed
Sat Sep 11 02:20:04 UTC 2021 go1.16 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/csi-driver-host-path/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 /logs/artifacts/junit_08.xml /logs/artifacts/junit_09.xml /logs/artifacts/junit_10.xml /logs/artifacts/junit_11.xml /logs/artifacts/junit_12.xml /logs/artifacts/junit_13.xml /logs/artifacts/junit_14.xml /logs/artifacts/junit_15.xml /logs/artifacts/junit_16.xml /logs/artifacts/junit_17.xml /logs/artifacts/junit_18.xml /logs/artifacts/junit_19.xml /logs/artifacts/junit_20.xml /logs/artifacts/junit_21.xml /logs/artifacts/junit_22.xml /logs/artifacts/junit_23.xml /logs/artifacts/junit_24.xml /logs/artifacts/junit_25.xml /logs/artifacts/junit_26.xml /logs/artifacts/junit_27.xml /logs/artifacts/junit_28.xml /logs/artifacts/junit_29.xml /logs/artifacts/junit_30.xml /logs/artifacts/junit_31.xml /logs/artifacts/junit_32.xml /logs/artifacts/junit_33.xml /logs/artifacts/junit_34.xml /logs/artifacts/junit_35.xml /logs/artifacts/junit_36.xml /logs/artifacts/junit_37.xml /logs/artifacts/junit_38.xml /logs/artifacts/junit_39.xml /logs/artifacts/junit_40.xml
Sat Sep 11 02:20:04 UTC 2021 go1.17 /home/prow/go/src/github.com/onsi/ginkgo$ git fetch --depth=1 https://github.com/onsi/ginkgo v1.7.0
... skipping 4 lines ...
Sat Sep 11 02:20:05 UTC 2021 go1.17 /home/prow/go/src/github.com/onsi/ginkgo$ git clean -fdx
Sat Sep 11 02:20:05 UTC 2021 go1.16 $ go get github.com/onsi/ginkgo/ginkgo
Sat Sep 11 02:20:06 UTC 2021 go1.17 /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.FWoQbi339P/e2e.test -- -report-dir /logs/artifacts -storage.testdriver=/home/prow/go/pkg/csiprow.FWoQbi339P/test-driver.yaml
Sep 11 02:20:08.802: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used.
I0911 02:20:08.802104  105808 test_context.go:457] Tolerating taints "node-role.kubernetes.io/master" when considering if nodes are ready
I0911 02:20:08.802383  105808 e2e.go:129] Starting e2e run "af4a7244-3b43-4064-9029-660efcb1ebc0" on Ginkgo node 1
{"msg":"Test Suite starting","total":3,"completed":0,"skipped":0,"failed":0}
Running Suite: Kubernetes e2e suite
===================================
Random Seed: 1631326807 - Will randomize all specs
Will run 3 of 5870 specs

Sep 11 02:20:08.871: INFO: >>> kubeConfig: /root/.kube/config
... skipping 91 lines ...

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumelimits.go:116
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSep 11 02:20:09.033: INFO: Running AfterSuite actions on all nodes
Sep 11 02:20:09.033: INFO: Running AfterSuite actions on node 1
Sep 11 02:20:09.033: INFO: Dumping logs locally to: /logs/artifacts
Sep 11 02:20:09.033: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec ../../cluster/log-dump/log-dump.sh: no such file or directory

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

Ran 0 of 5870 Specs in 0.166 seconds
SUCCESS! -- 0 Passed | 0 Failed | 0 Pending | 5870 Skipped
PASS

Ginkgo ran 1 suite in 2.015925519s
Test Suite Passed
Sat Sep 11 02:20:09 UTC 2021 go1.16 /home/prow/go/src/k8s.io/kubernetes$ go run /home/prow/go/src/github.com/kubernetes-csi/csi-driver-host-path/release-tools/filter-junit.go -t=External.Storage|CSI.mock.volume -o /logs/artifacts/junit_serial.xml /logs/artifacts/junit_01.xml
Sat Sep 11 02:20:09 UTC 2021 go1.17 $ kind export logs --name=csi-prow /logs/artifacts/cluster-logs/non-alpha
... skipping 21 lines ...