This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 178 succeeded
Started2020-01-09 05:55
Elapsed4h55m
Revision
Buildergke-prow-default-pool-cf4891d4-q0h1
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/db622cce-51a7-4004-a6f6-d00bdc66be36/targets/test'}}
pod7726b40d-32a4-11ea-9709-02f27a93e62e
resultstorehttps://source.cloud.google.com/results/invocations/db622cce-51a7-4004-a6f6-d00bdc66be36/targets/test
infra-commit4a88c259d
job-versionv1.16.5-beta.1.43+926ccb6ccb4e65
master_os_imagecos-73-11647-163-0
node_os_imagecos-73-11647-163-0
pod7726b40d-32a4-11ea-9709-02f27a93e62e
revisionv1.16.5-beta.1.43+926ccb6ccb4e65

Test Failures


UpgradeTest 5.17s

error during kubetest --test --test_args=--ginkgo.focus=\[Feature:MasterUpgrade\] --upgrade-target=ci/k8s-beta --report-dir=/workspace/_artifacts --disable-log-dump=true --report-prefix=upgrade --check-version-skew=false: exit status 1
				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 178 Passed Tests

Show 4572 Skipped Tests

Error lines from build-log.txt

... skipping 15 lines ...
I0109 05:55:03.941] process 47 exited with code 0 after 0.0m
I0109 05:55:03.942] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0109 05:55:03.942] Root: /workspace
I0109 05:55:03.942] cd to /workspace
I0109 05:55:03.942] Configure environment...
I0109 05:55:03.943] Call:  git show -s --format=format:%ct HEAD
W0109 05:55:03.946] fatal: not a git repository (or any of the parent directories): .git
I0109 05:55:03.947] process 60 exited with code 128 after 0.0m
W0109 05:55:03.947] Unable to print commit date for HEAD
I0109 05:55:03.947] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0109 05:55:04.496] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0109 05:55:04.819] process 61 exited with code 0 after 0.0m
I0109 05:55:04.820] Call:  gcloud config get-value account
... skipping 379 lines ...
W0109 05:59:21.657] Trying to find master named 'bootstrap-e2e-master'
W0109 05:59:21.658] Looking for address 'bootstrap-e2e-master-ip'
W0109 05:59:22.523] Using master: bootstrap-e2e-master (external IP: 34.83.255.142; internal IP: (not set))
I0109 05:59:22.624] Waiting up to 300 seconds for cluster initialization.
I0109 05:59:22.624] 
I0109 05:59:22.624]   This will continually check to see if the API for kubernetes is reachable.
I0109 05:59:22.624]   This may time out if there was some uncaught error during start up.
I0109 05:59:22.624] 
I0109 06:00:13.745] ............Kubernetes cluster created.
I0109 06:00:13.888] Cluster "k8s-jkns-gce-upgrade_bootstrap-e2e" set.
I0109 06:00:14.034] User "k8s-jkns-gce-upgrade_bootstrap-e2e" set.
I0109 06:00:14.174] Context "k8s-jkns-gce-upgrade_bootstrap-e2e" created.
I0109 06:00:14.315] Switched to context "k8s-jkns-gce-upgrade_bootstrap-e2e".
... skipping 146 lines ...
I0109 06:01:33.182]     	If true, adds the file directory to the header
I0109 06:01:33.182]   -allow-gathering-profiles
I0109 06:01:33.182]     	If set to true framework will allow to gather CPU/memory allocation pprof profiles from the master. (default true)
I0109 06:01:33.182]   -allowed-not-ready-nodes int
I0109 06:01:33.183]     	If non-zero, framework will allow for that many non-ready nodes when checking for all ready nodes.
I0109 06:01:33.183]   -alsologtostderr
I0109 06:01:33.183]     	log to standard error as well as files
I0109 06:01:33.183]   -application_metrics_count_limit int
I0109 06:01:33.183]     	Max number of application metrics to store (per container) (default 100)
I0109 06:01:33.183]   -boot_id_file string
I0109 06:01:33.184]     	Comma-separated list of files to check for boot-id. Use the first one that exists. (default "/proc/sys/kernel/random/boot_id")
I0109 06:01:33.184]   -cert-dir string
I0109 06:01:33.184]     	Path to the directory containing the certs. Default is empty, which doesn't use certs.
I0109 06:01:33.184]   -clean-start
I0109 06:01:33.184]     	If true, purge all namespaces except default and system before running tests. This serves to Cleanup test namespaces from failed/interrupted e2e runs in a long-lived cluster.
I0109 06:01:33.184]   -cloud-config-file string
I0109 06:01:33.184]     	Cloud config file.  Only required if provider is azure.
I0109 06:01:33.185]   -cloud-provider-gce-l7lb-src-cidrs value
I0109 06:01:33.185]     	CIDRs opened in GCE firewall for L7 LB traffic proxy & health checks (default 130.211.0.0/22,35.191.0.0/16)
I0109 06:01:33.185]   -cloud-provider-gce-lb-src-cidrs value
I0109 06:01:33.185]     	CIDRs opened in GCE firewall for L4 LB traffic proxy & health checks (default 130.211.0.0/22,209.85.152.0/22,209.85.204.0/22,35.191.0.0/16)
... skipping 97 lines ...
I0109 06:01:33.201]     	If set, ginkgo will emit node output to files when running in parallel.
I0109 06:01:33.201]   -ginkgo.dryRun
I0109 06:01:33.201]     	If set, ginkgo will walk the test hierarchy without actually running anything.  Best paired with -v.
I0109 06:01:33.201]   -ginkgo.failFast
I0109 06:01:33.202]     	If set, ginkgo will stop running a test suite after a failure occurs.
I0109 06:01:33.202]   -ginkgo.failOnPending
I0109 06:01:33.202]     	If set, ginkgo will mark the test suite as failed if any specs are pending.
I0109 06:01:33.202]   -ginkgo.flakeAttempts int
I0109 06:01:33.202]     	Make up to this many attempts to run each spec. Please note that if any of the attempts succeed, the suite will not be failed. But any failures will still be recorded. (default 1)
I0109 06:01:33.202]   -ginkgo.focus string
I0109 06:01:33.203]     	If set, ginkgo will only run specs that match this regular expression.
I0109 06:01:33.203]   -ginkgo.noColor
I0109 06:01:33.203]     	If set, suppress color output in default reporter.
I0109 06:01:33.203]   -ginkgo.noisyPendings
I0109 06:01:33.203]     	If set, default reporter will shout about pending tests. (default true)
... skipping 73 lines ...
I0109 06:01:33.217]     	If non-empty, use this log file
I0109 06:01:33.217]   -log_file_max_size uint
I0109 06:01:33.217]     	Defines the maximum size a log file can grow to. Unit is megabytes. If the value is 0, the maximum file size is unlimited. (default 1800)
I0109 06:01:33.217]   -logexporter-gcs-path string
I0109 06:01:33.218]     	Path to the GCS artifacts directory to dump logs from nodes. Logexporter gets enabled if this is non-empty.
I0109 06:01:33.218]   -logtostderr
I0109 06:01:33.218]     	log to standard error instead of files (default true)
I0109 06:01:33.218]   -machine_id_file string
I0109 06:01:33.218]     	Comma-separated list of files to check for machine-id. Use the first one that exists. (default "/etc/machine-id,/var/lib/dbus/machine-id")
I0109 06:01:33.218]   -master-os-distro string
I0109 06:01:33.219]     	The OS distribution of cluster master (debian, ubuntu, gci, coreos, or custom). (default "debian")
I0109 06:01:33.219]   -master-tag string
I0109 06:01:33.219]     	Network tags used on master instances. Valid only for gce, gke
... skipping 130 lines ...
I0109 06:01:33.240]   -vmodule value
I0109 06:01:33.241]     	comma-separated list of pattern=N settings for file-filtered logging
I0109 06:01:33.241]   -volume-dir string
I0109 06:01:33.241]     	Path to the directory containing the kubelet volumes. (default "/var/lib/kubelet")
I0109 06:01:33.241] 
I0109 06:01:33.241] Ginkgo ran 1 suite in 1.182577141s
I0109 06:01:33.241] Test Suite Failed
W0109 06:01:33.342] !!! Error in ./hack/ginkgo-e2e.sh:150
W0109 06:01:33.343]   Error in ./hack/ginkgo-e2e.sh:150. '"${ginkgo}" "${ginkgo_args[@]:+${ginkgo_args[@]}}" "${e2e_test}" -- "${auth_config[@]:+${auth_config[@]}}" --ginkgo.flakeAttempts="${FLAKE_ATTEMPTS}" --host="${KUBE_MASTER_URL}" --provider="${KUBERNETES_PROVIDER}" --gce-project="${PROJECT:-}" --gce-zone="${ZONE:-}" --gce-region="${REGION:-}" --gce-multizone="${MULTIZONE:-false}" --gke-cluster="${CLUSTER_NAME:-}" --kube-master="${KUBE_MASTER:-}" --cluster-tag="${CLUSTER_ID:-}" --cloud-config-file="${CLOUD_CONFIG:-}" --repo-root="${KUBE_ROOT}" --node-instance-group="${NODE_INSTANCE_GROUP:-}" --prefix="${KUBE_GCE_INSTANCE_PREFIX:-e2e}" --network="${KUBE_GCE_NETWORK:-${KUBE_GKE_NETWORK:-e2e}}" --node-tag="${NODE_TAG:-}" --master-tag="${MASTER_TAG:-}" --cluster-monitoring-mode="${KUBE_ENABLE_CLUSTER_MONITORING:-standalone}" --dns-domain="${KUBE_DNS_DOMAIN:-cluster.local}" --ginkgo.slowSpecThreshold="${GINKGO_SLOW_SPEC_THRESHOLD:-300}" ${KUBE_CONTAINER_RUNTIME:+"--container-runtime=${KUBE_CONTAINER_RUNTIME}"} ${MASTER_OS_DISTRIBUTION:+"--master-os-distro=${MASTER_OS_DISTRIBUTION}"} ${NODE_OS_DISTRIBUTION:+"--node-os-distro=${NODE_OS_DISTRIBUTION}"} ${NUM_NODES:+"--num-nodes=${NUM_NODES}"} ${E2E_REPORT_DIR:+"--report-dir=${E2E_REPORT_DIR}"} ${E2E_REPORT_PREFIX:+"--report-prefix=${E2E_REPORT_PREFIX}"} "${@:-}"' exited with status 1
W0109 06:01:33.343] Call stack:
W0109 06:01:33.343]   1: ./hack/ginkgo-e2e.sh:150 main(...)
W0109 06:01:33.343] Exiting with status 1
W0109 06:01:33.344] 2020/01/09 06:01:33 process.go:155: Step './hack/ginkgo-e2e.sh --ginkgo.focus=\[Feature:MasterUpgrade\] --upgrade-target=ci/k8s-beta --report-dir=/workspace/_artifacts --disable-log-dump=true --report-prefix=upgrade' finished in 2.277232864s
W0109 06:01:33.344] 2020/01/09 06:01:33 main.go:316: Something went wrong: encountered 1 errors: [error during ./hack/ginkgo-e2e.sh --ginkgo.focus=\[Feature:MasterUpgrade\] --upgrade-target=ci/k8s-beta --report-dir=/workspace/_artifacts --disable-log-dump=true --report-prefix=upgrade: exit status 1]
W0109 06:01:33.344] 2020/01/09 06:01:33 process.go:155: Step 'kubetest --test --test_args=--ginkgo.focus=\[Feature:MasterUpgrade\] --upgrade-target=ci/k8s-beta --report-dir=/workspace/_artifacts --disable-log-dump=true --report-prefix=upgrade --check-version-skew=false' finished in 5.171323257s
W0109 06:01:33.344] 2020/01/09 06:01:33 process.go:153: Running: ./hack/e2e-internal/e2e-status.sh
W0109 06:01:33.345] Project: k8s-jkns-gce-upgrade
W0109 06:01:33.345] Network Project: k8s-jkns-gce-upgrade
W0109 06:01:33.345] Zone: us-west1-b
I0109 06:01:33.548] Client Version: version.Info{Major:"1", Minor:"16+", GitVersion:"v1.16.5-beta.1.43+926ccb6ccb4e65", GitCommit:"926ccb6ccb4e65eb90bb9eb2d594671249009b12", GitTreeState:"clean", BuildDate:"2020-01-09T02:31:41Z", GoVersion:"go1.13.4", Compiler:"gc", Platform:"linux/amd64"}
... skipping 20 lines ...
I0109 06:01:36.267] 
I0109 06:01:40.795] Jan  9 06:01:40.795: INFO: cluster-master-image: cos-73-11647-163-0
I0109 06:01:40.796] Jan  9 06:01:40.795: INFO: cluster-node-image: cos-73-11647-163-0
I0109 06:01:40.796] Jan  9 06:01:40.795: INFO: >>> kubeConfig: /workspace/.kube/config
I0109 06:01:40.799] Jan  9 06:01:40.799: INFO: Waiting up to 30m0s for all (but 0) nodes to be schedulable
I0109 06:01:40.955] Jan  9 06:01:40.954: INFO: Waiting up to 10m0s for all pods (need at least 8) in namespace 'kube-system' to be running and ready
I0109 06:01:41.129] Jan  9 06:01:41.129: INFO: The status of Pod fluentd-gcp-v3.2.0-pr4zx is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0109 06:01:41.129] Jan  9 06:01:41.129: INFO: 27 / 28 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
I0109 06:01:41.129] Jan  9 06:01:41.129: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0109 06:01:41.130] Jan  9 06:01:41.129: INFO: POD                       NODE                             PHASE    GRACE  CONDITIONS
I0109 06:01:41.130] Jan  9 06:01:41.129: INFO: fluentd-gcp-v3.2.0-pr4zx  bootstrap-e2e-minion-group-4qmw  Running  60s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:00:49 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:01:36 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:01:36 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:00:49 +0000 UTC  }]
I0109 06:01:41.130] Jan  9 06:01:41.129: INFO: 
I0109 06:01:43.252] Jan  9 06:01:43.251: INFO: The status of Pod fluentd-gcp-v3.2.0-pr4zx is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0109 06:01:43.252] Jan  9 06:01:43.251: INFO: 27 / 28 pods in namespace 'kube-system' are running and ready (2 seconds elapsed)
I0109 06:01:43.252] Jan  9 06:01:43.251: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0109 06:01:43.253] Jan  9 06:01:43.251: INFO: POD                       NODE                             PHASE    GRACE  CONDITIONS
I0109 06:01:43.253] Jan  9 06:01:43.251: INFO: fluentd-gcp-v3.2.0-pr4zx  bootstrap-e2e-minion-group-4qmw  Running  60s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:00:49 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:01:36 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:01:36 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:00:49 +0000 UTC  }]
I0109 06:01:43.253] Jan  9 06:01:43.251: INFO: 
I0109 06:01:45.265] Jan  9 06:01:45.264: INFO: The status of Pod fluentd-gcp-v3.2.0-pr4zx is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0109 06:01:45.265] Jan  9 06:01:45.264: INFO: The status of Pod kube-addon-manager-bootstrap-e2e-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0109 06:01:45.265] Jan  9 06:01:45.264: INFO: 27 / 29 pods in namespace 'kube-system' are running and ready (4 seconds elapsed)
I0109 06:01:45.266] Jan  9 06:01:45.264: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0109 06:01:45.266] Jan  9 06:01:45.265: INFO: POD                                      NODE                             PHASE    GRACE  CONDITIONS
I0109 06:01:45.266] Jan  9 06:01:45.265: INFO: fluentd-gcp-v3.2.0-pr4zx                 bootstrap-e2e-minion-group-4qmw  Running  60s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:00:49 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:01:36 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:01:36 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-01-09 06:00:49 +0000 UTC  }]
I0109 06:01:45.267] Jan  9 06:01:45.265: INFO: kube-addon-manager-bootstrap-e2e-master  bootstrap-e2e-master             Pending         []
I0109 06:01:45.267] Jan  9 06:01:45.265: INFO: 
I0109 06:01:47.470] Jan  9 06:01:47.469: INFO: The status of Pod kube-addon-manager-bootstrap-e2e-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0109 06:01:47.470] Jan  9 06:01:47.469: INFO: 28 / 29 pods in namespace 'kube-system' are running and ready (6 seconds elapsed)
I0109 06:01:47.470] Jan  9 06:01:47.469: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0109 06:01:47.470] Jan  9 06:01:47.469: INFO: POD                                      NODE                  PHASE    GRACE  CONDITIONS
I0109 06:01:47.470] Jan  9 06:01:47.469: INFO: kube-addon-manager-bootstrap-e2e-master  bootstrap-e2e-master  Pending         []
I0109 06:01:47.471] Jan  9 06:01:47.469: INFO: 
I0109 06:01:49.257] Jan  9 06:01:49.256: INFO: 29 / 29 pods in namespace 'kube-system' are running and ready (8 seconds elapsed)
... skipping 746 lines ...
I0109 06:08:43.827] 
I0109 06:08:43.827]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:08:43.827] ------------------------------
I0109 06:08:43.827] S
I0109 06:08:43.827] ------------------------------
I0109 06:08:43.828] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0109 06:08:43.828]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0109 06:08:43.828]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:239
I0109 06:08:43.828] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0109 06:08:43.828]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:08:43.829] Jan  9 06:08:43.814: INFO: Driver local doesn't support InlineVolume -- skipping
I0109 06:08:43.829] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0109 06:08:43.829]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:08:43.829] [sig-storage] In-tree Volumes
I0109 06:08:43.830] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:08:43.830]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0109 06:08:43.830]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:69
I0109 06:08:43.830]     [Testpattern: Inline-volume (default fs)] subPath
I0109 06:08:43.830]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:08:43.830]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0109 06:08:43.831]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:239
I0109 06:08:43.831] 
I0109 06:08:43.831]       Driver local doesn't support InlineVolume -- skipping
I0109 06:08:43.831] 
I0109 06:08:43.831]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:08:43.831] ------------------------------
... skipping 213 lines ...
I0109 06:13:46.731] 
I0109 06:13:46.731]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1310
I0109 06:13:46.731] ------------------------------
I0109 06:13:46.731] SSSSSSSSS
I0109 06:13:46.732] ------------------------------
I0109 06:13:46.732] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0109 06:13:46.732]   should fail if subpath with backstepping is outside the volume [Slow]
I0109 06:13:46.732]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:261
I0109 06:13:46.732] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0109 06:13:46.733]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:13:46.733] Jan  9 06:13:46.726: INFO: Driver local doesn't support DynamicPV -- skipping
I0109 06:13:46.733] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0109 06:13:46.733]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:13:46.733] [sig-storage] In-tree Volumes
I0109 06:13:46.733] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:13:46.734]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0109 06:13:46.734]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:69
I0109 06:13:46.734]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0109 06:13:46.734]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:13:46.734]       should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
I0109 06:13:46.734]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:261
I0109 06:13:46.734] 
I0109 06:13:46.735]       Driver local doesn't support DynamicPV -- skipping
I0109 06:13:46.735] 
I0109 06:13:46.735]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:13:46.735] ------------------------------
... skipping 683 lines ...
I0109 06:20:36.901] STEP: Destroying namespace "disruptive-17" for this suite.
I0109 06:20:43.020] Jan  9 06:20:43.020: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0109 06:20:44.368] Jan  9 06:20:44.368: INFO: namespace disruptive-17 deletion completed in 7.46713666s
I0109 06:20:44.369] •SSSSSSSSSSS
I0109 06:20:44.369] ------------------------------
I0109 06:20:44.369] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0109 06:20:44.369]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0109 06:20:44.369]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:250
I0109 06:20:44.370] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0109 06:20:44.370]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:20:44.370] Jan  9 06:20:44.368: INFO: Driver local doesn't support InlineVolume -- skipping
I0109 06:20:44.370] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0109 06:20:44.370]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:20:44.371] [sig-storage] In-tree Volumes
I0109 06:20:44.371] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:20:44.371]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0109 06:20:44.371]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:69
I0109 06:20:44.372]     [Testpattern: Inline-volume (default fs)] subPath
I0109 06:20:44.372]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:20:44.372]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0109 06:20:44.372]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:250
I0109 06:20:44.372] 
I0109 06:20:44.373]       Driver local doesn't support InlineVolume -- skipping
I0109 06:20:44.373] 
I0109 06:20:44.373]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:20:44.373] ------------------------------
... skipping 64 lines ...
I0109 06:20:44.526]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:54
I0109 06:20:44.564] Jan  9 06:20:44.564: INFO: Default storage class: "standard"
I0109 06:20:44.565] Jan  9 06:20:44.564: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
I0109 06:20:44.603] [It] should create prometheus metrics for volume provisioning errors [Slow]
I0109 06:20:44.603]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:140
I0109 06:20:44.663] STEP: Creating an invalid storageclass
I0109 06:20:44.784] STEP: Creating a pod and expecting it to fail
I0109 06:22:44.939] Jan  9 06:22:44.939: INFO: Deleting pod "pv-9990"/"pvc-tester-8hrcs"
I0109 06:22:44.940] Jan  9 06:22:44.939: INFO: Deleting pod "pvc-tester-8hrcs" in namespace "pv-9990"
I0109 06:22:44.999] Jan  9 06:22:44.998: INFO: Wait up to 5m0s for pod "pvc-tester-8hrcs" to be fully deleted
I0109 06:22:45.036] STEP: Checking failure metrics
I0109 06:22:45.094] [AfterEach] [sig-storage] [Serial] Volume metrics
I0109 06:22:45.094]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0109 06:22:45.094] Jan  9 06:22:45.094: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0109 06:22:45.133] STEP: Destroying namespace "pv-9990" for this suite.
I0109 06:22:57.249] Jan  9 06:22:57.248: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0109 06:22:58.589] Jan  9 06:22:58.589: INFO: namespace pv-9990 deletion completed in 13.455641666s
I0109 06:22:58.589] [AfterEach] [sig-storage] [Serial] Volume metrics
I0109 06:22:58.590]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:80
I0109 06:22:58.627] Jan  9 06:22:58.626: INFO: Failed to get pvc pv-9990/pvc-7tfqh: persistentvolumeclaims "pvc-7tfqh" not found
I0109 06:22:58.667] •SSSSSSSSSS
I0109 06:22:58.667] ------------------------------
I0109 06:22:58.667] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow] 
I0109 06:22:58.668]   should access to two volumes with different volume mode and retain data across pod recreation on different node
I0109 06:22:58.668]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:232
I0109 06:22:58.668] [BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] multiVolume [Slow]
... skipping 641 lines ...
I0109 06:28:43.527] STEP: Destroying namespace "provisioning-4387" for this suite.
I0109 06:28:49.644] Jan  9 06:28:49.643: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0109 06:28:51.022] Jan  9 06:28:51.022: INFO: namespace provisioning-4387 deletion completed in 7.495726138s
I0109 06:28:51.023] •SSSSSSSSSSSSSSSSSSSSSS
I0109 06:28:51.023] ------------------------------
I0109 06:28:51.023] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0109 06:28:51.023]   should fail if subpath directory is outside the volume [Slow]
I0109 06:28:51.023]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:28:51.024] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:28:51.024]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:28:51.024] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:28:51.024]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0109 06:28:51.024] STEP: Creating a kubernetes client
I0109 06:28:51.025] Jan  9 06:28:51.022: INFO: >>> kubeConfig: /workspace/.kube/config
I0109 06:28:51.025] STEP: Building a namespace api object, basename provisioning
I0109 06:28:51.143] STEP: Waiting for a default service account to be provisioned in namespace
I0109 06:28:51.180] [It] should fail if subpath directory is outside the volume [Slow]
I0109 06:28:51.181]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:28:51.181] STEP: deploying csi gce-pd driver
I0109 06:28:51.217] Jan  9 06:28:51.217: INFO: Found CI service account key at /etc/service-account/service-account.json
I0109 06:28:51.218] Jan  9 06:28:51.217: INFO: Running cp [/etc/service-account/service-account.json /tmp/f6b53e2b-6928-45f8-97e6-74a0ab6299db/cloud-sa.json]
I0109 06:28:51.260] Jan  9 06:28:51.260: INFO: Shredding file /tmp/f6b53e2b-6928-45f8-97e6-74a0ab6299db/cloud-sa.json
I0109 06:28:51.260] Jan  9 06:28:51.260: INFO: Running shred [--remove /tmp/f6b53e2b-6928-45f8-97e6-74a0ab6299db/cloud-sa.json]
... skipping 21 lines ...
I0109 06:28:52.070] Jan  9 06:28:52.070: INFO: Test running for native CSI Driver, not checking metrics
I0109 06:28:52.071] Jan  9 06:28:52.070: INFO: Creating resource for dynamic PV
I0109 06:28:52.071] STEP: creating a StorageClass provisioning-6036-pd.csi.storage.gke.io-scmcjw4
I0109 06:28:52.139] STEP: creating a claim
I0109 06:28:52.140] Jan  9 06:28:52.139: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
I0109 06:28:52.244] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-chs4
I0109 06:28:52.288] STEP: Checking for subpath error in container status
I0109 06:29:20.370] Jan  9 06:29:20.370: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-chs4" in namespace "provisioning-6036"
I0109 06:29:20.414] Jan  9 06:29:20.413: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-chs4" to be fully deleted
I0109 06:29:30.492] STEP: Deleting pod
I0109 06:29:30.493] Jan  9 06:29:30.492: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-chs4" in namespace "provisioning-6036"
I0109 06:29:30.531] STEP: Deleting pvc
I0109 06:29:30.532] Jan  9 06:29:30.531: INFO: Deleting PersistentVolumeClaim "pd.csi.storage.gke.iobh8zm"
... skipping 307 lines ...
I0109 06:32:57.845] 
I0109 06:32:57.846]       Driver local doesn't support DynamicPV -- skipping
I0109 06:32:57.846] 
I0109 06:32:57.846]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:32:57.846] ------------------------------
I0109 06:32:57.846] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0109 06:32:57.846]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0109 06:32:57.847]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:250
I0109 06:32:57.847] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:32:57.847]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:32:57.847] Jan  9 06:32:57.842: INFO: Driver local doesn't support DynamicPV -- skipping
I0109 06:32:57.847] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:32:57.848]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:32:57.848] [sig-storage] In-tree Volumes
I0109 06:32:57.849] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:32:57.849]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0109 06:32:57.849]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:69
I0109 06:32:57.849]     [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:32:57.849]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:32:57.850]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0109 06:32:57.850]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:250
I0109 06:32:57.850] 
I0109 06:32:57.850]       Driver local doesn't support DynamicPV -- skipping
I0109 06:32:57.850] 
I0109 06:32:57.850]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:32:57.850] ------------------------------
I0109 06:32:57.851] SSSS
I0109 06:32:57.851] ------------------------------
I0109 06:32:57.851] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0109 06:32:57.851]   should fail if subpath directory is outside the volume [Slow]
I0109 06:32:57.851]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:32:57.851] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:32:57.852]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:32:57.852] Jan  9 06:32:57.844: INFO: Driver local doesn't support DynamicPV -- skipping
I0109 06:32:57.852] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:32:57.852]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:32:57.853] [sig-storage] In-tree Volumes
I0109 06:32:57.853] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:32:57.853]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0109 06:32:57.853]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:69
I0109 06:32:57.853]     [Testpattern: Dynamic PV (default fs)] subPath
I0109 06:32:57.853]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:32:57.854]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0109 06:32:57.854]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:32:57.854] 
I0109 06:32:57.854]       Driver local doesn't support DynamicPV -- skipping
I0109 06:32:57.854] 
I0109 06:32:57.854]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:32:57.854] ------------------------------
... skipping 2506 lines ...
I0109 06:46:12.798] STEP: Destroying namespace "sched-pod-priority-2787" for this suite.
I0109 06:46:18.914] Jan  9 06:46:18.914: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0109 06:46:20.264] Jan  9 06:46:20.263: INFO: namespace sched-pod-priority-2787 deletion completed in 7.465071977s
I0109 06:46:20.264] •SSSSSSSSSS
I0109 06:46:20.264] ------------------------------
I0109 06:46:20.265] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0109 06:46:20.265]   should fail if subpath directory is outside the volume [Slow]
I0109 06:46:20.265]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:46:20.265] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0109 06:46:20.265]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:46:20.266] Jan  9 06:46:20.264: INFO: Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
I0109 06:46:20.266] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0109 06:46:20.266]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:46:20.266] [sig-storage] CSI Volumes
I0109 06:46:20.267] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:46:20.267]   [Driver: pd.csi.storage.gke.io][Serial]
I0109 06:46:20.267]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:62
I0109 06:46:20.267]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0109 06:46:20.267]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:46:20.268]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0109 06:46:20.268]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:46:20.268] 
I0109 06:46:20.268]       Driver supports dynamic provisioning, skipping PreprovisionedPV pattern
I0109 06:46:20.268] 
I0109 06:46:20.269]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:625
I0109 06:46:20.269] ------------------------------
... skipping 558 lines ...
I0109 06:49:56.849] STEP: Destroying namespace "disruptive-7573" for this suite.
I0109 06:50:02.964] Jan  9 06:50:02.964: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0109 06:50:04.315] Jan  9 06:50:04.314: INFO: namespace disruptive-7573 deletion completed in 7.465575626s
I0109 06:50:04.315] •S
I0109 06:50:04.315] ------------------------------
I0109 06:50:04.315] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0109 06:50:04.316]   should fail if subpath directory is outside the volume [Slow]
I0109 06:50:04.316]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:50:04.316] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0109 06:50:04.316]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:50:04.316] Jan  9 06:50:04.314: INFO: Driver local doesn't support InlineVolume -- skipping
I0109 06:50:04.317] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0109 06:50:04.317]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:50:04.317] [sig-storage] In-tree Volumes
I0109 06:50:04.317] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:50:04.318]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0109 06:50:04.318]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:69
I0109 06:50:04.318]     [Testpattern: Inline-volume (default fs)] subPath
I0109 06:50:04.318]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:50:04.319]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0109 06:50:04.319]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:223
I0109 06:50:04.319] 
I0109 06:50:04.319]       Driver local doesn't support InlineVolume -- skipping
I0109 06:50:04.319] 
I0109 06:50:04.319]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:146
I0109 06:50:04.319] ------------------------------
... skipping 723 lines ...
I0109 06:55:46.558] 
I0109 06:55:46.558]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1769
I0109 06:55:46.559] ------------------------------
I0109 06:55:46.559] SSSS
I0109 06:55:46.559] ------------------------------
I0109 06:55:46.559] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0109 06:55:46.559]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0109 06:55:46.560]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:250
I0109 06:55:46.560] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0109 06:55:46.560]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:93
I0109 06:55:46.560] Jan  9 06:55:46.555: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0109 06:55:46.560] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0109 06:55:46.561]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
... skipping 3 lines ...
I0109 06:55:46.561] [sig-storage] CSI Volumes
I0109 06:55:46.562] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
I0109 06:55:46.562]   [Driver: pd.csi.storage.gke.io][Serial]
I0109 06:55:46.562]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:62
I0109 06:55:46.562]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0109 06:55:46.562]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0109 06:55:46.563]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0109 06:55:46.563]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:250
I0109 06:55:46.563] 
I0109 06:55:46.563]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0109 06:55:46.563] 
I0109 06:55:46.564]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:151
I0109 06:55:46.564] ------------------------------
... skipping 639 lines ...
I0109 06:58:39.216] Jan  9 06:58:39.212: INFO: Pod "volume-prep-provisioning-1408": Phase="Succeeded", Reason="", readiness=false. Elapsed: 26.559217304s
I0109 06:58:39.217] STEP: Saw pod success
I0109 06:58:39.217] Jan  9 06:58:39.212: INFO: Pod "volume-prep-provisioning-1408" satisfied condition "success or failure"
I0109 06:58:39.217] Jan  9 06:58:39.212: INFO: Deleting pod "volume-prep-provisioning-1408" in namespace "provisioning-1408"
I0109 06:58:39.261] Jan  9 06:58:39.260: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-1408" to be fully deleted
I0109 06:58:39.301] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-br57
I0109 06:58:39.344] STEP: Checking for subpath error in container status
I0109 06:58:49.427] Jan  9 06:58:49.424: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-br57" in namespace "provisioning-1408"
I0109 06:58:49.475] Jan  9 06:58:49.474: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-br57" to be fully deleted
I0109 06:58:49.513] STEP: Deleting pod
I0109 06:58:49.513] Jan  9 06:58: