This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 177 succeeded
Started2020-01-11 09:06
Elapsed6h31m
Revision
Buildergke-prow-default-pool-cf4891d4-qhc1
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/0252cd90-d3ab-4df8-ba7a-ab4a69fece84/targets/test'}}
pod84af7ed2-3451-11ea-9fef-d200904e1a96
resultstorehttps://source.cloud.google.com/results/invocations/0252cd90-d3ab-4df8-ba7a-ab4a69fece84/targets/test
infra-commitb82ca85d5
job-versionv1.15.8-beta.1.30+14ede42c4fe699
master_os_imagecos-73-11647-163-0
node_os_imagecos-73-11647-163-0
pod84af7ed2-3451-11ea-9fef-d200904e1a96
revisionv1.15.8-beta.1.30+14ede42c4fe699

Test Failures


diffResources 0.00s

Error: 1 leaked resources
+default-route-b1dddbed6e971d68  default  10.178.0.0/20  default                   1000
				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 177 Passed Tests

Show 4252 Skipped Tests

Error lines from build-log.txt

... skipping 15 lines ...
I0111 09:06:13.954] process 48 exited with code 0 after 0.0m
I0111 09:06:13.955] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0111 09:06:13.955] Root: /workspace
I0111 09:06:13.955] cd to /workspace
I0111 09:06:13.955] Configure environment...
I0111 09:06:13.956] Call:  git show -s --format=format:%ct HEAD
W0111 09:06:13.960] fatal: not a git repository (or any of the parent directories): .git
I0111 09:06:13.961] process 61 exited with code 128 after 0.0m
W0111 09:06:13.961] Unable to print commit date for HEAD
I0111 09:06:13.961] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0111 09:06:14.549] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0111 09:06:14.879] process 62 exited with code 0 after 0.0m
I0111 09:06:14.880] Call:  gcloud config get-value account
... skipping 313 lines ...
W0111 09:10:17.049] Trying to find master named 'test-9b5ed62f1f-master'
W0111 09:10:17.049] Looking for address 'test-9b5ed62f1f-master-ip'
W0111 09:10:18.017] Using master: test-9b5ed62f1f-master (external IP: 35.197.80.246)
I0111 09:10:18.117] Waiting up to 300 seconds for cluster initialization.
I0111 09:10:18.118] 
I0111 09:10:18.118]   This will continually check to see if the API for kubernetes is reachable.
I0111 09:10:18.118]   This may time out if there was some uncaught error during start up.
I0111 09:10:18.118] 
I0111 09:11:26.910] ................Kubernetes cluster created.
I0111 09:11:27.068] Cluster "k8s-jkns-e2e-kubeadm-ci-1-6_test-9b5ed62f1f" set.
I0111 09:11:27.222] User "k8s-jkns-e2e-kubeadm-ci-1-6_test-9b5ed62f1f" set.
I0111 09:11:27.384] Context "k8s-jkns-e2e-kubeadm-ci-1-6_test-9b5ed62f1f" created.
I0111 09:11:27.544] Switched to context "k8s-jkns-e2e-kubeadm-ci-1-6_test-9b5ed62f1f".
... skipping 19 lines ...
I0111 09:12:07.898] NAME                                STATUS                     ROLES    AGE   VERSION
I0111 09:12:07.898] test-9b5ed62f1f-master              Ready,SchedulingDisabled   <none>   21s   v1.15.8-beta.1.30+14ede42c4fe699
I0111 09:12:07.899] test-9b5ed62f1f-minion-group-22xv   Ready                      <none>   16s   v1.15.8-beta.1.30+14ede42c4fe699
I0111 09:12:07.899] test-9b5ed62f1f-minion-group-2b68   Ready                      <none>   19s   v1.15.8-beta.1.30+14ede42c4fe699
I0111 09:12:07.899] test-9b5ed62f1f-minion-group-z3w7   Ready                      <none>   17s   v1.15.8-beta.1.30+14ede42c4fe699
I0111 09:12:08.239] Validate output:
I0111 09:12:08.550] NAME                 STATUS    MESSAGE             ERROR
I0111 09:12:08.551] etcd-0               Healthy   {"health":"true"}   
I0111 09:12:08.551] scheduler            Healthy   ok                  
I0111 09:12:08.551] controller-manager   Healthy   ok                  
I0111 09:12:08.551] etcd-1               Healthy   {"health":"true"}   
I0111 09:12:08.559] Cluster validation succeeded
W0111 09:12:08.659] Done, listing cluster services:
... skipping 359 lines ...
I0111 09:15:40.145] Jan 11 09:15:40.143: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 09:15:40.192] STEP: Destroying namespace "pv-8896" for this suite.
I0111 09:15:54.332] Jan 11 09:15:54.328: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 09:15:55.971] Jan 11 09:15:55.967: INFO: namespace pv-8896 deletion completed in 15.775164965s
I0111 09:15:55.971] [AfterEach] [sig-storage] [Serial] Volume metrics
I0111 09:15:55.972]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0111 09:15:56.012] Jan 11 09:15:56.011: INFO: Failed to get pvc pv-8896/pvc-jh5x9: persistentvolumeclaims "pvc-jh5x9" not found
I0111 09:15:56.014] •SSSSSSSS
I0111 09:15:56.015] ------------------------------
I0111 09:15:56.015] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow] 
I0111 09:15:56.015]   should concurrently access the single volume from pods on different node
I0111 09:15:56.016]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:300
I0111 09:15:56.016] [BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
... skipping 121 lines ...
I0111 09:15:56.071] 
I0111 09:15:56.072]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:15:56.072] ------------------------------
I0111 09:15:56.073] S
I0111 09:15:56.073] ------------------------------
I0111 09:15:56.073] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 09:15:56.074]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:15:56.074]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:15:56.075] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:15:56.075]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:15:56.076] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:15:56.076]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 09:15:56.076] STEP: Creating a kubernetes client
I0111 09:15:56.079] Jan 11 09:15:56.023: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 09:15:56.080] STEP: Building a namespace api object, basename provisioning
I0111 09:15:56.154] STEP: Waiting for a default service account to be provisioned in namespace
I0111 09:15:56.196] [It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:15:56.196]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:15:58.374] Jan 11 09:15:58.373: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.80.246 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-3763 hostexec-test-9b5ed62f1f-minion-group-2b68 -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0111 09:15:59.058] Jan 11 09:15:59.058: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0111 09:15:59.058] Jan 11 09:15:59.058: INFO: stdout: "0\n"
I0111 09:15:59.058] Jan 11 09:15:59.058: INFO: Requires at least 1 scsi fs localSSD 
I0111 09:15:59.059] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0111 09:16:06.813] [sig-storage] In-tree Volumes
I0111 09:16:06.814] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:16:06.814]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 09:16:06.814]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 09:16:06.814]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:16:06.814]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:16:06.815]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [It]
I0111 09:16:06.815]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:16:06.815] 
I0111 09:16:06.815]       Requires at least 1 scsi fs localSSD 
I0111 09:16:06.815] 
I0111 09:16:06.815]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0111 09:16:06.815] ------------------------------
... skipping 49 lines ...
I0111 09:16:06.824] 
I0111 09:16:06.824]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:16:06.824] ------------------------------
I0111 09:16:06.824] SSSSSSSSS
I0111 09:16:06.824] ------------------------------
I0111 09:16:06.825] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0111 09:16:06.825]   should fail if subpath directory is outside the volume [Slow]
I0111 09:16:06.825]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 09:16:06.825] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 09:16:06.825]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:16:06.826] Jan 11 09:16:06.810: INFO: Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0111 09:16:06.826] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 09:16:06.826]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:16:06.826] [sig-storage] CSI Volumes
I0111 09:16:06.827] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:16:06.827]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 09:16:06.827]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 09:16:06.827]     [Testpattern: Inline-volume (default fs)] subPath
I0111 09:16:06.827]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:16:06.828]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0111 09:16:06.828]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 09:16:06.828] 
I0111 09:16:06.828]       Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0111 09:16:06.828] 
I0111 09:16:06.828]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:16:06.828] ------------------------------
... skipping 262 lines ...
I0111 09:18:05.997] I0111 09:18:05.996621    8503 runners.go:180] daemonrestart10-cb963fcc-b5f0-4dc4-9178-679ff89d7fb3 Pods: 10 out of 10 created, 10 running, 0 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0111 09:18:05.997] [It] Kubelet should not restart containers across restart
I0111 09:18:05.997]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_restart.go:299
I0111 09:18:06.084] Jan 11 09:18:06.084: INFO: Checking if Daemon kubelet on node 35.230.36.39 is up by polling for a 200 on its /healthz endpoint
I0111 09:18:11.511] Jan 11 09:18:11.511: INFO: Killing Daemon kubelet on node 35.230.36.39
I0111 09:18:11.972] Jan 11 09:18:11.972: INFO: Checking if Daemon kubelet on node 35.230.36.39 is up by polling for a 200 on its /healthz endpoint
I0111 09:18:17.462] Jan 11 09:18:17.462: INFO: node 35.230.36.39 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0111 09:18:17.462] 	stdout: 000
I0111 09:18:17.462] 	stderr: 
I0111 09:18:22.455] Jan 11 09:18:22.454: INFO: Checking if Daemon kubelet on node 34.82.206.220 is up by polling for a 200 on its /healthz endpoint
I0111 09:18:27.916] Jan 11 09:18:27.915: INFO: Killing Daemon kubelet on node 34.82.206.220
I0111 09:18:28.379] Jan 11 09:18:28.379: INFO: Checking if Daemon kubelet on node 34.82.206.220 is up by polling for a 200 on its /healthz endpoint
I0111 09:18:33.831] Jan 11 09:18:33.831: INFO: node 34.82.206.220 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0111 09:18:33.832] 	stdout: 000
I0111 09:18:33.832] 	stderr: 
I0111 09:18:38.799] Jan 11 09:18:38.798: INFO: node 34.82.206.220 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0111 09:18:38.799] 	stdout: 000
I0111 09:18:38.799] 	stderr: 
I0111 09:18:43.833] Jan 11 09:18:43.833: INFO: Checking if Daemon kubelet on node 34.82.68.107 is up by polling for a 200 on its /healthz endpoint
I0111 09:18:49.285] Jan 11 09:18:49.285: INFO: Killing Daemon kubelet on node 34.82.68.107
I0111 09:18:49.720] Jan 11 09:18:49.719: INFO: Checking if Daemon kubelet on node 34.82.68.107 is up by polling for a 200 on its /healthz endpoint
I0111 09:18:55.228] Jan 11 09:18:55.227: INFO: node 34.82.68.107 exec command, 'curl -s -o /dev/null -I -w "%{http_code}" http://localhost:10255/healthz' failed with exitcode 7: 
I0111 09:18:55.231] 	stdout: 000
I0111 09:18:55.231] 	stderr: 
I0111 09:19:00.228] [AfterEach] [sig-apps] DaemonRestart [Disruptive]
I0111 09:19:00.228]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0111 09:19:00.229] Jan 11 09:19:00.228: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 09:19:00.272] STEP: Destroying namespace "daemonrestart-9387" for this suite.
... skipping 970 lines ...
I0111 09:25:14.225] 
I0111 09:25:14.225]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:996
I0111 09:25:14.225] ------------------------------
I0111 09:25:14.225] S
I0111 09:25:14.226] ------------------------------
I0111 09:25:14.226] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0111 09:25:14.226]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:25:14.226]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:25:14.226] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:25:14.227]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:25:14.227] Jan 11 09:25:14.189: INFO: Driver local doesn't support DynamicPV -- skipping
I0111 09:25:14.231] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:25:14.231]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:25:14.232] [sig-storage] In-tree Volumes
I0111 09:25:14.233] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:25:14.233]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 09:25:14.233]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 09:25:14.233]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:25:14.233]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:25:14.234]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 09:25:14.234]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:25:14.234] 
I0111 09:25:14.234]       Driver local doesn't support DynamicPV -- skipping
I0111 09:25:14.234] 
I0111 09:25:14.235]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:25:14.235] ------------------------------
... skipping 68 lines ...
I0111 09:25:27.382] 
I0111 09:25:27.382]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/persistent_volumes-local.go:1197
I0111 09:25:27.383] ------------------------------
I0111 09:25:27.383] S
I0111 09:25:27.383] ------------------------------
I0111 09:25:27.384] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0111 09:25:27.384]   should fail if subpath with backstepping is outside the volume [Slow]
I0111 09:25:27.384]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0111 09:25:27.385] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:25:27.385]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:25:27.385] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:25:27.385]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 09:25:27.386] STEP: Creating a kubernetes client
I0111 09:25:27.386] Jan 11 09:25:27.376: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 09:25:27.386] STEP: Building a namespace api object, basename provisioning
I0111 09:25:27.527] STEP: Waiting for a default service account to be provisioned in namespace
I0111 09:25:27.571] [It] should fail if subpath with backstepping is outside the volume [Slow]
I0111 09:25:27.572]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0111 09:25:27.574] STEP: deploying csi gce-pd driver
I0111 09:25:27.612] Jan 11 09:25:27.611: INFO: Found CI service account key at /etc/service-account/service-account.json
I0111 09:25:27.613] Jan 11 09:25:27.611: INFO: Running cp [/etc/service-account/service-account.json /tmp/f8359fba-e6ab-4d1a-9155-fc3e80d0f162/cloud-sa.json]
I0111 09:25:27.667] Jan 11 09:25:27.663: INFO: Shredding file /tmp/f8359fba-e6ab-4d1a-9155-fc3e80d0f162/cloud-sa.json
I0111 09:25:27.668] Jan 11 09:25:27.663: INFO: Running shred [--remove /tmp/f8359fba-e6ab-4d1a-9155-fc3e80d0f162/cloud-sa.json]
... skipping 24 lines ...
I0111 09:25:28.608] Jan 11 09:25:28.607: INFO: creating *v1.StatefulSet: provisioning-5754/csi-gce-pd-controller
I0111 09:25:28.692] Jan 11 09:25:28.691: INFO: Test running for native CSI Driver, not checking metrics
I0111 09:25:28.693] Jan 11 09:25:28.691: INFO: Creating resource for dynamic PV
I0111 09:25:28.693] STEP: creating a StorageClass provisioning-5754-pd.csi.storage.gke.io-sczsrpt
I0111 09:25:28.788] STEP: creating a claim
I0111 09:25:28.884] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-gs9c
I0111 09:25:28.930] STEP: Checking for subpath error in container status
I0111 09:25:57.016] Jan 11 09:25:57.014: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-gs9c" in namespace "provisioning-5754"
I0111 09:25:57.061] Jan 11 09:25:57.061: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-gs9c" to be fully deleted
I0111 09:26:01.145] STEP: Deleting pod
I0111 09:26:01.146] Jan 11 09:26:01.145: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-gs9c" in namespace "provisioning-5754"
I0111 09:26:01.186] STEP: Deleting pvc
I0111 09:26:01.186] Jan 11 09:26:01.186: INFO: Deleting PersistentVolumeClaim "pvc-5fqzp"
... skipping 452 lines ...
I0111 09:28:11.379] STEP: Destroying namespace "dns-config-map-6431" for this suite.
I0111 09:28:17.505] Jan 11 09:28:17.505: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 09:28:19.053] Jan 11 09:28:19.053: INFO: namespace dns-config-map-6431 deletion completed in 7.67413742s
I0111 09:28:19.053] •SSSSSSSSSSS
I0111 09:28:19.053] ------------------------------
I0111 09:28:19.054] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 09:28:19.054]   should fail if subpath directory is outside the volume [Slow]
I0111 09:28:19.054]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 09:28:19.054] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:28:19.055]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:28:19.055] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:28:19.055]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 09:28:19.055] STEP: Creating a kubernetes client
I0111 09:28:19.055] Jan 11 09:28:19.053: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 09:28:19.055] STEP: Building a namespace api object, basename provisioning
I0111 09:28:19.209] STEP: Waiting for a default service account to be provisioned in namespace
I0111 09:28:19.250] [It] should fail if subpath directory is outside the volume [Slow]
I0111 09:28:19.251]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 09:28:21.421] Jan 11 09:28:21.420: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.80.246 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-8694 hostexec-test-9b5ed62f1f-minion-group-z3w7 -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0111 09:28:22.093] Jan 11 09:28:22.093: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0111 09:28:22.094] Jan 11 09:28:22.093: INFO: stdout: "0\n"
I0111 09:28:22.094] Jan 11 09:28:22.093: INFO: Requires at least 1 scsi fs localSSD 
I0111 09:28:22.094] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0111 09:28:29.838] [sig-storage] In-tree Volumes
I0111 09:28:29.839] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:28:29.839]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 09:28:29.839]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 09:28:29.839]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:28:29.839]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:28:29.840]       should fail if subpath directory is outside the volume [Slow] [It]
I0111 09:28:29.840]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 09:28:29.840] 
I0111 09:28:29.840]       Requires at least 1 scsi fs localSSD 
I0111 09:28:29.840] 
I0111 09:28:29.840]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0111 09:28:29.841] ------------------------------
... skipping 243 lines ...
I0111 09:30:46.060] Jan 11 09:30:46.059: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 09:30:46.103] STEP: Destroying namespace "pv-2148" for this suite.
I0111 09:30:52.228] Jan 11 09:30:52.228: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 09:30:53.793] Jan 11 09:30:53.793: INFO: namespace pv-2148 deletion completed in 7.690646366s
I0111 09:30:53.794] [AfterEach] [sig-storage] [Serial] Volume metrics
I0111 09:30:53.794]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0111 09:30:53.794] Jan 11 09:30:53.793: INFO: Failed to get pvc pv-2148/: resource name may not be empty
I0111 09:30:53.795] •SSSSSSSSSSSSSSSSSSSSSSSSSS
I0111 09:30:53.795] ------------------------------
I0111 09:30:53.795] [sig-storage] In-tree Volumes [Driver: nfs] [Testpattern: Dynamic PV (default fs)] subPath 
I0111 09:30:53.795]   should unmount if pod is force deleted while kubelet is down [Disruptive][Slow][LinuxOnly]
I0111 09:30:53.796]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:328
I0111 09:30:53.796] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
... skipping 390 lines ...
I0111 09:35:39.937] 
I0111 09:35:39.937]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:35:39.937] ------------------------------
I0111 09:35:39.937] S
I0111 09:35:39.937] ------------------------------
I0111 09:35:39.938] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0111 09:35:39.938]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:35:39.938]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:35:39.938] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:35:39.938]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:35:39.938] Jan 11 09:35:39.925: INFO: Driver local doesn't support DynamicPV -- skipping
I0111 09:35:39.939] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:35:39.939]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:35:39.939] [sig-storage] In-tree Volumes
I0111 09:35:39.940] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:35:39.940]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 09:35:39.940]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 09:35:39.940]     [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:35:39.940]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:35:39.940]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 09:35:39.941]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:35:39.941] 
I0111 09:35:39.941]       Driver local doesn't support DynamicPV -- skipping
I0111 09:35:39.941] 
I0111 09:35:39.941]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:35:39.941] ------------------------------
... skipping 49 lines ...
I0111 09:35:39.950] 
I0111 09:35:39.950]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:35:39.950] ------------------------------
I0111 09:35:39.950] SSSSS
I0111 09:35:39.950] ------------------------------
I0111 09:35:39.950] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode 
I0111 09:35:39.951]   should fail to create pod by failing to mount volume [Slow]
I0111 09:35:39.951]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0111 09:35:39.951] [BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0111 09:35:39.951]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:35:39.951] Jan 11 09:35:39.928: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 09:35:39.951] [AfterEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0111 09:35:39.952]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:35:39.952] [sig-storage] CSI Volumes
I0111 09:35:39.952] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:35:39.953]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 09:35:39.953]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 09:35:39.953]     [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0111 09:35:39.953]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:35:39.953]       should fail to create pod by failing to mount volume [Slow] [BeforeEach]
I0111 09:35:39.954]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0111 09:35:39.954] 
I0111 09:35:39.954]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 09:35:39.954] 
I0111 09:35:39.954]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:35:39.954] ------------------------------
... skipping 267 lines ...
I0111 09:37:38.225] 
I0111 09:37:38.225]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0111 09:37:38.225] ------------------------------
I0111 09:37:38.225] SSSSSS
I0111 09:37:38.225] ------------------------------
I0111 09:37:38.226] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 09:37:38.226]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:37:38.226]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:37:38.226] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:37:38.226]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:37:38.226] Jan 11 09:37:38.222: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 09:37:38.227] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:37:38.227]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:37:38.227] [sig-storage] CSI Volumes
I0111 09:37:38.228] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:37:38.228]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 09:37:38.228]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 09:37:38.228]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 09:37:38.228]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:37:38.228]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 09:37:38.229]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:37:38.229] 
I0111 09:37:38.229]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 09:37:38.229] 
I0111 09:37:38.229]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:37:38.230] ------------------------------
... skipping 733 lines ...
I0111 09:45:18.026] 
I0111 09:45:18.026]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:228
I0111 09:45:18.026] ------------------------------
I0111 09:45:18.027] SSSSS
I0111 09:45:18.027] ------------------------------
I0111 09:45:18.028] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0111 09:45:18.033]   should fail if subpath with backstepping is outside the volume [Slow]
I0111 09:45:18.033]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0111 09:45:18.034] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:45:18.035]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:45:18.036] Jan 11 09:45:18.012: INFO: Driver local doesn't support DynamicPV -- skipping
I0111 09:45:18.036] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:45:18.039]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:45:18.040] [sig-storage] In-tree Volumes
I0111 09:45:18.041] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:45:18.044]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 09:45:18.047]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 09:45:18.048]     [Testpattern: Dynamic PV (default fs)] subPath
I0111 09:45:18.048]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:45:18.049]       should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
I0111 09:45:18.049]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0111 09:45:18.049] 
I0111 09:45:18.050]       Driver local doesn't support DynamicPV -- skipping
I0111 09:45:18.050] 
I0111 09:45:18.050]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:45:18.051] ------------------------------
... skipping 135 lines ...
I0111 09:46:59.988] STEP: Destroying namespace "taint-multiple-pods-1806" for this suite.
I0111 09:47:10.114] Jan 11 09:47:10.113: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 09:47:11.672] Jan 11 09:47:11.672: INFO: namespace taint-multiple-pods-1806 deletion completed in 11.684115297s
I0111 09:47:11.673] •S
I0111 09:47:11.674] ------------------------------
I0111 09:47:11.675] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0111 09:47:11.676]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0111 09:47:11.677]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 09:47:11.677] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:47:11.678]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:47:11.679] Jan 11 09:47:11.677: INFO: Driver local doesn't support DynamicPV -- skipping
I0111 09:47:11.681] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:47:11.687]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:47:11.689] [sig-storage] In-tree Volumes
I0111 09:47:11.689] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:47:11.692]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 09:47:11.693]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 09:47:11.693]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:47:11.694]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:47:11.694]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 09:47:11.699]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 09:47:11.699] 
I0111 09:47:11.700]       Driver local doesn't support DynamicPV -- skipping
I0111 09:47:11.702] 
I0111 09:47:11.703]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:47:11.704] ------------------------------
... skipping 192 lines ...
I0111 09:49:15.422] 
I0111 09:49:15.422]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:49:15.423] ------------------------------
I0111 09:49:15.423] SSSSSS
I0111 09:49:15.423] ------------------------------
I0111 09:49:15.423] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0111 09:49:15.423]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:49:15.423]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:49:15.423] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 09:49:15.424]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:49:15.424] Jan 11 09:49:15.419: INFO: Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0111 09:49:15.424] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 09:49:15.424]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:49:15.424] [sig-storage] CSI Volumes
I0111 09:49:15.424] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:49:15.425]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 09:49:15.425]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 09:49:15.425]     [Testpattern: Inline-volume (default fs)] subPath
I0111 09:49:15.425]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:49:15.425]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 09:49:15.425]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:49:15.425] 
I0111 09:49:15.425]       Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0111 09:49:15.425] 
I0111 09:49:15.426]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:49:15.426] ------------------------------
... skipping 186 lines ...
I0111 09:51:38.119] STEP: Removing tags from one of the nodes: test-9b5ed62f1f-minion-group-22xv
I0111 09:51:39.172] Jan 11 09:51:39.172: INFO: Sent request to set tags [] on instance: test-9b5ed62f1f-minion-group-22xv
I0111 09:51:39.172] STEP: Accessing serivce through the external ip and examine got no response from the node without tags
I0111 09:51:39.172] Jan 11 09:51:39.172: INFO: Waiting up to 20m0s for satisfying expectedHosts for 15 times
I0111 09:51:40.172] Jan 11 09:51:40.172: INFO: Poking "http://34.82.165.33:29999/hostname"
I0111 09:51:40.250] Jan 11 09:51:40.250: INFO: Poke("http://34.82.165.33:29999/hostname"): success
I0111 09:51:40.250] Jan 11 09:51:40.250: INFO: Error hitting unexpected host: test-9b5ed62f1f-minion-group-22xv, reset counter: 0
I0111 09:51:41.172] Jan 11 09:51:41.172: INFO: Poking "http://34.82.165.33:29999/hostname"
I0111 09:51:41.339] Jan 11 09:51:41.243: INFO: Poke("http://34.82.165.33:29999/hostname"): success
I0111 09:51:41.382] Jan 11 09:51:41.243: INFO: Missing map[test-9b5ed62f1f-minion-group-2b68:{}], got map[test-9b5ed62f1f-minion-group-z3w7:{}]
I0111 09:51:42.172] Jan 11 09:51:42.172: INFO: Poking "http://34.82.165.33:29999/hostname"
I0111 09:51:42.243] Jan 11 09:51:42.243: INFO: Poke("http://34.82.165.33:29999/hostname"): success
I0111 09:51:42.244] Jan 11 09:51:42.243: INFO: Missing map[], got map[test-9b5ed62f1f-minion-group-2b68:{} test-9b5ed62f1f-minion-group-z3w7:{}]
... skipping 1815 lines ...
I0111 09:59:39.960] 
I0111 09:59:39.961]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 09:59:39.961] ------------------------------
I0111 09:59:39.961] SSSSSSSSSSS
I0111 09:59:39.961] ------------------------------
I0111 09:59:39.961] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0111 09:59:39.961]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0111 09:59:39.962]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:59:39.962] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:59:39.964]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 09:59:39.964] Jan 11 09:59:39.952: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0111 09:59:39.964] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:59:39.965]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 09:59:39.967] [sig-storage] CSI Volumes
I0111 09:59:39.967] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 09:59:39.967]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 09:59:39.968]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 09:59:39.968]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 09:59:39.968]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 09:59:39.968]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 09:59:39.969]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0111 09:59:39.969] 
I0111 09:59:39.969]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0111 09:59:39.969] 
I0111 09:59:39.969]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0111 09:59:39.969] ------------------------------
... skipping 189 lines ...
I0111 10:01:18.924] Jan 11 10:01:18.923: INFO: ssh prow@35.230.36.39:22: stdout:    ""
I0111 10:01:18.924] Jan 11 10:01:18.923: INFO: ssh prow@35.230.36.39:22: stderr:    ""
I0111 10:01:18.924] Jan 11 10:01:18.923: INFO: ssh prow@35.230.36.39:22: exit code: 0
I0111 10:01:18.925] Jan 11 10:01:18.923: INFO: Waiting up to 1m0s for node test-9b5ed62f1f-minion-group-22xv condition Ready to be true
I0111 10:01:18.961] STEP: Deleting pod
I0111 10:01:18.961] Jan 11 10:01:18.960: INFO: Deleting pod "pod-subpath-test-gcepd-vjbd" in namespace "provisioning-5579"
I0111 10:01:20.548] Jan 11 10:01:20.548: INFO: error deleting PD "test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562": googleapi: Error 400: The disk resource 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/disks/test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562' is already being used by 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-22xv', resourceInUseByAnotherResource
I0111 10:01:20.549] Jan 11 10:01:20.548: INFO: Couldn't delete PD "test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/disks/test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562' is already being used by 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-22xv', resourceInUseByAnotherResource
I0111 10:01:27.012] Jan 11 10:01:27.011: INFO: error deleting PD "test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562": googleapi: Error 400: The disk resource 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/disks/test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562' is already being used by 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-22xv', resourceInUseByAnotherResource
I0111 10:01:27.013] Jan 11 10:01:27.011: INFO: Couldn't delete PD "test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/disks/test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562' is already being used by 'projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-22xv', resourceInUseByAnotherResource
I0111 10:01:34.464] Jan 11 10:01:34.464: INFO: Successfully deleted PD "test-9b5ed62f1f-bdbce099-ba43-46d5-a05a-2ad457772562".
I0111 10:01:34.465] Jan 11 10:01:34.464: INFO: In-tree plugin kubernetes.io/gce-pd is not migrated, not validating any metrics
I0111 10:01:34.465] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 10:01:34.465]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0111 10:01:34.466] Jan 11 10:01:34.464: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 10:01:34.505] STEP: Destroying namespace "provisioning-5579" for this suite.
... skipping 77 lines ...
I0111 10:01:42.170] 
I0111 10:01:42.171]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 10:01:42.171] ------------------------------
I0111 10:01:42.171] SSSSSS
I0111 10:01:42.171] ------------------------------
I0111 10:01:42.172] [sig-apps] Daemon set [Serial] 
I0111 10:01:42.172]   should retry creating failed daemon pods [Conformance]
I0111 10:01:42.172]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
I0111 10:01:42.172] [BeforeEach] [sig-apps] Daemon set [Serial]
I0111 10:01:42.172]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 10:01:42.172] STEP: Creating a kubernetes client
I0111 10:01:42.173] Jan 11 10:01:42.162: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 10:01:42.173] STEP: Building a namespace api object, basename daemonsets
I0111 10:01:42.304] STEP: Waiting for a default service account to be provisioned in namespace
I0111 10:01:42.343] [BeforeEach] [sig-apps] Daemon set [Serial]
I0111 10:01:42.344]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:103
I0111 10:01:42.657] [It] should retry creating failed daemon pods [Conformance]
I0111 10:01:42.657]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
I0111 10:01:42.658] STEP: Creating a simple DaemonSet "daemon-set"
I0111 10:01:42.703] STEP: Check that daemon pods launch on every node of the cluster.
I0111 10:01:42.772] Jan 11 10:01:42.771: 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
I0111 10:01:42.831] Jan 11 10:01:42.830: INFO: Number of nodes with available pods: 0
I0111 10:01:42.836] Jan 11 10:01:42.830: INFO: Node test-9b5ed62f1f-minion-group-22xv is running more than one daemon pod
... skipping 3 lines ...
I0111 10:01:44.909] Jan 11 10:01:44.872: 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
I0111 10:01:44.913] Jan 11 10:01:44.912: INFO: Number of nodes with available pods: 1
I0111 10:01:44.913] Jan 11 10:01:44.912: INFO: Node test-9b5ed62f1f-minion-group-22xv is running more than one daemon pod
I0111 10:01:45.876] Jan 11 10:01:45.875: 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
I0111 10:01:45.917] Jan 11 10:01:45.917: INFO: Number of nodes with available pods: 3
I0111 10:01:45.917] Jan 11 10:01:45.917: INFO: Number of running nodes: 3, number of available pods: 3
I0111 10:01:45.955] STEP: Set a daemon pod's phase to 'Failed', check that the daemon pod is revived.
I0111 10:01:46.121] Jan 11 10:01:46.118: 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
I0111 10:01:46.156] Jan 11 10:01:46.156: INFO: Number of nodes with available pods: 2
I0111 10:01:46.157] Jan 11 10:01:46.156: INFO: Node test-9b5ed62f1f-minion-group-2b68 is running more than one daemon pod
I0111 10:01:47.195] Jan 11 10:01:47.195: 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
I0111 10:01:47.233] Jan 11 10:01:47.233: INFO: Number of nodes with available pods: 2
I0111 10:01:47.234] Jan 11 10:01:47.233: INFO: Node test-9b5ed62f1f-minion-group-2b68 is running more than one daemon pod
I0111 10:01:48.330] Jan 11 10:01:48.328: 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
I0111 10:01:48.415] Jan 11 10:01:48.371: INFO: Number of nodes with available pods: 2
I0111 10:01:48.416] Jan 11 10:01:48.371: INFO: Node test-9b5ed62f1f-minion-group-2b68 is running more than one daemon pod
I0111 10:01:49.195] Jan 11 10:01:49.194: 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
I0111 10:01:49.233] Jan 11 10:01:49.232: INFO: Number of nodes with available pods: 3
I0111 10:01:49.233] Jan 11 10:01:49.232: INFO: Number of running nodes: 3, number of available pods: 3
I0111 10:01:49.261] STEP: Wait for the failed daemon pod to be completely deleted.
I0111 10:01:49.270] [AfterEach] [sig-apps] Daemon set [Serial]
I0111 10:01:49.271]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:69
I0111 10:01:49.307] STEP: Deleting DaemonSet "daemon-set"
I0111 10:01:49.308] STEP: deleting DaemonSet.extensions daemon-set in namespace daemonsets-5370, will wait for the garbage collector to delete the pods
I0111 10:01:49.457] Jan 11 10:01:49.456: INFO: Deleting DaemonSet.extensions daemon-set took: 55.461941ms
I0111 10:01:50.157] Jan 11 10:01:50.156: INFO: Terminating DaemonSet.extensions daemon-set pods took: 700.221822ms
... skipping 249 lines ...
I0111 10:05:14.906] 
I0111 10:05:14.907]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0111 10:05:14.907] ------------------------------
I0111 10:05:14.907] S
I0111 10:05:14.907] ------------------------------
I0111 10:05:14.908] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0111 10:05:14.908]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0111 10:05:14.908]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 10:05:14.908] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 10:05:14.908]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 10:05:14.909] Jan 11 10:05:14.896: INFO: Driver local doesn't support InlineVolume -- skipping
I0111 10:05:14.909] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 10:05:14.909]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 10:05:14.910] [sig-storage] In-tree Volumes
I0111 10:05:14.910] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 10:05:14.911]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 10:05:14.911]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 10:05:14.911]     [Testpattern: Inline-volume (default fs)] subPath
I0111 10:05:14.911]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 10:05:14.911]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 10:05:14.912]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 10:05:14.912] 
I0111 10:05:14.912]       Driver local doesn't support InlineVolume -- skipping
I0111 10:05:14.912] 
I0111 10:05:14.912]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 10:05:14.912] ------------------------------
... skipping 658 lines ...
I0111 10:20:45.696] 
I0111 10:20:45.696]       Driver local doesn't support InlineVolume -- skipping
I0111 10:20:45.696] 
I0111 10:20:45.697]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 10:20:45.697] ------------------------------
I0111 10:20:45.697] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode 
I0111 10:20:45.697]   should fail to create pod by failing to mount volume [Slow]
I0111 10:20:45.697]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0111 10:20:45.697] [BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0111 10:20:45.698]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 10:20:45.698] [BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0111 10:20:45.698]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 10:20:45.698] STEP: Creating a kubernetes client
I0111 10:20:45.698] Jan 11 10:20:45.688: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 10:20:45.698] STEP: Building a namespace api object, basename volumemode
I0111 10:20:45.832] STEP: Waiting for a default service account to be provisioned in namespace
I0111 10:20:45.868] [It] should fail to create pod by failing to mount volume [Slow]
I0111 10:20:45.868]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0111 10:20:48.015] Jan 11 10:20:48.015: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.80.246 --kubeconfig=/workspace/.kube/config exec --namespace=volumemode-6970 hostexec-test-9b5ed62f1f-minion-group-z3w7 -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0111 10:20:48.664] Jan 11 10:20:48.664: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0111 10:20:48.664] Jan 11 10:20:48.664: INFO: stdout: "0\n"
I0111 10:20:48.664] Jan 11 10:20:48.664: INFO: Requires at least 1 scsi fs localSSD 
I0111 10:20:48.664] [AfterEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
... skipping 7 lines ...
I0111 10:20:56.144] [sig-storage] In-tree Volumes
I0111 10:20:56.144] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 10:20:56.144]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 10:20:56.144]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 10:20:56.144]     [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I0111 10:20:56.145]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 10:20:56.145]       should fail to create pod by failing to mount volume [Slow] [It]
I0111 10:20:56.145]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:168
I0111 10:20:56.145] 
I0111 10:20:56.145]       Requires at least 1 scsi fs localSSD 
I0111 10:20:56.145] 
I0111 10:20:56.146]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0111 10:20:56.146] ------------------------------
... skipping 234 lines ...
I0111 10:22:18.990] Jan 11 10:22:18.989: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 10:22:19.025] STEP: Destroying namespace "pv-561" for this suite.
I0111 10:22:33.133] Jan 11 10:22:33.133: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 10:22:34.655] Jan 11 10:22:34.655: INFO: namespace pv-561 deletion completed in 15.630018552s
I0111 10:22:34.655] [AfterEach] [sig-storage] [Serial] Volume metrics
I0111 10:22:34.655]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0111 10:22:34.689] Jan 11 10:22:34.689: INFO: Failed to get pvc pv-561/pvc-c5hr4: persistentvolumeclaims "pvc-c5hr4" not found
I0111 10:22:34.690] •SSS
I0111 10:22:34.690] ------------------------------
I0111 10:22:34.690] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] provisioning 
I0111 10:22:34.690]   should provision storage with defaults
I0111 10:22:34.691]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/provisioning.go:153
I0111 10:22:34.691] [BeforeEach] [Testpattern: Dynamic PV (default fs)] provisioning
... skipping 371 lines ...
I0111 10:24:20.447] 
I0111 10:24:20.448]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0111 10:24:20.448] ------------------------------
I0111 10:24:20.448] SSSSSSSSSSSSSSSSSSSSSS
I0111 10:24:20.448] ------------------------------
I0111 10:24:20.448] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (block volmode)] volumeMode 
I0111 10:24:20.448]   should fail in binding dynamic provisioned PV to PVC
I0111 10:24:20.449]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0111 10:24:20.449] [BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0111 10:24:20.449]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 10:24:20.449] [BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0111 10:24:20.449]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 10:24:20.449] STEP: Creating a kubernetes client
I0111 10:24:20.450] Jan 11 10:24:20.435: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 10:24:20.450] STEP: Building a namespace api object, basename volumemode
I0111 10:24:20.585] STEP: Waiting for a default service account to be provisioned in namespace
I0111 10:24:20.621] [It] should fail in binding dynamic provisioned PV to PVC
I0111 10:24:20.621]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0111 10:24:20.621] STEP: deploying csi gce-pd driver
I0111 10:24:20.655] Jan 11 10:24:20.654: INFO: Found CI service account key at /etc/service-account/service-account.json
I0111 10:24:20.655] Jan 11 10:24:20.654: INFO: Running cp [/etc/service-account/service-account.json /tmp/b520a970-3ddf-4958-9e7b-9091224cecf5/cloud-sa.json]
I0111 10:24:20.695] Jan 11 10:24:20.694: INFO: Shredding file /tmp/b520a970-3ddf-4958-9e7b-9091224cecf5/cloud-sa.json
I0111 10:24:20.695] Jan 11 10:24:20.694: INFO: Running shred [--remove /tmp/b520a970-3ddf-4958-9e7b-9091224cecf5/cloud-sa.json]
... skipping 210 lines ...
I0111 10:29:31.624] [sig-storage] CSI Volumes
I0111 10:29:31.624] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 10:29:31.625]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 10:29:31.625]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 10:29:31.625]     [Testpattern: Dynamic PV (block volmode)] volumeMode
I0111 10:29:31.625]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 10:29:31.625]       should fail in binding dynamic provisioned PV to PVC
I0111 10:29:31.626]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0111 10:29:31.626] ------------------------------
I0111 10:29:31.626] SSSSSSSSSSSSSSSSSSSSSSSSSS
I0111 10:29:31.626] ------------------------------
I0111 10:29:31.626] [sig-storage] CSI Volumes CSI Topology test using GCE PD driver [Serial] 
I0111 10:29:31.627]   should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding
I0111 10:29:31.627]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0111 10:29:31.627] [BeforeEach] CSI Topology test using GCE PD driver [Serial]
I0111 10:29:31.627]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 10:29:31.627] STEP: Creating a kubernetes client
I0111 10:29:31.627] Jan 11 10:29:31.624: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 10:29:31.628] STEP: Building a namespace api object, basename csitopology
... skipping 27 lines ...
I0111 10:29:32.446] Jan 11 10:29:32.446: INFO: creating *v1.RoleBinding: csitopology-7132/csi-controller-attacher-role-cfg
I0111 10:29:32.483] Jan 11 10:29:32.483: INFO: creating *v1.ClusterRoleBinding: csi-controller-provisioner-role-csitopology-7132
I0111 10:29:32.520] Jan 11 10:29:32.519: INFO: creating *v1.RoleBinding: csitopology-7132/csi-controller-provisioner-role-cfg
I0111 10:29:32.558] Jan 11 10:29:32.557: INFO: creating *v1.ClusterRoleBinding: psp-csi-controller-driver-registrar-role-csitopology-7132
I0111 10:29:32.595] Jan 11 10:29:32.594: INFO: creating *v1.DaemonSet: csitopology-7132/csi-gce-pd-node
I0111 10:29:32.637] Jan 11 10:29:32.637: INFO: creating *v1.StatefulSet: csitopology-7132/csi-gce-pd-controller
I0111 10:29:32.719] [It] should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding
I0111 10:29:32.720]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0111 10:29:32.797] Jan 11 10:29:32.797: INFO: Requires more than one zone
I0111 10:29:32.798] [AfterEach] CSI Topology test using GCE PD driver [Serial]
I0111 10:29:32.798]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0111 10:29:32.798] Jan 11 10:29:32.798: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 10:29:32.843] STEP: Destroying namespace "csitopology-7132" for this suite.
... skipping 27 lines ...
I0111 10:29:57.098] 
I0111 10:29:57.098] S [SKIPPING] [25.474 seconds]
I0111 10:29:57.098] [sig-storage] CSI Volumes
I0111 10:29:57.098] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 10:29:57.099]   CSI Topology test using GCE PD driver [Serial]
I0111 10:29:57.099]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:64
I0111 10:29:57.099]     should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding [It]
I0111 10:29:57.099]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0111 10:29:57.099] 
I0111 10:29:57.100]     Requires more than one zone
I0111 10:29:57.100] 
I0111 10:29:57.100]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:104
I0111 10:29:57.100] ------------------------------
... skipping 485 lines ...
I0111 10:34:52.815] 
I0111 10:34:52.816]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 10:34:52.816] ------------------------------
I0111 10:34:52.816] SS
I0111 10:34:52.816] ------------------------------
I0111 10:34:52.816] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 10:34:52.816]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0111 10:34:52.816]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 10:34:52.817] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 10:34:52.817]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 10:34:52.817] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 10:34:52.817]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0111 10:34:52.817] STEP: Creating a kubernetes client
I0111 10:34:52.818] Jan 11 10:34:52.807: INFO: >>> kubeConfig: /workspace/.kube/config
I0111 10:34:52.818] STEP: Building a namespace api object, basename provisioning
I0111 10:34:52.950] STEP: Waiting for a default service account to be provisioned in namespace
I0111 10:34:52.984] [It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0111 10:34:52.984]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 10:34:57.137] Jan 11 10:34:57.136: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.80.246 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-9085 hostexec-test-9b5ed62f1f-minion-group-zs7d -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0111 10:34:57.788] Jan 11 10:34:57.787: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0111 10:34:57.788] Jan 11 10:34:57.787: INFO: stdout: "0\n"
I0111 10:34:57.788] Jan 11 10:34:57.787: INFO: Requires at least 1 scsi fs localSSD 
I0111 10:34:57.788] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0111 10:35:05.324] [sig-storage] In-tree Volumes
I0111 10:35:05.325] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 10:35:05.325]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 10:35:05.325]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 10:35:05.325]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 10:35:05.325]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 10:35:05.326]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [It]
I0111 10:35:05.326]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 10:35:05.326] 
I0111 10:35:05.326]       Requires at least 1 scsi fs localSSD 
I0111 10:35:05.326] 
I0111 10:35:05.326]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0111 10:35:05.326] ------------------------------
... skipping 1348 lines ...
I0111 11:02:44.449] Jan 11 11:02:44.449: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 11:02:44.484] STEP: Destroying namespace "pv-111" for this suite.
I0111 11:02:50.591] Jan 11 11:02:50.590: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 11:02:52.000] Jan 11 11:02:52.000: INFO: namespace pv-111 deletion completed in 7.516132728s
I0111 11:02:52.001] [AfterEach] [sig-storage] [Serial] Volume metrics
I0111 11:02:52.001]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0111 11:02:52.001] Jan 11 11:02:52.000: INFO: Failed to get pvc pv-111/: resource name may not be empty
I0111 11:02:52.001] •SSSSSSSSSSS
I0111 11:02:52.001] ------------------------------
I0111 11:02:52.001] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow] 
I0111 11:02:52.002]   should access to two volumes with the same volume mode and retain data across pod recreation on different node
I0111 11:02:52.002]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:147
I0111 11:02:52.002] [BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
... skipping 667 lines ...
I0111 11:11:15.720] Jan 11 11:11:15.720: INFO: Pod "volume-prep-provisioning-8498": Phase="Succeeded", Reason="", readiness=false. Elapsed: 28.531641346s
I0111 11:11:15.720] STEP: Saw pod success
I0111 11:11:15.721] Jan 11 11:11:15.720: INFO: Pod "volume-prep-provisioning-8498" satisfied condition "success or failure"
I0111 11:11:15.721] Jan 11 11:11:15.720: INFO: Deleting pod "volume-prep-provisioning-8498" in namespace "provisioning-8498"
I0111 11:11:15.769] Jan 11 11:11:15.768: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-8498" to be fully deleted
I0111 11:11:15.803] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-f69j
I0111 11:11:15.840] STEP: Checking for subpath error in container status
I0111 11:11:35.917] Jan 11 11:11:35.916: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-f69j" in namespace "provisioning-8498"
I0111 11:11:35.964] Jan 11 11:11:35.964: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-f69j" to be fully deleted
I0111 11:11:35.998] STEP: Deleting pod
I0111 11:11:35.998] Jan 11 11:11:35.997: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-f69j" in namespace "provisioning-8498"
I0111 11:11:36.032] STEP: Deleting pvc
I0111 11:11:36.032] Jan 11 11:11:36.031: INFO: Deleting PersistentVolumeClaim "pvc-f7zz2"
... skipping 598 lines ...
I0111 11:18:20.413] Jan 11 11:18:20.413: INFO: GCE PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6" appears to have successfully detached from "test-9b5ed62f1f-minion-group-t976".
I0111 11:18:20.413] STEP: defer: cleaning up PD-RW test env
I0111 11:18:20.413] Jan 11 11:18:20.413: INFO: defer cleanup errors can usually be ignored
I0111 11:18:20.413] STEP: defer: delete host0Pod
I0111 11:18:20.448] STEP: defer: detach and delete PDs
I0111 11:18:20.448] Jan 11 11:18:20.447: INFO: Detaching GCE PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6" from node "test-9b5ed62f1f-minion-group-t976".
I0111 11:18:22.692] Jan 11 11:18:22.692: INFO: Error detaching PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6": googleapi: Error 400: INVALID_USAGE - No attached disk found with device name 'test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6'
I0111 11:18:22.693] STEP: Waiting for PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6" to detach from "test-9b5ed62f1f-minion-group-t976"
I0111 11:18:22.693] Jan 11 11:18:22.692: INFO: Waiting for GCE PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6" to detach from node "test-9b5ed62f1f-minion-group-t976".
I0111 11:18:22.887] Jan 11 11:18:22.887: INFO: GCE PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6" appears to have successfully detached from "test-9b5ed62f1f-minion-group-t976".
I0111 11:18:22.888] STEP: Deleting PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6"
I0111 11:18:25.311] Jan 11 11:18:25.311: INFO: Successfully deleted PD "test-9b5ed62f1f-82b7188a-ad95-4974-8eb1-55183300ddb6".
I0111 11:18:25.311] [AfterEach] [sig-storage] Pod Disks
... skipping 225 lines ...
I0111 11:21:03.194] Jan 11 11:21:03.193: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0111 11:21:03.229] STEP: Destroying namespace "pv-9595" for this suite.
I0111 11:21:17.336] Jan 11 11:21:17.336: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 11:21:18.704] Jan 11 11:21:18.703: INFO: namespace pv-9595 deletion completed in 15.475041297s
I0111 11:21:18.704] [AfterEach] [sig-storage] [Serial] Volume metrics
I0111 11:21:18.704]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0111 11:21:18.738] Jan 11 11:21:18.738: INFO: Failed to get pvc pv-9595/pvc-kgmp9: persistentvolumeclaims "pvc-kgmp9" not found
I0111 11:21:18.739] •SSSSSSS
I0111 11:21:18.739] ------------------------------
I0111 11:21:18.739] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 11:21:18.739]   should unmount if pod is gracefully deleted while kubelet is down [Disruptive][Slow][LinuxOnly]
I0111 11:21:18.739]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:321
I0111 11:21:18.740] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 43 lines ...
I0111 11:21:18.747] 
I0111 11:21:18.747]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 11:21:18.747] ------------------------------
I0111 11:21:18.747] SSSS
I0111 11:21:18.747] ------------------------------
I0111 11:21:18.748] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 11:21:18.748]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0111 11:21:18.748]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 11:21:18.748] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 11:21:18.748]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 11:21:18.749] Jan 11 11:21:18.741: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 11:21:18.749] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 11:21:18.749]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 11:21:18.750] [sig-storage] CSI Volumes
I0111 11:21:18.750] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 11:21:18.750]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 11:21:18.750]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 11:21:18.750]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 11:21:18.750]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 11:21:18.751]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 11:21:18.751]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 11:21:18.751] 
I0111 11:21:18.751]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 11:21:18.751] 
I0111 11:21:18.751]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 11:21:18.751] ------------------------------
... skipping 16 lines ...
I0111 11:21:18.968] Jan 11 11:21:18.968: INFO: Waiting up to 20m0s for service "lb-hc-int" to have a LoadBalancer
I0111 11:22:09.204] STEP: modify the health check interval
I0111 11:22:31.350] STEP: restart kube-controller-manager
I0111 11:22:31.350] Jan 11 11:22:31.349: INFO: Restarting controller-manager via ssh, running: pidof kube-controller-manager | xargs sudo kill
I0111 11:22:32.278] Jan 11 11:22:32.278: INFO: ssh prow@35.197.80.246:22: command:   curl http://localhost:10252/healthz
I0111 11:22:32.279] Jan 11 11:22:32.278: INFO: ssh prow@35.197.80.246:22: stdout:    ""
I0111 11:22:32.279] Jan 11 11:22:32.278: INFO: ssh prow@35.197.80.246: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"
I0111 11:22:32.279] Jan 11 11:22:32.278: INFO: ssh prow@35.197.80.246:22: exit code: 7
I0111 11:22:37.734] STEP: health check should be reconciled
I0111 11:22:37.893] Jan 11 11:22:37.893: INFO: hc.CheckIntervalSec = 7
I0111 11:22:58.027] Jan 11 11:22:58.027: INFO: hc.CheckIntervalSec = 7
I0111 11:23:18.023] Jan 11 11:23:18.022: INFO: hc.CheckIntervalSec = 8
I0111 11:23:18.151] [AfterEach] [sig-network] Services
... skipping 82 lines ...
I0111 11:23:25.668] 
I0111 11:23:25.668]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 11:23:25.668] ------------------------------
I0111 11:23:25.668] SSSS
I0111 11:23:25.668] ------------------------------
I0111 11:23:25.669] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0111 11:23:25.669]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0111 11:23:25.669]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 11:23:25.669] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 11:23:25.669]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 11:23:25.669] Jan 11 11:23:25.659: INFO: Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0111 11:23:25.670] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0111 11:23:25.670]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 11:23:25.670] [sig-storage] CSI Volumes
I0111 11:23:25.671] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 11:23:25.671]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 11:23:25.671]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 11:23:25.671]     [Testpattern: Inline-volume (default fs)] subPath
I0111 11:23:25.671]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 11:23:25.672]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0111 11:23:25.672]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0111 11:23:25.672] 
I0111 11:23:25.672]       Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0111 11:23:25.672] 
I0111 11:23:25.672]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 11:23:25.672] ------------------------------
... skipping 75 lines ...
I0111 11:24:03.336] STEP: Destroying namespace "dns-config-map-1028" for this suite.
I0111 11:24:09.472] Jan 11 11:24:09.472: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 11:24:10.889] Jan 11 11:24:10.889: INFO: namespace dns-config-map-1028 deletion completed in 7.552904379s
I0111 11:24:10.889] •
I0111 11:24:10.889] ------------------------------
I0111 11:24:10.889] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0111 11:24:10.889]   should fail if subpath directory is outside the volume [Slow]
I0111 11:24:10.890]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 11:24:10.890] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 11:24:10.890]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 11:24:10.890] Jan 11 11:24:10.889: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 11:24:10.890] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 11:24:10.891]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 11:24:10.891] [sig-storage] CSI Volumes
I0111 11:24:10.891] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 11:24:10.891]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 11:24:10.892]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 11:24:10.892]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0111 11:24:10.892]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 11:24:10.892]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0111 11:24:10.892]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 11:24:10.892] 
I0111 11:24:10.893]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0111 11:24:10.893] 
I0111 11:24:10.893]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 11:24:10.893] ------------------------------
... skipping 284 lines ...
I0111 11:27:50.133] STEP: Destroying namespace "pv-5412" for this suite.
I0111 11:27:50.810] W0111 11:27:50.809269    8503 reflector.go:302] k8s.io/kubernetes/test/e2e/scheduling/taints.go:139: watch of *v1.Pod ended with: too old resource version: 12560 (13676)
I0111 11:28:04.239] Jan 11 11:28:04.239: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0111 11:28:05.608] Jan 11 11:28:05.608: INFO: namespace pv-5412 deletion completed in 15.475149879s
I0111 11:28:05.608] [AfterEach] [sig-storage] [Serial] Volume metrics
I0111 11:28:05.609]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0111 11:28:05.642] Jan 11 11:28:05.642: INFO: Failed to get pvc pv-5412/pvc-rjjfq: persistentvolumeclaims "pvc-rjjfq" not found
I0111 11:28:05.643] •
I0111 11:28:05.643] ------------------------------
I0111 11:28:05.643] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] volumeIO 
I0111 11:28:05.643]   should write files of various sizes, verify size, validate content [Slow]
I0111 11:28:05.643]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volume_io.go:129
I0111 11:28:05.644] [BeforeEach] [Testpattern: Dynamic PV (default fs)] volumeIO
... skipping 3347 lines ...
I0111 12:18:29.718] 
I0111 12:18:29.718]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 12:18:29.718] ------------------------------
I0111 12:18:29.718] SSSSSSSSSSSSSSSSS
I0111 12:18:29.718] ------------------------------
I0111 12:18:29.719] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0111 12:18:29.719]   should fail if subpath directory is outside the volume [Slow]
I0111 12:18:29.719]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 12:18:29.719] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 12:18:29.719]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 12:18:29.719] Jan 11 12:18:29.711: INFO: Driver local doesn't support DynamicPV -- skipping
I0111 12:18:29.719] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0111 12:18:29.719]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 12:18:29.720] [sig-storage] In-tree Volumes
I0111 12:18:29.720] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 12:18:29.720]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0111 12:18:29.720]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0111 12:18:29.720]     [Testpattern: Dynamic PV (default fs)] subPath
I0111 12:18:29.721]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 12:18:29.721]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0111 12:18:29.721]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 12:18:29.721] 
I0111 12:18:29.721]       Driver local doesn't support DynamicPV -- skipping
I0111 12:18:29.721] 
I0111 12:18:29.721]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0111 12:18:29.722] ------------------------------
I0111 12:18:29.722] SSSSSSS
I0111 12:18:29.722] ------------------------------
I0111 12:18:29.722] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0111 12:18:29.722]   should fail if subpath directory is outside the volume [Slow]
I0111 12:18:29.722]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 12:18:29.722] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 12:18:29.723]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0111 12:18:29.723] Jan 11 12:18:29.712: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0111 12:18:29.723] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 12:18:29.723]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0111 12:18:29.724] [sig-storage] CSI Volumes
I0111 12:18:29.724] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0111 12:18:29.724]   [Driver: pd.csi.storage.gke.io][Serial]
I0111 12:18:29.724]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0111 12:18:29.724]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0111 12:18:29.724]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0111 12:18:29.724]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0111 12:18:29.725]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0111 12:18:29.725] 
I0111 12:18:29.725]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0111 12:18:29.725] 
I0111 12:18:29.725]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0111 12:18:29.725] ------------------------------
... skipping 293 lines ...
I0111 12:20:12.147] Jan 11 12:20:12.146: INFO: 	Container prometheus-to-sd-exporter ready: true, restart count 0
I0111 12:20:12.148] Jan 11 12:20:12.146: INFO: metadata-proxy-v0.1-k7vxx from kube-system started at 2020-01-11 10:12:00 +0000 UTC (2 container statuses recorded)
I0111 12:20:12.148] Jan 11 12:20:12.146: INFO: 	Container metadata-proxy ready: true, restart count 0
I0111 12:20:12.148] Jan 11 12:20:12.146: INFO: 	Container prometheus-to-sd-exporter ready: true, restart count 0
I0111 12:20:12.148] [It] validates MaxPods limit number of pods that are allowed to run [Slow]
I0111 12:20:12.148]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:104
I0111 12:20:12.154] Jan 11 12:20:12.146: INFO: Node: {{ } {test-9b5ed62f1f-minion-group-t976   /api/v1/nodes/test-9b5ed62f1f-minion-group-t976 895882aa-f708-41cc-8c87-de1621bc7f5b 34863 0 2020-01-11 10:10:11 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:n1-standard-2 beta.kubernetes.io/metadata-proxy-ready:true beta.kubernetes.io/os:linux cloud.google.com/metadata-proxy-ready:true failure-domain.beta.kubernetes.io/region:us-west1 failure-domain.beta.kubernetes.io/zone:us-west1-b kubernetes.io/arch:amd64 kubernetes.io/hostname:test-9b5ed62f1f-minion-group-t976 kubernetes.io/os:linux topology.gke.io/zone:us-west1-b] map[csi.volume.kubernetes.io/nodeid:{"csi-hostpath-provisioning-5340":"test-9b5ed62f1f-minion-group-t976","pd.csi.storage.gke.io":"projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-t976"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] nil []  []} {10.64.4.0/24 gce://k8s-jkns-e2e-kubeadm-ci-1-6/us-west1-b/test-9b5ed62f1f-minion-group-t976 false [] nil } {map[attachable-volumes-gce-pd:{{127 0} {<nil>} 127 DecimalSI} cpu:{{2 0} {<nil>} 2 DecimalSI} ephemeral-storage:{{101241290752 0} {<nil>}  BinarySI} hugepages-2Mi:{{0 0} {<nil>} 0 DecimalSI} memory:{{7841861632 0} {<nil>} 7658068Ki BinarySI} pods:{{110 0} {<nil>} 110 DecimalSI}] map[attachable-volumes-gce-pd:{{127 0} {<nil>} 127 DecimalSI} cpu:{{2 0} {<nil>} 2 DecimalSI} ephemeral-storage:{{91117161526 0} {<nil>} 91117161526 DecimalSI} hugepages-2Mi:{{0 0} {<nil>} 0 DecimalSI} memory:{{7579717632 0} {<nil>} 7402068Ki BinarySI} pods:{{110 0} {<nil>} 110 DecimalSI}]  [{FrequentUnregisterNetDevice False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:15:12 +0000 UTC UnregisterNetDevice node is functioning properly} {FrequentKubeletRestart False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:15:11 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:15:13 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:15:14 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {KernelDeadlock False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:10:10 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {ReadonlyFilesystem False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:10:10 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {CorruptDockerOverlay2 False 2020-01-11 12:19:31 +0000 UTC 2020-01-11 10:15:13 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {NetworkUnavailable False 2020-01-11 10:10:27 +0000 UTC 2020-01-11 10:10:27 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2020-01-11 12:19:26 +0000 UTC 2020-01-11 12:14:15 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2020-01-11 12:19:26 +0000 UTC 2020-01-11 12:14:15 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2020-01-11 12:19:26 +0000 UTC 2020-01-11 12:14:15 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2020-01-11 12:19:26 +0000 UTC 2020-01-11 12:14:16 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}] [{InternalIP 10.138.0.6} {ExternalIP 34.83.221.238} {InternalDNS test-9b5ed62f1f-minion-group-t976.c.k8s-jkns-e2e-kubeadm-ci-1-6.internal} {Hostname test-9b5ed62f1f-minion-group-t976.c.k8s-jkns-e2e-kubeadm-ci-1-6.internal}] {{10250}} {efa77e7e2595f62367181b3289a337f1 EFA77E7E-2595-F623-6718-1B3289A337F1 0788d186-5200-40e3-a7c5-94a766841ea5 4.14.94+ Container-Optimized OS from Google docker://18.9.3 v1.15.8-beta.1.30+14ede42c4fe699 v1.15.8-beta.1.30+14ede42c4fe699 linux amd64} [{[quay.io/kubernetes_incubator/nfs-provisioner@sha256:dabc18cedd5cf4f1a245620856b5120e2c063c7d9cd8c113f235dd2fd320dee7 quay.io/kubernetes_incubator/nfs-provisioner:v2.2.0-k8s1.12] 356099717} {[gcr.io/kubernetes-e2e-test-images/volume/gluster@sha256:e2d3308b2d27499d59f120ff46dfc6c4cb307a3f207f02894ecab902583761c9 gcr.io/kubernetes-e2e-test-images/volume/gluster:1.0] 332011484} {[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:6c8574a40816676cd908cfa89d16463002b56ca05fa76d0c912e116bc0ab867e gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.8] 264721247} {[gcr.io/gke-release/gcp-compute-persistent-disk-csi-driver@sha256:65527562c25ba2ecb1a6e8f80bf9aa5ad100be32c3417c4b31119eeea09b6080 gcr.io/gke-release/gcp-compute-persistent-disk-csi-driver:v0.4.0-gke.0] 133411124} {[k8s.gcr.io/kube-proxy:v1.15.8-beta.1.30_14ede42c4fe699] 95490163} {[gcr.io/gke-release/csi-driver-registrar@sha256:035cb65aeba1ef8927c1c97eff69658aaec7000b75beb35fc955c68add26ac0c gcr.io/gke-release/csi-driver-registrar:v1.0.1-gke.0] 69713992} {[gcr.io/gke-release/csi-attacher@sha256:ffb2694c1228c9da3f5516cced5bf54c35b0bc1debd74890fa10fdbe5977dbea gcr.io/gke-release/csi-attacher:v1.0.1-gke.0] 63581374} {[gcr.io/kubernetes-e2e-test-images/resource-consumer@sha256:284fcd047cdec35e1b212919bd878ba5ef72f1da12f49ddc199d219fa8b64f4a gcr.io/kubernetes-e2e-test-images/resource-consumer:1.5] 49818305} {[quay.io/k8scsi/csi-snapshotter@sha256:2668993c32cad68a5ce6f05ec26f6e6cdb9ae8460ec52ed0425aeaed0b588e23 quay.io/k8scsi/csi-snapshotter:v1.0.1] 49172762} {[quay.io/k8scsi/csi-provisioner@sha256:e75af0fded9ac260bbee9c5f1b12ac8a73fec2541230f6becdafafee8e7899af quay.io/k8scsi/csi-provisioner:v1.1.0-rc1] 46920215} {[quay.io/k8scsi/csi-provisioner@sha256:9828f32a1b350bef5f813857c2a3223e8aec79a9762bd78545eaea8fa79735d1 quay.io/k8scsi/csi-provisioner:v1.1.0] 46916119} {[quay.io/k8scsi/csi-attacher@sha256:0aba670b4d9d6b2e720bbf575d733156c676b693ca26501235444490300db838 quay.io/k8scsi/csi-attacher:v1.1.0] 42839085} {[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0] 41861013} {[k8s.gcr.io/coredns@sha256:02382353821b12c21b062c59184e227e001079bb13ebd01f9d3270ba0fcbf1e4 k8s.gcr.io/coredns:1.3.1] 40303560} {[quay.io/k8scsi/hostpathplugin@sha256:89964ca9a7188f1aa251337b4ed4bf42b6245241568ffcc5d6cb10935f0207b5 quay.io/k8scsi/hostpathplugin:v1.1.0-rc1] 27233251} {[nginx@sha256:a3a0c4126587884f8d3090efca87f5af075d7e7ac8308cffc09a5a082d5f4760 nginx:1.14-alpine] 16032814} {[quay.io/k8scsi/csi-node-driver-registrar@sha256:13daf82fb99e951a4bff8ae5fc7c17c3a8fe7130be6400990d8f6076c32d4599 quay.io/k8scsi/csi-node-driver-registrar:v1.1.0] 15815995} {[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[gcr.io/kubernetes-e2e-test-images/dnsutils@sha256:2abeee84efb79c14d731966e034af33bf324d3b26ca28497555511ff094b3ddd gcr.io/kubernetes-e2e-test-images/dnsutils:1.1] 9349974} {[gcr.io/kubernetes-e2e-test-images/hostexec@sha256:90dfe59da029f9e536385037bc64e86cd3d6e55bae613ddbe69e554d79b0639d gcr.io/kubernetes-e2e-test-images/hostexec:1.1] 8490662} {[gcr.io/kubernetes-e2e-test-images/serve-hostname@sha256:bab70473a6d8ef65a22625dc9a1b0f0452e811530fdbe77e4408523460177ff1 gcr.io/kubernetes-e2e-test-images/serve-hostname:1.1] 5851985} {[busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9 busybox:1.29] 1154361} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}] [] [] &NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,}}}
I0111 12:20:12.161] Jan 11 12:20:12.147: INFO: Node: {{ } {test-9b5ed62f1f-minion-group-z3w7   /api/v1/nodes/test-9b5ed62f1f-minion-group-z3w7 77a442da-c220-4aa6-8f6f-c04b4636441b 34879 0 2020-01-11 09:11:50 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:n1-standard-2 beta.kubernetes.io/metadata-proxy-ready:true beta.kubernetes.io/os:linux cloud.google.com/metadata-proxy-ready:true failure-domain.beta.kubernetes.io/region:us-west1 failure-domain.beta.kubernetes.io/zone:us-west1-b kubernetes.io/arch:amd64 kubernetes.io/hostname:test-9b5ed62f1f-minion-group-z3w7 kubernetes.io/os:linux topology.gke.io/zone:us-west1-b] map[csi.volume.kubernetes.io/nodeid:{"csi-hostpath-v0-provisioning-4356":"test-9b5ed62f1f-minion-group-z3w7","pd.csi.storage.gke.io":"projects/k8s-jkns-e2e-kubeadm-ci-1-6/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-z3w7"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] nil []  []} {10.64.2.0/24 gce://k8s-jkns-e2e-kubeadm-ci-1-6/us-west1-b/test-9b5ed62f1f-minion-group-z3w7 false [] nil } {map[attachable-volumes-gce-pd:{{127 0} {<nil>} 127 DecimalSI} cpu:{{2 0} {<nil>} 2 DecimalSI} ephemeral-storage:{{101241290752 0} {<nil>}  BinarySI} hugepages-2Mi:{{0 0} {<nil>} 0 DecimalSI} memory:{{7841861632 0} {<nil>} 7658068Ki BinarySI} pods:{{110 0} {<nil>} 110 DecimalSI}] map[attachable-volumes-gce-pd:{{127 0} {<nil>} 127 DecimalSI} cpu:{{2 0} {<nil>} 2 DecimalSI} ephemeral-storage:{{91117161526 0} {<nil>} 91117161526 DecimalSI} hugepages-2Mi:{{0 0} {<nil>} 0 DecimalSI} memory:{{7579717632 0} {<nil>} 7402068Ki BinarySI} pods:{{110 0} {<nil>} 110 DecimalSI}]  [{CorruptDockerOverlay2 False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:16:02 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {FrequentUnregisterNetDevice False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:16:02 +0000 UTC UnregisterNetDevice node is functioning properly} {KernelDeadlock False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:11:01 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {ReadonlyFilesystem False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:11:01 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {FrequentKubeletRestart False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:16:02 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:16:03 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2020-01-11 12:19:36 +0000 UTC 2020-01-11 09:16:04 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {NetworkUnavailable False 2020-01-11 09:12:06 +0000 UTC 2020-01-11 09:12:06 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2020-01-11 12:19:34 +0000 UTC 2020-01-11 11:36:01 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2020-01-11 12:19:34 +0000 UTC 2020-01-11 11:36:01 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2020-01-11 12:19:34 +0000 UTC 2020-01-11 11:36:01 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2020-01-11 12:19:34 +0000 UTC 2020-01-11 11:36:02 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}] [{InternalIP 10.138.0.4} {ExternalIP 34.82.68.107} {InternalDNS test-9b5ed62f1f-minion-group-z3w7.c.k8s-jkns-e2e-kubeadm-ci-1-6.internal} {Hostname test-9b5ed62f1f-minion-group-z3w7.c.k8s-jkns-e2e-kubeadm-ci-1-6.internal}] {{10250}} {a5df8e40ce0914b89d8e49e5e29895ce A5DF8E40-CE09-14B8-9D8E-49E5E29895CE adf38ec5-a986-49da-910e-270097776275 4.14.94+ Container-Optimized OS from Google docker://18.9.3 v1.15.8-beta.1.30+14ede42c4fe699 v1.15.8-beta.1.30+14ede42c4fe699 linux amd64} [{[quay.io/kubernetes_incubator/nfs-provisioner@sha256:dabc18cedd5cf4f1a245620856b5120e2c063c7d9cd8c113f235dd2fd320dee7 quay.io/kubernetes_incubator/nfs-provisioner:v2.2.0-k8s1.12] 356099717} {[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:6c8574a40816676cd908cfa89d16463002b56ca05fa76d0c912e116bc0ab867e gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.8] 264721247} {[gcr.io/gke-release/gcp-compute-persistent-disk-csi-driver@sha256:65527562c25ba2ecb1a6e8f80bf9aa5ad100be32c3417c4b31119eeea09b6080 gcr.io/gke-release/gcp-compute-persistent-disk-csi-driver:v0.4.0-gke.0] 133411124} {[k8s.gcr.io/kubernetes-dashboard-amd64@sha256:0ae6b69432e78069c5ce2bcde0fe409c5c4d6f0f4d9cd50a17974fea38898747 k8s.gcr.io/kubernetes-dashboard-amd64:v1.10.1] 121711221} {[k8s.gcr.io/kube-proxy:v1.15.8-beta.1.30_14ede42c4fe699] 95490163} {[k8s.gcr.io/fluentd-gcp-scaler@sha256:4f28f10fb89506768910b858f7a18ffb996824a16d70d5ac895e49687df9ff58 k8s.gcr.io/fluentd-gcp-scaler:0.5.2] 90498960} {[k8s.gcr.io/heapster-amd64@sha256:9fae0af136ce0cf4f88393b3670f7139ffc464692060c374d2ae748e13144521 k8s.gcr.io/heapster-amd64:v1.6.0-beta.1] 76016169} {[gcr.io/gke-release/csi-driver-registrar@sha256:035cb65aeba1ef8927c1c97eff69658aaec7000b75beb35fc955c68add26ac0c gcr.io/gke-release/csi-driver-registrar:v1.0.1-gke.0] 69713992} {[quay.io/k8scsi/csi-provisioner@sha256:b23381325dc242cb761861514195efc600f6a403fcd20a2ac4c6b26704def66f quay.io/k8scsi/csi-provisioner:v0.4.1] 53938589} {[gcr.io/kubernetes-e2e-test-images/resource-consumer@sha256:284fcd047cdec35e1b212919bd878ba5ef72f1da12f49ddc199d219fa8b64f4a gcr.io/kubernetes-e2e-test-images/resource-consumer:1.5] 49818305} {[quay.io/k8scsi/csi-attacher@sha256:6fe3b8b1f8a1b3706fe740a278266cef53a9308d9b1ae736a46684c0d0cb52e1 quay.io/k8scsi/csi-attacher:v0.4.1] 49452185} {[k8s.gcr.io/cluster-proportional-autoscaler-amd64@sha256:0abeb6a79ad5aec10e920110446a97fb75180da8680094acb6715de62507f4b0 k8s.gcr.io/cluster-proportional-autoscaler-amd64:1.6.0] 47668785} {[quay.io/k8scsi/driver-registrar@sha256:b7c6cdbd3ad9e585f79365a5b9f5a83551629c2a3235ed0825d7629371963c10 quay.io/k8scsi/driver-registrar:v0.4.1] 47425903} {[k8s.gcr.io/event-exporter@sha256:06acf489ab092b4fb49273e426549a52c0fcd1dbcb67e03d5935b5ee1a899c3e k8s.gcr.io/event-exporter:v0.2.5] 47261019} {[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0] 41861013} {[k8s.gcr.io/coredns@sha256:02382353821b12c21b062c59184e227e001079bb13ebd01f9d3270ba0fcbf1e4 k8s.gcr.io/coredns:1.3.1] 40303560} {[k8s.gcr.io/metrics-server-amd64@sha256:4ca116565ff6a46e582bada50ba3550f95b368db1d2415829241a565a6c38e2a k8s.gcr.io/metrics-server-amd64:v0.3.3] 39933796} {[k8s.gcr.io/addon-resizer@sha256:8075ed6db9baad249d9cf2656c0ecaad8d87133baf20286b1953dfb3fb06e75d k8s.gcr.io/addon-resizer:1.8.5] 35110823} {[quay.io/k8scsi/hostpathplugin@sha256:0aa496f3e7ff7240abbf306e4244a75c5e59cbf2e4dbc246a6db2ca1bc67c6b1 quay.io/k8scsi/hostpathplugin:v0.4.1] 18583451} {[nginx@sha256:a3a0c4126587884f8d3090efca87f5af075d7e7ac8308cffc09a5a082d5f4760 nginx:1.14-alpine] 16032814} {[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[gcr.io/kubernetes-e2e-test-images/dnsutils@sha256:2abeee84efb79c14d731966e034af33bf324d3b26ca28497555511ff094b3ddd gcr.io/kubernetes-e2e-test-images/dnsutils:1.1] 9349974} {[gcr.io/kubernetes-e2e-test-images/hostexec@sha256:90dfe59da029f9e536385037bc64e86cd3d6e55bae613ddbe69e554d79b0639d gcr.io/kubernetes-e2e-test-images/hostexec:1.1] 8490662} {[gcr.io/kubernetes-e2e-test-images/resource-consumer-controller@sha256:c4d8a3668c3c50a17182089c2122c248a8c7c75f382f4454f2f665d28e138e7c gcr.io/kubernetes-e2e-test-images/resource-consumer-controller:1.0] 7195398} {[gcr.io/kubernetes-e2e-test-images/netexec@sha256:203f0e11dde4baf4b08e27de094890eb3447d807c8b3e990b764b799d3a9e8b7 gcr.io/kubernetes-e2e-test-images/netexec:1.1] 6705349} {[gcr.io/kubernetes-e2e-test-images/serve-hostname@sha256:bab70473a6d8ef65a22625dc9a1b0f0452e811530fdbe77e4408523460177ff1 gcr.io/kubernetes-e2e-test-images/serve-hostname:1.1] 5851985} {[k8s.gcr.io/defaultbackend-amd64@sha256:4dc5e07c8ca4e23bddb3153737d7b8c556e5fb2f29c4558b7cd6e6df99c512c7 k8s.gcr.io/defaultbackend-amd64:1.5] 5132544} {[gcr.io/kubernetes-e2e-test-images/mounttest@sha256:c0bd6f0755f42af09a68c9a47fb993136588a76b3200ec305796b60d629d85d2 gcr.io/kubernetes-e2e-test-images/mounttest:1.0] 1563521} {[busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9 busybox:1.29] 1154361} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}] [] [] &NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,}}}
I0111 12:20:12.168] Jan 11 12:20:12.147: INFO: Node: {{ } {test-9b5ed62f1f-minion-group-zs7d   /api/v1/nodes/test-9b5ed62f1f-minion-group-zs7d b1766cc0-cc3f-47f0-99a3-ce5be7c5037c 34983 0 2020-01-11 10:12:00 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:n1-standard-2 beta.kubernetes.io/metadata-proxy-ready:true beta.kubernetes.io/os:linux cloud.google.com/metadata-proxy-ready:true failure-domain.beta.kubernetes.io/region:us-west1 failure-domain.beta.kubernetes.io/zone:us-west1-b kubernetes.io/arch:amd64 kubernetes.io/hostname:test-9b5ed62f1f-minion-group-zs7d kubernetes.io/os:linux topology.gke.io/zone:us-west1-b] map[csi.volume.kubernetes.io/nodeid:{"csi-hostpath-provisioning-419":"test-9b