This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 176 succeeded
Started2020-01-17 06:06
Elapsed6h43m
Revision
Buildergke-prow-default-pool-cf4891d4-dklz
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/4dd611ee-6ba3-4d4f-bbef-9b08ebbea78d/targets/test'}}
pod68548c1d-38ef-11ea-8780-468616f72408
resultstorehttps://source.cloud.google.com/results/invocations/4dd611ee-6ba3-4d4f-bbef-9b08ebbea78d/targets/test
infra-commit573707797
job-versionv1.15.9-beta.0.1+576595374055cc
master_os_imagecos-73-11647-163-0
node_os_imagecos-73-11647-163-0
pod68548c1d-38ef-11ea-8780-468616f72408
revisionv1.15.9-beta.0.1+576595374055cc

Test Failures


listResources After 1m19s

Failed to list resources (error during ./cluster/gce/list-resources.sh: exit status 2):
Project: k8s-gce-upg-1-5-1-6-up-clu
Region: 
Zone: 
Instance prefix: test-9b5ed62f1f
Network: test-9b5ed62f1f
Provider: gce


[ compute instance-templates ]



[ compute instance-groups ]



[ compute instances ]

				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 176 Passed Tests

Show 4252 Skipped Tests

Error lines from build-log.txt

... skipping 15 lines ...
I0117 06:06:39.339] process 47 exited with code 0 after 0.0m
I0117 06:06:39.339] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0117 06:06:39.340] Root: /workspace
I0117 06:06:39.340] cd to /workspace
I0117 06:06:39.340] Configure environment...
I0117 06:06:39.341] Call:  git show -s --format=format:%ct HEAD
W0117 06:06:39.346] fatal: not a git repository (or any of the parent directories): .git
I0117 06:06:39.347] process 60 exited with code 128 after 0.0m
W0117 06:06:39.347] Unable to print commit date for HEAD
I0117 06:06:39.347] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0117 06:06:40.004] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0117 06:06:40.379] process 61 exited with code 0 after 0.0m
I0117 06:06:40.379] Call:  gcloud config get-value account
... skipping 156 lines ...
W0117 06:07:25.619] Project: k8s-gce-upg-1-5-1-6-up-clu
W0117 06:07:25.619] Network Project: k8s-gce-upg-1-5-1-6-up-clu
W0117 06:07:25.619] Zone: us-west1-b
I0117 06:07:28.101] Bringing down cluster
W0117 06:07:28.201] INSTANCE_GROUPS=
W0117 06:07:28.202] NODE_NAMES=
W0117 06:07:31.722] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 06:07:31.723]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 06:07:31.723]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 06:07:31.723]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 06:07:31.723] 
W0117 06:07:37.700] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 06:07:37.700]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 06:07:37.701]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 06:07:37.701]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 06:07:37.701] 
I0117 06:07:55.645] Deleting firewall rules remaining in network test-9b5ed62f1f: 
I0117 06:07:58.895] Property "clusters.k8s-gce-upg-1-5-1-6-up-clu_test-9b5ed62f1f" unset.
... skipping 4 lines ...
I0117 06:07:59.715] Done
W0117 06:07:59.815] 2020/01/17 06:07:59 process.go:155: Step './hack/e2e-internal/e2e-down.sh' finished in 37.33567732s
W0117 06:07:59.815] 2020/01/17 06:07:59 e2e.go:456: Listing resources...
W0117 06:07:59.815] 2020/01/17 06:07:59 process.go:153: Running: ./cluster/gce/list-resources.sh
W0117 06:08:00.844] Listed 0 items.
W0117 06:08:02.216] Listed 0 items.
W0117 06:08:04.427] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 06:08:04.427]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 06:08:04.428]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 06:08:04.428]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 06:08:04.428] 
W0117 06:08:04.530] Attempt 1 failed to list instances. Retrying.
W0117 06:08:16.182] Listed 0 items.
W0117 06:08:17.554] Listed 0 items.
W0117 06:08:18.868] Listed 0 items.
W0117 06:08:21.328] 
W0117 06:08:21.329] To show all fields of the firewall, please show in JSON format: --format=json
W0117 06:08:21.329] To show all fields in table format, please see the examples in --help.
... skipping 133 lines ...
W0117 06:11:11.862] Trying to find master named 'test-9b5ed62f1f-master'
W0117 06:11:11.862] Looking for address 'test-9b5ed62f1f-master-ip'
W0117 06:11:12.863] Using master: test-9b5ed62f1f-master (external IP: 34.82.121.244)
I0117 06:11:12.964] Waiting up to 300 seconds for cluster initialization.
I0117 06:11:12.964] 
I0117 06:11:12.964]   This will continually check to see if the API for kubernetes is reachable.
I0117 06:11:12.964]   This may time out if there was some uncaught error during start up.
I0117 06:11:12.964] 
I0117 06:12:08.296] .............Kubernetes cluster created.
I0117 06:12:08.576] Cluster "k8s-gce-upg-1-5-1-6-up-clu_test-9b5ed62f1f" set.
I0117 06:12:08.864] User "k8s-gce-upg-1-5-1-6-up-clu_test-9b5ed62f1f" set.
I0117 06:12:09.114] Context "k8s-gce-upg-1-5-1-6-up-clu_test-9b5ed62f1f" created.
I0117 06:12:09.404] Switched to context "k8s-gce-upg-1-5-1-6-up-clu_test-9b5ed62f1f".
... skipping 23 lines ...
I0117 06:12:40.449] NAME                                STATUS                     ROLES    AGE   VERSION
I0117 06:12:40.449] test-9b5ed62f1f-master              Ready,SchedulingDisabled   <none>   7s    v1.15.9-beta.0.1+576595374055cc
I0117 06:12:40.450] test-9b5ed62f1f-minion-group-39p2   Ready                      <none>   9s    v1.15.9-beta.0.1+576595374055cc
I0117 06:12:40.450] test-9b5ed62f1f-minion-group-m5pm   Ready                      <none>   9s    v1.15.9-beta.0.1+576595374055cc
I0117 06:12:40.450] test-9b5ed62f1f-minion-group-vgn3   Ready                      <none>   9s    v1.15.9-beta.0.1+576595374055cc
I0117 06:12:40.952] Validate output:
I0117 06:12:41.434] NAME                 STATUS    MESSAGE             ERROR
I0117 06:12:41.434] etcd-1               Healthy   {"health":"true"}   
I0117 06:12:41.435] controller-manager   Healthy   ok                  
I0117 06:12:41.435] scheduler            Healthy   ok                  
I0117 06:12:41.435] etcd-0               Healthy   {"health":"true"}   
I0117 06:12:41.445] Cluster validation succeeded
W0117 06:12:41.545] Done, listing cluster services:
... skipping 107 lines ...
I0117 06:13:20.000] 
I0117 06:13:25.653] Jan 17 06:13:25.653: INFO: cluster-master-image: cos-73-11647-163-0
I0117 06:13:25.653] Jan 17 06:13:25.653: INFO: cluster-node-image: cos-73-11647-163-0
I0117 06:13:25.654] Jan 17 06:13:25.653: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 06:13:25.656] Jan 17 06:13:25.656: INFO: Waiting up to 30m0s for all (but 0) nodes to be schedulable
I0117 06:13:25.832] Jan 17 06:13:25.832: INFO: Waiting up to 10m0s for all pods (need at least 8) in namespace 'kube-system' to be running and ready
I0117 06:13:25.991] Jan 17 06:13:25.991: INFO: The status of Pod etcd-empty-dir-cleanup-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:25.992] Jan 17 06:13:25.991: INFO: The status of Pod fluentd-gcp-v3.2.0-wzfvx is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:25.992] Jan 17 06:13:25.991: INFO: The status of Pod kube-apiserver-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:25.992] Jan 17 06:13:25.991: INFO: 22 / 25 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
I0117 06:13:25.992] Jan 17 06:13:25.991: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0117 06:13:25.992] Jan 17 06:13:25.991: INFO: POD                                            NODE                               PHASE    GRACE  CONDITIONS
I0117 06:13:25.993] Jan 17 06:13:25.991: INFO: etcd-empty-dir-cleanup-test-9b5ed62f1f-master  test-9b5ed62f1f-master             Pending         []
I0117 06:13:25.993] Jan 17 06:13:25.991: INFO: fluentd-gcp-v3.2.0-wzfvx                       test-9b5ed62f1f-minion-group-m5pm  Running  60s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:12:32 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:13:20 +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-17 06:13:20 +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-17 06:12:32 +0000 UTC  }]
I0117 06:13:25.994] Jan 17 06:13:25.991: INFO: kube-apiserver-test-9b5ed62f1f-master          test-9b5ed62f1f-master             Pending         []
I0117 06:13:25.994] Jan 17 06:13:25.991: INFO: 
I0117 06:13:28.109] Jan 17 06:13:28.109: INFO: The status of Pod fluentd-gcp-v3.2.0-wzfvx is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:28.109] Jan 17 06:13:28.109: INFO: 24 / 25 pods in namespace 'kube-system' are running and ready (2 seconds elapsed)
I0117 06:13:28.110] Jan 17 06:13:28.109: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0117 06:13:28.110] Jan 17 06:13:28.109: INFO: POD                       NODE                               PHASE    GRACE  CONDITIONS
I0117 06:13:28.111] Jan 17 06:13:28.109: INFO: fluentd-gcp-v3.2.0-wzfvx  test-9b5ed62f1f-minion-group-m5pm  Running  60s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:12:32 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:13:20 +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-17 06:13:20 +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-17 06:12:32 +0000 UTC  }]
I0117 06:13:28.111] Jan 17 06:13:28.109: INFO: 
I0117 06:13:30.105] Jan 17 06:13:30.104: INFO: The status of Pod fluentd-gcp-v3.2.0-wzfvx is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:30.105] Jan 17 06:13:30.104: INFO: 24 / 25 pods in namespace 'kube-system' are running and ready (4 seconds elapsed)
I0117 06:13:30.105] Jan 17 06:13:30.104: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0117 06:13:30.106] Jan 17 06:13:30.104: INFO: POD                       NODE                               PHASE    GRACE  CONDITIONS
I0117 06:13:30.107] Jan 17 06:13:30.105: INFO: fluentd-gcp-v3.2.0-wzfvx  test-9b5ed62f1f-minion-group-m5pm  Running  60s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:12:32 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:13:20 +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-17 06:13:20 +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-17 06:12:32 +0000 UTC  }]
I0117 06:13:30.107] Jan 17 06:13:30.105: INFO: 
I0117 06:13:32.104] Jan 17 06:13:32.104: INFO: The status of Pod etcd-server-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:32.105] Jan 17 06:13:32.104: INFO: The status of Pod fluentd-gcp-v3.2.0-4mcgv is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:32.105] Jan 17 06:13:32.104: INFO: The status of Pod l7-lb-controller-v1.2.3-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:32.106] Jan 17 06:13:32.104: INFO: 24 / 27 pods in namespace 'kube-system' are running and ready (6 seconds elapsed)
I0117 06:13:32.106] Jan 17 06:13:32.104: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0117 06:13:32.107] Jan 17 06:13:32.104: INFO: POD                                             NODE                               PHASE    GRACE  CONDITIONS
I0117 06:13:32.107] Jan 17 06:13:32.104: INFO: etcd-server-test-9b5ed62f1f-master              test-9b5ed62f1f-master             Pending         []
I0117 06:13:32.108] Jan 17 06:13:32.104: INFO: fluentd-gcp-v3.2.0-4mcgv                        test-9b5ed62f1f-minion-group-m5pm  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:13:31 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-01-17 06:13:31 +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-17 06:13:31 +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-17 06:13:31 +0000 UTC  }]
I0117 06:13:32.108] Jan 17 06:13:32.104: INFO: l7-lb-controller-v1.2.3-test-9b5ed62f1f-master  test-9b5ed62f1f-master             Pending         []
I0117 06:13:32.108] Jan 17 06:13:32.104: INFO: 
I0117 06:13:34.105] Jan 17 06:13:34.105: INFO: The status of Pod etcd-server-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:34.106] Jan 17 06:13:34.105: INFO: The status of Pod kube-addon-manager-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:34.106] Jan 17 06:13:34.105: INFO: The status of Pod l7-lb-controller-v1.2.3-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:34.107] Jan 17 06:13:34.105: INFO: 25 / 28 pods in namespace 'kube-system' are running and ready (8 seconds elapsed)
I0117 06:13:34.107] Jan 17 06:13:34.105: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0117 06:13:34.107] Jan 17 06:13:34.105: INFO: POD                                             NODE                    PHASE    GRACE  CONDITIONS
I0117 06:13:34.107] Jan 17 06:13:34.105: INFO: etcd-server-test-9b5ed62f1f-master              test-9b5ed62f1f-master  Pending         []
I0117 06:13:34.107] Jan 17 06:13:34.105: INFO: kube-addon-manager-test-9b5ed62f1f-master       test-9b5ed62f1f-master  Pending         []
I0117 06:13:34.108] Jan 17 06:13:34.105: INFO: l7-lb-controller-v1.2.3-test-9b5ed62f1f-master  test-9b5ed62f1f-master  Pending         []
I0117 06:13:34.108] Jan 17 06:13:34.105: INFO: 
I0117 06:13:36.106] Jan 17 06:13:36.105: INFO: The status of Pod etcd-server-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:36.106] Jan 17 06:13:36.105: INFO: The status of Pod kube-addon-manager-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:36.106] Jan 17 06:13:36.105: INFO: The status of Pod l7-lb-controller-v1.2.3-test-9b5ed62f1f-master is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0117 06:13:36.107] Jan 17 06:13:36.105: INFO: 25 / 28 pods in namespace 'kube-system' are running and ready (10 seconds elapsed)
I0117 06:13:36.107] Jan 17 06:13:36.105: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0117 06:13:36.107] Jan 17 06:13:36.105: INFO: POD                                             NODE                    PHASE    GRACE  CONDITIONS
I0117 06:13:36.107] Jan 17 06:13:36.105: INFO: etcd-server-test-9b5ed62f1f-master              test-9b5ed62f1f-master  Pending         []
I0117 06:13:36.108] Jan 17 06:13:36.105: INFO: kube-addon-manager-test-9b5ed62f1f-master       test-9b5ed62f1f-master  Pending         []
I0117 06:13:36.108] Jan 17 06:13:36.105: INFO: l7-lb-controller-v1.2.3-test-9b5ed62f1f-master  test-9b5ed62f1f-master  Pending         []
... skipping 308 lines ...
I0117 06:15:51.310] STEP: Destroying namespace "provisioning-9358" for this suite.
I0117 06:15:57.427] Jan 17 06:15:57.426: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 06:15:58.918] Jan 17 06:15:58.914: INFO: namespace provisioning-9358 deletion completed in 7.605475169s
I0117 06:15:58.918] •SSSS
I0117 06:15:58.919] ------------------------------
I0117 06:15:58.919] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0117 06:15:58.919]   should fail if subpath directory is outside the volume [Slow]
I0117 06:15:58.919]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 06:15:58.919] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 06:15:58.920]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 06:15:58.920] Jan 17 06:15:58.914: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0117 06:15:58.920] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 06:15:58.920]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 06:15:58.921] [sig-storage] CSI Volumes
I0117 06:15:58.921] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 06:15:58.921]   [Driver: pd.csi.storage.gke.io][Serial]
I0117 06:15:58.921]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0117 06:15:58.922]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 06:15:58.922]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 06:15:58.922]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0117 06:15:58.922]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 06:15:58.922] 
I0117 06:15:58.923]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0117 06:15:58.923] 
I0117 06:15:58.923]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0117 06:15:58.923] ------------------------------
... skipping 75 lines ...
I0117 06:15:58.947] 
I0117 06:15:58.947]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0117 06:15:58.948] ------------------------------
I0117 06:15:58.948] SSS
I0117 06:15:58.948] ------------------------------
I0117 06:15:58.948] [sig-storage] CSI Volumes CSI Topology test using GCE PD driver [Serial] 
I0117 06:15:58.948]   should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding
I0117 06:15:58.948]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0117 06:15:58.949] [BeforeEach] CSI Topology test using GCE PD driver [Serial]
I0117 06:15:58.949]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 06:15:58.949] STEP: Creating a kubernetes client
I0117 06:15:58.950] Jan 17 06:15:58.925: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 06:15:58.950] STEP: Building a namespace api object, basename csitopology
... skipping 27 lines ...
I0117 06:16:00.003] Jan 17 06:16:00.002: INFO: creating *v1.RoleBinding: csitopology-5609/csi-controller-attacher-role-cfg
I0117 06:16:00.040] Jan 17 06:16:00.039: INFO: creating *v1.ClusterRoleBinding: csi-controller-provisioner-role-csitopology-5609
I0117 06:16:00.076] Jan 17 06:16:00.075: INFO: creating *v1.RoleBinding: csitopology-5609/csi-controller-provisioner-role-cfg
I0117 06:16:00.115] Jan 17 06:16:00.114: INFO: creating *v1.ClusterRoleBinding: psp-csi-controller-driver-registrar-role-csitopology-5609
I0117 06:16:00.155] Jan 17 06:16:00.154: INFO: creating *v1.DaemonSet: csitopology-5609/csi-gce-pd-node
I0117 06:16:00.197] Jan 17 06:16:00.196: INFO: creating *v1.StatefulSet: csitopology-5609/csi-gce-pd-controller
I0117 06:16:00.286] [It] should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding
I0117 06:16:00.286]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0117 06:16:00.361] Jan 17 06:16:00.359: INFO: Requires more than one zone
I0117 06:16:00.361] [AfterEach] CSI Topology test using GCE PD driver [Serial]
I0117 06:16:00.361]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0117 06:16:00.362] Jan 17 06:16:00.360: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 06:16:00.418] STEP: Destroying namespace "csitopology-5609" for this suite.
... skipping 26 lines ...
I0117 06:16:24.718] 
I0117 06:16:24.719] S [SKIPPING] [25.789 seconds]
I0117 06:16:24.719] [sig-storage] CSI Volumes
I0117 06:16:24.719] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 06:16:24.720]   CSI Topology test using GCE PD driver [Serial]
I0117 06:16:24.720]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:64
I0117 06:16:24.721]     should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding [It]
I0117 06:16:24.721]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0117 06:16:24.721] 
I0117 06:16:24.721]     Requires more than one zone
I0117 06:16:24.721] 
I0117 06:16:24.722]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:104
I0117 06:16:24.722] ------------------------------
... skipping 678 lines ...
I0117 06:30:58.484] 
I0117 06:30:58.484]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 06:30:58.484] 
I0117 06:30:58.485]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 06:30:58.485] ------------------------------
I0117 06:30:58.485] [sig-apps] Daemon set [Serial] 
I0117 06:30:58.485]   should retry creating failed daemon pods [Conformance]
I0117 06:30:58.485]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
I0117 06:30:58.485] [BeforeEach] [sig-apps] Daemon set [Serial]
I0117 06:30:58.486]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 06:30:58.486] STEP: Creating a kubernetes client
I0117 06:30:58.486] Jan 17 06:30:58.478: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 06:30:58.486] STEP: Building a namespace api object, basename daemonsets
I0117 06:30:58.586] STEP: Waiting for a default service account to be provisioned in namespace
I0117 06:30:58.620] [BeforeEach] [sig-apps] Daemon set [Serial]
I0117 06:30:58.620]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:103
I0117 06:30:58.836] [It] should retry creating failed daemon pods [Conformance]
I0117 06:30:58.836]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
I0117 06:30:58.836] STEP: Creating a simple DaemonSet "daemon-set"
I0117 06:30:58.878] STEP: Check that daemon pods launch on every node of the cluster.
I0117 06:30:58.965] Jan 17 06:30:58.965: INFO: DaemonSet pods can't tolerate node test-9b5ed62f1f-master with taints [{Key:node-role.kubernetes.io/master Value: Effect:NoSchedule TimeAdded:<nil>} {Key:node.kubernetes.io/unschedulable Value: Effect:NoSchedule TimeAdded:<nil>}], skip checking this node
I0117 06:30:59.059] Jan 17 06:30:59.059: INFO: Number of nodes with available pods: 0
I0117 06:30:59.059] Jan 17 06:30:59.059: INFO: Node test-9b5ed62f1f-minion-group-39p2 is running more than one daemon pod
... skipping 3 lines ...
I0117 06:31:01.096] Jan 17 06:31:01.096: INFO: DaemonSet pods can't tolerate node test-9b5ed62f1f-master with taints [{Key:node-role.kubernetes.io/master Value: Effect:NoSchedule TimeAdded:<nil>} {Key:node.kubernetes.io/unschedulable Value: Effect:NoSchedule TimeAdded:<nil>}], skip checking this node
I0117 06:31:01.131] Jan 17 06:31:01.130: INFO: Number of nodes with available pods: 0
I0117 06:31:01.131] Jan 17 06:31:01.130: INFO: Node test-9b5ed62f1f-minion-group-39p2 is running more than one daemon pod
I0117 06:31:02.095] Jan 17 06:31:02.094: INFO: DaemonSet pods can't tolerate node test-9b5ed62f1f-master with taints [{Key:node-role.kubernetes.io/master Value: Effect:NoSchedule TimeAdded:<nil>} {Key:node.kubernetes.io/unschedulable Value: Effect:NoSchedule TimeAdded:<nil>}], skip checking this node
I0117 06:31:02.129] Jan 17 06:31:02.129: INFO: Number of nodes with available pods: 3
I0117 06:31:02.130] Jan 17 06:31:02.129: INFO: Number of running nodes: 3, number of available pods: 3
I0117 06:31:02.164] STEP: Set a daemon pod's phase to 'Failed', check that the daemon pod is revived.
I0117 06:31:02.290] Jan 17 06:31:02.290: INFO: DaemonSet pods can't tolerate node test-9b5ed62f1f-master with taints [{Key:node-role.kubernetes.io/master Value: Effect:NoSchedule TimeAdded:<nil>} {Key:node.kubernetes.io/unschedulable Value: Effect:NoSchedule TimeAdded:<nil>}], skip checking this node
I0117 06:31:02.334] Jan 17 06:31:02.333: INFO: Number of nodes with available pods: 2
I0117 06:31:02.334] Jan 17 06:31:02.333: INFO: Node test-9b5ed62f1f-minion-group-vgn3 is running more than one daemon pod
I0117 06:31:03.370] Jan 17 06:31:03.370: INFO: DaemonSet pods can't tolerate node test-9b5ed62f1f-master with taints [{Key:node-role.kubernetes.io/master Value: Effect:NoSchedule TimeAdded:<nil>} {Key:node.kubernetes.io/unschedulable Value: Effect:NoSchedule TimeAdded:<nil>}], skip checking this node
I0117 06:31:03.405] Jan 17 06:31:03.405: INFO: Number of nodes with available pods: 2
I0117 06:31:03.405] Jan 17 06:31:03.405: INFO: Node test-9b5ed62f1f-minion-group-vgn3 is running more than one daemon pod
I0117 06:31:04.371] Jan 17 06:31:04.371: INFO: DaemonSet pods can't tolerate node test-9b5ed62f1f-master with taints [{Key:node-role.kubernetes.io/master Value: Effect:NoSchedule TimeAdded:<nil>} {Key:node.kubernetes.io/unschedulable Value: Effect:NoSchedule TimeAdded:<nil>}], skip checking this node
I0117 06:31:04.407] Jan 17 06:31:04.406: INFO: Number of nodes with available pods: 3
I0117 06:31:04.407] Jan 17 06:31:04.406: INFO: Number of running nodes: 3, number of available pods: 3
I0117 06:31:04.407] STEP: Wait for the failed daemon pod to be completely deleted.
I0117 06:31:04.441] [AfterEach] [sig-apps] Daemon set [Serial]
I0117 06:31:04.441]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:69
I0117 06:31:04.476] STEP: Deleting DaemonSet "daemon-set"
I0117 06:31:04.476] STEP: deleting DaemonSet.extensions daemon-set in namespace daemonsets-418, will wait for the garbage collector to delete the pods
I0117 06:31:04.601] Jan 17 06:31:04.600: INFO: Deleting DaemonSet.extensions daemon-set took: 39.575815ms
I0117 06:31:05.101] Jan 17 06:31:05.101: INFO: Terminating DaemonSet.extensions daemon-set pods took: 500.501833ms
... skipping 469 lines ...
I0117 06:44:41.397] Jan 17 06:44:41.397: INFO: Waiting up to 20m0s for service "lb-hc-int" to have a LoadBalancer
I0117 06:45:37.652] STEP: modify the health check interval
I0117 06:45:57.339] STEP: restart kube-controller-manager
I0117 06:45:57.340] Jan 17 06:45:57.339: INFO: Restarting controller-manager via ssh, running: pidof kube-controller-manager | xargs sudo kill
I0117 06:45:58.379] Jan 17 06:45:58.378: INFO: ssh prow@34.82.121.244:22: command:   curl http://localhost:10252/healthz
I0117 06:45:58.379] Jan 17 06:45:58.378: INFO: ssh prow@34.82.121.244:22: stdout:    ""
I0117 06:45:58.379] Jan 17 06:45:58.378: INFO: ssh prow@34.82.121.244:22: stderr:    "  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current\n                                 Dload  Upload   Total   Spent    Left  Speed\n\r  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0curl: (7) Failed to connect to localhost port 10252: Connection refused\n"
I0117 06:45:58.380] Jan 17 06:45:58.378: INFO: ssh prow@34.82.121.244:22: exit code: 7
I0117 06:46:03.875] STEP: health check should be reconciled
I0117 06:46:04.033] Jan 17 06:46:04.033: INFO: hc.CheckIntervalSec = 7
I0117 06:46:24.232] Jan 17 06:46:24.232: INFO: hc.CheckIntervalSec = 7
I0117 06:46:44.219] Jan 17 06:46:44.219: INFO: hc.CheckIntervalSec = 8
I0117 06:46:44.351] [AfterEach] [sig-network] Services
... skipping 400 lines ...
I0117 06:50:57.427] 
I0117 06:50:57.427]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/persistent_volumes-local.go:1197
I0117 06:50:57.428] ------------------------------
I0117 06:50:57.428] SSSSSS
I0117 06:50:57.428] ------------------------------
I0117 06:50:57.428] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0117 06:50:57.428]   should fail if subpath directory is outside the volume [Slow]
I0117 06:50:57.429]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 06:50:57.429] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 06:50:57.429]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 06:50:57.429] Jan 17 06:50:57.424: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 06:50:57.429] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 06:50:57.429]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 06:50:57.430] [sig-storage] CSI Volumes
I0117 06:50:57.430] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 06:50:57.430]   [Driver: pd.csi.storage.gke.io][Serial]
I0117 06:50:57.431]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0117 06:50:57.431]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 06:50:57.431]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 06:50:57.431]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0117 06:50:57.431]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 06:50:57.431] 
I0117 06:50:57.432]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 06:50:57.432] 
I0117 06:50:57.432]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 06:50:57.432] ------------------------------
... skipping 493 lines ...
I0117 07:15:32.167] Jan 17 07:15:32.166: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 07:15:32.203] STEP: Destroying namespace "kubelet-perf-7776" for this suite.
I0117 07:15:44.310] Jan 17 07:15:44.310: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 07:15:45.668] Jan 17 07:15:45.667: INFO: namespace kubelet-perf-7776 deletion completed in 13.464520445s
I0117 07:15:45.668] [AfterEach] [k8s.io] [sig-node] Kubelet [Serial] [Slow]
I0117 07:15:45.668]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/node/kubelet_perf.go:211
I0117 07:15:47.032] Jan 17 07:15:47.031: INFO: runtime operation error metrics:
I0117 07:15:47.032] node "test-9b5ed62f1f-master" runtime operation error rate:
I0117 07:15:47.032] operation "list_containers": total - 5422; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.032] operation "inspect_image": total - 5; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.033] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.033] operation "remove_container": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.033] operation "start_container": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.033] operation "stop_container": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.033] operation "create_container": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.033] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.034] operation "inspect_container": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.034] operation "list_images": total - 428; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.034] operation "version": total - 443; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.034] 
I0117 07:15:47.034] 
I0117 07:15:47.035] node "test-9b5ed62f1f-minion-group-39p2" runtime operation error rate:
I0117 07:15:47.035] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.035] operation "create_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.035] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.035] operation "inspect_container": total - 1941; error rate - 0.005667; timeout rate - 0.000000
I0117 07:15:47.036] operation "list_images": total - 412; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.036] operation "version": total - 444; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.036] operation "list_containers": total - 5968; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.036] operation "remove_container": total - 202; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.036] operation "start_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.036] operation "stop_container": total - 440; error rate - 0.002273; timeout rate - 0.000000
I0117 07:15:47.037] operation "inspect_image": total - 701; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.037] 
I0117 07:15:47.037] 
I0117 07:15:47.037] node "test-9b5ed62f1f-minion-group-m5pm" runtime operation error rate:
I0117 07:15:47.037] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.037] operation "inspect_container": total - 1864; error rate - 0.009657; timeout rate - 0.000000
I0117 07:15:47.037] operation "version": total - 445; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.038] operation "list_images": total - 409; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.038] operation "stop_container": total - 443; error rate - 0.002257; timeout rate - 0.000000
I0117 07:15:47.038] operation "create_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.038] operation "remove_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.039] operation "start_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.039] operation "inspect_image": total - 654; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.039] operation "list_containers": total - 5842; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.039] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.040] 
I0117 07:15:47.040] 
I0117 07:15:47.040] node "test-9b5ed62f1f-minion-group-vgn3" runtime operation error rate:
I0117 07:15:47.040] operation "list_containers": total - 5978; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.041] operation "version": total - 444; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.041] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.041] operation "remove_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.041] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0117 07:15:47.041] operation "stop_container": total - 451; error rate - 0.004435; timeout rate - 0.000000
I0117 07:15:47.041] operation "create_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.042] operation "start_container": total - 200; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.042] operation "inspect_container": total - 1979; error rate - 0.014149; timeout rate - 0.000000
I0117 07:15:47.042] operation "inspect_image": total - 701; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.042] operation "list_images": total - 411; error rate - 0.000000; timeout rate - 0.000000
I0117 07:15:47.043] 
I0117 07:15:47.043] 
I0117 07:15:47.043] 
I0117 07:15:47.043] • [SLOW TEST:1480.450 seconds]
I0117 07:15:47.043] [k8s.io] [sig-node] Kubelet [Serial] [Slow]
I0117 07:15:47.043] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:692
... skipping 242 lines ...
I0117 07:18:53.961] STEP: Destroying namespace "daemonsets-4508" for this suite.
I0117 07:19:00.070] Jan 17 07:19:00.069: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 07:19:01.552] Jan 17 07:19:01.551: INFO: namespace daemonsets-4508 deletion completed in 7.590384048s
I0117 07:19:01.552] •SSSSSSS
I0117 07:19:01.552] ------------------------------
I0117 07:19:01.552] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0117 07:19:01.553]   should fail if subpath directory is outside the volume [Slow]
I0117 07:19:01.553]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 07:19:01.553] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 07:19:01.553]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:19:01.553] Jan 17 07:19:01.552: INFO: Driver local doesn't support DynamicPV -- skipping
I0117 07:19:01.554] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 07:19:01.554]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:19:01.555] [sig-storage] In-tree Volumes
I0117 07:19:01.555] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:19:01.555]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 07:19:01.555]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 07:19:01.555]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 07:19:01.556]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:19:01.556]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0117 07:19:01.556]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 07:19:01.556] 
I0117 07:19:01.557]       Driver local doesn't support DynamicPV -- skipping
I0117 07:19:01.557] 
I0117 07:19:01.557]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:19:01.557] ------------------------------
... skipping 54 lines ...
I0117 07:19:04.003] STEP: Destroying namespace "downward-api-31" for this suite.
I0117 07:19:10.109] Jan 17 07:19:10.109: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 07:19:11.445] Jan 17 07:19:11.444: INFO: namespace downward-api-31 deletion completed in 7.441776971s
I0117 07:19:11.445] •SS
I0117 07:19:11.445] ------------------------------
I0117 07:19:11.445] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0117 07:19:11.446]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0117 07:19:11.446]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 07:19:11.446] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0117 07:19:11.446]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:19:11.447] Jan 17 07:19:11.444: INFO: Driver local doesn't support InlineVolume -- skipping
I0117 07:19:11.447] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0117 07:19:11.447]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:19:11.448] [sig-storage] In-tree Volumes
I0117 07:19:11.449] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:19:11.449]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 07:19:11.449]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 07:19:11.449]     [Testpattern: Inline-volume (default fs)] subPath
I0117 07:19:11.449]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:19:11.450]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0117 07:19:11.450]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 07:19:11.450] 
I0117 07:19:11.450]       Driver local doesn't support InlineVolume -- skipping
I0117 07:19:11.450] 
I0117 07:19:11.450]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:19:11.451] ------------------------------
... skipping 15 lines ...
I0117 07:19:21.678] I0117 07:19:21.677594    8423 runners.go:180] daemonrestart10-0c7877b6-b9bb-4db8-b8c6-a9a45f21759b Pods: 10 out of 10 created, 10 running, 0 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0117 07:19:21.678] [It] Kubelet should not restart containers across restart
I0117 07:19:21.678]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_restart.go:299
I0117 07:19:21.752] Jan 17 07:19:21.752: INFO: Checking if Daemon kubelet on node 34.82.146.205 is up by polling for a 200 on its /healthz endpoint
I0117 07:19:27.218] Jan 17 07:19:27.218: INFO: Killing Daemon kubelet on node 34.82.146.205
I0117 07:19:27.687] Jan 17 07:19:27.687: INFO: Checking if Daemon kubelet on node 34.82.146.205 is up by polling for a 200 on its /healthz endpoint
I0117 07:19:33.109] Jan 17 07:19:33.109: INFO: node 34.82.146.205 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0117 07:19:33.109] 	stdout: 000
I0117 07:19:33.109] 	stderr: 
I0117 07:19:38.139] Jan 17 07:19:38.139: INFO: node 34.82.146.205 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0117 07:19:38.139] 	stdout: 000
I0117 07:19:38.141] 	stderr: 
I0117 07:19:43.110] Jan 17 07:19:43.110: INFO: Checking if Daemon kubelet on node 35.247.64.104 is up by polling for a 200 on its /healthz endpoint
I0117 07:19:48.569] Jan 17 07:19:48.569: INFO: Killing Daemon kubelet on node 35.247.64.104
I0117 07:19:49.023] Jan 17 07:19:49.022: INFO: Checking if Daemon kubelet on node 35.247.64.104 is up by polling for a 200 on its /healthz endpoint
I0117 07:19:54.445] Jan 17 07:19:54.445: INFO: node 35.247.64.104 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0117 07:19:54.446] 	stdout: 000
I0117 07:19:54.446] 	stderr: 
I0117 07:19:59.443] Jan 17 07:19:59.443: INFO: node 35.247.64.104 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0117 07:19:59.444] 	stdout: 000
I0117 07:19:59.444] 	stderr: 
I0117 07:20:04.481] Jan 17 07:20:04.481: INFO: Checking if Daemon kubelet on node 104.196.237.246 is up by polling for a 200 on its /healthz endpoint
I0117 07:20:09.941] Jan 17 07:20:09.941: INFO: Killing Daemon kubelet on node 104.196.237.246
I0117 07:20:10.411] Jan 17 07:20:10.410: INFO: Checking if Daemon kubelet on node 104.196.237.246 is up by polling for a 200 on its /healthz endpoint
I0117 07:20:15.863] Jan 17 07:20:15.863: INFO: node 104.196.237.246 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0117 07:20:15.864] 	stdout: 000
I0117 07:20:15.864] 	stderr: 
I0117 07:20:20.947] [AfterEach] [sig-apps] DaemonRestart [Disruptive]
I0117 07:20:20.947]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0117 07:20:20.949] Jan 17 07:20:20.947: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 07:20:20.982] STEP: Destroying namespace "daemonrestart-4154" for this suite.
... skipping 257 lines ...
I0117 07:23:06.335] 
I0117 07:23:06.336]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1292
I0117 07:23:06.336] ------------------------------
I0117 07:23:06.336] SSSSS
I0117 07:23:06.336] ------------------------------
I0117 07:23:06.336] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 07:23:06.336]   should fail if subpath with backstepping is outside the volume [Slow]
I0117 07:23:06.336]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0117 07:23:06.337] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:23:06.337]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:23:06.337] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:23:06.337]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 07:23:06.337] STEP: Creating a kubernetes client
I0117 07:23:06.338] Jan 17 07:23:06.332: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 07:23:06.338] STEP: Building a namespace api object, basename provisioning
I0117 07:23:06.441] STEP: Waiting for a default service account to be provisioned in namespace
I0117 07:23:06.476] [It] should fail if subpath with backstepping is outside the volume [Slow]
I0117 07:23:06.477]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0117 07:23:06.477] STEP: deploying csi gce-pd driver
I0117 07:23:06.511] Jan 17 07:23:06.511: INFO: Found CI service account key at /etc/service-account/service-account.json
I0117 07:23:06.512] Jan 17 07:23:06.511: INFO: Running cp [/etc/service-account/service-account.json /tmp/f7e85cf2-0b71-455c-98e2-06d477984927/cloud-sa.json]
I0117 07:23:06.551] Jan 17 07:23:06.551: INFO: Shredding file /tmp/f7e85cf2-0b71-455c-98e2-06d477984927/cloud-sa.json
I0117 07:23:06.552] Jan 17 07:23:06.551: INFO: Running shred [--remove /tmp/f7e85cf2-0b71-455c-98e2-06d477984927/cloud-sa.json]
... skipping 24 lines ...
I0117 07:23:07.326] Jan 17 07:23:07.325: INFO: creating *v1.StatefulSet: provisioning-1803/csi-gce-pd-controller
I0117 07:23:07.407] Jan 17 07:23:07.407: INFO: Test running for native CSI Driver, not checking metrics
I0117 07:23:07.408] Jan 17 07:23:07.407: INFO: Creating resource for dynamic PV
I0117 07:23:07.408] STEP: creating a StorageClass provisioning-1803-pd.csi.storage.gke.io-scx6dm2
I0117 07:23:07.483] STEP: creating a claim
I0117 07:23:07.563] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-rzs8
I0117 07:23:07.603] STEP: Checking for subpath error in container status
I0117 07:23:37.672] Jan 17 07:23:37.672: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-rzs8" in namespace "provisioning-1803"
I0117 07:23:37.714] Jan 17 07:23:37.713: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-rzs8" to be fully deleted
I0117 07:23:39.788] STEP: Deleting pod
I0117 07:23:39.788] Jan 17 07:23:39.788: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-rzs8" in namespace "provisioning-1803"
I0117 07:23:39.822] STEP: Deleting pvc
I0117 07:23:39.823] Jan 17 07:23:39.822: INFO: Deleting PersistentVolumeClaim "pvc-dmlgc"
... skipping 244 lines ...
I0117 07:24:17.253] 
I0117 07:24:17.253]       Driver gluster doesn't support DynamicPV -- skipping
I0117 07:24:17.253] 
I0117 07:24:17.254]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:24:17.254] ------------------------------
I0117 07:24:17.254] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (block volmode)] volumeMode 
I0117 07:24:17.254]   should fail in binding dynamic provisioned PV to PVC
I0117 07:24:17.254]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0117 07:24:17.255] [BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0117 07:24:17.255]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:24:17.255] Jan 17 07:24:17.236: INFO: Driver local doesn't support DynamicPV -- skipping
I0117 07:24:17.255] [AfterEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0117 07:24:17.255]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:24:17.256] [sig-storage] In-tree Volumes
I0117 07:24:17.256] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:24:17.256]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 07:24:17.257]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 07:24:17.257]     [Testpattern: Dynamic PV (block volmode)] volumeMode
I0117 07:24:17.257]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:24:17.257]       should fail in binding dynamic provisioned PV to PVC [BeforeEach]
I0117 07:24:17.257]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0117 07:24:17.257] 
I0117 07:24:17.258]       Driver local doesn't support DynamicPV -- skipping
I0117 07:24:17.258] 
I0117 07:24:17.258]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:24:17.258] ------------------------------
... skipping 526 lines ...
I0117 07:39:49.661] 
I0117 07:39:49.661]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1536
I0117 07:39:49.661] ------------------------------
I0117 07:39:49.662] SSSSSSS
I0117 07:39:49.662] ------------------------------
I0117 07:39:49.662] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 07:39:49.662]   should fail if subpath directory is outside the volume [Slow]
I0117 07:39:49.662]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 07:39:49.662] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:39:49.663]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:39:49.663] Jan 17 07:39:49.646: INFO: Driver local doesn't support DynamicPV -- skipping
I0117 07:39:49.663] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:39:49.663]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:39:49.664] [sig-storage] In-tree Volumes
I0117 07:39:49.664] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:39:49.664]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 07:39:49.664]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 07:39:49.664]     [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:39:49.665]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:39:49.665]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0117 07:39:49.665]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 07:39:49.665] 
I0117 07:39:49.665]       Driver local doesn't support DynamicPV -- skipping
I0117 07:39:49.665] 
I0117 07:39:49.666]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:39:49.666] ------------------------------
... skipping 509 lines ...
I0117 07:41:39.867] 
I0117 07:41:39.867]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:41:39.867] ------------------------------
I0117 07:41:39.867] SSSSSSSSSSSSSS
I0117 07:41:39.868] ------------------------------
I0117 07:41:39.868] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0117 07:41:39.868]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0117 07:41:39.868]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 07:41:39.869] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 07:41:39.869]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:41:39.869] Jan 17 07:41:39.854: INFO: Driver local doesn't support DynamicPV -- skipping
I0117 07:41:39.869] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 07:41:39.869]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:41:39.870] [sig-storage] In-tree Volumes
I0117 07:41:39.870] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:41:39.870]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 07:41:39.870]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 07:41:39.871]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0117 07:41:39.871]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:41:39.871]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0117 07:41:39.871]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 07:41:39.871] 
I0117 07:41:39.871]       Driver local doesn't support DynamicPV -- skipping
I0117 07:41:39.871] 
I0117 07:41:39.871]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:41:39.872] ------------------------------
... skipping 402 lines ...
I0117 07:44:35.112] STEP: Destroying namespace "provisioning-5879" for this suite.
I0117 07:44:57.221] Jan 17 07:44:57.221: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 07:44:58.566] Jan 17 07:44:58.566: INFO: namespace provisioning-5879 deletion completed in 23.453440199s
I0117 07:44:58.567] •S
I0117 07:44:58.567] ------------------------------
I0117 07:44:58.567] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 07:44:58.567]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0117 07:44:58.568]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 07:44:58.568] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:44:58.568]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:44:58.568] Jan 17 07:44:58.566: INFO: Driver local doesn't support DynamicPV -- skipping
I0117 07:44:58.569] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:44:58.569]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:44:58.569] [sig-storage] In-tree Volumes
I0117 07:44:58.570] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:44:58.570]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 07:44:58.570]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 07:44:58.570]     [Testpattern: Dynamic PV (default fs)] subPath
I0117 07:44:58.571]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:44:58.571]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0117 07:44:58.572]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 07:44:58.572] 
I0117 07:44:58.572]       Driver local doesn't support DynamicPV -- skipping
I0117 07:44:58.572] 
I0117 07:44:58.572]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:44:58.573] ------------------------------
... skipping 479 lines ...
I0117 07:49:51.135] STEP: Trying to apply a random label on the found node.
I0117 07:49:51.211] STEP: verifying the node has the label failure-domain.beta.kubernetes.io/zone equivalence-e2e-test
I0117 07:49:51.247] STEP: Trying to schedule RC with Pod Affinity should success.
I0117 07:49:56.419] STEP: Remove node failure domain label
I0117 07:49:56.420] STEP: removing the label failure-domain.beta.kubernetes.io/zone off the node test-9b5ed62f1f-minion-group-vgn3
I0117 07:49:56.500] STEP: verifying the node doesn't have the label failure-domain.beta.kubernetes.io/zone
I0117 07:49:56.535] STEP: Trying to schedule another equivalent Pod should fail due to node label has been removed.
I0117 07:49:56.571] STEP: Considering event: 
I0117 07:49:56.572] Type = [Normal], Name = [with-label-bb19cf61-3821-476b-8582-32c5c9c3579c.15ea9d2211722224], Reason = [Scheduled], Message = [Successfully assigned equivalence-cache-3966/with-label-bb19cf61-3821-476b-8582-32c5c9c3579c to test-9b5ed62f1f-minion-group-vgn3]
I0117 07:49:56.572] STEP: Considering event: 
I0117 07:49:56.572] Type = [Normal], Name = [with-label-bb19cf61-3821-476b-8582-32c5c9c3579c.15ea9d2245244b5d], Reason = [Pulled], Message = [Container image "k8s.gcr.io/pause:3.1" already present on machine]
I0117 07:49:56.573] STEP: Considering event: 
I0117 07:49:56.573] Type = [Normal], Name = [with-label-bb19cf61-3821-476b-8582-32c5c9c3579c.15ea9d224868fb3f], Reason = [Created], Message = [Created container with-label-bb19cf61-3821-476b-8582-32c5c9c3579c]
... skipping 126 lines ...
I0117 07:51:15.989] Jan 17 07:51:15.988: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 07:51:16.025] STEP: Destroying namespace "pv-9843" for this suite.
I0117 07:51:30.133] Jan 17 07:51:30.133: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 07:51:31.499] Jan 17 07:51:31.499: INFO: namespace pv-9843 deletion completed in 15.474221629s
I0117 07:51:31.500] [AfterEach] [sig-storage] [Serial] Volume metrics
I0117 07:51:31.500]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0117 07:51:31.534] Jan 17 07:51:31.533: INFO: Failed to get pvc pv-9843/pvc-9jrlb: persistentvolumeclaims "pvc-9jrlb" not found
I0117 07:51:31.534] •SSSSSSSSSSSS
I0117 07:51:31.534] ------------------------------
I0117 07:51:31.535] [sig-scheduling] NoExecuteTaintManager Single Pod [Serial] 
I0117 07:51:31.535]   removing taint cancels eviction
I0117 07:51:31.535]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/scheduling/taints.go:279
I0117 07:51:31.535] [BeforeEach] [sig-scheduling] NoExecuteTaintManager Single Pod [Serial]
... skipping 103 lines ...
I0117 07:54:37.749] STEP: Destroying namespace "dns-config-map-3383" for this suite.
I0117 07:54:43.863] Jan 17 07:54:43.863: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 07:54:45.214] Jan 17 07:54:45.214: INFO: namespace dns-config-map-3383 deletion completed in 7.465303104s
I0117 07:54:45.215] •SSS
I0117 07:54:45.215] ------------------------------
I0117 07:54:45.215] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0117 07:54:45.215]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0117 07:54:45.216]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 07:54:45.216] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0117 07:54:45.216]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 07:54:45.216] Jan 17 07:54:45.214: INFO: Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0117 07:54:45.216] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0117 07:54:45.217]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 07:54:45.217] [sig-storage] CSI Volumes
I0117 07:54:45.217] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 07:54:45.218]   [Driver: pd.csi.storage.gke.io][Serial]
I0117 07:54:45.218]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0117 07:54:45.218]     [Testpattern: Inline-volume (default fs)] subPath
I0117 07:54:45.219]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 07:54:45.219]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0117 07:54:45.219]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 07:54:45.219] 
I0117 07:54:45.219]       Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0117 07:54:45.220] 
I0117 07:54:45.220]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 07:54:45.220] ------------------------------
... skipping 802 lines ...
I0117 08:06:03.112] Jan 17 08:06:03.111: INFO: GCE PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9" appears to have successfully detached from "test-9b5ed62f1f-minion-group-39p2".
I0117 08:06:03.112] STEP: defer: cleaning up PD-RW test env
I0117 08:06:03.112] Jan 17 08:06:03.111: INFO: defer cleanup errors can usually be ignored
I0117 08:06:03.112] STEP: defer: delete host0Pod
I0117 08:06:03.148] STEP: defer: detach and delete PDs
I0117 08:06:03.148] Jan 17 08:06:03.148: INFO: Detaching GCE PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9" from node "test-9b5ed62f1f-minion-group-39p2".
I0117 08:06:06.686] Jan 17 08:06:06.685: INFO: Error detaching PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9": googleapi: Error 400: INVALID_USAGE - No attached disk found with device name 'test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9'
I0117 08:06:06.686] STEP: Waiting for PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9" to detach from "test-9b5ed62f1f-minion-group-39p2"
I0117 08:06:06.687] Jan 17 08:06:06.685: INFO: Waiting for GCE PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9" to detach from node "test-9b5ed62f1f-minion-group-39p2".
I0117 08:06:06.944] Jan 17 08:06:06.944: INFO: GCE PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9" appears to have successfully detached from "test-9b5ed62f1f-minion-group-39p2".
I0117 08:06:06.945] STEP: Deleting PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9"
I0117 08:06:09.439] Jan 17 08:06:09.439: INFO: Successfully deleted PD "test-9b5ed62f1f-60006f5c-2fa0-4583-a2fa-58fe1ac54fa9".
I0117 08:06:09.439] STEP: defer: re-create host0 node object
... skipping 249 lines ...
I0117 08:11:06.688] STEP: Destroying namespace "node-lease-test-5151" for this suite.
I0117 08:11:12.797] Jan 17 08:11:12.797: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 08:11:14.134] Jan 17 08:11:14.134: INFO: namespace node-lease-test-5151 deletion completed in 7.445902493s
I0117 08:11:14.135] •S
I0117 08:11:14.135] ------------------------------
I0117 08:11:14.135] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0117 08:11:14.135]   should fail if subpath directory is outside the volume [Slow]
I0117 08:11:14.135]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:11:14.136] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0117 08:11:14.136]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:11:14.136] Jan 17 08:11:14.134: INFO: Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0117 08:11:14.136] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0117 08:11:14.136]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 08:11:14.137] [sig-storage] CSI Volumes
I0117 08:11:14.137] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 08:11:14.137]   [Driver: pd.csi.storage.gke.io][Serial]
I0117 08:11:14.137]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0117 08:11:14.138]     [Testpattern: Inline-volume (default fs)] subPath
I0117 08:11:14.138]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 08:11:14.138]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0117 08:11:14.138]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:11:14.138] 
I0117 08:11:14.139]       Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0117 08:11:14.139] 
I0117 08:11:14.139]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 08:11:14.139] ------------------------------
... skipping 2026 lines ...
I0117 08:27:55.529] Jan 17 08:27:55.528: INFO: Waiting up to 1m0s for node test-9b5ed62f1f-minion-group-l4gr condition Ready to be true
I0117 08:27:55.563] STEP: Deleting pod
I0117 08:27:55.563] Jan 17 08:27:55.563: INFO: Deleting pod "pod-subpath-test-gcepd-preprovisionedpv-bzj9" in namespace "provisioning-9233"
I0117 08:27:55.597] STEP: Deleting pv and pvc
I0117 08:27:55.598] Jan 17 08:27:55.597: INFO: Deleting PersistentVolumeClaim "pvc-nttw2"
I0117 08:27:55.636] Jan 17 08:27:55.636: INFO: Deleting PersistentVolume "gcepd-dxvww"
I0117 08:27:57.135] Jan 17 08:27:57.134: INFO: error deleting PD "test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c": googleapi: Error 400: The disk resource 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/disks/test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c' is already being used by 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-l4gr', resourceInUseByAnotherResource
I0117 08:27:57.136] Jan 17 08:27:57.134: INFO: Couldn't delete PD "test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/disks/test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c' is already being used by 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-l4gr', resourceInUseByAnotherResource
I0117 08:28:03.781] Jan 17 08:28:03.780: INFO: error deleting PD "test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c": googleapi: Error 400: The disk resource 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/disks/test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c' is already being used by 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-l4gr', resourceInUseByAnotherResource
I0117 08:28:03.781] Jan 17 08:28:03.780: INFO: Couldn't delete PD "test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/disks/test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c' is already being used by 'projects/k8s-gce-upg-1-5-1-6-up-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-l4gr', resourceInUseByAnotherResource
I0117 08:28:11.103] Jan 17 08:28:11.103: INFO: Successfully deleted PD "test-9b5ed62f1f-8e130b2b-1744-46b4-a511-6abd5b695b8c".
I0117 08:28:11.103] Jan 17 08:28:11.103: INFO: In-tree plugin kubernetes.io/gce-pd is not migrated, not validating any metrics
I0117 08:28:11.103] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:28:11.104]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0117 08:28:11.104] Jan 17 08:28:11.103: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 08:28:11.143] STEP: Destroying namespace "provisioning-9233" for this suite.
... skipping 639 lines ...
I0117 08:33:40.261] STEP: Destroying namespace "volume-4436" for this suite.
I0117 08:34:02.369] Jan 17 08:34:02.369: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 08:34:03.716] Jan 17 08:34:03.715: INFO: namespace volume-4436 deletion completed in 23.454709125s
I0117 08:34:03.716] •SSSSSSSSSSSSSSSSSS
I0117 08:34:03.716] ------------------------------
I0117 08:34:03.717] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode 
I0117 08:34:03.717]   should fail to create pod by failing to mount volume [Slow]
I0117 08:34:03.717]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0117 08:34:03.717] [BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0117 08:34:03.717]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:34:03.717] Jan 17 08:34:03.716: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 08:34:03.718] [AfterEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0117 08:34:03.718]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 08:34:03.719] [sig-storage] CSI Volumes
I0117 08:34:03.719] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 08:34:03.719]   [Driver: pd.csi.storage.gke.io][Serial]
I0117 08:34:03.719]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0117 08:34:03.719]     [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0117 08:34:03.720]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 08:34:03.720]       should fail to create pod by failing to mount volume [Slow] [BeforeEach]
I0117 08:34:03.720]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0117 08:34:03.720] 
I0117 08:34:03.720]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 08:34:03.720] 
I0117 08:34:03.720]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 08:34:03.721] ------------------------------
... skipping 368 lines ...
I0117 08:34:11.374] 
I0117 08:34:11.375]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 08:34:11.375] ------------------------------
I0117 08:34:11.375] SSSSSSSSSSSSSSSSSSSS
I0117 08:34:11.375] ------------------------------
I0117 08:34:11.375] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0117 08:34:11.376]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0117 08:34:11.376]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 08:34:11.376] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:34:11.376]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:34:11.376] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:34:11.377]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 08:34:11.377] STEP: Creating a kubernetes client
I0117 08:34:11.377] Jan 17 08:34:11.359: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 08:34:11.377] STEP: Building a namespace api object, basename provisioning
I0117 08:34:11.467] STEP: Waiting for a default service account to be provisioned in namespace
I0117 08:34:11.502] [It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0117 08:34:11.502]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 08:34:13.650] Jan 17 08:34:13.649: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://34.82.121.244 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-7860 hostexec-test-9b5ed62f1f-minion-group-l4gr -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0117 08:34:14.342] Jan 17 08:34:14.342: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0117 08:34:14.343] Jan 17 08:34:14.342: INFO: stdout: "0\n"
I0117 08:34:14.343] Jan 17 08:34:14.342: INFO: Requires at least 1 scsi fs localSSD 
I0117 08:34:14.343] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0117 08:34:21.816] [sig-storage] In-tree Volumes
I0117 08:34:21.816] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 08:34:21.816]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 08:34:21.816]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 08:34:21.816]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:34:21.817]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 08:34:21.817]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [It]
I0117 08:34:21.817]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 08:34:21.817] 
I0117 08:34:21.817]       Requires at least 1 scsi fs localSSD 
I0117 08:34:21.818] 
I0117 08:34:21.818]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0117 08:34:21.818] ------------------------------
... skipping 408 lines ...
I0117 08:40:19.251] 
I0117 08:40:19.252]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 08:40:19.252] ------------------------------
I0117 08:40:19.252] SSSSSSSSSSSSS
I0117 08:40:19.252] ------------------------------
I0117 08:40:19.252] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 08:40:19.253]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0117 08:40:19.253]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 08:40:19.253] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:40:19.253]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:40:19.253] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:40:19.253]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 08:40:19.254] STEP: Creating a kubernetes client
I0117 08:40:19.254] Jan 17 08:40:19.248: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 08:40:19.254] STEP: Building a namespace api object, basename provisioning
I0117 08:40:19.357] STEP: Waiting for a default service account to be provisioned in namespace
I0117 08:40:19.392] [It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0117 08:40:19.393]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 08:40:19.393] STEP: deploying csi gce-pd driver
I0117 08:40:19.427] Jan 17 08:40:19.426: INFO: Found CI service account key at /etc/service-account/service-account.json
I0117 08:40:19.427] Jan 17 08:40:19.426: INFO: Running cp [/etc/service-account/service-account.json /tmp/90e20701-cecc-4b76-93a8-9fb708cc6769/cloud-sa.json]
I0117 08:40:19.476] Jan 17 08:40:19.475: INFO: Shredding file /tmp/90e20701-cecc-4b76-93a8-9fb708cc6769/cloud-sa.json
I0117 08:40:19.476] Jan 17 08:40:19.475: INFO: Running shred [--remove /tmp/90e20701-cecc-4b76-93a8-9fb708cc6769/cloud-sa.json]
... skipping 24 lines ...
I0117 08:40:20.260] Jan 17 08:40:20.259: INFO: creating *v1.StatefulSet: provisioning-2447/csi-gce-pd-controller
I0117 08:40:20.315] Jan 17 08:40:20.314: INFO: Test running for native CSI Driver, not checking metrics
I0117 08:40:20.315] Jan 17 08:40:20.314: INFO: Creating resource for dynamic PV
I0117 08:40:20.315] STEP: creating a StorageClass provisioning-2447-pd.csi.storage.gke.io-sczj2hb
I0117 08:40:20.408] STEP: creating a claim
I0117 08:40:20.490] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-kshc
I0117 08:40:20.530] STEP: Checking for subpath error in container status
I0117 08:40:50.641] Jan 17 08:40:50.641: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-kshc" in namespace "provisioning-2447"
I0117 08:40:50.686] Jan 17 08:40:50.685: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-kshc" to be fully deleted
I0117 08:40:56.773] STEP: Deleting pod
I0117 08:40:56.773] Jan 17 08:40:56.773: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-kshc" in namespace "provisioning-2447"
I0117 08:40:56.814] STEP: Deleting pvc
I0117 08:40:56.815] Jan 17 08:40:56.814: INFO: Deleting PersistentVolumeClaim "pvc-plwks"
... skipping 559 lines ...
I0117 08:47:25.090] STEP: Destroying namespace "provisioning-3065" for this suite.
I0117 08:47:31.198] Jan 17 08:47:31.197: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 08:47:32.617] Jan 17 08:47:32.617: INFO: namespace provisioning-3065 deletion completed in 7.529971018s
I0117 08:47:32.617] •SSSSSSSSS
I0117 08:47:32.618] ------------------------------
I0117 08:47:32.618] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 08:47:32.618]   should fail if subpath directory is outside the volume [Slow]
I0117 08:47:32.619]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:47:32.619] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:47:32.619]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:47:32.619] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:47:32.619]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 08:47:32.620] STEP: Creating a kubernetes client
I0117 08:47:32.620] Jan 17 08:47:32.617: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 08:47:32.620] STEP: Building a namespace api object, basename provisioning
I0117 08:47:32.762] STEP: Waiting for a default service account to be provisioned in namespace
I0117 08:47:32.797] [It] should fail if subpath directory is outside the volume [Slow]
I0117 08:47:32.798]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:47:32.798] STEP: deploying csi gce-pd driver
I0117 08:47:32.831] Jan 17 08:47:32.831: INFO: Found CI service account key at /etc/service-account/service-account.json
I0117 08:47:32.832] Jan 17 08:47:32.831: INFO: Running cp [/etc/service-account/service-account.json /tmp/9d9c090d-eb60-462b-a7be-a9515fbbc823/cloud-sa.json]
I0117 08:47:32.873] Jan 17 08:47:32.872: INFO: Shredding file /tmp/9d9c090d-eb60-462b-a7be-a9515fbbc823/cloud-sa.json
I0117 08:47:32.873] Jan 17 08:47:32.872: INFO: Running shred [--remove /tmp/9d9c090d-eb60-462b-a7be-a9515fbbc823/cloud-sa.json]
... skipping 24 lines ...
I0117 08:47:33.648] Jan 17 08:47:33.648: INFO: creating *v1.StatefulSet: provisioning-2444/csi-gce-pd-controller
I0117 08:47:33.709] Jan 17 08:47:33.709: INFO: Test running for native CSI Driver, not checking metrics
I0117 08:47:33.710] Jan 17 08:47:33.709: INFO: Creating resource for dynamic PV
I0117 08:47:33.710] STEP: creating a StorageClass provisioning-2444-pd.csi.storage.gke.io-sczcwhq
I0117 08:47:33.782] STEP: creating a claim
I0117 08:47:33.881] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-hpck
I0117 08:47:33.920] STEP: Checking for subpath error in container status
I0117 08:48:03.990] Jan 17 08:48:03.989: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-hpck" in namespace "provisioning-2444"
I0117 08:48:04.032] Jan 17 08:48:04.032: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-hpck" to be fully deleted
I0117 08:48:12.103] STEP: Deleting pod
I0117 08:48:12.103] Jan 17 08:48:12.103: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-hpck" in namespace "provisioning-2444"
I0117 08:48:12.138] STEP: Deleting pvc
I0117 08:48:12.138] Jan 17 08:48:12.137: INFO: Deleting PersistentVolumeClaim "pvc-bk68q"
... skipping 26 lines ...
I0117 08:48:13.088] STEP: Destroying namespace "provisioning-2444" for this suite.
I0117 08:48:35.195] Jan 17 08:48:35.194: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 08:48:36.530] Jan 17 08:48:36.530: INFO: namespace provisioning-2444 deletion completed in 23.441923211s
I0117 08:48:36.530] •S
I0117 08:48:36.531] ------------------------------
I0117 08:48:36.531] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0117 08:48:36.531]   should fail if subpath directory is outside the volume [Slow]
I0117 08:48:36.531]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:48:36.532] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:48:36.532]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:48:36.532] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:48:36.533]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 08:48:36.533] STEP: Creating a kubernetes client
I0117 08:48:36.533] Jan 17 08:48:36.530: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 08:48:36.533] STEP: Building a namespace api object, basename provisioning
I0117 08:48:36.675] STEP: Waiting for a default service account to be provisioned in namespace
I0117 08:48:36.711] [It] should fail if subpath directory is outside the volume [Slow]
I0117 08:48:36.711]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:48:38.860] Jan 17 08:48:38.859: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://34.82.121.244 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-4481 hostexec-test-9b5ed62f1f-minion-group-vgn3 -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0117 08:48:39.565] Jan 17 08:48:39.565: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0117 08:48:39.566] Jan 17 08:48:39.565: INFO: stdout: "0\n"
I0117 08:48:39.566] Jan 17 08:48:39.565: INFO: Requires at least 1 scsi fs localSSD 
I0117 08:48:39.567] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0117 08:48:47.059] [sig-storage] In-tree Volumes
I0117 08:48:47.059] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 08:48:47.059]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 08:48:47.060]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 08:48:47.060]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 08:48:47.060]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 08:48:47.061]       should fail if subpath directory is outside the volume [Slow] [It]
I0117 08:48:47.061]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0117 08:48:47.061] 
I0117 08:48:47.061]       Requires at least 1 scsi fs localSSD 
I0117 08:48:47.061] 
I0117 08:48:47.062]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0117 08:48:47.062] ------------------------------
... skipping 525 lines ...
I0117 08:53:16.838] Jan 17 08:53:16.837: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 08:53:16.873] STEP: Destroying namespace "pv-2574" for this suite.
I0117 08:53:30.981] Jan 17 08:53:30.980: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 08:53:32.323] Jan 17 08:53:32.322: INFO: namespace pv-2574 deletion completed in 15.449447938s
I0117 08:53:32.323] [AfterEach] [sig-storage] [Serial] Volume metrics
I0117 08:53:32.323]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0117 08:53:32.357] Jan 17 08:53:32.357: INFO: Failed to get pvc pv-2574/pvc-zbbpf: persistentvolumeclaims "pvc-zbbpf" not found
I0117 08:53:32.358] •SS
I0117 08:53:32.358] ------------------------------
I0117 08:53:32.358] [sig-storage] In-tree Volumes [Driver: hostPathSymlink] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0117 08:53:32.359]   should unmount if pod is gracefully deleted while kubelet is down [Disruptive][Slow][LinuxOnly]
I0117 08:53:32.359]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:321
I0117 08:53:32.359] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
... skipping 520 lines ...
I0117 08:59:28.518] 
I0117 08:59:28.518]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 08:59:28.518] ------------------------------
I0117 08:59:28.518] SSSSSS
I0117 08:59:28.519] ------------------------------
I0117 08:59:28.519] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 08:59:28.519]   should fail if subpath with backstepping is outside the volume [Slow]
I0117 08:59:28.519]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0117 08:59:28.519] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:59:28.520]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 08:59:28.520] Jan 17 08:59:28.511: INFO: Driver local doesn't support DynamicPV -- skipping
I0117 08:59:28.520] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:59:28.520]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 08:59:28.521] [sig-storage] In-tree Volumes
I0117 08:59:28.521] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 08:59:28.521]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0117 08:59:28.521]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0117 08:59:28.521]     [Testpattern: Dynamic PV (default fs)] subPath
I0117 08:59:28.522]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 08:59:28.522]       should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
I0117 08:59:28.522]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0117 08:59:28.522] 
I0117 08:59:28.522]       Driver local doesn't support DynamicPV -- skipping
I0117 08:59:28.522] 
I0117 08:59:28.523]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 08:59:28.523] ------------------------------
... skipping 514 lines ...
I0117 09:09:03.413] 
I0117 09:09:03.413]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 09:09:03.413] ------------------------------
I0117 09:09:03.413] SSSSSSSSSSSSS
I0117 09:09:03.413] ------------------------------
I0117 09:09:03.414] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0117 09:09:03.414]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0117 09:09:03.414]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 09:09:03.414] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 09:09:03.414]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 09:09:03.414] Jan 17 09:09:03.409: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 09:09:03.415] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 09:09:03.415]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0117 09:09:03.415] [sig-storage] CSI Volumes
I0117 09:09:03.415] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0117 09:09:03.415]   [Driver: pd.csi.storage.gke.io][Serial]
I0117 09:09:03.415]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0117 09:09:03.416]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0117 09:09:03.416]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0117 09:09:03.416]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0117 09:09:03.416]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0117 09:09:03.416] 
I0117 09:09:03.416]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0117 09:09:03.416] 
I0117 09:09:03.416]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 09:09:03.417] ------------------------------
... skipping 502 lines ...
I0117 09:14:46.422] Jan 17 09:14:46.422: INFO: GCE PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db" appears to have successfully detached from "test-9b5ed62f1f-minion-group-l4gr".
I0117 09:14:46.423] STEP: defer: cleaning up PD-RW test env
I0117 09:14:46.423] Jan 17 09:14:46.422: INFO: defer cleanup errors can usually be ignored
I0117 09:14:46.423] STEP: defer: delete host0Pod
I0117 09:14:46.458] STEP: defer: detach and delete PDs
I0117 09:14:46.458] Jan 17 09:14:46.458: INFO: Detaching GCE PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db" from node "test-9b5ed62f1f-minion-group-l4gr".
I0117 09:14:49.915] Jan 17 09:14:49.914: INFO: Error detaching PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db": googleapi: Error 400: INVALID_USAGE - No attached disk found with device name 'test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db'
I0117 09:14:49.915] STEP: Waiting for PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db" to detach from "test-9b5ed62f1f-minion-group-l4gr"
I0117 09:14:49.915] Jan 17 09:14:49.914: INFO: Waiting for GCE PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db" to detach from node "test-9b5ed62f1f-minion-group-l4gr".
I0117 09:14:50.163] Jan 17 09:14:50.163: INFO: GCE PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db" appears to have successfully detached from "test-9b5ed62f1f-minion-group-l4gr".
I0117 09:14:50.163] STEP: Deleting PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db"
I0117 09:14:52.586] Jan 17 09:14:52.586: INFO: Successfully deleted PD "test-9b5ed62f1f-b1a524ae-dd91-4f85-88ba-52336039b0db".
I0117 09:14:52.586] [AfterEach] [sig-storage] Pod Disks
... skipping 915 lines ...
I0117 09:25:19.359] Jan 17 09:25:19.359: INFO: Pod "volume-prep-provisioning-930": Phase="Succeeded", Reason="", readiness=false. Elapsed: 28.536092247s
I0117 09:25:19.360] STEP: Saw pod success
I0117 09:25:19.360] Jan 17 09:25:19.359: INFO: Pod "volume-prep-provisioning-930" satisfied condition "success or failure"
I0117 09:25:19.360] Jan 17 09:25:19.359: INFO: Deleting pod "volume-prep-provisioning-930" in namespace "provisioning-930"
I0117 09:25:19.407] Jan 17 09:25:19.406: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-930" to be fully deleted
I0117 09:25:19.441] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vnqm
I0117 09:25:19.478] STEP: Checking for subpath error in container status
I0117 09:25:55.592] Jan 17 09:25:55.592: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vnqm" in namespace "provisioning-930"
I0117 09:25:55.646] Jan 17 09:25:55.645: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vnqm" to be fully deleted
I0117 09:25:55.684] STEP: Deleting pod
I0117 09:25:55.685] Jan 17 09:25:55.684: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vnqm" in namespace "provisioning-930"
I0117 09:25:55.733] STEP: Deleting pvc
I0117 09:25:55.734] Jan 17 09:25:55.733: INFO: Deleting PersistentVolumeClaim "pvc-mpgnt"
... skipping 311 lines ...
I0117 09:27:08.635] 
I0117 09:27:08.635]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:123
I0117 09:27:08.635] ------------------------------
I0117 09:27:08.635] SSSSSSSSS
I0117 09:27:08.635] ------------------------------
I0117 09:27:08.636] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 09:27:08.636]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0117 09:27:08.636]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 09:27:08.636] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 09:27:08.637]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0117 09:27:08.637] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0117 09:27:08.637]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0117 09:27:08.637] STEP: Creating a kubernetes client
I0117 09:27:08.637] Jan 17 09:27:08.632: INFO: >>> kubeConfig: /workspace/.kube/config
I0117 09:27:08.639] STEP: Building a namespace api object, basename provisioning
I0117 09:27:08.757] STEP: Waiting for a default service account to be provisioned in namespace
I0117 09:27:08.791] [It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0117 09:27:08.791]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0117 09:27:08.791] STEP: deploying csi gce-pd driver
I0117 09:27:08.827] Jan 17 09:27:08.826: INFO: Found CI service account key at /etc/service-account/service-account.json
I0117 09:27:08.827] Jan 17 09:27:08.826: INFO: Running cp [/etc/service-account/service-account.json /tmp/284bbfcc-7359-48e7-9fd1-060154a1314e/cloud-sa.json]
I0117 09:27:08.871] Jan 17 09:27:08.870: INFO: Shredding file /tmp/284bbfcc-7359-48e7-9fd1-060154a1314e/cloud-sa.json
I0117 09:27:08.871] Jan 17 09:27:08.870: INFO: Running shred [--remove /tmp/284bbfcc-7359-48e7-9fd1-060154a1314e/cloud-sa.json]
... skipping 24 lines ...
I0117 09:27:09.766] Jan 17 09:27:09.764: INFO: creating *v1.StatefulSet: provisioning-567/csi-gce-pd-controller
I0117 09:27:09.838] Jan 17 09:27:09.837: INFO: Test running for native CSI Driver, not checking metrics
I0117 09:27:09.838] Jan 17 09:27:09.837: INFO: Creating resource for dynamic PV
I0117 09:27:09.839] STEP: creating a StorageClass provisioning-567-pd.csi.storage.gke.io-scxzfjh
I0117 09:27:09.911] STEP: creating a claim
I0117 09:27:10.011] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-7nhn
I0117 09:27:10.059] STEP: Checking for subpath error in container status
I0117 09:27:40.139] Jan 17 09:27:40.139: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-7nhn" in namespace "provisioning-567"
I0117 09:27:40.180] Jan 17 09:27:40.180: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-7nhn" to be fully deleted
I0117 09:27:52.250] STEP: Deleting pod
I0117 09:27:52.250] Jan 17 09:27:52.249: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-7nhn" in namespace "provisioning-567"
I0117 09:27:52.284] STEP: Deleting pvc
I0117 09:27:52.285] Jan 17 09:27:52.284: INFO: Deleting PersistentVolumeClaim "pvc-l7nht"
... skipping 440 lines ...
I0117 09:31:05.527] [BeforeEach] [sig-storage] [Serial] Volume metrics
I0117 09:31:05.527]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:53
I0117 09:31:05.563] Jan 17 09:31:05.562: INFO: Default storage class: "standard"
I0117 09:31:05.604] [It] should create prometheus metrics for volume provisioning errors [Slow]
I0117 09:31:05.604]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:136
I0117 09:31:05.687] STEP: Creating an invalid storageclass
I0117 09:31:05.800] STEP: Creating a pod and expecting it to fail
I0117 09:33:05.949] Jan 17 09:33:05.946: INFO: Deleting pod "pv-8273"/"pvc-tester-cdtgd"
I0117 09:33:05.949] Jan 17 09:33:05.946: INFO: Deleting pod "pvc-tester-cdtgd" in namespace "pv-8273"
I0117 09:33:05.996] Jan 17 09:33:05.994: INFO: Wait up to 5m0s for pod "pvc-tester-cdtgd" to be fully deleted
I0117 09:33:06.030] STEP: Checking failure metrics
I0117 09:33:06.106] [AfterEach] [sig-storage] [Serial] Volume metrics
I0117 09:33:06.106]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0117 09:33:06.107] Jan 17 09:33:06.106: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0117 09:33:06.142] STEP: Destroying namespace "pv-8273" for this suite.
I0117 09:33:20.255] Jan 17 09:33:20.254: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0117 09:33:21.599] Jan 17 09:33:21.598: INFO: namespace pv-8273 deletion completed in 15.456397903s
I0117 09:33:21.599] [AfterEach] [sig-storage] [Serial] Volume metrics
I0117 09:33:21.599]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0117 09:33:21.635] Jan 17 09:33:21.634: INFO: Failed to get pvc pv-8273/pvc-d54q7: persistentvolumeclaims "pvc-d54q7" not found
I0117 09:33:21.672] •SSSSSSSSSS
I0117 09:33:21.676] ------------------------------
I0117 09:33:21.676] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0117 09:33:21.676]   should unmount if pod is gracefully deleted while kubelet is down [Disruptive][Slow][LinuxOnly]
I0117 09:33:21.677]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:321
I0117 09:33:21.677] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
... skipping 287 lines ...
I0117 09:36:37.516] 
I0117 09:36:37.517]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0117 09:36:37.517] ------------------------------
I0117 09:36:37.517] SS
I0117 09:36:37.517] ------------------------------
I0117 09:36:37.517] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0117 09:36:37.517]   should fail</