This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 178 succeeded
Started2020-02-12 15:49
Elapsed6h53m
Revision
Buildergke-prow-default-pool-cf4891d4-7178
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/5894f92a-9ef8-4430-9bff-c4fb12d0cf98/targets/test'}}
pod25fdb1bd-4daf-11ea-8c6e-1aa579f21cc7
resultstorehttps://source.cloud.google.com/results/invocations/5894f92a-9ef8-4430-9bff-c4fb12d0cf98/targets/test
infra-commit1668e26f3
job-versionv1.15.11-beta.0.1+3b43c8064a328d
master_os_imagecos-73-11647-163-0
node_os_imagecos-73-11647-163-0
pod25fdb1bd-4daf-11ea-8c6e-1aa579f21cc7
revisionv1.15.11-beta.0.1+3b43c8064a328d

No Test Failures!


Show 178 Passed Tests

Show 4252 Skipped Tests

Error lines from build-log.txt

... skipping 15 lines ...
I0212 15:49:40.447] process 47 exited with code 0 after 0.0m
I0212 15:49:40.448] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0212 15:49:40.448] Root: /workspace
I0212 15:49:40.448] cd to /workspace
I0212 15:49:40.448] Configure environment...
I0212 15:49:40.449] Call:  git show -s --format=format:%ct HEAD
W0212 15:49:40.454] fatal: not a git repository (or any of the parent directories): .git
I0212 15:49:40.454] process 60 exited with code 128 after 0.0m
W0212 15:49:40.454] Unable to print commit date for HEAD
I0212 15:49:40.455] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0212 15:49:41.019] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0212 15:49:41.355] process 61 exited with code 0 after 0.0m
I0212 15:49:41.355] Call:  gcloud config get-value account
... skipping 322 lines ...
W0212 15:53:01.575] Trying to find master named 'test-9b5ed62f1f-master'
W0212 15:53:01.575] Looking for address 'test-9b5ed62f1f-master-ip'
W0212 15:53:02.491] Using master: test-9b5ed62f1f-master (external IP: 35.197.5.181)
I0212 15:53:02.591] Waiting up to 300 seconds for cluster initialization.
I0212 15:53:02.592] 
I0212 15:53:02.592]   This will continually check to see if the API for kubernetes is reachable.
I0212 15:53:02.592]   This may time out if there was some uncaught error during start up.
I0212 15:53:02.592] 
I0212 15:54:20.446] ................Kubernetes cluster created.
I0212 15:54:20.933] Cluster "k8s-gce-dg-1-6-1-5-dwngr-clu_test-9b5ed62f1f" set.
I0212 15:54:21.288] User "k8s-gce-dg-1-6-1-5-dwngr-clu_test-9b5ed62f1f" set.
I0212 15:54:21.698] Context "k8s-gce-dg-1-6-1-5-dwngr-clu_test-9b5ed62f1f" created.
I0212 15:54:22.114] Switched to context "k8s-gce-dg-1-6-1-5-dwngr-clu_test-9b5ed62f1f".
... skipping 18 lines ...
I0212 15:54:49.504] NAME                                STATUS                     ROLES    AGE   VERSION
I0212 15:54:49.505] test-9b5ed62f1f-master              Ready,SchedulingDisabled   <none>   9s    v1.15.11-beta.0.1+3b43c8064a328d
I0212 15:54:49.506] test-9b5ed62f1f-minion-group-9597   Ready                      <none>   6s    v1.15.11-beta.0.1+3b43c8064a328d
I0212 15:54:49.507] test-9b5ed62f1f-minion-group-h1jm   Ready                      <none>   5s    v1.15.11-beta.0.1+3b43c8064a328d
I0212 15:54:49.507] test-9b5ed62f1f-minion-group-mxb1   Ready                      <none>   6s    v1.15.11-beta.0.1+3b43c8064a328d
I0212 15:54:50.239] Validate output:
I0212 15:54:50.863] NAME                 STATUS    MESSAGE             ERROR
I0212 15:54:50.872] etcd-1               Healthy   {"health":"true"}   
I0212 15:54:50.872] scheduler            Healthy   ok                  
I0212 15:54:50.872] etcd-0               Healthy   {"health":"true"}   
I0212 15:54:50.872] controller-manager   Healthy   ok                  
I0212 15:54:50.885] Cluster validation succeeded
W0212 15:54:50.986] Done, listing cluster services:
... skipping 102 lines ...
I0212 15:55:35.281] 
I0212 15:55:41.758] Feb 12 15:55:41.758: INFO: cluster-master-image: cos-73-11647-163-0
I0212 15:55:41.759] Feb 12 15:55:41.758: INFO: cluster-node-image: cos-73-11647-163-0
I0212 15:55:41.759] Feb 12 15:55:41.758: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 15:55:41.767] Feb 12 15:55:41.767: INFO: Waiting up to 30m0s for all (but 0) nodes to be schedulable
I0212 15:55:41.969] Feb 12 15:55:41.969: INFO: Waiting up to 10m0s for all pods (need at least 8) in namespace 'kube-system' to be running and ready
I0212 15:55:42.152] Feb 12 15:55:42.151: INFO: The status of Pod fluentd-gcp-v3.2.0-h6nkf is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0212 15:55:42.152] Feb 12 15:55:42.151: INFO: 27 / 28 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
I0212 15:55:42.152] Feb 12 15:55:42.151: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0212 15:55:42.153] Feb 12 15:55:42.151: INFO: POD                       NODE                    PHASE    GRACE  CONDITIONS
I0212 15:55:42.153] Feb 12 15:55:42.151: INFO: fluentd-gcp-v3.2.0-h6nkf  test-9b5ed62f1f-master  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-12 15:55:41 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-12 15:55:41 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-12 15:55:41 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-12 15:55:41 +0000 UTC  }]
I0212 15:55:42.153] Feb 12 15:55:42.151: INFO: 
I0212 15:55:44.281] Feb 12 15:55:44.280: INFO: 28 / 28 pods in namespace 'kube-system' are running and ready (2 seconds elapsed)
... skipping 371 lines ...
I0212 16:01:01.320] 
I0212 16:01:01.320]       Driver local doesn't support InlineVolume -- skipping
I0212 16:01:01.320] 
I0212 16:01:01.320]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:01:01.320] ------------------------------
I0212 16:01:01.321] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (block volmode)] volumeMode 
I0212 16:01:01.321]   should fail in binding dynamic provisioned PV to PVC
I0212 16:01:01.321]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0212 16:01:01.321] [BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0212 16:01:01.321]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:01:01.322] [BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0212 16:01:01.322]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 16:01:01.322] STEP: Creating a kubernetes client
I0212 16:01:01.322] Feb 12 16:01:01.309: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 16:01:01.323] STEP: Building a namespace api object, basename volumemode
I0212 16:01:01.463] STEP: Waiting for a default service account to be provisioned in namespace
I0212 16:01:01.501] [It] should fail in binding dynamic provisioned PV to PVC
I0212 16:01:01.501]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0212 16:01:01.502] STEP: deploying csi gce-pd driver
I0212 16:01:01.541] Feb 12 16:01:01.540: INFO: Found CI service account key at /etc/service-account/service-account.json
I0212 16:01:01.541] Feb 12 16:01:01.540: INFO: Running cp [/etc/service-account/service-account.json /tmp/23480d36-7992-45d7-9d63-8bed2c2d41ee/cloud-sa.json]
I0212 16:01:01.583] Feb 12 16:01:01.583: INFO: Shredding file /tmp/23480d36-7992-45d7-9d63-8bed2c2d41ee/cloud-sa.json
I0212 16:01:01.583] Feb 12 16:01:01.583: INFO: Running shred [--remove /tmp/23480d36-7992-45d7-9d63-8bed2c2d41ee/cloud-sa.json]
... skipping 210 lines ...
I0212 16:06:29.104] [sig-storage] CSI Volumes
I0212 16:06:29.105] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 16:06:29.105]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 16:06:29.105]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 16:06:29.105]     [Testpattern: Dynamic PV (block volmode)] volumeMode
I0212 16:06:29.105]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 16:06:29.106]       should fail in binding dynamic provisioned PV to PVC
I0212 16:06:29.106]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0212 16:06:29.106] ------------------------------
I0212 16:06:29.107] SSSSSSS
I0212 16:06:29.107] ------------------------------
I0212 16:06:29.107] [sig-apps] Network Partition [Disruptive] [Slow] [k8s.io] Pods should return to running and ready state after network partition is healed 
I0212 16:06:29.107]   All pods on the unreachable node should be marked as NotReady upon the node turn NotReady AND all pods should be mark back to Ready when the node get back to Ready before pod eviction timeout
... skipping 295 lines ...
I0212 16:07:57.665] 
I0212 16:07:57.665]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:07:57.665] ------------------------------
I0212 16:07:57.666] SSSS
I0212 16:07:57.666] ------------------------------
I0212 16:07:57.666] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0212 16:07:57.667]   should fail if subpath with backstepping is outside the volume [Slow]
I0212 16:07:57.667]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:07:57.667] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 16:07:57.667]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:07:57.667] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 16:07:57.668]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 16:07:57.668] STEP: Creating a kubernetes client
I0212 16:07:57.668] Feb 12 16:07:57.654: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 16:07:57.668] STEP: Building a namespace api object, basename provisioning
I0212 16:07:57.771] STEP: Waiting for a default service account to be provisioned in namespace
I0212 16:07:57.808] [It] should fail if subpath with backstepping is outside the volume [Slow]
I0212 16:07:57.809]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:08:01.967] Feb 12 16:08:01.967: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.5.181 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-2062 hostexec-test-9b5ed62f1f-minion-group-mxb1 -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0212 16:08:02.694] Feb 12 16:08:02.694: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0212 16:08:02.694] Feb 12 16:08:02.694: INFO: stdout: "0\n"
I0212 16:08:02.695] Feb 12 16:08:02.694: INFO: Requires at least 1 scsi fs localSSD 
I0212 16:08:02.695] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0212 16:08:10.324] [sig-storage] In-tree Volumes
I0212 16:08:10.325] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 16:08:10.325]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 16:08:10.325]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 16:08:10.325]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 16:08:10.325]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 16:08:10.326]       should fail if subpath with backstepping is outside the volume [Slow] [It]
I0212 16:08:10.326]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:08:10.326] 
I0212 16:08:10.326]       Requires at least 1 scsi fs localSSD 
I0212 16:08:10.327] 
I0212 16:08:10.327]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0212 16:08:10.327] ------------------------------
... skipping 125 lines ...
I0212 16:08:10.350] 
I0212 16:08:10.350]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:08:10.351] ------------------------------
I0212 16:08:10.351] SSSSS
I0212 16:08:10.351] ------------------------------
I0212 16:08:10.351] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0212 16:08:10.351]   should fail if subpath with backstepping is outside the volume [Slow]
I0212 16:08:10.352]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:08:10.352] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 16:08:10.352]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:08:10.352] Feb 12 16:08:10.332: INFO: Driver local doesn't support DynamicPV -- skipping
I0212 16:08:10.352] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 16:08:10.353]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 16:08:10.353] [sig-storage] In-tree Volumes
I0212 16:08:10.353] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 16:08:10.354]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 16:08:10.354]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 16:08:10.354]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 16:08:10.354]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 16:08:10.354]       should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
I0212 16:08:10.355]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:08:10.355] 
I0212 16:08:10.355]       Driver local doesn't support DynamicPV -- skipping
I0212 16:08:10.355] 
I0212 16:08:10.355]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:08:10.355] ------------------------------
... skipping 1954 lines ...
I0212 16:28:45.324] 
I0212 16:28:45.324]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0212 16:28:45.325] ------------------------------
I0212 16:28:45.325] SSSSSSSSSS
I0212 16:28:45.325] ------------------------------
I0212 16:28:45.325] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0212 16:28:45.325]   should fail if subpath directory is outside the volume [Slow]
I0212 16:28:45.326]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 16:28:45.326] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 16:28:45.326]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:28:45.326] Feb 12 16:28:45.319: INFO: Driver local doesn't support InlineVolume -- skipping
I0212 16:28:45.326] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 16:28:45.327]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 16:28:45.327] [sig-storage] In-tree Volumes
I0212 16:28:45.328] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 16:28:45.328]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 16:28:45.328]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 16:28:45.328]     [Testpattern: Inline-volume (default fs)] subPath
I0212 16:28:45.328]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 16:28:45.329]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0212 16:28:45.329]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 16:28:45.329] 
I0212 16:28:45.329]       Driver local doesn't support InlineVolume -- skipping
I0212 16:28:45.329] 
I0212 16:28:45.329]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:28:45.330] ------------------------------
... skipping 373 lines ...
I0212 16:36:41.356] Feb 12 16:36:41.356: INFO: GCE PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d" appears to have successfully detached from "test-9b5ed62f1f-minion-group-h1jm".
I0212 16:36:41.357] STEP: defer: cleaning up PD-RW test env
I0212 16:36:41.357] Feb 12 16:36:41.356: INFO: defer cleanup errors can usually be ignored
I0212 16:36:41.357] STEP: defer: delete host0Pod
I0212 16:36:41.395] STEP: defer: detach and delete PDs
I0212 16:36:41.395] Feb 12 16:36:41.395: INFO: Detaching GCE PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d" from node "test-9b5ed62f1f-minion-group-h1jm".
I0212 16:36:43.005] Feb 12 16:36:43.004: INFO: Error detaching PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d": googleapi: Error 400: INVALID_USAGE - No attached disk found with device name 'test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d'
I0212 16:36:43.005] STEP: Waiting for PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d" to detach from "test-9b5ed62f1f-minion-group-h1jm"
I0212 16:36:43.005] Feb 12 16:36:43.004: INFO: Waiting for GCE PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d" to detach from node "test-9b5ed62f1f-minion-group-h1jm".
I0212 16:36:43.142] Feb 12 16:36:43.142: INFO: GCE PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d" appears to have successfully detached from "test-9b5ed62f1f-minion-group-h1jm".
I0212 16:36:43.142] STEP: Deleting PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d"
I0212 16:36:44.904] Feb 12 16:36:44.904: INFO: Successfully deleted PD "test-9b5ed62f1f-4a9e68cd-0054-420d-a2ef-7af1aaa2fd2d".
I0212 16:36:44.904] STEP: defer: re-create host0 node object
... skipping 692 lines ...
I0212 16:44:26.925] 
I0212 16:44:26.925]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:44:26.925] ------------------------------
I0212 16:44:26.925] SSSS
I0212 16:44:26.926] ------------------------------
I0212 16:44:26.926] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0212 16:44:26.926]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0212 16:44:26.926]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 16:44:26.927] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 16:44:26.927]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:44:26.927] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 16:44:26.927]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 16:44:26.927] STEP: Creating a kubernetes client
I0212 16:44:26.927] Feb 12 16:44:26.920: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 16:44:26.928] STEP: Building a namespace api object, basename provisioning
I0212 16:44:27.071] STEP: Waiting for a default service account to be provisioned in namespace
I0212 16:44:27.111] [It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0212 16:44:27.111]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 16:44:27.111] STEP: deploying csi gce-pd driver
I0212 16:44:27.148] Feb 12 16:44:27.147: INFO: Found CI service account key at /etc/service-account/service-account.json
I0212 16:44:27.148] Feb 12 16:44:27.147: INFO: Running cp [/etc/service-account/service-account.json /tmp/65ca2c96-6aef-45fb-a979-56388d429396/cloud-sa.json]
I0212 16:44:27.190] Feb 12 16:44:27.190: INFO: Shredding file /tmp/65ca2c96-6aef-45fb-a979-56388d429396/cloud-sa.json
I0212 16:44:27.191] Feb 12 16:44:27.190: INFO: Running shred [--remove /tmp/65ca2c96-6aef-45fb-a979-56388d429396/cloud-sa.json]
... skipping 24 lines ...
I0212 16:44:28.040] Feb 12 16:44:28.039: INFO: creating *v1.StatefulSet: provisioning-9298/csi-gce-pd-controller
I0212 16:44:28.113] Feb 12 16:44:28.113: INFO: Test running for native CSI Driver, not checking metrics
I0212 16:44:28.113] Feb 12 16:44:28.113: INFO: Creating resource for dynamic PV
I0212 16:44:28.114] STEP: creating a StorageClass provisioning-9298-pd.csi.storage.gke.io-sc4c97b
I0212 16:44:28.226] STEP: creating a claim
I0212 16:44:28.330] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-v8bt
I0212 16:44:28.372] STEP: Checking for subpath error in container status
I0212 16:44:58.449] Feb 12 16:44:58.448: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-v8bt" in namespace "provisioning-9298"
I0212 16:44:58.492] Feb 12 16:44:58.491: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-v8bt" to be fully deleted
I0212 16:45:10.569] STEP: Deleting pod
I0212 16:45:10.569] Feb 12 16:45:10.568: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-v8bt" in namespace "provisioning-9298"
I0212 16:45:10.606] STEP: Deleting pvc
I0212 16:45:10.606] Feb 12 16:45:10.606: INFO: Deleting PersistentVolumeClaim "pvc-758m7"
... skipping 276 lines ...
I0212 16:46:46.417] 
I0212 16:46:46.417]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:79
I0212 16:46:46.417] ------------------------------
I0212 16:46:46.417] S
I0212 16:46:46.417] ------------------------------
I0212 16:46:46.417] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0212 16:46:46.418]   should fail if subpath directory is outside the volume [Slow]
I0212 16:46:46.418]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 16:46:46.418] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 16:46:46.418]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:46:46.418] Feb 12 16:46:46.405: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0212 16:46:46.419] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 16:46:46.419]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 16:46:46.419] [sig-storage] CSI Volumes
I0212 16:46:46.420] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 16:46:46.420]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 16:46:46.420]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 16:46:46.420]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 16:46:46.420]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 16:46:46.421]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0212 16:46:46.421]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 16:46:46.421] 
I0212 16:46:46.421]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0212 16:46:46.421] 
I0212 16:46:46.422]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0212 16:46:46.422] ------------------------------
... skipping 539 lines ...
I0212 16:52:12.916] 
I0212 16:52:12.916]       Driver local doesn't support InlineVolume -- skipping
I0212 16:52:12.916] 
I0212 16:52:12.916]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:52:12.917] ------------------------------
I0212 16:52:12.917] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (block volmode)] volumeMode 
I0212 16:52:12.917]   should fail in binding dynamic provisioned PV to PVC
I0212 16:52:12.918]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0212 16:52:12.918] [BeforeEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0212 16:52:12.918]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:52:12.919] Feb 12 16:52:12.912: INFO: Driver local doesn't support DynamicPV -- skipping
I0212 16:52:12.919] [AfterEach] [Testpattern: Dynamic PV (block volmode)] volumeMode
I0212 16:52:12.919]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 16:52:12.920] [sig-storage] In-tree Volumes
I0212 16:52:12.920] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 16:52:12.920]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 16:52:12.920]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 16:52:12.920]     [Testpattern: Dynamic PV (block volmode)] volumeMode
I0212 16:52:12.921]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 16:52:12.921]       should fail in binding dynamic provisioned PV to PVC [BeforeEach]
I0212 16:52:12.921]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:239
I0212 16:52:12.921] 
I0212 16:52:12.922]       Driver local doesn't support DynamicPV -- skipping
I0212 16:52:12.922] 
I0212 16:52:12.922]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 16:52:12.922] ------------------------------
... skipping 37 lines ...
I0212 16:52:13.108] [BeforeEach] [sig-storage] [Serial] Volume metrics
I0212 16:52:13.108]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:53
I0212 16:52:13.146] Feb 12 16:52:13.146: INFO: Default storage class: "standard"
I0212 16:52:13.184] [It] should create prometheus metrics for volume provisioning errors [Slow]
I0212 16:52:13.185]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:136
I0212 16:52:13.251] STEP: Creating an invalid storageclass
I0212 16:52:13.369] STEP: Creating a pod and expecting it to fail
I0212 16:54:13.524] Feb 12 16:54:13.524: INFO: Deleting pod "pv-1433"/"pvc-tester-gksds"
I0212 16:54:13.525] Feb 12 16:54:13.524: INFO: Deleting pod "pvc-tester-gksds" in namespace "pv-1433"
I0212 16:54:13.575] Feb 12 16:54:13.575: INFO: Wait up to 5m0s for pod "pvc-tester-gksds" to be fully deleted
I0212 16:54:13.614] STEP: Checking failure metrics
I0212 16:54:13.708] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 16:54:13.709]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0212 16:54:13.709] Feb 12 16:54:13.708: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 16:54:13.751] STEP: Destroying namespace "pv-1433" for this suite.
I0212 16:54:27.871] Feb 12 16:54:27.870: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 16:54:29.474] Feb 12 16:54:29.470: INFO: namespace pv-1433 deletion completed in 15.723676278s
I0212 16:54:29.475] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 16:54:29.475]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0212 16:54:29.510] Feb 12 16:54:29.508: INFO: Failed to get pvc pv-1433/pvc-sxkvl: persistentvolumeclaims "pvc-sxkvl" not found
I0212 16:54:29.553] •SSS
I0212 16:54:29.554] ------------------------------
I0212 16:54:29.554] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: dir-link-bindmounted] [Testpattern: Dynamic PV (default fs)] subPath 
I0212 16:54:29.555]   should unmount if pod is gracefully deleted while kubelet is down [Disruptive][Slow][LinuxOnly]
I0212 16:54:29.555]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:321
I0212 16:54:29.555] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
... skipping 208 lines ...
I0212 16:55:03.562] STEP: Destroying namespace "daemonsets-7634" for this suite.
I0212 16:55:09.685] Feb 12 16:55:09.682: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 16:55:11.179] Feb 12 16:55:11.179: INFO: namespace daemonsets-7634 deletion completed in 7.616783359s
I0212 16:55:11.179] •SSSSSSSS
I0212 16:55:11.179] ------------------------------
I0212 16:55:11.180] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0212 16:55:11.180]   should fail if subpath with backstepping is outside the volume [Slow]
I0212 16:55:11.180]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:55:11.180] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 16:55:11.181]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 16:55:11.181] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 16:55:11.181]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 16:55:11.181] STEP: Creating a kubernetes client
I0212 16:55:11.181] Feb 12 16:55:11.179: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 16:55:11.182] STEP: Building a namespace api object, basename provisioning
I0212 16:55:11.301] STEP: Waiting for a default service account to be provisioned in namespace
I0212 16:55:11.339] [It] should fail if subpath with backstepping is outside the volume [Slow]
I0212 16:55:11.340]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 16:55:11.340] STEP: deploying csi gce-pd driver
I0212 16:55:11.382] Feb 12 16:55:11.378: INFO: Found CI service account key at /etc/service-account/service-account.json
I0212 16:55:11.383] Feb 12 16:55:11.379: INFO: Running cp [/etc/service-account/service-account.json /tmp/136c68bd-98bf-40cc-9b23-6711bbcc0b87/cloud-sa.json]
I0212 16:55:11.450] Feb 12 16:55:11.448: INFO: Shredding file /tmp/136c68bd-98bf-40cc-9b23-6711bbcc0b87/cloud-sa.json
I0212 16:55:11.451] Feb 12 16:55:11.448: INFO: Running shred [--remove /tmp/136c68bd-98bf-40cc-9b23-6711bbcc0b87/cloud-sa.json]
... skipping 24 lines ...
I0212 16:55:12.382] Feb 12 16:55:12.346: INFO: creating *v1.StatefulSet: provisioning-6049/csi-gce-pd-controller
I0212 16:55:12.420] Feb 12 16:55:12.420: INFO: Test running for native CSI Driver, not checking metrics
I0212 16:55:12.420] Feb 12 16:55:12.420: INFO: Creating resource for dynamic PV
I0212 16:55:12.420] STEP: creating a StorageClass provisioning-6049-pd.csi.storage.gke.io-scsl687
I0212 16:55:12.489] STEP: creating a claim
I0212 16:55:12.579] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-td7d
I0212 16:55:12.622] STEP: Checking for subpath error in container status
I0212 16:55:40.718] Feb 12 16:55:40.717: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-td7d" in namespace "provisioning-6049"
I0212 16:55:40.761] Feb 12 16:55:40.761: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-td7d" to be fully deleted
I0212 16:55:50.844] STEP: Deleting pod
I0212 16:55:50.845] Feb 12 16:55:50.843: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-td7d" in namespace "provisioning-6049"
I0212 16:55:50.882] STEP: Deleting pvc
I0212 16:55:50.882] Feb 12 16:55:50.881: INFO: Deleting PersistentVolumeClaim "pvc-vg4vd"
... skipping 1382 lines ...
I0212 17:19:19.408] Feb 12 17:19:19.407: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 17:19:19.446] STEP: Destroying namespace "pv-658" for this suite.
I0212 17:19:33.560] Feb 12 17:19:33.560: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 17:19:34.997] Feb 12 17:19:34.997: INFO: namespace pv-658 deletion completed in 15.551351329s
I0212 17:19:34.997] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 17:19:34.998]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0212 17:19:35.035] Feb 12 17:19:35.034: INFO: Failed to get pvc pv-658/pvc-j9mj4: persistentvolumeclaims "pvc-j9mj4" not found
I0212 17:19:35.035] •
I0212 17:19:35.035] ------------------------------
I0212 17:19:35.035] [sig-storage] PersistentVolumes-local  Stress with local volumes [Serial] 
I0212 17:19:35.035]   should be able to process many pods and reuse local volumes
I0212 17:19:35.036]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/persistent_volumes-local.go:521
I0212 17:19:35.036] [BeforeEach] [sig-storage] PersistentVolumes-local 
... skipping 944 lines ...
I0212 17:21:42.623] STEP: Destroying namespace "persistent-local-volumes-test-8146" for this suite.
I0212 17:21:50.740] Feb 12 17:21:50.740: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 17:21:52.251] Feb 12 17:21:52.251: INFO: namespace persistent-local-volumes-test-8146 deletion completed in 9.627834076s
I0212 17:21:52.251] •S
I0212 17:21:52.251] ------------------------------
I0212 17:21:52.251] [sig-storage] CSI Volumes CSI Topology test using GCE PD driver [Serial] 
I0212 17:21:52.252]   should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with immediate volume binding
I0212 17:21:52.252]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:97
I0212 17:21:52.252] [BeforeEach] CSI Topology test using GCE PD driver [Serial]
I0212 17:21:52.252]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 17:21:52.252] STEP: Creating a kubernetes client
I0212 17:21:52.253] Feb 12 17:21:52.251: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 17:21:52.253] STEP: Building a namespace api object, basename csitopology
... skipping 27 lines ...
I0212 17:21:53.148] Feb 12 17:21:53.148: INFO: creating *v1.RoleBinding: csitopology-6242/csi-controller-attacher-role-cfg
I0212 17:21:53.189] Feb 12 17:21:53.188: INFO: creating *v1.ClusterRoleBinding: csi-controller-provisioner-role-csitopology-6242
I0212 17:21:53.227] Feb 12 17:21:53.227: INFO: creating *v1.RoleBinding: csitopology-6242/csi-controller-provisioner-role-cfg
I0212 17:21:53.265] Feb 12 17:21:53.265: INFO: creating *v1.ClusterRoleBinding: psp-csi-controller-driver-registrar-role-csitopology-6242
I0212 17:21:53.350] Feb 12 17:21:53.350: INFO: creating *v1.DaemonSet: csitopology-6242/csi-gce-pd-node
I0212 17:21:53.392] Feb 12 17:21:53.392: INFO: creating *v1.StatefulSet: csitopology-6242/csi-gce-pd-controller
I0212 17:21:53.461] [It] should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with immediate volume binding
I0212 17:21:53.461]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:97
I0212 17:21:53.545] Feb 12 17:21:53.545: INFO: Requires more than one zone
I0212 17:21:53.547] [AfterEach] CSI Topology test using GCE PD driver [Serial]
I0212 17:21:53.547]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0212 17:21:53.547] Feb 12 17:21:53.546: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 17:21:53.588] STEP: Destroying namespace "csitopology-6242" for this suite.
... skipping 26 lines ...
I0212 17:22:17.946] 
I0212 17:22:17.946] S [SKIPPING] [25.695 seconds]
I0212 17:22:17.946] [sig-storage] CSI Volumes
I0212 17:22:17.946] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 17:22:17.947]   CSI Topology test using GCE PD driver [Serial]
I0212 17:22:17.947]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:64
I0212 17:22:17.947]     should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with immediate volume binding [It]
I0212 17:22:17.947]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:97
I0212 17:22:17.947] 
I0212 17:22:17.948]     Requires more than one zone
I0212 17:22:17.948] 
I0212 17:22:17.948]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:98
I0212 17:22:17.948] ------------------------------
... skipping 750 lines ...
I0212 17:33:00.030] 
I0212 17:33:00.031]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 17:33:00.031] ------------------------------
I0212 17:33:00.031] SSSSSSSSSSSSSSS
I0212 17:33:00.031] ------------------------------
I0212 17:33:00.031] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0212 17:33:00.032]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0212 17:33:00.032]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 17:33:00.032] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 17:33:00.032]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 17:33:00.032] Feb 12 17:33:00.025: INFO: Driver local doesn't support InlineVolume -- skipping
I0212 17:33:00.032] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 17:33:00.033]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 17:33:00.033] [sig-storage] In-tree Volumes
I0212 17:33:00.033] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 17:33:00.033]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 17:33:00.034]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 17:33:00.034]     [Testpattern: Inline-volume (default fs)] subPath
I0212 17:33:00.034]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 17:33:00.034]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0212 17:33:00.034]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 17:33:00.035] 
I0212 17:33:00.035]       Driver local doesn't support InlineVolume -- skipping
I0212 17:33:00.035] 
I0212 17:33:00.035]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 17:33:00.035] ------------------------------
... skipping 369 lines ...
I0212 17:34:50.642] Feb 12 17:34:50.634: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 17:34:50.676] STEP: Destroying namespace "pv-7360" for this suite.
I0212 17:35:04.831] Feb 12 17:35:04.831: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 17:35:06.439] Feb 12 17:35:06.439: INFO: namespace pv-7360 deletion completed in 15.762620126s
I0212 17:35:06.439] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 17:35:06.440]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0212 17:35:06.481] Feb 12 17:35:06.476: INFO: Failed to get pvc pv-7360/pvc-w9gz8: persistentvolumeclaims "pvc-w9gz8" not found
I0212 17:35:06.482] •SSSSS
I0212 17:35:06.482] ------------------------------
I0212 17:35:06.483] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (ext3)] volumes 
I0212 17:35:06.483]   should allow exec of files on the volume
I0212 17:35:06.483]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:173
I0212 17:35:06.483] [BeforeEach] [Testpattern: Pre-provisioned PV (ext3)] volumes
... skipping 17 lines ...
I0212 17:35:06.490] 
I0212 17:35:06.490]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0212 17:35:06.491] ------------------------------
I0212 17:35:06.491] SSSS
I0212 17:35:06.491] ------------------------------
I0212 17:35:06.491] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0212 17:35:06.491]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0212 17:35:06.492]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 17:35:06.492] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 17:35:06.492]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 17:35:06.492] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 17:35:06.493]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 17:35:06.493] STEP: Creating a kubernetes client
I0212 17:35:06.493] Feb 12 17:35:06.478: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 17:35:06.493] STEP: Building a namespace api object, basename provisioning
I0212 17:35:06.629] STEP: Waiting for a default service account to be provisioned in namespace
I0212 17:35:06.668] [It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0212 17:35:06.668]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 17:35:06.669] STEP: deploying csi gce-pd driver
I0212 17:35:06.705] Feb 12 17:35:06.704: INFO: Found CI service account key at /etc/service-account/service-account.json
I0212 17:35:06.706] Feb 12 17:35:06.704: INFO: Running cp [/etc/service-account/service-account.json /tmp/1b3066f7-7848-44fe-8de2-5f7273fe8ab7/cloud-sa.json]
I0212 17:35:06.750] Feb 12 17:35:06.749: INFO: Shredding file /tmp/1b3066f7-7848-44fe-8de2-5f7273fe8ab7/cloud-sa.json
I0212 17:35:06.750] Feb 12 17:35:06.749: INFO: Running shred [--remove /tmp/1b3066f7-7848-44fe-8de2-5f7273fe8ab7/cloud-sa.json]
... skipping 24 lines ...
I0212 17:35:07.649] Feb 12 17:35:07.648: INFO: creating *v1.StatefulSet: provisioning-2994/csi-gce-pd-controller
I0212 17:35:07.703] Feb 12 17:35:07.702: INFO: Test running for native CSI Driver, not checking metrics
I0212 17:35:07.703] Feb 12 17:35:07.702: INFO: Creating resource for dynamic PV
I0212 17:35:07.703] STEP: creating a StorageClass provisioning-2994-pd.csi.storage.gke.io-scl6tsd
I0212 17:35:07.778] STEP: creating a claim
I0212 17:35:07.877] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vrpb
I0212 17:35:07.918] STEP: Checking for subpath error in container status
I0212 17:35:35.994] Feb 12 17:35:35.994: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vrpb" in namespace "provisioning-2994"
I0212 17:35:36.039] Feb 12 17:35:36.039: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vrpb" to be fully deleted
I0212 17:35:40.115] STEP: Deleting pod
I0212 17:35:40.116] Feb 12 17:35:40.115: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-vrpb" in namespace "provisioning-2994"
I0212 17:35:40.157] STEP: Deleting pvc
I0212 17:35:40.158] Feb 12 17:35:40.157: INFO: Deleting PersistentVolumeClaim "pvc-h5787"
... skipping 186 lines ...
I0212 17:39:19.637] Feb 12 17:39:19.636: INFO: namespace daemonrestart-1372 deletion completed in 53.565997828s
I0212 17:39:19.637] [AfterEach] [sig-apps] DaemonRestart [Disruptive]
I0212 17:39:19.637]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_restart.go:246
I0212 17:39:19.638] •SSSSSSSSSSSSSSSSS
I0212 17:39:19.638] ------------------------------
I0212 17:39:19.638] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0212 17:39:19.638]   should fail if subpath with backstepping is outside the volume [Slow]
I0212 17:39:19.639]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 17:39:19.639] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 17:39:19.639]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 17:39:19.639] Feb 12 17:39:19.637: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0212 17:39:19.639] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 17:39:19.640]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 17:39:19.640] [sig-storage] CSI Volumes
I0212 17:39:19.640] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 17:39:19.641]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 17:39:19.641]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 17:39:19.641]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 17:39:19.641]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 17:39:19.642]       should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
I0212 17:39:19.642]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 17:39:19.642] 
I0212 17:39:19.642]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0212 17:39:19.642] 
I0212 17:39:19.642]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0212 17:39:19.643] ------------------------------
... skipping 1380 lines ...
I0212 17:57:03.339] STEP: Destroying namespace "emptydir-wrapper-6563" for this suite.
I0212 17:57:09.456] Feb 12 17:57:09.456: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 17:57:10.903] Feb 12 17:57:10.903: INFO: namespace emptydir-wrapper-6563 deletion completed in 7.56388154s
I0212 17:57:10.904] •SSSSSSSSSSS
I0212 17:57:10.904] ------------------------------
I0212 17:57:10.904] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0212 17:57:10.904]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0212 17:57:10.905]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 17:57:10.905] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 17:57:10.905]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 17:57:10.905] Feb 12 17:57:10.903: INFO: Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0212 17:57:10.905] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 17:57:10.906]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 17:57:10.906] [sig-storage] CSI Volumes
I0212 17:57:10.907] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 17:57:10.907]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 17:57:10.907]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 17:57:10.907]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 17:57:10.907]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 17:57:10.908]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0212 17:57:10.908]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 17:57:10.908] 
I0212 17:57:10.908]       Driver pd.csi.storage.gke.io doesn't support ntfs -- skipping
I0212 17:57:10.908] 
I0212 17:57:10.908]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
I0212 17:57:10.908] ------------------------------
... skipping 197 lines ...
I0212 17:59:26.183] 
I0212 17:59:26.183]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:334
I0212 17:59:26.183] ------------------------------
I0212 17:59:26.183] SSSSSS
I0212 17:59:26.183] ------------------------------
I0212 17:59:26.184] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0212 17:59:26.184]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0212 17:59:26.184]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 17:59:26.184] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 17:59:26.185]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 17:59:26.185] Feb 12 17:59:26.180: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0212 17:59:26.185] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 17:59:26.185]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 17:59:26.186] [sig-storage] CSI Volumes
I0212 17:59:26.186] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 17:59:26.186]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 17:59:26.186]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 17:59:26.187]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 17:59:26.187]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 17:59:26.187]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0212 17:59:26.187]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 17:59:26.187] 
I0212 17:59:26.187]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0212 17:59:26.187] 
I0212 17:59:26.188]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 17:59:26.188] ------------------------------
... skipping 92 lines ...
I0212 17:59:37.133] 
I0212 17:59:37.133]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/persistent_volumes-local.go:1197
I0212 17:59:37.133] ------------------------------
I0212 17:59:37.133] SSSSS
I0212 17:59:37.133] ------------------------------
I0212 17:59:37.134] [sig-storage] CSI Volumes CSI Topology test using GCE PD driver [Serial] 
I0212 17:59:37.134]   should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding
I0212 17:59:37.134]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0212 17:59:37.134] [BeforeEach] CSI Topology test using GCE PD driver [Serial]
I0212 17:59:37.135]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 17:59:37.135] STEP: Creating a kubernetes client
I0212 17:59:37.135] Feb 12 17:59:37.130: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 17:59:37.135] STEP: Building a namespace api object, basename csitopology
... skipping 27 lines ...
I0212 17:59:38.024] Feb 12 17:59:38.024: INFO: creating *v1.RoleBinding: csitopology-5325/csi-controller-attacher-role-cfg
I0212 17:59:38.063] Feb 12 17:59:38.063: INFO: creating *v1.ClusterRoleBinding: csi-controller-provisioner-role-csitopology-5325
I0212 17:59:38.102] Feb 12 17:59:38.102: INFO: creating *v1.RoleBinding: csitopology-5325/csi-controller-provisioner-role-cfg
I0212 17:59:38.141] Feb 12 17:59:38.141: INFO: creating *v1.ClusterRoleBinding: psp-csi-controller-driver-registrar-role-csitopology-5325
I0212 17:59:38.182] Feb 12 17:59:38.182: INFO: creating *v1.DaemonSet: csitopology-5325/csi-gce-pd-node
I0212 17:59:38.223] Feb 12 17:59:38.223: INFO: creating *v1.StatefulSet: csitopology-5325/csi-gce-pd-controller
I0212 17:59:38.302] [It] should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding
I0212 17:59:38.303]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0212 17:59:38.357] Feb 12 17:59:38.357: INFO: Requires more than one zone
I0212 17:59:38.358] [AfterEach] CSI Topology test using GCE PD driver [Serial]
I0212 17:59:38.358]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0212 17:59:38.358] Feb 12 17:59:38.357: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 17:59:38.408] STEP: Destroying namespace "csitopology-5325" for this suite.
... skipping 26 lines ...
I0212 18:00:02.853] 
I0212 18:00:02.853] S [SKIPPING] [25.723 seconds]
I0212 18:00:02.853] [sig-storage] CSI Volumes
I0212 18:00:02.853] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 18:00:02.854]   CSI Topology test using GCE PD driver [Serial]
I0212 18:00:02.854]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:64
I0212 18:00:02.854]     should fail to schedule a pod with a zone missing from AllowedTopologies; PD is provisioned with delayed volume binding [It]
I0212 18:00:02.854]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:103
I0212 18:00:02.855] 
I0212 18:00:02.855]     Requires more than one zone
I0212 18:00:02.855] 
I0212 18:00:02.855]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:104
I0212 18:00:02.855] ------------------------------
... skipping 23 lines ...
I0212 18:00:02.860] 
I0212 18:00:02.860]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 18:00:02.860] ------------------------------
I0212 18:00:02.860] SSSSSSS
I0212 18:00:02.860] ------------------------------
I0212 18:00:02.861] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0212 18:00:02.861]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0212 18:00:02.861]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 18:00:02.861] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 18:00:02.861]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 18:00:02.861] Feb 12 18:00:02.855: INFO: Driver local doesn't support DynamicPV -- skipping
I0212 18:00:02.862] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 18:00:02.862]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 18:00:02.862] [sig-storage] In-tree Volumes
I0212 18:00:02.863] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 18:00:02.863]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 18:00:02.863]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 18:00:02.863]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 18:00:02.863]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 18:00:02.863]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0212 18:00:02.864]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 18:00:02.864] 
I0212 18:00:02.864]       Driver local doesn't support DynamicPV -- skipping
I0212 18:00:02.864] 
I0212 18:00:02.864]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 18:00:02.864] ------------------------------
... skipping 212 lines ...
I0212 18:03:28.828] Feb 12 18:03:28.828: INFO: ssh prow@35.233.187.133:22: stdout:    ""
I0212 18:03:28.828] Feb 12 18:03:28.828: INFO: ssh prow@35.233.187.133:22: stderr:    ""
I0212 18:03:28.828] Feb 12 18:03:28.828: INFO: ssh prow@35.233.187.133:22: exit code: 0
I0212 18:03:28.829] Feb 12 18:03:28.828: INFO: Waiting up to 1m0s for node test-9b5ed62f1f-minion-group-2wr7 condition Ready to be true
I0212 18:03:28.866] STEP: Deleting pod
I0212 18:03:28.867] Feb 12 18:03:28.865: INFO: Deleting pod "pod-subpath-test-gcepd-2z7d" in namespace "provisioning-8950"
I0212 18:03:29.670] Feb 12 18:03:29.669: INFO: error deleting PD "test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf": googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-2wr7', resourceInUseByAnotherResource
I0212 18:03:29.671] Feb 12 18:03:29.669: INFO: Couldn't delete PD "test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-2wr7', resourceInUseByAnotherResource
I0212 18:03:35.403] Feb 12 18:03:35.403: INFO: error deleting PD "test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf": googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-2wr7', resourceInUseByAnotherResource
I0212 18:03:35.404] Feb 12 18:03:35.403: INFO: Couldn't delete PD "test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-2wr7', resourceInUseByAnotherResource
I0212 18:03:42.083] Feb 12 18:03:42.083: INFO: Successfully deleted PD "test-9b5ed62f1f-9a698298-8cc6-4ed3-9234-e148a0055ccf".
I0212 18:03:42.083] Feb 12 18:03:42.083: INFO: In-tree plugin kubernetes.io/gce-pd is not migrated, not validating any metrics
I0212 18:03:42.084] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 18:03:42.084]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0212 18:03:42.084] Feb 12 18:03:42.083: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 18:03:42.121] STEP: Destroying namespace "provisioning-8950" for this suite.
... skipping 1469 lines ...
I0212 18:40:14.571] Feb 12 18:40:14.570: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 18:40:14.618] STEP: Destroying namespace "pv-7512" for this suite.
I0212 18:40:20.746] Feb 12 18:40:20.744: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 18:40:22.309] Feb 12 18:40:22.308: INFO: namespace pv-7512 deletion completed in 7.690218051s
I0212 18:40:22.309] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 18:40:22.309]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0212 18:40:22.309] Feb 12 18:40:22.309: INFO: Failed to get pvc pv-7512/: resource name may not be empty
I0212 18:40:22.310] •SSSSSSSSSSSS
I0212 18:40:22.310] ------------------------------
I0212 18:40:22.310] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0212 18:40:22.310]   should be able to unmount after the subpath directory is deleted
I0212 18:40:22.311]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:418
I0212 18:40:22.311] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
... skipping 266 lines ...
I0212 18:43:09.667] 
I0212 18:43:09.667]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 18:43:09.667] ------------------------------
I0212 18:43:09.667] SSSSSS
I0212 18:43:09.667] ------------------------------
I0212 18:43:09.668] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0212 18:43:09.668]   should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0212 18:43:09.668]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 18:43:09.668] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 18:43:09.669]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 18:43:09.669] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 18:43:09.669]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 18:43:09.669] STEP: Creating a kubernetes client
I0212 18:43:09.669] Feb 12 18:43:09.664: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 18:43:09.669] STEP: Building a namespace api object, basename provisioning
I0212 18:43:09.820] STEP: Waiting for a default service account to be provisioned in namespace
I0212 18:43:09.860] [It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
I0212 18:43:09.861]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 18:43:12.036] Feb 12 18:43:12.035: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.5.181 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-26 hostexec-test-9b5ed62f1f-minion-group-xd0h -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0212 18:43:12.718] Feb 12 18:43:12.717: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0212 18:43:12.718] Feb 12 18:43:12.718: INFO: stdout: "0\n"
I0212 18:43:12.719] Feb 12 18:43:12.718: INFO: Requires at least 1 scsi fs localSSD 
I0212 18:43:12.719] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0212 18:43:20.591] [sig-storage] In-tree Volumes
I0212 18:43:20.591] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 18:43:20.591]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 18:43:20.591]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 18:43:20.591]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 18:43:20.592]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 18:43:20.592]       should fail if subpath file is outside the volume [Slow][LinuxOnly] [It]
I0212 18:43:20.592]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:232
I0212 18:43:20.592] 
I0212 18:43:20.592]       Requires at least 1 scsi fs localSSD 
I0212 18:43:20.592] 
I0212 18:43:20.593]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0212 18:43:20.593] ------------------------------
... skipping 188 lines ...
I0212 18:45:41.609] Feb 12 18:45:41.609: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 18:45:41.651] STEP: Destroying namespace "pv-4088" for this suite.
I0212 18:45:47.775] Feb 12 18:45:47.775: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 18:45:49.330] Feb 12 18:45:49.330: INFO: namespace pv-4088 deletion completed in 7.678488253s
I0212 18:45:49.330] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 18:45:49.330]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0212 18:45:49.331] Feb 12 18:45:49.330: INFO: Failed to get pvc pv-4088/: resource name may not be empty
I0212 18:45:49.331] •SSSSSSSSSSS
I0212 18:45:49.331] ------------------------------
I0212 18:45:49.332] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] volumes 
I0212 18:45:49.332]   should be mountable
I0212 18:45:49.332]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/volumes.go:142
I0212 18:45:49.332] [BeforeEach] [Testpattern: Inline-volume (default fs)] volumes
... skipping 74 lines ...
I0212 18:45:57.292] 
I0212 18:45:57.292]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 18:45:57.292] ------------------------------
I0212 18:45:57.292] SSSSS
I0212 18:45:57.292] ------------------------------
I0212 18:45:57.292] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0212 18:45:57.292]   should fail if subpath directory is outside the volume [Slow]
I0212 18:45:57.293]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 18:45:57.293] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 18:45:57.293]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 18:45:57.293] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 18:45:57.293]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 18:45:57.293] STEP: Creating a kubernetes client
I0212 18:45:57.293] Feb 12 18:45:57.285: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 18:45:57.294] STEP: Building a namespace api object, basename provisioning
I0212 18:45:57.411] STEP: Waiting for a default service account to be provisioned in namespace
I0212 18:45:57.451] [It] should fail if subpath directory is outside the volume [Slow]
I0212 18:45:57.451]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 18:46:01.622] Feb 12 18:46:01.622: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://35.197.5.181 --kubeconfig=/workspace/.kube/config exec --namespace=provisioning-8070 hostexec-test-9b5ed62f1f-minion-group-mxb1 -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c ls -1 /mnt/disks/by-uuid/google-local-ssds-scsi-fs/ | wc -l'
I0212 18:46:02.338] Feb 12 18:46:02.337: INFO: stderr: "ls: cannot access '/mnt/disks/by-uuid/google-local-ssds-scsi-fs/': No such file or directory\n"
I0212 18:46:02.338] Feb 12 18:46:02.337: INFO: stdout: "0\n"
I0212 18:46:02.338] Feb 12 18:46:02.337: INFO: Requires at least 1 scsi fs localSSD 
I0212 18:46:02.339] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 7 lines ...
I0212 18:46:10.060] [sig-storage] In-tree Volumes
I0212 18:46:10.060] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 18:46:10.060]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 18:46:10.061]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 18:46:10.061]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 18:46:10.061]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 18:46:10.061]       should fail if subpath directory is outside the volume [Slow] [It]
I0212 18:46:10.062]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 18:46:10.062] 
I0212 18:46:10.062]       Requires at least 1 scsi fs localSSD 
I0212 18:46:10.062] 
I0212 18:46:10.062]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1720
I0212 18:46:10.062] ------------------------------
... skipping 460 lines ...
I0212 19:08:15.610] Feb 12 19:08:15.608: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 19:08:15.647] STEP: Destroying namespace "kubelet-perf-4987" for this suite.
I0212 19:08:21.763] Feb 12 19:08:21.762: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 19:08:23.204] Feb 12 19:08:23.203: INFO: namespace kubelet-perf-4987 deletion completed in 7.556008957s
I0212 19:08:23.204] [AfterEach] [k8s.io] [sig-node] Kubelet [Serial] [Slow]
I0212 19:08:23.204]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/node/kubelet_perf.go:211
I0212 19:08:23.752] Feb 12 19:08:23.751: INFO: runtime operation error metrics:
I0212 19:08:23.752] node "test-9b5ed62f1f-minion-group-mxb1" runtime operation error rate:
I0212 19:08:23.752] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.752] operation "remove_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.752] operation "stop_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.753] operation "list_containers": total - 4444; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.753] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.753] operation "version": total - 363; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.753] operation "start_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.753] operation "create_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.754] operation "inspect_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.754] operation "inspect_image": total - 4; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.754] operation "list_images": total - 349; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.754] 
I0212 19:08:23.754] 
I0212 19:08:23.754] node "test-9b5ed62f1f-minion-group-xd0h" runtime operation error rate:
I0212 19:08:23.754] operation "start_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.755] operation "stop_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.755] operation "create_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.755] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.755] operation "version": total - 363; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.756] operation "inspect_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.756] operation "inspect_image": total - 4; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.756] operation "list_containers": total - 4439; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.756] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.756] operation "remove_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.756] operation "list_images": total - 349; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.757] 
I0212 19:08:23.757] 
I0212 19:08:23.757] node "test-9b5ed62f1f-master" runtime operation error rate:
I0212 19:08:23.757] operation "remove_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.757] operation "create_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.757] operation "inspect_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.758] operation "list_containers": total - 4436; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.758] operation "inspect_image": total - 4; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.758] operation "list_images": total - 350; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.758] operation "start_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.759] operation "stop_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.759] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.759] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.759] operation "version": total - 363; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.759] 
I0212 19:08:23.759] 
I0212 19:08:23.759] node "test-9b5ed62f1f-minion-group-2wr7" runtime operation error rate:
I0212 19:08:23.759] operation "inspect_image": total - 4; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.760] operation "list_images": total - 350; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.760] operation "stop_container": total - 2; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.760] operation "version": total - 362; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.760] operation "info": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.760] operation "remove_container": total - 2; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.760] operation "inspect_container": total - 2; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.761] operation "create_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.761] operation "pull_image": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.761] operation "list_containers": total - 4442; error rate - 0.000000; timeout rate - 0.000000
I0212 19:08:23.761] operation "start_container": total - 0; error rate - NaN; timeout rate - NaN
I0212 19:08:23.761] 
I0212 19:08:23.761] 
I0212 19:08:23.761] 
I0212 19:08:23.761] • [SLOW TEST:1209.790 seconds]
I0212 19:08:23.762] [k8s.io] [sig-node] Kubelet [Serial] [Slow]
I0212 19:08:23.762] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:692
... skipping 1377 lines ...
I0212 19:23:25.325] Feb 12 19:23:25.325: INFO: GCE PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a" appears to have successfully detached from "test-9b5ed62f1f-minion-group-2wr7".
I0212 19:23:25.326] STEP: defer: cleaning up PD-RW test env
I0212 19:23:25.326] Feb 12 19:23:25.325: INFO: defer cleanup errors can usually be ignored
I0212 19:23:25.326] STEP: defer: delete host0Pod
I0212 19:23:25.363] STEP: defer: detach and delete PDs
I0212 19:23:25.364] Feb 12 19:23:25.363: INFO: Detaching GCE PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a" from node "test-9b5ed62f1f-minion-group-2wr7".
I0212 19:23:26.935] Feb 12 19:23:26.934: INFO: Error detaching PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a": googleapi: Error 400: INVALID_USAGE - No attached disk found with device name 'test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a'
I0212 19:23:26.935] STEP: Waiting for PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a" to detach from "test-9b5ed62f1f-minion-group-2wr7"
I0212 19:23:26.935] Feb 12 19:23:26.934: INFO: Waiting for GCE PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a" to detach from node "test-9b5ed62f1f-minion-group-2wr7".
I0212 19:23:27.043] Feb 12 19:23:27.043: INFO: GCE PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a" appears to have successfully detached from "test-9b5ed62f1f-minion-group-2wr7".
I0212 19:23:27.043] STEP: Deleting PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a"
I0212 19:23:28.781] Feb 12 19:23:28.781: INFO: Successfully deleted PD "test-9b5ed62f1f-270cb83f-523a-402d-87d1-ed36a68cbe4a".
I0212 19:23:28.781] [AfterEach] [sig-storage] Pod Disks
... skipping 111 lines ...
I0212 19:25:19.288] STEP: Destroying namespace "provisioning-8208" for this suite.
I0212 19:25:25.403] Feb 12 19:25:25.403: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 19:25:26.839] Feb 12 19:25:26.839: INFO: namespace provisioning-8208 deletion completed in 7.550586189s
I0212 19:25:26.839] •SSSSSSSS
I0212 19:25:26.840] ------------------------------
I0212 19:25:26.840] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0212 19:25:26.840]   should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
I0212 19:25:26.840]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 19:25:26.840] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 19:25:26.841]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 19:25:26.841] Feb 12 19:25:26.839: INFO: Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0212 19:25:26.841] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 19:25:26.841]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 19:25:26.842] [sig-storage] CSI Volumes
I0212 19:25:26.842] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 19:25:26.842]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 19:25:26.842]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 19:25:26.842]     [Testpattern: Inline-volume (default fs)] subPath
I0212 19:25:26.843]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 19:25:26.843]       should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
I0212 19:25:26.843]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:243
I0212 19:25:26.843] 
I0212 19:25:26.843]       Driver pd.csi.storage.gke.io doesn't support InlineVolume -- skipping
I0212 19:25:26.843] 
I0212 19:25:26.843]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 19:25:26.844] ------------------------------
... skipping 203 lines ...
I0212 19:28:02.880] Feb 12 19:28:02.880: INFO: namespace sched-pred-3925 deletion completed in 15.605443306s
I0212 19:28:02.881] [AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
I0212 19:28:02.881]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:72
I0212 19:28:02.881] •SSSS
I0212 19:28:02.881] ------------------------------
I0212 19:28:02.881] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
I0212 19:28:02.882]   should fail if subpath directory is outside the volume [Slow]
I0212 19:28:02.882]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 19:28:02.882] [BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 19:28:02.882]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 19:28:02.883] Feb 12 19:28:02.880: INFO: Driver local doesn't support DynamicPV -- skipping
I0212 19:28:02.883] [AfterEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 19:28:02.883]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 19:28:02.884] [sig-storage] In-tree Volumes
I0212 19:28:02.884] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 19:28:02.884]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 19:28:02.884]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 19:28:02.884]     [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
I0212 19:28:02.885]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 19:28:02.885]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0212 19:28:02.885]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 19:28:02.885] 
I0212 19:28:02.885]       Driver local doesn't support DynamicPV -- skipping
I0212 19:28:02.885] 
I0212 19:28:02.886]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 19:28:02.886] ------------------------------
... skipping 16 lines ...
I0212 19:28:03.127] Feb 12 19:28:03.127: INFO: Waiting up to 20m0s for service "lb-hc-int" to have a LoadBalancer
I0212 19:28:41.423] STEP: modify the health check interval
I0212 19:29:02.406] STEP: restart kube-controller-manager
I0212 19:29:02.407] Feb 12 19:29:02.406: INFO: Restarting controller-manager via ssh, running: pidof kube-controller-manager | xargs sudo kill
I0212 19:29:03.371] Feb 12 19:29:03.371: INFO: ssh prow@35.197.5.181:22: command:   curl http://localhost:10252/healthz
I0212 19:29:03.372] Feb 12 19:29:03.371: INFO: ssh prow@35.197.5.181:22: stdout:    ""
I0212 19:29:03.372] Feb 12 19:29:03.371: INFO: ssh prow@35.197.5.181: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"
I0212 19:29:03.372] Feb 12 19:29:03.371: INFO: ssh prow@35.197.5.181:22: exit code: 7
I0212 19:29:08.863] STEP: health check should be reconciled
I0212 19:29:09.048] Feb 12 19:29:09.048: INFO: hc.CheckIntervalSec = 7
I0212 19:29:29.203] Feb 12 19:29:29.203: INFO: hc.CheckIntervalSec = 7
I0212 19:29:49.228] Feb 12 19:29:49.228: INFO: hc.CheckIntervalSec = 8
I0212 19:29:49.364] [AfterEach] [sig-network] Services
... skipping 265 lines ...
I0212 19:32:41.531] Feb 12 19:32:41.531: INFO: Pod "volume-prep-provisioning-2237": Phase="Succeeded", Reason="", readiness=false. Elapsed: 26.534898506s
I0212 19:32:41.532] STEP: Saw pod success
I0212 19:32:41.532] Feb 12 19:32:41.531: INFO: Pod "volume-prep-provisioning-2237" satisfied condition "success or failure"
I0212 19:32:41.532] Feb 12 19:32:41.531: INFO: Deleting pod "volume-prep-provisioning-2237" in namespace "provisioning-2237"
I0212 19:32:41.580] Feb 12 19:32:41.580: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-2237" to be fully deleted
I0212 19:32:41.617] STEP: Creating pod pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-h72c
I0212 19:32:41.656] STEP: Checking for subpath error in container status
I0212 19:32:51.733] Feb 12 19:32:51.733: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-h72c" in namespace "provisioning-2237"
I0212 19:32:51.783] Feb 12 19:32:51.783: INFO: Wait up to 5m0s for pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-h72c" to be fully deleted
I0212 19:32:51.820] STEP: Deleting pod
I0212 19:32:51.821] Feb 12 19:32:51.820: INFO: Deleting pod "pod-subpath-test-pd-csi-storage-gke-io-dynamicpv-h72c" in namespace "provisioning-2237"
I0212 19:32:51.858] STEP: Deleting pvc
I0212 19:32:51.858] Feb 12 19:32:51.858: INFO: Deleting PersistentVolumeClaim "pvc-tpsd5"
... skipping 576 lines ...
I0212 19:49:41.136] 
I0212 19:49:41.136]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 19:49:41.136] ------------------------------
I0212 19:49:41.136] SSSSSSSS
I0212 19:49:41.136] ------------------------------
I0212 19:49:41.137] [sig-storage] CSI Volumes [Driver: pd.csi.storage.gke.io][Serial] [Testpattern: Pre-provisioned PV (default fs)] subPath 
I0212 19:49:41.137]   should fail if subpath directory is outside the volume [Slow]
I0212 19:49:41.137]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 19:49:41.137] [BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 19:49:41.137]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 19:49:41.137] Feb 12 19:49:41.128: INFO: Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0212 19:49:41.138] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 19:49:41.138]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 19:49:41.139] [sig-storage] CSI Volumes
I0212 19:49:41.139] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 19:49:41.139]   [Driver: pd.csi.storage.gke.io][Serial]
I0212 19:49:41.139]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/csi_volumes.go:58
I0212 19:49:41.139]     [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 19:49:41.139]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 19:49:41.140]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0212 19:49:41.140]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 19:49:41.140] 
I0212 19:49:41.140]       Driver pd.csi.storage.gke.io doesn't support PreprovisionedPV -- skipping
I0212 19:49:41.140] 
I0212 19:49:41.140]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 19:49:41.141] ------------------------------
... skipping 651 lines ...
I0212 19:54:47.414] Feb 12 19:54:47.413: INFO: Waiting up to 1m0s for node test-9b5ed62f1f-minion-group-xd0h condition Ready to be true
I0212 19:54:47.448] STEP: Deleting pod
I0212 19:54:47.448] Feb 12 19:54:47.448: INFO: Deleting pod "pod-subpath-test-gcepd-preprovisionedpv-g7b4" in namespace "provisioning-6353"
I0212 19:54:47.482] STEP: Deleting pv and pvc
I0212 19:54:47.483] Feb 12 19:54:47.482: INFO: Deleting PersistentVolumeClaim "pvc-wbnrv"
I0212 19:54:47.520] Feb 12 19:54:47.520: INFO: Deleting PersistentVolume "gcepd-djbgr"
I0212 19:54:48.281] Feb 12 19:54:48.280: INFO: error deleting PD "test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056": googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-xd0h', resourceInUseByAnotherResource
I0212 19:54:48.282] Feb 12 19:54:48.281: INFO: Couldn't delete PD "test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-xd0h', resourceInUseByAnotherResource
I0212 19:54:54.003] Feb 12 19:54:54.003: INFO: error deleting PD "test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056": googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-xd0h', resourceInUseByAnotherResource
I0212 19:54:54.004] Feb 12 19:54:54.003: INFO: Couldn't delete PD "test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056", sleeping 5s: googleapi: Error 400: The disk resource 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/disks/test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056' is already being used by 'projects/k8s-gce-dg-1-6-1-5-dwngr-clu/zones/us-west1-b/instances/test-9b5ed62f1f-minion-group-xd0h', resourceInUseByAnotherResource
I0212 19:55:00.675] Feb 12 19:55:00.675: INFO: Successfully deleted PD "test-9b5ed62f1f-22ce697e-f2af-4d10-b754-ecd9e3b35056".
I0212 19:55:00.676] Feb 12 19:55:00.675: INFO: In-tree plugin kubernetes.io/gce-pd is not migrated, not validating any metrics
I0212 19:55:00.676] [AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
I0212 19:55:00.676]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0212 19:55:00.677] Feb 12 19:55:00.675: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 19:55:00.711] STEP: Destroying namespace "provisioning-6353" for this suite.
... skipping 200 lines ...
I0212 19:59:11.750] Feb 12 19:59:11.749: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0212 19:59:11.784] STEP: Destroying namespace "pv-3200" for this suite.
I0212 19:59:25.890] Feb 12 19:59:25.889: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 19:59:27.214] Feb 12 19:59:27.214: INFO: namespace pv-3200 deletion completed in 15.429874801s
I0212 19:59:27.215] [AfterEach] [sig-storage] [Serial] Volume metrics
I0212 19:59:27.215]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/volume_metrics.go:76
I0212 19:59:27.250] Feb 12 19:59:27.250: INFO: Failed to get pvc pv-3200/pvc-6zzjl: persistentvolumeclaims "pvc-6zzjl" not found
I0212 19:59:27.251] •SSSSS
I0212 19:59:27.251] ------------------------------
I0212 19:59:27.251] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow] 
I0212 19:59:27.251]   should access to two volumes with different volume mode and retain data across pod recreation on the same node
I0212 19:59:27.252]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:188
I0212 19:59:27.252] [BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
... skipping 261 lines ...
I0212 20:01:49.887] STEP: Destroying namespace "provisioning-2545" for this suite.
I0212 20:02:12.056] Feb 12 20:02:12.056: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 20:02:13.389] Feb 12 20:02:13.388: INFO: namespace provisioning-2545 deletion completed in 23.50153979s
I0212 20:02:13.389] •SS
I0212 20:02:13.389] ------------------------------
I0212 20:02:13.389] [sig-apps] Daemon set [Serial] 
I0212 20:02:13.389]   should retry creating failed daemon pods [Conformance]
I0212 20:02:13.390]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
I0212 20:02:13.390] [BeforeEach] [sig-apps] Daemon set [Serial]
I0212 20:02:13.390]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
I0212 20:02:13.390] STEP: Creating a kubernetes client
I0212 20:02:13.390] Feb 12 20:02:13.389: INFO: >>> kubeConfig: /workspace/.kube/config
I0212 20:02:13.391] STEP: Building a namespace api object, basename daemonsets
I0212 20:02:13.541] STEP: Waiting for a default service account to be provisioned in namespace
I0212 20:02:13.575] [BeforeEach] [sig-apps] Daemon set [Serial]
I0212 20:02:13.575]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:103
I0212 20:02:13.787] [It] should retry creating failed daemon pods [Conformance]
I0212 20:02:13.787]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
I0212 20:02:13.787] STEP: Creating a simple DaemonSet "daemon-set"
I0212 20:02:13.825] STEP: Check that daemon pods launch on every node of the cluster.
I0212 20:02:13.895] Feb 12 20:02:13.895: 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
I0212 20:02:13.948] Feb 12 20:02:13.947: INFO: Number of nodes with available pods: 0
I0212 20:02:13.948] Feb 12 20:02:13.947: INFO: Node test-9b5ed62f1f-minion-group-2wr7 is running more than one daemon pod
... skipping 3 lines ...
I0212 20:02:15.994] Feb 12 20:02:15.994: 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
I0212 20:02:16.029] Feb 12 20:02:16.028: INFO: Number of nodes with available pods: 2
I0212 20:02:16.029] Feb 12 20:02:16.028: INFO: Node test-9b5ed62f1f-minion-group-2wr7 is running more than one daemon pod
I0212 20:02:16.987] Feb 12 20:02:16.986: 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
I0212 20:02:17.021] Feb 12 20:02:17.021: INFO: Number of nodes with available pods: 3
I0212 20:02:17.021] Feb 12 20:02:17.021: INFO: Number of running nodes: 3, number of available pods: 3
I0212 20:02:17.057] STEP: Set a daemon pod's phase to 'Failed', check that the daemon pod is revived.
I0212 20:02:17.187] Feb 12 20:02:17.187: 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
I0212 20:02:17.222] Feb 12 20:02:17.222: INFO: Number of nodes with available pods: 2
I0212 20:02:17.222] Feb 12 20:02:17.222: INFO: Node test-9b5ed62f1f-minion-group-2wr7 is running more than one daemon pod
I0212 20:02:18.258] Feb 12 20:02:18.257: 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
I0212 20:02:18.292] Feb 12 20:02:18.292: INFO: Number of nodes with available pods: 2
I0212 20:02:18.292] Feb 12 20:02:18.292: INFO: Node test-9b5ed62f1f-minion-group-2wr7 is running more than one daemon pod
I0212 20:02:19.262] Feb 12 20:02:19.262: 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
I0212 20:02:19.297] Feb 12 20:02:19.296: INFO: Number of nodes with available pods: 3
I0212 20:02:19.297] Feb 12 20:02:19.297: INFO: Number of running nodes: 3, number of available pods: 3
I0212 20:02:19.297] STEP: Wait for the failed daemon pod to be completely deleted.
I0212 20:02:19.331] [AfterEach] [sig-apps] Daemon set [Serial]
I0212 20:02:19.331]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:69
I0212 20:02:19.366] STEP: Deleting DaemonSet "daemon-set"
I0212 20:02:19.367] STEP: deleting DaemonSet.extensions daemon-set in namespace daemonsets-7724, will wait for the garbage collector to delete the pods
I0212 20:02:19.490] Feb 12 20:02:19.489: INFO: Deleting DaemonSet.extensions daemon-set took: 38.716436ms
I0212 20:02:20.090] Feb 12 20:02:20.090: INFO: Terminating DaemonSet.extensions daemon-set pods took: 600.315623ms
... skipping 325 lines ...
I0212 20:05:37.722] STEP: Destroying namespace "volume-provisioning-1291" for this suite.
I0212 20:05:43.834] Feb 12 20:05:43.833: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0212 20:05:45.267] Feb 12 20:05:45.267: INFO: namespace volume-provisioning-1291 deletion completed in 7.545226628s
I0212 20:05:45.268] •SSSSSSSSSSS
I0212 20:05:45.268] ------------------------------
I0212 20:05:45.268] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Dynamic PV (default fs)] subPath 
I0212 20:05:45.268]   should fail if subpath directory is outside the volume [Slow]
I0212 20:05:45.269]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 20:05:45.269] [BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 20:05:45.269]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 20:05:45.269] Feb 12 20:05:45.267: INFO: Driver local doesn't support DynamicPV -- skipping
I0212 20:05:45.269] [AfterEach] [Testpattern: Dynamic PV (default fs)] subPath
I0212 20:05:45.270]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 20:05:45.270] [sig-storage] In-tree Volumes
I0212 20:05:45.271] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 20:05:45.271]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 20:05:45.271]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 20:05:45.271]     [Testpattern: Dynamic PV (default fs)] subPath
I0212 20:05:45.271]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 20:05:45.272]       should fail if subpath directory is outside the volume [Slow] [BeforeEach]
I0212 20:05:45.272]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216
I0212 20:05:45.272] 
I0212 20:05:45.272]       Driver local doesn't support DynamicPV -- skipping
I0212 20:05:45.272] 
I0212 20:05:45.272]       /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
I0212 20:05:45.272] ------------------------------
... skipping 456 lines ...
I0212 20:13:04.989] Feb 12 20:13:04.989: INFO: namespace sched-priority-9096 deletion completed in 21.442699288s
I0212 20:13:04.989] [AfterEach] [sig-scheduling] SchedulerPriorities [Serial]
I0212 20:13:04.989]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/scheduling/priorities.go:71
I0212 20:13:04.990] •SSSSSSSSSSSSSSSSSSSSS
I0212 20:13:04.990] ------------------------------
I0212 20:13:04.990] [sig-storage] In-tree Volumes [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial] [Testpattern: Inline-volume (default fs)] subPath 
I0212 20:13:04.990]   should fail if subpath with backstepping is outside the volume [Slow]
I0212 20:13:04.991]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254
I0212 20:13:04.991] [BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 20:13:04.991]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:92
I0212 20:13:04.991] Feb 12 20:13:04.989: INFO: Driver local doesn't support InlineVolume -- skipping
I0212 20:13:04.991] [AfterEach] [Testpattern: Inline-volume (default fs)] subPath
I0212 20:13:04.992]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0212 20:13:04.992] [sig-storage] In-tree Volumes
I0212 20:13:04.992] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
I0212 20:13:04.992]   [Driver: local][LocalVolumeType: gce-localssd-scsi-fs] [Serial]
I0212 20:13:04.993]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
I0212 20:13:04.993]     [Testpattern: Inline-volume (default fs)] subPath
I0212 20:13:04.993]     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
I0212 20:13:04.993]       should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
I0212 20:13:04.993]       [9