This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 120 succeeded
Started2022-08-01 15:52
Elapsed31m53s
Revisionmaster

Test Failures


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 6m34s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=External\sStorage\s\[Driver\:\shostpath\.csi\.k8s\.io\]\s\[Testpattern\:\sDynamic\sPV\s\(block\svolmode\)\]\smultiVolume\s\[Slow\]\sshould\sconcurrently\saccess\sthe\ssingle\svolume\sfrom\spods\son\sthe\ssame\snode$'
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:295
Aug  1 16:15:21.249: Unexpected error:
    <*errors.errorString | 0xc00069cad0>: {
        s: "pod \"pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe\" is not Running: timed out waiting for the condition",
    }
    pod "pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe" is not Running: 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/multivolume.go:586
				
				Click to see stdout/stderrfrom junit_final.xml

Find pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe mentions in log files | View test history on testgrid


Show 120 Passed Tests

Show 232 Skipped Tests

Error lines from build-log.txt

... skipping 49 lines ...
non alpha feature gates for latest Kubernetes: CSI_PROW_E2E_GATES_LATEST=
non alpha E2E feature gates: CSI_PROW_E2E_GATES=
external-snapshotter version tag: CSI_SNAPSHOTTER_VERSION=master
tests that need to be skipped: CSI_PROW_E2E_SKIP=Disruptive
work directory: CSI_PROW_WORK=/home/prow/go/pkg/csiprow.csbG0GCDfI
artifacts: ARTIFACTS=/logs/artifacts
Mon Aug  1 15:52:24 UTC 2022 go1.19rc2 $ curl --fail --location -o /home/prow/go/pkg/csiprow.csbG0GCDfI/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
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0

100 6660k  100 6660k    0     0  24.0M      0 --:--:-- --:--:-- --:--:-- 24.0M
No kind clusters found.
INFO: kind-config.yaml:
... skipping 172 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 b9a7786 Merge pull request #352 from pohly/release-4.3.0
Mon Aug  1 15:55:25 UTC 2022 go1.19rc2 /home/prow/go/src/github.com/kubernetes-csi/csi-test$ git clean -fdx
Mon Aug  1 15:55:25 UTC 2022 go1.19rc2 /home/prow/go/src/github.com/kubernetes-csi/csi-test/cmd/csi-sanity$ curl --fail --location https://dl.google.com/go/go1.18.linux-amd64.tar.gz
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
 13  135M   13 17.8M    0     0  24.6M      0  0:00:05 --:--:--  0:00:05 24.5M
 30  135M   30 41.3M    0     0  23.3M      0  0:00:05  0:00:01  0:00:04 23.3M
 45  135M   45 61.6M    0     0  22.5M      0  0:00:05  0:00:02  0:00:03 22.5M
 74  135M   74  100M    0     0  26.8M      0  0:00:05  0:00:03  0:00:02 26.8M
 91  135M   91  124M    0     0  26.2M      0  0:00:05  0:00:04  0:00:01 26.2M
100  135M  100  135M    0     0  24.0M      0  0:00:05  0:00:05 --:--:-- 23.9M
Mon Aug  1 15:55:31 UTC 2022 go1.18 /home/prow/go/src/github.com/kubernetes-csi/csi-test/cmd/csi-sanity$ go build -o /home/prow/go/pkg/csiprow.csbG0GCDfI/csi-sanity
Mon Aug  1 15:55:48 UTC 2022 go1.19rc2 $ /home/prow/go/pkg/csiprow.csbG0GCDfI/csi-sanity -ginkgo.v -csi.junitfile /logs/artifacts/junit_sanity.xml -csi.endpoint dns:///172.18.0.2:31757 -csi.stagingdir /tmp/staging -csi.mountdir /tmp/mount -csi.createstagingpathcmd /home/prow/go/pkg/csiprow.csbG0GCDfI/mkdir_in_pod.sh -csi.createmountpathcmd /home/prow/go/pkg/csiprow.csbG0GCDfI/mkdir_in_pod.sh -csi.removestagingpathcmd /home/prow/go/pkg/csiprow.csbG0GCDfI/rmdir_in_pod.sh -csi.removemountpathcmd /home/prow/go/pkg/csiprow.csbG0GCDfI/rmdir_in_pod.sh -csi.checkpathcmd /home/prow/go/pkg/csiprow.csbG0GCDfI/checkdir_in_pod.sh
Running Suite: CSI Driver Test Suite
... skipping 71 lines ...
I0801 15:55:58.458500   11051 resources.go:322] deleting snapshot ID 6ef7e698-11b2-11ed-9921-0e8179b5e380
I0801 15:55:58.516879   11051 resources.go:322] deleting snapshot ID 6ef007f8-11b2-11ed-9921-0e8179b5e380
I0801 15:55:58.532581   11051 resources.go:322] deleting snapshot ID 6eec41bc-11b2-11ed-9921-0e8179b5e380
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1384
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:1401
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories
•
------------------------------
CreateSnapshot [Controller Server] 
... skipping 6 lines ...
STEP: creating a snapshot with the same name and source volume ID
I0801 15:56:03.776986   11051 resources.go:322] deleting snapshot ID 72453cbb-11b2-11ed-9921-0e8179b5e380
I0801 15:56:03.784429   11051 resources.go:322] deleting snapshot ID 72453cbb-11b2-11ed-9921-0e8179b5e380
•
------------------------------
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:1432
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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
I0801 15:56:07.204877   11051 resources.go:322] deleting snapshot ID 7455170a-11b2-11ed-9921-0e8179b5e380
I0801 15:56:07.252010   11051 resources.go:322] deleting snapshot ID 7455170a-11b2-11ed-9921-0e8179b5e380
•
------------------------------
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:1328
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories
•
------------------------------
DeleteSnapshot [Controller Server] 
... skipping 33 lines ...
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:182
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:194
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:175
    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:268
------------------------------
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:376
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:391
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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.2:31757
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:460
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:501
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:545
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories
STEP: creating a volume
•
------------------------------
... skipping 5 lines ...
STEP: creating a snapshot
STEP: creating a volume from source snapshot
I0801 15:56:32.460189   11051 resources.go:322] deleting snapshot ID 83612448-11b2-11ed-9921-0e8179b5e380
•
------------------------------
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:595
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:641
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:671
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:734
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:748
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:825
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:852
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [1.018 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:845
    should fail when no volume id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:852

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:848
------------------------------
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:867
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.997 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:845
    should fail when no node id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:867

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:848
------------------------------
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:883
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.888 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:845
    should fail when no volume capability is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:883

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:848
------------------------------
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:900
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.841 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:845
    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:900

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:848
------------------------------
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:940
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.849 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:845
    should fail when the volume does not exist [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:940

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:848
------------------------------
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:962
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.776 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:845
    should fail when the node does not exist [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:962

    ControllerPublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:848
------------------------------
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:1001
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.796 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:845
    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:1001

    ControllerPublishVolume not supported

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

    Controller Publish, UnpublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1059
------------------------------
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:1079
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories

S [SKIPPING] in Spec Setup (BeforeEach) [0.795 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:1072
    should fail when no volume id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1079

    ControllerUnpublishVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1075
------------------------------
... skipping 19 lines ...
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories
STEP: verifying name size and characters
•
------------------------------
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:1490
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:1507
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories
•
------------------------------
ExpandVolume [Controller Server] 
... skipping 18 lines ...
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:364
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:381
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:395
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:410
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:429
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:441
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:527
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:546
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:565
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:616
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:630
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:652
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:666
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:680
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:695
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:742
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:757
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
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:776
STEP: reusing connection to CSI driver at dns:///172.18.0.2:31757
STEP: creating mount and staging directories
•
------------------------------
Node Service NodeExpandVolume 
... skipping 52 lines ...
STEP: publishing the volume on a node
STEP: Get node volume stats
•
JUnit report was created: /logs/artifacts/junit_sanity.xml

Ran 67 of 78 Specs in 93.253 seconds
SUCCESS! -- 67 Passed | 0 Failed | 1 Pending | 10 Skipped
Mon Aug  1 15:57:22 UTC 2022 go1.19rc2 $ git init /home/prow/go/src/k8s.io/kubernetes
Initialized empty Git repository in /home/prow/go/src/k8s.io/kubernetes/.git/
Mon Aug  1 15:57:22 UTC 2022 go1.19rc2 /home/prow/go/src/k8s.io/kubernetes$ git fetch --depth=1 https://github.com/kubernetes/kubernetes v1.22.0
From https://github.com/kubernetes/kubernetes
 * tag                 v1.22.0    -> FETCH_HEAD
Mon Aug  1 15:57:34 UTC 2022 go1.19rc2 /home/prow/go/src/k8s.io/kubernetes$ git checkout FETCH_HEAD
... skipping 11 lines ...
HEAD is now at c2b5237c Release commit for Kubernetes v1.22.0
Mon Aug  1 15:57:37 UTC 2022 go1.19rc2 /home/prow/go/src/k8s.io/kubernetes$ git clean -fdx

Using a modified version of k/k/test/e2e:


Mon Aug  1 15:57:37 UTC 2022 go1.19rc2 $ 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
 11  123M   11 14.7M    0     0  28.2M      0  0:00:04 --:--:--  0:00:04 28.1M
 28  123M   28 35.1M    0     0  22.4M      0  0:00:05  0:00:01  0:00:04 22.4M
 46  123M   46 57.0M    0     0  22.2M      0  0:00:05  0:00:02  0:00:03 22.2M
 71  123M   71 88.3M    0     0  24.9M      0  0:00:04  0:00:03  0:00:01 24.9M
 90  123M   90  111M    0     0  24.7M      0  0:00:04  0:00:04 --:--:-- 24.7M
100  123M  100  123M    0     0  23.1M      0  0:00:05  0:00:05 --:--:-- 22.6M
Mon Aug  1 15:57:43 UTC 2022 go1.16 $ 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 214 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:174
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:256

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

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

S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:240

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

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:262
------------------------------
... skipping 585 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:174
  [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:296

    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:262
------------------------------
... skipping 451 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:174
  [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:296

    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:262
------------------------------
... skipping 245 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:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:267

    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:262
------------------------------
... skipping 254 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:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:278

    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:262
------------------------------
... skipping 149 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:174
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:267

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

    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:262
------------------------------
... skipping 27 lines ...
STEP: Creating a kubernetes client
Aug  1 16:09:10.584: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename topology
W0801 16:09:38.470603   59758 warnings.go:70] policy/v1beta1 PodSecurityPolicy is deprecated in v1.21+, unavailable in v1.25+
Aug  1 16:09:38.470: 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
Aug  1 16:09:39.071: 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:186
Aug  1 16:09:39.073: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-6046" for this suite.


S [SKIPPING] [30.460 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:174
  [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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 166 lines ...

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

[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[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:185
STEP: Creating a kubernetes client
Aug  1 16:09:09.529: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
W0801 16:09:19.793775   59998 warnings.go:70] policy/v1beta1 PodSecurityPolicy is deprecated in v1.21+, unavailable in v1.25+
Aug  1 16:09:19.793: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
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:296
Aug  1 16:09:20.339: INFO: Creating resource for dynamic PV
Aug  1 16:09:20.339: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-7091-e2e-scxpqk7
STEP: creating a claim
Aug  1 16:09:21.807: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io74nw6] to have phase Bound
Aug  1 16:09:23.146: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nw6 found but phase is Pending instead of Bound.
Aug  1 16:09:25.870: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nw6 found but phase is Pending instead of Bound.
Aug  1 16:09:28.844: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nw6 found but phase is Pending instead of Bound.
Aug  1 16:09:31.447: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nw6 found but phase is Pending instead of Bound.
Aug  1 16:09:34.521: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nw6 found but phase is Pending instead of Bound.
Aug  1 16:09:37.075: INFO: PersistentVolumeClaim hostpath.csi.k8s.io74nw6 found and phase=Bound (15.268547296s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Aug  1 16:09:51.636: INFO: Deleting pod "pod-9c6cfb6b-0e6c-4b8c-a62d-dcdf4e551f5c" in namespace "volumemode-7091"
Aug  1 16:09:52.183: INFO: Wait up to 5m0s for pod "pod-9c6cfb6b-0e6c-4b8c-a62d-dcdf4e551f5c" to be fully deleted
STEP: Deleting pvc
Aug  1 16:10:02.276: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io74nw6"
Aug  1 16:10:03.004: INFO: Waiting up to 5m0s for PersistentVolume pvc-e7fdd621-896b-43d4-b0be-3a1381683531 to get deleted
Aug  1 16:10:03.950: INFO: PersistentVolume pvc-e7fdd621-896b-43d4-b0be-3a1381683531 found and phase=Bound (945.196515ms)
... skipping 12 lines ...

• [SLOW TEST:92.446 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:174
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:296
------------------------------
{"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":94,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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:166
... skipping 29 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:166
------------------------------
{"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":58,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ext4)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
Aug  1 16:10:48.764: INFO: Driver hostpath.csi.k8s.io doesn't support ext4 -- skipping
... skipping 41 lines ...
[BeforeEach] [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Aug  1 16:10:49.031: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename topology
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192
Aug  1 16:10:55.760: 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:186
Aug  1 16:10:55.761: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "topology-5595" for this suite.


S [SKIPPING] [8.476 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:174
  [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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 40 lines ...
Aug  1 16:10:23.339: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohvl56 found but phase is Pending instead of Bound.
Aug  1 16:10:26.708: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohvl56 found but phase is Pending instead of Bound.
Aug  1 16:10:28.972: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohvl56 found but phase is Pending instead of Bound.
Aug  1 16:10:31.679: INFO: PersistentVolumeClaim hostpath.csi.k8s.iohvl56 found and phase=Bound (1m6.063757004s)
STEP: Expanding non-expandable pvc
Aug  1 16:10:33.911: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Aug  1 16:10:35.260: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:39.051: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:40.228: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:41.738: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:43.391: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:46.231: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:47.930: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:51.823: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:55.466: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:01.248: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:03.398: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:06.745: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:09.579: INFO: Error updating pvc hostpath.csi.k8s.iohvl56: persistentvolumeclaims "hostpath.csi.k8s.iohvl56" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Aug  1 16:11:09.579: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.iohvl56"
Aug  1 16:11:10.661: INFO: Waiting up to 5m0s for PersistentVolume pvc-c99dd2c7-fd4f-4d25-8478-4b2c230c89fd to get deleted
Aug  1 16:11:11.812: INFO: PersistentVolume pvc-c99dd2c7-fd4f-4d25-8478-4b2c230c89fd found and phase=Bound (1.150962135s)
Aug  1 16:11:17.501: INFO: PersistentVolume pvc-c99dd2c7-fd4f-4d25-8478-4b2c230c89fd found and phase=Bound (6.840341728s)
Aug  1 16:11:23.779: INFO: PersistentVolume pvc-c99dd2c7-fd4f-4d25-8478-4b2c230c89fd found and phase=Released (13.11830661s)
... skipping 10 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:157
------------------------------
{"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":103,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volume-expand 
  should not allow expansion of pvcs without AllowVolumeExpansion property
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_expand.go:157
... skipping 29 lines ...
Aug  1 16:10:37.595: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlgsf found but phase is Pending instead of Bound.
Aug  1 16:10:40.231: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlgsf found but phase is Pending instead of Bound.
Aug  1 16:10:42.503: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlgsf found but phase is Pending instead of Bound.
Aug  1 16:10:44.844: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxlgsf found and phase=Bound (46.840038506s)
STEP: Expanding non-expandable pvc
Aug  1 16:10:45.534: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Aug  1 16:10:46.456: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:50.919: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:55.551: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:10:57.304: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:00.575: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:04.654: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:08.559: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:12.226: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:15.412: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:17.503: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
Aug  1 16:11:18.627: INFO: Error updating pvc hostpath.csi.k8s.ioxlgsf: persistentvolumeclaims "hostpath.csi.k8s.ioxlgsf" is forbidden: only dynamically provisioned pvc can be resized and the storageclass that provisions the pvc must support resize
STEP: Deleting pvc
Aug  1 16:11:18.627: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioxlgsf"
Aug  1 16:11:19.246: INFO: Waiting up to 5m0s for PersistentVolume pvc-f9c273aa-cd9c-4539-8b52-b8acd4c0f468 to get deleted
Aug  1 16:11:19.701: INFO: PersistentVolume pvc-f9c273aa-cd9c-4539-8b52-b8acd4c0f468 found and phase=Bound (454.136425ms)
Aug  1 16:11:25.594: INFO: PersistentVolume pvc-f9c273aa-cd9c-4539-8b52-b8acd4c0f468 found and phase=Released (6.347546484s)
Aug  1 16:11:30.791: INFO: PersistentVolume pvc-f9c273aa-cd9c-4539-8b52-b8acd4c0f468 was removed
... skipping 9 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volume-expand
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:157
------------------------------
{"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":361,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
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:294
... skipping 19 lines ...
Aug  1 16:09:11.850: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8bthx found but phase is Pending instead of Bound.
Aug  1 16:09:14.245: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8bthx found but phase is Pending instead of Bound.
Aug  1 16:09:16.583: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8bthx found but phase is Pending instead of Bound.
Aug  1 16:09:19.668: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8bthx found and phase=Bound (7.926931128s)
STEP: Creating pod pod-subpath-test-dynamicpv-mbbz
STEP: Creating a pod to test multi_subpath
Aug  1 16:09:22.247: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-mbbz" in namespace "provisioning-5504" to be "Succeeded or Failed"
Aug  1 16:09:23.537: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 1.28987859s
Aug  1 16:09:26.132: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 3.884894417s
Aug  1 16:09:28.867: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.619066069s
Aug  1 16:09:33.343: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 11.095739987s
Aug  1 16:09:36.311: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 14.064023645s
Aug  1 16:09:38.482: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 16.234200625s
... skipping 31 lines ...
Aug  1 16:11:18.056: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 1m55.808358161s
Aug  1 16:11:20.335: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 1m58.087476396s
Aug  1 16:11:22.516: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 2m0.268541319s
Aug  1 16:11:25.579: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Pending", Reason="", readiness=false. Elapsed: 2m3.331659121s
Aug  1 16:11:28.608: INFO: Pod "pod-subpath-test-dynamicpv-mbbz": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2m6.36069298s
STEP: Saw pod success
Aug  1 16:11:28.608: INFO: Pod "pod-subpath-test-dynamicpv-mbbz" satisfied condition "Succeeded or Failed"
Aug  1 16:11:29.163: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-mbbz container test-container-subpath-dynamicpv-mbbz: <nil>
STEP: delete the pod
Aug  1 16:11:31.333: INFO: Waiting for pod pod-subpath-test-dynamicpv-mbbz to disappear
Aug  1 16:11:31.581: INFO: Pod pod-subpath-test-dynamicpv-mbbz no longer exists
STEP: Deleting pod
Aug  1 16:11:31.592: INFO: Deleting pod "pod-subpath-test-dynamicpv-mbbz" in namespace "provisioning-5504"
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:294
------------------------------
{"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":46,"failed":0}

SSSSSS
------------------------------
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:149
... skipping 29 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:149
------------------------------
{"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":171,"failed":0}

SSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (ext3)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
Aug  1 16:12:00.682: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "PreprovisionedPV" - skipping
... skipping 99 lines ...
Aug  1 16:09:58.799: INFO: PersistentVolumeClaim hostpath.csi.k8s.iok6xp7 found but phase is Pending instead of Bound.
Aug  1 16:10:01.548: INFO: PersistentVolumeClaim hostpath.csi.k8s.iok6xp7 found but phase is Pending instead of Bound.
Aug  1 16:10:04.323: INFO: PersistentVolumeClaim hostpath.csi.k8s.iok6xp7 found but phase is Pending instead of Bound.
Aug  1 16:10:06.856: INFO: PersistentVolumeClaim hostpath.csi.k8s.iok6xp7 found and phase=Bound (32.971949901s)
STEP: Creating pod pod-subpath-test-dynamicpv-62xt
STEP: Creating a pod to test atomic-volume-subpath
Aug  1 16:10:10.243: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-62xt" in namespace "provisioning-6991" to be "Succeeded or Failed"
Aug  1 16:10:10.987: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 744.114454ms
Aug  1 16:10:13.943: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 3.6998378s
Aug  1 16:10:16.602: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.35888292s
Aug  1 16:10:18.897: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.653521212s
Aug  1 16:10:21.856: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 11.612695356s
Aug  1 16:10:25.528: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 15.284478391s
... skipping 31 lines ...
Aug  1 16:11:48.874: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 1m38.631159726s
Aug  1 16:11:51.055: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 1m40.811856964s
Aug  1 16:11:53.168: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 1m42.9243628s
Aug  1 16:11:55.180: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Pending", Reason="", readiness=false. Elapsed: 1m44.93649056s
Aug  1 16:11:57.884: INFO: Pod "pod-subpath-test-dynamicpv-62xt": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m47.640655268s
STEP: Saw pod success
Aug  1 16:11:57.884: INFO: Pod "pod-subpath-test-dynamicpv-62xt" satisfied condition "Succeeded or Failed"
Aug  1 16:11:57.931: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-62xt container test-container-subpath-dynamicpv-62xt: <nil>
STEP: delete the pod
Aug  1 16:11:58.265: INFO: Waiting for pod pod-subpath-test-dynamicpv-62xt to disappear
Aug  1 16:11:58.355: INFO: Pod pod-subpath-test-dynamicpv-62xt no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-62xt
Aug  1 16:11:58.355: INFO: Deleting pod "pod-subpath-test-dynamicpv-62xt" in namespace "provisioning-6991"
... skipping 16 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:230
------------------------------
{"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":105,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
Aug  1 16:12:05.083: INFO: Driver "hostpath.csi.k8s.io" does not support volume type "InlineVolume" - skipping
... skipping 24 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:256

    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:262
------------------------------
... skipping 91 lines ...
Aug  1 16:10:40.151: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioktdkw found but phase is Pending instead of Bound.
Aug  1 16:10:42.669: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioktdkw found but phase is Pending instead of Bound.
Aug  1 16:10:45.148: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioktdkw found but phase is Pending instead of Bound.
Aug  1 16:10:47.368: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioktdkw found and phase=Bound (1m0.028792919s)
STEP: Creating pod pod-subpath-test-dynamicpv-vndd
STEP: Creating a pod to test subpath
Aug  1 16:10:47.467: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-vndd" in namespace "provisioning-2456" to be "Succeeded or Failed"
Aug  1 16:10:48.037: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 569.966874ms
Aug  1 16:10:52.832: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 5.364068293s
Aug  1 16:10:55.393: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 7.925886589s
Aug  1 16:10:57.779: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.311879506s
Aug  1 16:11:01.020: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 13.552745497s
Aug  1 16:11:03.397: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 15.929772001s
... skipping 23 lines ...
Aug  1 16:12:04.390: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m16.922907905s
Aug  1 16:12:06.704: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m19.236342636s
Aug  1 16:12:09.364: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m21.896657037s
Aug  1 16:12:12.224: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Pending", Reason="", readiness=false. Elapsed: 1m24.756652297s
Aug  1 16:12:14.953: INFO: Pod "pod-subpath-test-dynamicpv-vndd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m27.485640206s
STEP: Saw pod success
Aug  1 16:12:14.953: INFO: Pod "pod-subpath-test-dynamicpv-vndd" satisfied condition "Succeeded or Failed"
Aug  1 16:12:15.299: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-vndd container test-container-subpath-dynamicpv-vndd: <nil>
STEP: delete the pod
Aug  1 16:12:17.987: INFO: Waiting for pod pod-subpath-test-dynamicpv-vndd to disappear
Aug  1 16:12:19.404: INFO: Pod pod-subpath-test-dynamicpv-vndd no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-vndd
Aug  1 16:12:19.404: INFO: Deleting pod "pod-subpath-test-dynamicpv-vndd" in namespace "provisioning-2456"
... skipping 17 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:365
------------------------------
{"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":159,"failed":0}

SSSSSSSSSSSSS
------------------------------
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:183
... skipping 35 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:183
------------------------------
{"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":96,"failed":0}

SSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
... skipping 160 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:174
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:278

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

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

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: hostpath.csi.k8s.io]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:240

    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:262
------------------------------
... skipping 74 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [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/framework/testsuite.go:50
    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:174
------------------------------
{"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":39,"failed":0}
Aug  1 16:15:11.400: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:11.400: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:11.400: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:11.400: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:11.400: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:11.400: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 167 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should store data
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:159
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] volumes should store data","total":-1,"completed":1,"skipped":16,"failed":0}
Aug  1 16:15:15.909: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:15.909: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:15.909: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:15.909: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:15.909: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:15.909: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 36 lines ...
Aug  1 16:10:37.596: INFO: PersistentVolumeClaim hostpath.csi.k8s.iop6rj7 found but phase is Pending instead of Bound.
Aug  1 16:10:40.543: INFO: PersistentVolumeClaim hostpath.csi.k8s.iop6rj7 found but phase is Pending instead of Bound.
Aug  1 16:10:42.940: INFO: PersistentVolumeClaim hostpath.csi.k8s.iop6rj7 found but phase is Pending instead of Bound.
Aug  1 16:10:45.156: INFO: PersistentVolumeClaim hostpath.csi.k8s.iop6rj7 found and phase=Bound (43.048370516s)
STEP: Creating pod pod-subpath-test-dynamicpv-5pcp
STEP: Creating a pod to test subpath
Aug  1 16:10:46.791: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-5pcp" in namespace "provisioning-8788" to be "Succeeded or Failed"
Aug  1 16:10:47.044: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 252.891487ms
Aug  1 16:10:50.998: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.207013403s
Aug  1 16:10:54.486: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 7.69479629s
Aug  1 16:10:56.845: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 10.053303071s
Aug  1 16:11:01.019: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 14.228068396s
Aug  1 16:11:03.476: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 16.684243947s
... skipping 53 lines ...
Aug  1 16:14:31.560: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 3m44.768601623s
Aug  1 16:14:34.662: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 3m47.870748131s
Aug  1 16:14:37.018: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 3m50.226749449s
Aug  1 16:14:39.996: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Pending", Reason="", readiness=false. Elapsed: 3m53.204308262s
Aug  1 16:14:45.097: INFO: Pod "pod-subpath-test-dynamicpv-5pcp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 3m58.305669152s
STEP: Saw pod success
Aug  1 16:14:45.097: INFO: Pod "pod-subpath-test-dynamicpv-5pcp" satisfied condition "Succeeded or Failed"
Aug  1 16:14:45.481: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-5pcp container test-container-volume-dynamicpv-5pcp: <nil>
STEP: delete the pod
Aug  1 16:14:46.951: INFO: Waiting for pod pod-subpath-test-dynamicpv-5pcp to disappear
Aug  1 16:14:47.855: INFO: Pod pod-subpath-test-dynamicpv-5pcp no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-5pcp
Aug  1 16:14:47.855: INFO: Deleting pod "pod-subpath-test-dynamicpv-5pcp" in namespace "provisioning-8788"
... skipping 20 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should support existing directory
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:205
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing directory","total":-1,"completed":1,"skipped":339,"failed":0}
Aug  1 16:15:16.248: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:16.248: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[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:185
STEP: Creating a kubernetes client
Aug  1 16:09:09.743: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
W0801 16:09:22.040405   59936 warnings.go:70] policy/v1beta1 PodSecurityPolicy is deprecated in v1.21+, unavailable in v1.25+
Aug  1 16:09:22.040: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278
Aug  1 16:09:23.803: INFO: Creating resource for dynamic PV
Aug  1 16:09:23.804: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-6349-e2e-scvv5kk
STEP: creating a claim
Aug  1 16:09:24.128: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
... skipping 8 lines ...
Aug  1 16:09:47.170: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6w7bs found but phase is Pending instead of Bound.
Aug  1 16:09:50.016: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6w7bs found but phase is Pending instead of Bound.
Aug  1 16:09:53.323: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6w7bs found but phase is Pending instead of Bound.
Aug  1 16:09:56.296: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6w7bs found but phase is Pending instead of Bound.
Aug  1 16:09:59.566: INFO: PersistentVolumeClaim hostpath.csi.k8s.io6w7bs found and phase=Bound (33.540236256s)
STEP: Creating pod pod-subpath-test-dynamicpv-pwc9
STEP: Checking for subpath error in container status
Aug  1 16:12:02.201: INFO: Deleting pod "pod-subpath-test-dynamicpv-pwc9" in namespace "provisioning-6349"
Aug  1 16:12:04.390: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-pwc9" to be fully deleted
STEP: Deleting pod
Aug  1 16:13:54.740: INFO: Deleting pod "pod-subpath-test-dynamicpv-pwc9" in namespace "provisioning-6349"
STEP: Deleting pvc
Aug  1 16:13:57.171: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io6w7bs"
... skipping 21 lines ...

• [SLOW TEST:367.100 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:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:278
------------------------------
{"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":123,"failed":0}
Aug  1 16:15:16.846: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:16.846: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:16.846: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:16.846: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:16.846: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:16.846: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 45 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:166
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should create read/write inline ephemeral volume","total":-1,"completed":1,"skipped":53,"failed":0}
Aug  1 16:15:19.063: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:19.063: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:19.063: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:19.063: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:19.063: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:19.063: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 43 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:149
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should create read-only inline ephemeral volume","total":-1,"completed":1,"skipped":146,"failed":0}
Aug  1 16:15:19.947: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:19.947: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:19.947: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:19.947: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:19.947: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:19.947: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 36 lines ...
Aug  1 16:11:20.425: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovchrj found but phase is Pending instead of Bound.
Aug  1 16:11:23.745: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovchrj found but phase is Pending instead of Bound.
Aug  1 16:11:25.928: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovchrj found but phase is Pending instead of Bound.
Aug  1 16:11:28.624: INFO: PersistentVolumeClaim hostpath.csi.k8s.iovchrj found and phase=Bound (43.321769979s)
STEP: Creating pod pod-subpath-test-dynamicpv-g56v
STEP: Creating a pod to test subpath
Aug  1 16:11:30.085: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-g56v" in namespace "provisioning-8953" to be "Succeeded or Failed"
Aug  1 16:11:30.916: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 830.92905ms
Aug  1 16:11:33.066: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 2.980741384s
Aug  1 16:11:35.122: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 5.03709311s
Aug  1 16:11:37.210: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 7.125080128s
Aug  1 16:11:39.631: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 9.545831482s
Aug  1 16:11:41.990: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 11.904534301s
... skipping 44 lines ...
Aug  1 16:14:48.168: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 3m18.082701653s
Aug  1 16:14:50.537: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 3m20.451888394s
Aug  1 16:14:53.187: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 3m23.101794406s
Aug  1 16:14:55.347: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Pending", Reason="", readiness=false. Elapsed: 3m25.261987824s
Aug  1 16:14:57.356: INFO: Pod "pod-subpath-test-dynamicpv-g56v": Phase="Succeeded", Reason="", readiness=false. Elapsed: 3m27.270594971s
STEP: Saw pod success
Aug  1 16:14:57.356: INFO: Pod "pod-subpath-test-dynamicpv-g56v" satisfied condition "Succeeded or Failed"
Aug  1 16:14:57.364: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-g56v container test-container-subpath-dynamicpv-g56v: <nil>
STEP: delete the pod
Aug  1 16:14:57.459: INFO: Waiting for pod pod-subpath-test-dynamicpv-g56v to disappear
Aug  1 16:14:57.481: INFO: Pod pod-subpath-test-dynamicpv-g56v no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-g56v
Aug  1 16:14:57.481: INFO: Deleting pod "pod-subpath-test-dynamicpv-g56v" in namespace "provisioning-8953"
... skipping 19 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:219
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support existing single file [LinuxOnly]","total":-1,"completed":2,"skipped":156,"failed":0}
Aug  1 16:15:20.231: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:20.231: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:20.231: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:20.231: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:20.231: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:20.231: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 24 lines ...
Aug  1 16:09:08.657: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug  1 16:09:09.584: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iog8rh5] to have phase Bound
Aug  1 16:09:09.787: INFO: PersistentVolumeClaim hostpath.csi.k8s.iog8rh5 found but phase is Pending instead of Bound.
Aug  1 16:09:11.878: INFO: PersistentVolumeClaim hostpath.csi.k8s.iog8rh5 found and phase=Bound (2.293806317s)
STEP: Creating pod pod-subpath-test-dynamicpv-52qv
STEP: Creating a pod to test subpath
Aug  1 16:09:12.408: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-52qv" in namespace "provisioning-8121" to be "Succeeded or Failed"
Aug  1 16:09:12.546: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 138.479314ms
Aug  1 16:09:14.875: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.466873958s
Aug  1 16:09:17.223: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.815566758s
Aug  1 16:09:19.694: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 7.286063864s
Aug  1 16:09:22.634: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 10.225902531s
Aug  1 16:09:25.601: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 13.193409351s
... skipping 39 lines ...
Aug  1 16:11:26.594: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 2m14.18620011s
Aug  1 16:11:29.037: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 2m16.628713763s
Aug  1 16:11:31.318: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 2m18.910296806s
Aug  1 16:11:33.699: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 2m21.291627227s
Aug  1 16:11:35.835: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2m23.427471552s
STEP: Saw pod success
Aug  1 16:11:35.835: INFO: Pod "pod-subpath-test-dynamicpv-52qv" satisfied condition "Succeeded or Failed"
Aug  1 16:11:35.917: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-52qv container test-container-subpath-dynamicpv-52qv: <nil>
STEP: delete the pod
Aug  1 16:11:36.438: INFO: Waiting for pod pod-subpath-test-dynamicpv-52qv to disappear
Aug  1 16:11:36.532: INFO: Pod pod-subpath-test-dynamicpv-52qv no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-52qv
Aug  1 16:11:36.533: INFO: Deleting pod "pod-subpath-test-dynamicpv-52qv" in namespace "provisioning-8121"
STEP: Creating pod pod-subpath-test-dynamicpv-52qv
STEP: Creating a pod to test subpath
Aug  1 16:11:36.841: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-52qv" in namespace "provisioning-8121" to be "Succeeded or Failed"
Aug  1 16:11:36.968: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 127.086693ms
Aug  1 16:11:39.195: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.353511096s
Aug  1 16:11:41.360: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.519238688s
Aug  1 16:11:43.431: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 6.590220339s
Aug  1 16:11:45.805: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 8.963586601s
Aug  1 16:11:47.823: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 10.981322282s
... skipping 45 lines ...
Aug  1 16:14:52.481: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 3m15.639950072s
Aug  1 16:14:54.574: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 3m17.732692908s
Aug  1 16:14:56.872: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 3m20.031225744s
Aug  1 16:14:58.931: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Pending", Reason="", readiness=false. Elapsed: 3m22.089977744s
Aug  1 16:15:01.108: INFO: Pod "pod-subpath-test-dynamicpv-52qv": Phase="Succeeded", Reason="", readiness=false. Elapsed: 3m24.267239967s
STEP: Saw pod success
Aug  1 16:15:01.109: INFO: Pod "pod-subpath-test-dynamicpv-52qv" satisfied condition "Succeeded or Failed"
Aug  1 16:15:01.164: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-52qv container test-container-subpath-dynamicpv-52qv: <nil>
STEP: delete the pod
Aug  1 16:15:02.473: INFO: Waiting for pod pod-subpath-test-dynamicpv-52qv to disappear
Aug  1 16:15:02.758: INFO: Pod pod-subpath-test-dynamicpv-52qv no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-52qv
Aug  1 16:15:02.758: INFO: Deleting pod "pod-subpath-test-dynamicpv-52qv" in namespace "provisioning-8121"
... skipping 18 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:395
------------------------------
{"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":29,"failed":0}
Aug  1 16:15:20.626: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:20.626: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[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:185
STEP: Creating a kubernetes client
Aug  1 16:09:15.705: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267
Aug  1 16:09:45.487: INFO: Creating resource for dynamic PV
Aug  1 16:09:45.487: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-716-e2e-scnrjnf
STEP: creating a claim
Aug  1 16:09:46.073: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
... skipping 15 lines ...
Aug  1 16:10:32.753: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc6kbn found but phase is Pending instead of Bound.
Aug  1 16:10:35.527: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc6kbn found but phase is Pending instead of Bound.
Aug  1 16:10:38.443: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc6kbn found but phase is Pending instead of Bound.
Aug  1 16:10:40.983: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc6kbn found but phase is Pending instead of Bound.
Aug  1 16:10:43.339: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioc6kbn found and phase=Bound (53.323003705s)
STEP: Creating pod pod-subpath-test-dynamicpv-8tjp
STEP: Checking for subpath error in container status
Aug  1 16:14:09.863: INFO: Deleting pod "pod-subpath-test-dynamicpv-8tjp" in namespace "provisioning-716"
Aug  1 16:14:13.438: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-8tjp" to be fully deleted
STEP: Deleting pod
Aug  1 16:14:39.775: INFO: Deleting pod "pod-subpath-test-dynamicpv-8tjp" in namespace "provisioning-716"
STEP: Deleting pvc
Aug  1 16:14:40.206: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ioc6kbn"
... skipping 15 lines ...

• [SLOW TEST:364.928 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:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:267
------------------------------
{"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":197,"failed":0}
Aug  1 16:15:20.634: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:20.634: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:20.634: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:20.634: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:20.634: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:20.634: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 46 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:166
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should create read/write inline ephemeral volume","total":-1,"completed":1,"skipped":106,"failed":0}
Aug  1 16:15:21.901: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:21.901: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:21.901: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:21.901: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:21.901: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:21.901: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 146 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should store data
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:159
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] volumes should store data","total":-1,"completed":1,"skipped":9,"failed":0}
Aug  1 16:15:22.679: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:22.679: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:22.679: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:22.679: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:22.679: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:22.679: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 69 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:351
------------------------------
{"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":212,"failed":0}
Aug  1 16:15:22.794: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:22.794: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:22.794: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:22.794: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:22.794: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:22.794: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 45 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:221
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should support multiple inline ephemeral volumes","total":-1,"completed":1,"skipped":267,"failed":0}
Aug  1 16:15:23.015: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:23.015: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:23.015: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:23.015: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:23.015: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:23.016: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:23.016: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:23.016: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


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

[BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[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:185
STEP: Creating a kubernetes client
Aug  1 16:11:31.670: 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:296
Aug  1 16:11:33.319: INFO: Creating resource for dynamic PV
Aug  1 16:11:33.319: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-4155-e2e-scmk2jh
STEP: creating a claim
Aug  1 16:11:34.583: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.io9vmjx] to have phase Bound
Aug  1 16:11:34.850: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9vmjx found but phase is Pending instead of Bound.
Aug  1 16:11:36.928: INFO: PersistentVolumeClaim hostpath.csi.k8s.io9vmjx found and phase=Bound (2.344984293s)
STEP: Creating pod
STEP: Waiting for the pod to fail
Aug  1 16:13:53.205: INFO: Deleting pod "pod-68b2d8c0-9df5-4379-ad76-2c6f40c5dbf5" in namespace "volumemode-4155"
Aug  1 16:13:53.473: INFO: Wait up to 5m0s for pod "pod-68b2d8c0-9df5-4379-ad76-2c6f40c5dbf5" to be fully deleted
STEP: Deleting pvc
Aug  1 16:15:00.346: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io9vmjx"
Aug  1 16:15:01.090: INFO: Waiting up to 5m0s for PersistentVolume pvc-8ba53686-e383-4e89-b41f-e3ddc007a96d to get deleted
Aug  1 16:15:01.200: INFO: PersistentVolume pvc-8ba53686-e383-4e89-b41f-e3ddc007a96d found and phase=Bound (110.125232ms)
... skipping 10 lines ...

• [SLOW TEST:232.381 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:174
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:296
------------------------------
{"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":394,"failed":0}
Aug  1 16:15:24.055: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:24.055: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:24.055: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:24.055: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:24.055: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:24.055: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 43 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:149
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should create read-only inline ephemeral volume","total":-1,"completed":1,"skipped":100,"failed":0}
Aug  1 16:15:25.183: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:25.183: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


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

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[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:185
STEP: Creating a kubernetes client
Aug  1 16:09:39.969: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:256
Aug  1 16:09:56.372: INFO: Creating resource for dynamic PV
Aug  1 16:09:56.372: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-2952-e2e-sc7jfbq
STEP: creating a claim
Aug  1 16:09:56.933: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
... skipping 16 lines ...
Aug  1 16:10:44.849: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2spxb found but phase is Pending instead of Bound.
Aug  1 16:10:47.051: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2spxb found but phase is Pending instead of Bound.
Aug  1 16:10:50.935: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2spxb found but phase is Pending instead of Bound.
Aug  1 16:10:54.487: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2spxb found but phase is Pending instead of Bound.
Aug  1 16:10:56.772: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2spxb found and phase=Bound (57.822434447s)
STEP: Creating pod pod-subpath-test-dynamicpv-l47k
STEP: Checking for subpath error in container status
Aug  1 16:14:49.048: INFO: Deleting pod "pod-subpath-test-dynamicpv-l47k" in namespace "provisioning-2952"
Aug  1 16:14:49.134: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-l47k" to be fully deleted
STEP: Deleting pod
Aug  1 16:15:19.287: INFO: Deleting pod "pod-subpath-test-dynamicpv-l47k" in namespace "provisioning-2952"
STEP: Deleting pvc
Aug  1 16:15:19.349: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io2spxb"
... skipping 9 lines ...

• [SLOW TEST:345.399 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:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:256
------------------------------
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:240

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[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:185
STEP: Creating a kubernetes client
Aug  1 16:09:17.673: 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:240
Aug  1 16:09:49.159: INFO: Creating resource for dynamic PV
Aug  1 16:09:49.159: INFO: Using claimSize:1Mi, test suite supported size:{ 1Mi}, driver(hostpath.csi.k8s.io) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-9341-e2e-scfgx7z
STEP: creating a claim
Aug  1 16:09:50.410: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
... skipping 16 lines ...
Aug  1 16:10:39.091: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8wfgc found but phase is Pending instead of Bound.
Aug  1 16:10:41.885: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8wfgc found but phase is Pending instead of Bound.
Aug  1 16:10:44.241: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8wfgc found but phase is Pending instead of Bound.
Aug  1 16:10:46.787: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8wfgc found but phase is Pending instead of Bound.
Aug  1 16:10:50.964: INFO: PersistentVolumeClaim hostpath.csi.k8s.io8wfgc found and phase=Bound (58.625531062s)
STEP: Creating pod pod-subpath-test-dynamicpv-6vwf
STEP: Checking for subpath error in container status
Aug  1 16:12:26.703: INFO: Deleting pod "pod-subpath-test-dynamicpv-6vwf" in namespace "provisioning-9341"
Aug  1 16:12:30.167: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-6vwf" to be fully deleted
STEP: Deleting pod
Aug  1 16:15:01.450: INFO: Deleting pod "pod-subpath-test-dynamicpv-6vwf" in namespace "provisioning-9341"
STEP: Deleting pvc
Aug  1 16:15:01.460: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.io8wfgc"
... skipping 12 lines ...

• [SLOW TEST:367.702 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:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:240
------------------------------
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:246

... skipping 65 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [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/framework/testsuite.go:50
    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:246
------------------------------
{"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":312,"failed":0}
Aug  1 16:15:25.378: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:25.378: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should fail if subpath directory is outside the volume [Slow][LinuxOnly]","total":-1,"completed":1,"skipped":310,"failed":0}
Aug  1 16:15:25.379: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:25.379: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it","total":-1,"completed":1,"skipped":154,"failed":0}
Aug  1 16:15:25.380: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:25.380: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:25.380: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:25.380: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:25.380: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:25.380: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 41 lines ...
Aug  1 16:10:11.619: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2jdpz found but phase is Pending instead of Bound.
Aug  1 16:10:13.785: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2jdpz found but phase is Pending instead of Bound.
Aug  1 16:10:16.305: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2jdpz found but phase is Pending instead of Bound.
Aug  1 16:10:18.911: INFO: PersistentVolumeClaim hostpath.csi.k8s.io2jdpz found and phase=Bound (55.440186672s)
STEP: Creating pod pod-subpath-test-dynamicpv-bkr9
STEP: Creating a pod to test subpath
Aug  1 16:10:23.369: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-bkr9" in namespace "provisioning-5810" to be "Succeeded or Failed"
Aug  1 16:10:25.219: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 1.849768283s
Aug  1 16:10:29.202: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 5.832841708s
Aug  1 16:10:31.725: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.355524157s
Aug  1 16:10:34.591: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 11.221816006s
Aug  1 16:10:37.545: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 14.175807294s
Aug  1 16:10:39.659: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 16.290000147s
... skipping 71 lines ...
Aug  1 16:14:56.873: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m33.503727659s
Aug  1 16:14:58.923: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m35.553576121s
Aug  1 16:15:01.108: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m37.73865163s
Aug  1 16:15:03.334: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m39.964529899s
Aug  1 16:15:05.666: INFO: Pod "pod-subpath-test-dynamicpv-bkr9": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4m42.296442903s
STEP: Saw pod success
Aug  1 16:15:05.666: INFO: Pod "pod-subpath-test-dynamicpv-bkr9" satisfied condition "Succeeded or Failed"
Aug  1 16:15:05.962: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-bkr9 container test-container-volume-dynamicpv-bkr9: <nil>
STEP: delete the pod
Aug  1 16:15:08.817: INFO: Waiting for pod pod-subpath-test-dynamicpv-bkr9 to disappear
Aug  1 16:15:08.979: INFO: Pod pod-subpath-test-dynamicpv-bkr9 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-bkr9
Aug  1 16:15:08.979: INFO: Deleting pod "pod-subpath-test-dynamicpv-bkr9" in namespace "provisioning-5810"
... skipping 18 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:194
------------------------------
{"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":102,"failed":0}
Aug  1 16:15:26.856: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:26.856: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:26.856: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:26.856: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:26.856: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:26.856: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 58 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:445
------------------------------
{"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":28,"failed":0}
Aug  1 16:15:27.143: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:27.143: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:27.143: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:27.143: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:27.143: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:27.143: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 56 lines ...
Aug  1 16:11:12.403: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqtbh2 found but phase is Pending instead of Bound.
Aug  1 16:11:15.090: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqtbh2 found but phase is Pending instead of Bound.
Aug  1 16:11:17.400: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqtbh2 found but phase is Pending instead of Bound.
Aug  1 16:11:20.336: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioqtbh2 found and phase=Bound (1m36.10852382s)
STEP: Creating pod pod-subpath-test-dynamicpv-kjm7
STEP: Creating a pod to test subpath
Aug  1 16:11:22.520: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-kjm7" in namespace "provisioning-8797" to be "Succeeded or Failed"
Aug  1 16:11:24.101: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 1.581644965s
Aug  1 16:11:26.776: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.256576166s
Aug  1 16:11:29.316: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.796563647s
Aug  1 16:11:32.010: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 9.490555644s
Aug  1 16:11:34.376: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 11.856432575s
Aug  1 16:11:36.432: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 13.91171178s
... skipping 54 lines ...
Aug  1 16:14:56.872: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 3m34.35194385s
Aug  1 16:14:58.919: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 3m36.399634536s
Aug  1 16:15:01.168: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 3m38.648125618s
Aug  1 16:15:03.240: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Pending", Reason="", readiness=false. Elapsed: 3m40.720602783s
Aug  1 16:15:05.667: INFO: Pod "pod-subpath-test-dynamicpv-kjm7": Phase="Succeeded", Reason="", readiness=false. Elapsed: 3m43.147360997s
STEP: Saw pod success
Aug  1 16:15:05.667: INFO: Pod "pod-subpath-test-dynamicpv-kjm7" satisfied condition "Succeeded or Failed"
Aug  1 16:15:05.963: INFO: Trying to get logs from node csi-prow-worker2 pod pod-subpath-test-dynamicpv-kjm7 container test-container-subpath-dynamicpv-kjm7: <nil>
STEP: delete the pod
Aug  1 16:15:07.515: INFO: Waiting for pod pod-subpath-test-dynamicpv-kjm7 to disappear
Aug  1 16:15:08.651: INFO: Pod pod-subpath-test-dynamicpv-kjm7 no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-kjm7
Aug  1 16:15:08.651: INFO: Deleting pod "pod-subpath-test-dynamicpv-kjm7" in namespace "provisioning-8797"
... skipping 18 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:380
------------------------------
{"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":341,"failed":0}
Aug  1 16:15:27.359: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:27.359: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:27.359: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:27.359: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:27.359: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:27.359: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 72 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:320
------------------------------
{"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":11,"failed":0}
Aug  1 16:15:28.121: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:28.122: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:28.122: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:28.122: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:28.122: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:28.122: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 66 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [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/framework/testsuite.go:50
    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:246
------------------------------
{"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":115,"failed":0}
Aug  1 16:15:29.418: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:29.418: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:29.418: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:29.418: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:29.418: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:29.418: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 71 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] volumeMode
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:351
------------------------------
{"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":172,"failed":0}
Aug  1 16:15:31.169: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:31.169: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:31.169: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:31.169: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:31.169: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:31.169: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 37 lines ...
Aug  1 16:10:07.071: INFO: PersistentVolumeClaim hostpath.csi.k8s.iogzdwp found but phase is Pending instead of Bound.
Aug  1 16:10:09.831: INFO: PersistentVolumeClaim hostpath.csi.k8s.iogzdwp found but phase is Pending instead of Bound.
Aug  1 16:10:12.175: INFO: PersistentVolumeClaim hostpath.csi.k8s.iogzdwp found but phase is Pending instead of Bound.
Aug  1 16:10:14.975: INFO: PersistentVolumeClaim hostpath.csi.k8s.iogzdwp found but phase is Pending instead of Bound.
Aug  1 16:10:17.663: INFO: PersistentVolumeClaim hostpath.csi.k8s.iogzdwp found and phase=Bound (48.668014936s)
STEP: Creating pod1 with a volume on {Name:csi-prow-worker2 Selector:map[] Affinity:nil}
Aug  1 16:15:21.249: FAIL: Unexpected error:
    <*errors.errorString | 0xc00069cad0>: {
        s: "pod \"pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe\" is not Running: timed out waiting for the condition",
    }
    pod "pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe" is not Running: timed out waiting for the condition
occurred

... skipping 148 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should concurrently access the single volume from pods on the same node [It]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:295

    Aug  1 16:15:21.249: Unexpected error:
        <*errors.errorString | 0xc00069cad0>: {
            s: "pod \"pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe\" is not Running: timed out waiting for the condition",
        }
        pod "pod-a66ee1ee-03de-40c2-bc14-de11cd182dbe" is not Running: 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/multivolume.go:586
------------------------------
{"msg":"FAILED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node","total":-1,"completed":0,"skipped":161,"failed":1,"failures":["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"]}
Aug  1 16:15:44.869: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:44.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:44.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:44.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:44.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:44.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 69 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [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/framework/testsuite.go:50
    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:174
------------------------------
{"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":162,"failed":0}
Aug  1 16:15:46.869: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:46.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:46.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:46.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:46.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:46.869: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 45 lines ...
Aug  1 16:10:55.139: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxh4v2 found but phase is Pending instead of Bound.
Aug  1 16:10:57.567: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxh4v2 found but phase is Pending instead of Bound.
Aug  1 16:11:00.499: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxh4v2 found but phase is Pending instead of Bound.
Aug  1 16:11:03.397: INFO: PersistentVolumeClaim hostpath.csi.k8s.ioxh4v2 found and phase=Bound (1m7.144484018s)
STEP: Creating pod exec-volume-test-dynamicpv-4q8c
STEP: Creating a pod to test exec-volume-test
Aug  1 16:11:05.875: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-4q8c" in namespace "volume-6904" to be "Succeeded or Failed"
Aug  1 16:11:07.206: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 1.331279166s
Aug  1 16:11:10.488: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 4.613142782s
Aug  1 16:11:13.584: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 7.7094418s
Aug  1 16:11:17.380: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 11.505293849s
Aug  1 16:11:19.824: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 13.948936609s
Aug  1 16:11:22.487: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 16.61183829s
... skipping 76 lines ...
Aug  1 16:15:36.243: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 4m30.368127509s
Aug  1 16:15:38.267: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 4m32.392502503s
Aug  1 16:15:40.345: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 4m34.47055479s
Aug  1 16:15:42.466: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Pending", Reason="", readiness=false. Elapsed: 4m36.590978694s
Aug  1 16:15:44.531: INFO: Pod "exec-volume-test-dynamicpv-4q8c": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4m38.656563969s
STEP: Saw pod success
Aug  1 16:15:44.532: INFO: Pod "exec-volume-test-dynamicpv-4q8c" satisfied condition "Succeeded or Failed"
Aug  1 16:15:44.579: INFO: Trying to get logs from node csi-prow-worker2 pod exec-volume-test-dynamicpv-4q8c container exec-container-dynamicpv-4q8c: <nil>
STEP: delete the pod
Aug  1 16:15:44.860: INFO: Waiting for pod exec-volume-test-dynamicpv-4q8c to disappear
Aug  1 16:15:45.064: INFO: Pod exec-volume-test-dynamicpv-4q8c no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-4q8c
Aug  1 16:15:45.064: INFO: Deleting pod "exec-volume-test-dynamicpv-4q8c" in namespace "volume-6904"
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:196
------------------------------
{"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":112,"failed":0}
Aug  1 16:15:50.701: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:50.701: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:50.701: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:50.701: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:50.701: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:50.701: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 185 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:135
------------------------------
{"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":2,"skipped":187,"failed":0}
Aug  1 16:15:53.296: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node","total":-1,"completed":1,"skipped":97,"failed":0}
Aug  1 16:15:53.296: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:53.296: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 27 lines ...
Aug  1 16:11:16.023: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion4pwn found but phase is Pending instead of Bound.
Aug  1 16:11:18.708: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion4pwn found but phase is Pending instead of Bound.
Aug  1 16:11:21.321: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion4pwn found but phase is Pending instead of Bound.
Aug  1 16:11:23.503: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion4pwn found but phase is Pending instead of Bound.
Aug  1 16:11:25.928: INFO: PersistentVolumeClaim hostpath.csi.k8s.ion4pwn found and phase=Bound (19.968463667s)
STEP: Creating pod to format volume volume-prep-provisioning-6468
Aug  1 16:11:28.058: INFO: Waiting up to 5m0s for pod "volume-prep-provisioning-6468" in namespace "provisioning-6468" to be "Succeeded or Failed"
Aug  1 16:11:28.608: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 549.928944ms
Aug  1 16:11:30.885: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 2.826573755s
Aug  1 16:11:32.939: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 4.880291309s
Aug  1 16:11:35.147: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 7.088822165s
Aug  1 16:11:37.210: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 9.151976606s
Aug  1 16:11:39.819: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 11.760782083s
... skipping 51 lines ...
Aug  1 16:14:56.872: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 3m28.813224436s
Aug  1 16:14:58.922: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 3m30.863696115s
Aug  1 16:15:01.117: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 3m33.058186796s
Aug  1 16:15:03.315: INFO: Pod "volume-prep-provisioning-6468": Phase="Pending", Reason="", readiness=false. Elapsed: 3m35.256163397s
Aug  1 16:15:05.608: INFO: Pod "volume-prep-provisioning-6468": Phase="Succeeded", Reason="", readiness=false. Elapsed: 3m37.549335396s
STEP: Saw pod success
Aug  1 16:15:05.608: INFO: Pod "volume-prep-provisioning-6468" satisfied condition "Succeeded or Failed"
Aug  1 16:15:05.608: INFO: Deleting pod "volume-prep-provisioning-6468" in namespace "provisioning-6468"
Aug  1 16:15:06.357: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-6468" to be fully deleted
STEP: Creating pod pod-subpath-test-dynamicpv-slmw
STEP: Checking for subpath error in container status
Aug  1 16:15:49.285: INFO: Deleting pod "pod-subpath-test-dynamicpv-slmw" in namespace "provisioning-6468"
Aug  1 16:15:49.331: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-slmw" to be fully deleted
STEP: Deleting pod
Aug  1 16:15:49.362: INFO: Deleting pod "pod-subpath-test-dynamicpv-slmw" in namespace "provisioning-6468"
STEP: Deleting pvc
Aug  1 16:15:49.395: INFO: Deleting PersistentVolumeClaim "hostpath.csi.k8s.ion4pwn"
... skipping 12 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:423
------------------------------
{"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":2,"skipped":197,"failed":0}
Aug  1 16:15:54.724: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:54.724: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:54.724: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:54.724: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:54.724: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:54.724: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 152 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should access to two volumes with the same volume mode and retain data across pod recreation on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:135
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with the same volume mode and retain data across pod recreation on the same node","total":-1,"completed":1,"skipped":83,"failed":0}
Aug  1 16:15:59.696: INFO: Running AfterSuite actions on all nodes
Aug  1 16:15:59.696: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:15:59.696: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:15:59.696: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:15:59.696: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:15:59.696: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 9 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Aug  1 16:12:51.414: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename ephemeral
Aug  1 16:13:03.345: INFO: Unexpected error while creating namespace: Internal error occurred: resource quota evaluation timed out
STEP: Waiting for a default service account to be provisioned in namespace
[It] 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:221
Aug  1 16:15:06.247: INFO: Creating resource for CSI ephemeral inline volume
STEP: checking the requested inline volume exists in the pod running on node {Name:csi-prow-worker2 Selector:map[] Affinity:nil}
Aug  1 16:15:29.856: INFO: Pod inline-volume-tester-5ggtt has the following logs: /dev/root on /mnt/test-0 type ext4 (rw,relatime)
... skipping 12 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: CSI Ephemeral-volume (default fs)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:221
------------------------------
{"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":2,"skipped":172,"failed":0}
Aug  1 16:16:04.278: INFO: Running AfterSuite actions on all nodes
Aug  1 16:16:04.278: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:16:04.278: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:16:04.278: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:16:04.278: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:16:04.278: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 95 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:432
------------------------------
{"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":148,"failed":0}
Aug  1 16:16:04.523: INFO: Running AfterSuite actions on all nodes
Aug  1 16:16:04.523: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:16:04.525: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:16:04.525: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:16:04.525: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:16:04.525: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 211 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:432
------------------------------
{"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":184,"failed":0}
Aug  1 16:16:12.948: INFO: Running AfterSuite actions on all nodes
Aug  1 16:16:12.948: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:16:12.948: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:16:12.948: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:16:12.948: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:16:12.948: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 101 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should concurrently access the single volume from pods on the same node
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:295
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] should concurrently access the single volume from pods on the same node","total":-1,"completed":2,"skipped":410,"failed":0}
Aug  1 16:16:18.555: INFO: Running AfterSuite actions on all nodes
Aug  1 16:16:18.555: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:16:18.555: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:16:18.555: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:16:18.555: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:16:18.555: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 54 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:183
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should support two pods which share the same volume","total":-1,"completed":1,"skipped":134,"failed":0}
Aug  1 16:17:29.184: INFO: Running AfterSuite actions on all nodes
Aug  1 16:17:29.184: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:17:29.184: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:17:29.184: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:17:29.184: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:17:29.184: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 54 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:183
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should support two pods which share the same volume","total":-1,"completed":1,"skipped":142,"failed":0}
Aug  1 16:17:30.023: INFO: Running AfterSuite actions on all nodes
Aug  1 16:17:30.023: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:17:30.023: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:17:30.023: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:17:30.023: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:17:30.023: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 66 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:331
------------------------------
{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (default fs)] subPath should support restarting containers using file as subpath [Slow][LinuxOnly]","total":-1,"completed":2,"skipped":245,"failed":0}
Aug  1 16:17:34.496: INFO: Running AfterSuite actions on all nodes
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:17:34.496: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3


{"msg":"PASSED External Storage [Driver: hostpath.csi.k8s.io] [Testpattern: Dynamic PV (block volmode)] multiVolume [Slow] should access to two volumes with different volume mode and retain data across pod recreation on the same node","total":-1,"completed":2,"skipped":52,"failed":0}
Aug  1 16:16:09.814: INFO: Running AfterSuite actions on all nodes
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:16:09.814: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3
Aug  1 16:17:34.569: INFO: Running AfterSuite actions on node 1
Aug  1 16:17:34.569: INFO: Dumping logs locally to: /logs/artifacts
Aug  1 16:17:34.571: 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 (block volmode)] multiVolume [Slow] [It] should concurrently access the single volume from pods on the same node 
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:586

Ran 48 of 6664 Specs in 513.721 seconds
FAIL! -- 47 Passed | 1 Failed | 0 Pending | 6616 Skipped


Ginkgo ran 1 suite in 9m33.918869915s
Test Suite Failed
Mon Aug  1 16:17:34 UTC 2022 go1.18 /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
Mon Aug  1 16:17:38 UTC 2022 go1.19rc2 /home/prow/go/src/k8s.io/kubernetes$ env KUBECONFIG=/root/.kube/config KUBE_TEST_REPO_LIST=/home/prow/go/pkg/csiprow.csbG0GCDfI/e2e-repo-list ginkgo -v -p -nodes 40 -focus=External.Storage.*(\[Feature:VolumeSnapshotDataSource\]) -skip=\[Serial\]|\[Disruptive\] /home/prow/go/pkg/csiprow.csbG0GCDfI/e2e.test -- -report-dir /logs/artifacts -storage.testdriver=/home/prow/go/pkg/csiprow.csbG0GCDfI/test-driver.yaml
Running Suite: Kubernetes e2e suite
===================================
Random Seed: 1659370658 - Will randomize all specs
Will run 6664 specs

Running in parallel across 40 nodes

Aug  1 16:19:00.082: INFO: >>> kubeConfig: /root/.kube/config
Aug  1 16:19:00.096: INFO: Waiting up to 30m0s for all (but 0) nodes to be schedulable
Aug  1 16:19:27.362: INFO: Waiting up to 10m0s for all pods (need at least 0) in namespace 'kube-system' to be running and ready
Aug  1 16:19:29.579: INFO: The status of Pod kube-controller-manager-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:29.580: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (2 seconds elapsed)
Aug  1 16:19:29.580: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:29.580: INFO: POD                                             NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:29.580: INFO: kube-controller-manager-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:43 +0000 UTC ContainersNotReady containers with unready status: [kube-controller-manager]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:43 +0000 UTC ContainersNotReady containers with unready status: [kube-controller-manager]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:29.580: INFO: 
Aug  1 16:19:33.025: INFO: The status of Pod kube-scheduler-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:33.025: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (5 seconds elapsed)
Aug  1 16:19:33.025: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:33.025: INFO: POD                                    NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:33.025: INFO: kube-scheduler-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:33.025: INFO: 
Aug  1 16:19:35.054: INFO: The status of Pod kube-scheduler-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:35.054: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (7 seconds elapsed)
Aug  1 16:19:35.054: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:35.054: INFO: POD                                    NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:35.054: INFO: kube-scheduler-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:35.054: INFO: 
Aug  1 16:19:37.004: INFO: The status of Pod kube-scheduler-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:37.004: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (9 seconds elapsed)
Aug  1 16:19:37.004: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:37.004: INFO: POD                                    NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:37.004: INFO: kube-scheduler-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:37.004: INFO: 
Aug  1 16:19:39.678: INFO: The status of Pod kube-scheduler-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:39.678: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (12 seconds elapsed)
Aug  1 16:19:39.678: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:39.678: INFO: POD                                    NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:39.678: INFO: kube-scheduler-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:39.679: INFO: 
Aug  1 16:19:42.020: INFO: The status of Pod kube-scheduler-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:42.020: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (14 seconds elapsed)
Aug  1 16:19:42.020: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:42.021: INFO: POD                                    NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:42.021: INFO: kube-scheduler-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:42.021: INFO: 
Aug  1 16:19:43.787: INFO: The status of Pod kube-scheduler-csi-prow-control-plane is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
Aug  1 16:19:43.788: INFO: 13 / 14 pods in namespace 'kube-system' are running and ready (16 seconds elapsed)
Aug  1 16:19:43.788: INFO: expected 4 pod replicas in namespace 'kube-system', 4 are Running and Ready.
Aug  1 16:19:43.788: INFO: POD                                    NODE                    PHASE    GRACE  CONDITIONS
Aug  1 16:19:43.788: INFO: kube-scheduler-csi-prow-control-plane  csi-prow-control-plane  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2022-08-01 16:18:41 +0000 UTC ContainersNotReady containers with unready status: [kube-scheduler]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2022-08-01 15:53:37 +0000 UTC  }]
Aug  1 16:19:43.788: INFO: 
Aug  1 16:19:45.848: INFO: 14 / 14 pods in namespace 'kube-system' are running and ready (18 seconds elapsed)
... skipping 960 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (default fs)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:201
------------------------------
{"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":10,"failed":0}
Aug  1 16:21:56.222: INFO: Running AfterSuite actions on all nodes
Aug  1 16:21:56.222: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:21:56.222: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:21:56.222: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:21:56.222: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:21:56.222: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 106 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/external/external.go:174
  [Testpattern: Dynamic PV (block volmode)] provisioning
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    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:201
------------------------------
{"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":171,"failed":0}
Aug  1 16:21:56.300: INFO: Running AfterSuite actions on all nodes
Aug  1 16:21:56.300: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:21:56.300: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:21:56.300: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:21:56.300: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:21:56.300: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 33 lines ...
Aug  1 16:20:44.596: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokjqwb found but phase is Pending instead of Bound.
Aug  1 16:20:48.368: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokjqwb found but phase is Pending instead of Bound.
Aug  1 16:20:50.840: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokjqwb found but phase is Pending instead of Bound.
Aug  1 16:20:54.052: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokjqwb found but phase is Pending instead of Bound.
Aug  1 16:20:57.655: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokjqwb found and phase=Bound (41.620861721s)
STEP: [init] starting a pod to use the claim
Aug  1 16:20:58.629: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-5jr45" in namespace "snapshotting-4165" to be "Succeeded or Failed"
Aug  1 16:20:58.788: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 158.990414ms
Aug  1 16:21:01.652: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 3.023252589s
Aug  1 16:21:04.127: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 5.498437977s
Aug  1 16:21:06.400: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 7.770580077s
Aug  1 16:21:08.563: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 9.934456928s
Aug  1 16:21:11.144: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 12.514948742s
... skipping 2 lines ...
Aug  1 16:21:18.299: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 19.670477604s
Aug  1 16:21:20.491: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 21.862207027s
Aug  1 16:21:22.566: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 23.937358121s
Aug  1 16:21:24.658: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Pending", Reason="", readiness=false. Elapsed: 26.02952688s
Aug  1 16:21:27.009: INFO: Pod "pvc-snapshottable-tester-5jr45": Phase="Succeeded", Reason="", readiness=false. Elapsed: 28.379811805s
STEP: Saw pod success
Aug  1 16:21:27.009: INFO: Pod "pvc-snapshottable-tester-5jr45" satisfied condition "Succeeded or Failed"
Aug  1 16:21:28.044: INFO: Pod pvc-snapshottable-tester-5jr45 has the following logs: 
Aug  1 16:21:28.044: INFO: Deleting pod "pvc-snapshottable-tester-5jr45" in namespace "snapshotting-4165"
Aug  1 16:21:28.448: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-5jr45" to be fully deleted
Aug  1 16:21:28.625: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokjqwb] to have phase Bound
Aug  1 16:21:28.690: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokjqwb found and phase=Bound (64.553162ms)
STEP: [init] checking the claim
... skipping 20 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:248
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Aug  1 16:21:44.454: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-9vmgq" in namespace "snapshotting-4165" to be "Succeeded or Failed"
Aug  1 16:21:44.458: INFO: Pod "pvc-snapshottable-data-tester-9vmgq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.750098ms
Aug  1 16:21:46.463: INFO: Pod "pvc-snapshottable-data-tester-9vmgq": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009654121s
Aug  1 16:21:48.471: INFO: Pod "pvc-snapshottable-data-tester-9vmgq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.017645192s
Aug  1 16:21:50.477: INFO: Pod "pvc-snapshottable-data-tester-9vmgq": Phase="Pending", Reason="", readiness=false. Elapsed: 6.022886963s
Aug  1 16:21:52.502: INFO: Pod "pvc-snapshottable-data-tester-9vmgq": Phase="Pending", Reason="", readiness=false. Elapsed: 8.048342533s
Aug  1 16:21:54.510: INFO: Pod "pvc-snapshottable-data-tester-9vmgq": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.056416793s
STEP: Saw pod success
Aug  1 16:21:54.510: INFO: Pod "pvc-snapshottable-data-tester-9vmgq" satisfied condition "Succeeded or Failed"
Aug  1 16:21:54.623: INFO: Pod pvc-snapshottable-data-tester-9vmgq has the following logs: 
Aug  1 16:21:54.623: INFO: Deleting pod "pvc-snapshottable-data-tester-9vmgq" in namespace "snapshotting-4165"
Aug  1 16:21:54.679: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-9vmgq" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Aug  1 16:22:02.849: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:34765 --kubeconfig=/root/.kube/config --namespace=snapshotting-4165 exec restored-pvc-tester-2r2cd --namespace=snapshotting-4165 -- cat /mnt/test/data'
... skipping 42 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:108
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:230
        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:248
------------------------------
{"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":83,"failed":0}
Aug  1 16:22:49.139: INFO: Running AfterSuite actions on all nodes
Aug  1 16:22:49.139: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:22:49.139: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:22:49.139: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:22:49.139: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:22:49.139: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 30 lines ...
Aug  1 16:20:29.305: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokq2dx found but phase is Pending instead of Bound.
Aug  1 16:20:36.079: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokq2dx found but phase is Pending instead of Bound.
Aug  1 16:20:40.851: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokq2dx found but phase is Pending instead of Bound.
Aug  1 16:20:44.321: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokq2dx found but phase is Pending instead of Bound.
Aug  1 16:20:46.943: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokq2dx found and phase=Bound (30.775844205s)
STEP: [init] starting a pod to use the claim
Aug  1 16:20:50.903: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-mzgjq" in namespace "snapshotting-2381" to be "Succeeded or Failed"
Aug  1 16:20:52.996: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 2.092790747s
Aug  1 16:20:55.859: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.955862667s
Aug  1 16:20:58.389: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 7.485939454s
Aug  1 16:21:00.826: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 9.922372962s
Aug  1 16:21:03.443: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 12.539833932s
Aug  1 16:21:05.910: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 15.006546472s
... skipping 4 lines ...
Aug  1 16:21:18.295: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 27.392041447s
Aug  1 16:21:20.490: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 29.586589354s
Aug  1 16:21:22.652: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Pending", Reason="", readiness=false. Elapsed: 31.748531201s
Aug  1 16:21:24.738: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Running", Reason="", readiness=true. Elapsed: 33.834424202s
Aug  1 16:21:27.009: INFO: Pod "pvc-snapshottable-tester-mzgjq": Phase="Succeeded", Reason="", readiness=false. Elapsed: 36.106020035s
STEP: Saw pod success
Aug  1 16:21:27.009: INFO: Pod "pvc-snapshottable-tester-mzgjq" satisfied condition "Succeeded or Failed"
Aug  1 16:21:27.948: INFO: Pod pvc-snapshottable-tester-mzgjq has the following logs: 
Aug  1 16:21:27.948: INFO: Deleting pod "pvc-snapshottable-tester-mzgjq" in namespace "snapshotting-2381"
Aug  1 16:21:28.407: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-mzgjq" to be fully deleted
Aug  1 16:21:28.602: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iokq2dx] to have phase Bound
Aug  1 16:21:28.647: INFO: PersistentVolumeClaim hostpath.csi.k8s.iokq2dx found and phase=Bound (45.134505ms)
STEP: [init] checking the claim
... skipping 39 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:248
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Aug  1 16:21:46.666: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-qcctj" in namespace "snapshotting-2381" to be "Succeeded or Failed"
Aug  1 16:21:46.677: INFO: Pod "pvc-snapshottable-data-tester-qcctj": Phase="Pending", Reason="", readiness=false. Elapsed: 10.946626ms
Aug  1 16:21:48.682: INFO: Pod "pvc-snapshottable-data-tester-qcctj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.016647172s
Aug  1 16:21:50.691: INFO: Pod "pvc-snapshottable-data-tester-qcctj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.025357751s
Aug  1 16:21:52.698: INFO: Pod "pvc-snapshottable-data-tester-qcctj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 6.032058026s
STEP: Saw pod success
Aug  1 16:21:52.698: INFO: Pod "pvc-snapshottable-data-tester-qcctj" satisfied condition "Succeeded or Failed"
Aug  1 16:21:52.730: INFO: Pod pvc-snapshottable-data-tester-qcctj has the following logs: 
Aug  1 16:21:52.730: INFO: Deleting pod "pvc-snapshottable-data-tester-qcctj" in namespace "snapshotting-2381"
Aug  1 16:21:52.770: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-qcctj" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Aug  1 16:22:06.883: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:34765 --kubeconfig=/root/.kube/config --namespace=snapshotting-2381 exec restored-pvc-tester-b68dz --namespace=snapshotting-2381 -- cat /mnt/test/data'
... skipping 42 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:108
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:230
        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:248
------------------------------
{"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":77,"failed":0}
Aug  1 16:22:49.764: INFO: Running AfterSuite actions on all nodes
Aug  1 16:22:49.764: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:22:49.764: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:22:49.764: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:22:49.764: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:22:49.764: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 24 lines ...
Aug  1 16:20:14.673: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
Aug  1 16:20:15.758: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iofvxdj] to have phase Bound
Aug  1 16:20:15.776: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofvxdj found but phase is Pending instead of Bound.
Aug  1 16:20:19.740: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofvxdj found but phase is Pending instead of Bound.
Aug  1 16:20:22.179: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofvxdj found and phase=Bound (6.421586123s)
STEP: [init] starting a pod to use the claim
Aug  1 16:20:28.297: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-bh7bj" in namespace "snapshotting-7039" to be "Succeeded or Failed"
Aug  1 16:20:29.708: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 1.410076067s
Aug  1 16:20:36.119: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 7.821382547s
Aug  1 16:20:40.583: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 12.285708119s
Aug  1 16:20:44.585: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 16.287779612s
Aug  1 16:20:48.280: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 19.98233223s
Aug  1 16:20:51.742: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 23.444104453s
Aug  1 16:20:55.122: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 26.824159299s
Aug  1 16:20:57.724: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 29.426360976s
Aug  1 16:20:59.936: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Pending", Reason="", readiness=false. Elapsed: 31.638290856s
Aug  1 16:21:02.117: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Running", Reason="", readiness=true. Elapsed: 33.819039478s
Aug  1 16:21:04.312: INFO: Pod "pvc-snapshottable-tester-bh7bj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 36.014430511s
STEP: Saw pod success
Aug  1 16:21:04.312: INFO: Pod "pvc-snapshottable-tester-bh7bj" satisfied condition "Succeeded or Failed"
Aug  1 16:21:06.407: INFO: Pod pvc-snapshottable-tester-bh7bj has the following logs: 
Aug  1 16:21:06.407: INFO: Deleting pod "pvc-snapshottable-tester-bh7bj" in namespace "snapshotting-7039"
Aug  1 16:21:08.004: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-bh7bj" to be fully deleted
Aug  1 16:21:08.563: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iofvxdj] to have phase Bound
Aug  1 16:21:09.696: INFO: PersistentVolumeClaim hostpath.csi.k8s.iofvxdj found and phase=Bound (1.132018758s)
STEP: [init] checking the claim
... skipping 16 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:248
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Aug  1 16:21:20.823: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-ts2xc" in namespace "snapshotting-7039" to be "Succeeded or Failed"
Aug  1 16:21:20.834: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Pending", Reason="", readiness=false. Elapsed: 11.277322ms
Aug  1 16:21:22.881: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Pending", Reason="", readiness=false. Elapsed: 2.0582527s
Aug  1 16:21:25.029: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Pending", Reason="", readiness=false. Elapsed: 4.206102999s
Aug  1 16:21:27.368: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Pending", Reason="", readiness=false. Elapsed: 6.54540245s
Aug  1 16:21:29.418: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Pending", Reason="", readiness=false. Elapsed: 8.594661641s
Aug  1 16:21:31.573: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Pending", Reason="", readiness=false. Elapsed: 10.749965177s
Aug  1 16:21:34.379: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Running", Reason="", readiness=true. Elapsed: 13.556388s
Aug  1 16:21:36.460: INFO: Pod "pvc-snapshottable-data-tester-ts2xc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 15.636914652s
STEP: Saw pod success
Aug  1 16:21:36.460: INFO: Pod "pvc-snapshottable-data-tester-ts2xc" satisfied condition "Succeeded or Failed"
Aug  1 16:21:36.860: INFO: Pod pvc-snapshottable-data-tester-ts2xc has the following logs: 
Aug  1 16:21:36.860: INFO: Deleting pod "pvc-snapshottable-data-tester-ts2xc" in namespace "snapshotting-7039"
Aug  1 16:21:37.100: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-ts2xc" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Aug  1 16:21:59.813: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:34765 --kubeconfig=/root/.kube/config --namespace=snapshotting-7039 exec restored-pvc-tester-86l4d --namespace=snapshotting-7039 -- cat /mnt/test/data'
... skipping 33 lines ...
Aug  1 16:22:24.880: INFO: volumesnapshotcontents snapcontent-35928069-35a5-46cc-a2dc-ee8eadc020d1 has been found and is not deleted
Aug  1 16:22:25.890: INFO: volumesnapshotcontents snapcontent-35928069-35a5-46cc-a2dc-ee8eadc020d1 has been found and is not deleted
Aug  1 16:22:26.901: INFO: volumesnapshotcontents snapcontent-35928069-35a5-46cc-a2dc-ee8eadc020d1 has been found and is not deleted
Aug  1 16:22:27.944: INFO: volumesnapshotcontents snapcontent-35928069-35a5-46cc-a2dc-ee8eadc020d1 has been found and is not deleted
Aug  1 16:22:28.960: INFO: volumesnapshotcontents snapcontent-35928069-35a5-46cc-a2dc-ee8eadc020d1 has been found and is not deleted
Aug  1 16:22:29.976: INFO: volumesnapshotcontents snapcontent-35928069-35a5-46cc-a2dc-ee8eadc020d1 has been found and is not deleted
Aug  1 16:22:30.979: 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:226
Aug  1 16:22:31.398: INFO: Pod restored-pvc-tester-86l4d has the following logs: 
Aug  1 16:22:31.398: INFO: Deleting pod "restored-pvc-tester-86l4d" in namespace "snapshotting-7039"
Aug  1 16:22:31.569: INFO: Wait up to 5m0s for pod "restored-pvc-tester-86l4d" to be fully deleted
Aug  1 16:23:05.811: INFO: deleting claim "snapshotting-7039"/"pvc-7q4bw"
... skipping 28 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:108
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:230
        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:248
------------------------------
{"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":92,"failed":0}
Aug  1 16:23:13.596: INFO: Running AfterSuite actions on all nodes
Aug  1 16:23:13.596: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:23:13.596: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:23:13.596: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:23:13.596: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:23:13.596: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 30 lines ...
Aug  1 16:20:29.536: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocbckm found but phase is Pending instead of Bound.
Aug  1 16:20:36.266: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocbckm found but phase is Pending instead of Bound.
Aug  1 16:20:40.457: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocbckm found but phase is Pending instead of Bound.
Aug  1 16:20:44.665: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocbckm found but phase is Pending instead of Bound.
Aug  1 16:20:48.330: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocbckm found and phase=Bound (32.379501367s)
STEP: [init] starting a pod to use the claim
Aug  1 16:20:50.058: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-nt4tg" in namespace "snapshotting-6388" to be "Succeeded or Failed"
Aug  1 16:20:50.909: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 851.066674ms
Aug  1 16:20:54.439: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.380784762s
Aug  1 16:20:57.595: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 7.536813091s
Aug  1 16:20:59.935: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 9.877017303s
Aug  1 16:21:02.136: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 12.07800387s
Aug  1 16:21:04.312: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 14.25319205s
... skipping 2 lines ...
Aug  1 16:21:12.545: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 22.486718433s
Aug  1 16:21:16.105: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 26.046908501s
Aug  1 16:21:18.276: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Pending", Reason="", readiness=false. Elapsed: 28.217174545s
Aug  1 16:21:20.490: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Running", Reason="", readiness=true. Elapsed: 30.432037766s
Aug  1 16:21:22.567: INFO: Pod "pvc-snapshottable-tester-nt4tg": Phase="Succeeded", Reason="", readiness=false. Elapsed: 32.508831976s
STEP: Saw pod success
Aug  1 16:21:22.567: INFO: Pod "pvc-snapshottable-tester-nt4tg" satisfied condition "Succeeded or Failed"
Aug  1 16:21:22.891: INFO: Pod pvc-snapshottable-tester-nt4tg has the following logs: 
Aug  1 16:21:22.891: INFO: Deleting pod "pvc-snapshottable-tester-nt4tg" in namespace "snapshotting-6388"
Aug  1 16:21:22.951: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-nt4tg" to be fully deleted
Aug  1 16:21:22.979: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [hostpath.csi.k8s.iocbckm] to have phase Bound
Aug  1 16:21:22.984: INFO: PersistentVolumeClaim hostpath.csi.k8s.iocbckm found and phase=Bound (4.109818ms)
STEP: [init] checking the claim
... skipping 37 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:248
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Aug  1 16:21:39.671: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-6snh2" in namespace "snapshotting-6388" to be "Succeeded or Failed"
Aug  1 16:21:39.682: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.556964ms
Aug  1 16:21:41.691: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.019249491s
Aug  1 16:21:43.700: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.028260708s
Aug  1 16:21:45.704: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.032529359s
Aug  1 16:21:47.710: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.038017448s
Aug  1 16:21:49.715: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.043412487s
Aug  1 16:21:51.729: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 12.057477286s
Aug  1 16:21:53.790: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 14.118094762s
Aug  1 16:21:55.972: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 16.299948259s
Aug  1 16:21:57.975: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Pending", Reason="", readiness=false. Elapsed: 18.303717218s
Aug  1 16:21:59.995: INFO: Pod "pvc-snapshottable-data-tester-6snh2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.323287817s
STEP: Saw pod success
Aug  1 16:21:59.995: INFO: Pod "pvc-snapshottable-data-tester-6snh2" satisfied condition "Succeeded or Failed"
Aug  1 16:22:00.156: INFO: Pod pvc-snapshottable-data-tester-6snh2 has the following logs: 
Aug  1 16:22:00.156: INFO: Deleting pod "pvc-snapshottable-data-tester-6snh2" in namespace "snapshotting-6388"
Aug  1 16:22:00.244: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-6snh2" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Aug  1 16:22:14.410: INFO: Running '/usr/local/bin/kubectl --server=https://127.0.0.1:34765 --kubeconfig=/root/.kube/config --namespace=snapshotting-6388 exec restored-pvc-tester-mrg5t --namespace=snapshotting-6388 -- cat /mnt/test/data'
... skipping 28 lines ...
Aug  1 16:22:37.270: INFO: volumesnapshotcontents pre-provisioned-snapcontent-63901ba1-ce5b-4152-b766-c315fec864ac has been found and is not deleted
Aug  1 16:22:38.580: INFO: volumesnapshotcontents pre-provisioned-snapcontent-63901ba1-ce5b-4152-b766-c315fec864ac has been found and is not deleted
Aug  1 16:22:40.968: INFO: volumesnapshotcontents pre-provisioned-snapcontent-63901ba1-ce5b-4152-b766-c315fec864ac has been found and is not deleted
Aug  1 16:22:42.214: INFO: volumesnapshotcontents pre-provisioned-snapcontent-63901ba1-ce5b-4152-b766-c315fec864ac has been found and is not deleted
Aug  1 16:22:43.410: INFO: volumesnapshotcontents pre-provisioned-snapcontent-63901ba1-ce5b-4152-b766-c315fec864ac has been found and is not deleted
Aug  1 16:22:44.487: INFO: volumesnapshotcontents pre-provisioned-snapcontent-63901ba1-ce5b-4152-b766-c315fec864ac has been found and is not deleted
Aug  1 16:22:45.495: 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:226
Aug  1 16:22:45.813: INFO: Pod restored-pvc-tester-mrg5t has the following logs: 
Aug  1 16:22:45.813: INFO: Deleting pod "restored-pvc-tester-mrg5t" in namespace "snapshotting-6388"
Aug  1 16:22:45.861: INFO: Wait up to 5m0s for pod "restored-pvc-tester-mrg5t" to be fully deleted
Aug  1 16:23:19.900: INFO: deleting claim "snapshotting-6388"/"pvc-qjj42"
... skipping 28 lines ...
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:108
      
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:230
        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:248
------------------------------
{"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":209,"failed":0}
Aug  1 16:23:27.907: INFO: Running AfterSuite actions on all nodes
Aug  1 16:23:27.907: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func17.2
Aug  1 16:23:27.907: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func8.2
Aug  1 16:23:27.907: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage.glob..func7.2
Aug  1 16:23:27.907: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:23:27.907: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
... skipping 8 lines ...
Aug  1 16:19:53.176: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:19:53.176: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:19:53.176: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:19:53.176: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3
Aug  1 16:23:27.949: INFO: Running AfterSuite actions on node 1
Aug  1 16:23:27.949: INFO: Dumping logs locally to: /logs/artifacts
Aug  1 16:23:27.950: 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 6664 Specs in 277.888 seconds
SUCCESS! -- 6 Passed | 0 Failed | 0 Pending | 6658 Skipped


Ginkgo ran 1 suite in 5m49.173433715s
Test Suite Passed
Mon Aug  1 16:23:28 UTC 2022 go1.18 /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
Mon Aug  1 16:23:29 UTC 2022 go1.19rc2 /home/prow/go/src/k8s.io/kubernetes$ env KUBECONFIG=/root/.kube/config KUBE_TEST_REPO_LIST=/home/prow/go/pkg/csiprow.csbG0GCDfI/e2e-repo-list ginkgo -v -focus=External.Storage.*(\[Serial\]|\[Disruptive\]) -skip=\[Feature:|Disruptive /home/prow/go/pkg/csiprow.csbG0GCDfI/e2e.test -- -report-dir /logs/artifacts -storage.testdriver=/home/prow/go/pkg/csiprow.csbG0GCDfI/test-driver.yaml
Aug  1 16:23:30.613: INFO: Driver loaded from path [/home/prow/go/pkg/csiprow.csbG0GCDfI/test-driver.yaml]: &{DriverInfo:{Name:hostpath.csi.k8s.io InTreePluginName: FeatureTag: MaxFileSize:0 SupportedSizeRange:{Max: Min:1Mi} SupportedFsType:map[:{}] SupportedMountOption:map[] RequiredMountOption:map[] Capabilities:map[block:true controllerExpansion:true exec:true multipods:true nodeExpansion:true onlineExpansion:true persistence:true singleNodeVolume:true snapshotDataSource:true topology:true] RequiredAccessModes:[] TopologyKeys:[] NumAllowedTopologies:0 StressTestOptions:<nil> VolumeSnapshotStressTestOptions:<nil> PerformanceTestOptions:<nil>} StorageClass:{FromName:true FromFile: FromExistingClassName:} SnapshotClass:{FromName:true FromFile: FromExistingClassName:} InlineVolumes:[{Attributes:map[] Shared:false ReadOnly:false}] ClientNodeName:csi-prow-worker2 Timeouts:map[]}
Aug  1 16:23:30.614: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used.
I0801 16:23:30.614347   88704 e2e.go:129] Starting e2e run "e0491f7b-d709-4778-8feb-478c4637a374" on Ginkgo node 1
{"msg":"Test Suite starting","total":4,"completed":0,"skipped":0,"failed":0}
Running Suite: Kubernetes e2e suite
===================================
Random Seed: 1659371010 - Will randomize all specs
Will run 4 of 6664 specs

Aug  1 16:23:35.117: INFO: >>> kubeConfig: /root/.kube/config
... skipping 120 lines ...
Aug  1 16:23:37.025: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func17.3
Aug  1 16:23:37.025: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func9.2
Aug  1 16:23:37.025: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func4.2
Aug  1 16:23:37.025: INFO: Running Cleanup Action: k8s.io/kubernetes/test/e2e/storage/vsphere.glob..func1.3
Aug  1 16:23:37.025: INFO: Running AfterSuite actions on node 1
Aug  1 16:23:37.025: INFO: Dumping logs locally to: /logs/artifacts
Aug  1 16:23:37.032: 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":4,"completed":0,"skipped":6664,"failed":0}

Ran 0 of 6664 Specs in 1.921 seconds
SUCCESS! -- 0 Passed | 0 Failed | 0 Pending | 6664 Skipped
PASS

Ginkgo ran 1 suite in 6.809593384s
Test Suite Passed
Mon Aug  1 16:23:37 UTC 2022 go1.18 /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
Mon Aug  1 16:23:38 UTC 2022 go1.19rc2 $ kind export logs --name=csi-prow /logs/artifacts/cluster-logs/non-alpha
... skipping 14 lines ...