This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 10 succeeded
Started2020-03-30 20:07
Elapsed2h0m
Revisionmaster
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/5c61c69e-1292-4fb9-93a7-492cd8e88d45/targets/test'}}
resultstorehttps://source.cloud.google.com/results/invocations/5c61c69e-1292-4fb9-93a7-492cd8e88d45/targets/test

No Test Failures!


Show 10 Passed Tests

Show 379 Skipped Tests

Error lines from build-log.txt

... skipping 355 lines ...
Trying to find master named 'e2e-efc1d9eb24-6c91a-master'
Looking for address 'e2e-efc1d9eb24-6c91a-master-ip'
Using master: e2e-efc1d9eb24-6c91a-master (external IP: 34.83.227.10; internal IP: (not set))
Waiting up to 300 seconds for cluster initialization.

  This will continually check to see if the API for kubernetes is reachable.
  This may time out if there was some uncaught error during start up.

...Kubernetes cluster created.
Cluster "kubernetes-petset_e2e-efc1d9eb24-6c91a" set.
User "kubernetes-petset_e2e-efc1d9eb24-6c91a" set.
Context "kubernetes-petset_e2e-efc1d9eb24-6c91a" created.
Switched to context "kubernetes-petset_e2e-efc1d9eb24-6c91a".
... skipping 38 lines ...
e2e-efc1d9eb24-6c91a-minion-group-4c9f         Ready                      <none>   4m31s   v1.17.5-beta.0.22+ea3eeb494a528e
e2e-efc1d9eb24-6c91a-minion-group-h2bp         Ready                      <none>   4m36s   v1.17.5-beta.0.22+ea3eeb494a528e
e2e-efc1d9eb24-6c91a-windows-node-group-g33z   Ready                      <none>   15s     v1.17.5-beta.0.22+ea3eeb494a528e
e2e-efc1d9eb24-6c91a-windows-node-group-t84x   Ready                      <none>   16s     v1.17.5-beta.0.22+ea3eeb494a528e
e2e-efc1d9eb24-6c91a-windows-node-group-xl8x   Ready                      <none>   14s     v1.17.5-beta.0.22+ea3eeb494a528e
Validate output:
NAME                 STATUS    MESSAGE             ERROR
etcd-1               Healthy   {"health":"true"}   
scheduler            Healthy   ok                  
controller-manager   Healthy   ok                  
etcd-0               Healthy   {"health":"true"}   
Cluster validation succeeded
Done, listing cluster services:
... skipping 115 lines ...
timed out waiting for the condition on pods/prepull-test-containers-pbwsz
timed out waiting for the condition on pods/prepull-test-containers-tfvs9
+ kubectl get pods -o wide
NAME                            READY   STATUS             RESTARTS   AGE    IP          NODE                                           NOMINATED NODE   READINESS GATES
prepull-test-containers-lmhxn   16/17   CrashLoopBackOff   52         101m   10.64.3.3   e2e-efc1d9eb24-6c91a-windows-node-group-g33z   <none>           <none>
prepull-test-containers-pbwsz   16/17   CrashLoopBackOff   52         101m   10.64.2.3   e2e-efc1d9eb24-6c91a-windows-node-group-t84x   <none>           <none>
prepull-test-containers-tfvs9   16/17   Error              53         101m   10.64.1.3   e2e-efc1d9eb24-6c91a-windows-node-group-xl8x   <none>           <none>
+ kubectl delete -f /home/prow/go/src/k8s.io/windows-testing/gce/prepull-1.17.yaml
daemonset.apps "prepull-test-containers" deleted
+ kubectl wait --for=delete pod -l prepull-test-images=e2e --timeout 3m
pod/prepull-test-containers-lmhxn condition met
pod/prepull-test-containers-tfvs9 condition met
error: timed out waiting for the condition on pods/prepull-test-containers-pbwsz
+ curl https://raw.githubusercontent.com/kubernetes-sigs/windows-testing/master/images/image-repo-list -o /workspace/repo-list.yaml
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100   249  100   249    0     0   1202      0 --:--:-- --:--:-- --:--:--  1202
+ export KUBE_TEST_REPO_LIST=/workspace/repo-list.yaml
+ KUBE_TEST_REPO_LIST=/workspace/repo-list.yaml
... skipping 296 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
  [Driver: local][LocalVolumeType: dir]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:70
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:100
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:273

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 11 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
  [Driver: local][LocalVolumeType: dir]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:70
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:100
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:235

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 101 lines ...
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Mar 30 22:04:32.176: INFO: Waiting up to 3m0s for all (but 3) nodes to be ready
STEP: Destroying namespace "kubectl-8505" for this suite.

•
------------------------------
{"msg":"PASSED [sig-cli] Kubectl client Kubectl version should check is all data is printed  [Conformance]","total":-1,"completed":1,"skipped":6,"failed":0}

SSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] volumes
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:04:32.373: INFO: Driver local doesn't support DynamicPV -- skipping
... skipping 35 lines ...
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Mar 30 22:04:32.867: INFO: Waiting up to 3m0s for all (but 3) nodes to be ready
STEP: Destroying namespace "pods-9952" for this suite.

•
------------------------------
{"msg":"PASSED [k8s.io] [sig-node] Pods Extended [k8s.io] Pods Set QOS Class should be set on Pods with matching resource requests and limits for memory and cpu [Conformance]","total":-1,"completed":1,"skipped":48,"failed":0}

SSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:04:33.185: INFO: Only supported for providers [aws] (not gce)
... skipping 6 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
  [Driver: aws]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:70
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:100
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:235

      Only supported for providers [aws] (not gce)

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/drivers/in_tree.go:1590
------------------------------
... skipping 46 lines ...
• [SLOW TEST:26.498 seconds]
[sig-api-machinery] CustomResourcePublishOpenAPI [Privileged:ClusterAdmin]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  removes definition from spec when one version gets changed to not be served [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-api-machinery] CustomResourcePublishOpenAPI [Privileged:ClusterAdmin] removes definition from spec when one version gets changed to not be served [Conformance]","total":-1,"completed":1,"skipped":35,"failed":0}

SSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-api-machinery] CustomResourcePublishOpenAPI [Privileged:ClusterAdmin]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
... skipping 18 lines ...
• [SLOW TEST:26.687 seconds]
[sig-api-machinery] CustomResourcePublishOpenAPI [Privileged:ClusterAdmin]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  updates the published spec when one version gets renamed [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-api-machinery] CustomResourcePublishOpenAPI [Privileged:ClusterAdmin] updates the published spec when one version gets renamed [Conformance]","total":-1,"completed":1,"skipped":28,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:04:58.151: INFO: Driver hostPath doesn't support DynamicPV -- skipping
... skipping 109 lines ...
• [SLOW TEST:40.549 seconds]
[sig-api-machinery] Garbage collector
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  should orphan pods created by rc if delete options say so [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-api-machinery] Garbage collector should orphan pods created by rc if delete options say so [Conformance]","total":-1,"completed":2,"skipped":48,"failed":0}

SSS
------------------------------
[BeforeEach] [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
... skipping 74 lines ...
• [SLOW TEST:101.471 seconds]
[sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  should mutate custom resource with pruning [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin] should mutate custom resource with pruning [Conformance]","total":-1,"completed":1,"skipped":4,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
... skipping 72 lines ...
• [SLOW TEST:91.171 seconds]
[sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  should mutate custom resource with different stored version [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin] should mutate custom resource with different stored version [Conformance]","total":-1,"completed":2,"skipped":79,"failed":0}

SSSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] Projected configMap
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
... skipping 18 lines ...
• [SLOW TEST:119.367 seconds]
[sig-storage] Projected configMap
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/common/projected_configmap.go:34
  optional updates should be reflected in volume [NodeConformance] [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-storage] Projected configMap optional updates should be reflected in volume [NodeConformance] [Conformance]","total":-1,"completed":2,"skipped":73,"failed":0}

SSSS
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (ntfs)][sig-windows] volumes
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:06:32.589: INFO: Driver gluster doesn't support ntfs -- skipping
... skipping 64 lines ...
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:716
  when create a pod with lifecycle hook
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/common/lifecycle_hook.go:42
    should execute poststart exec hook properly [NodeConformance] [Conformance]
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [k8s.io] Container Lifecycle Hook when create a pod with lifecycle hook should execute poststart exec hook properly [NodeConformance] [Conformance]","total":-1,"completed":1,"skipped":8,"failed":0}

SSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:06:56.491: INFO: Driver local doesn't support DynamicPV -- skipping
... skipping 54 lines ...
Mar 30 22:05:12.686: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Pending", Reason="", readiness=false. Elapsed: 41.153876311s
Mar 30 22:05:14.724: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Pending", Reason="", readiness=false. Elapsed: 43.192028097s
Mar 30 22:05:16.764: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Pending", Reason="", readiness=false. Elapsed: 45.232006511s
Mar 30 22:05:18.803: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Pending", Reason="", readiness=false. Elapsed: 47.271298449s
Mar 30 22:05:20.860: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Pending", Reason="", readiness=false. Elapsed: 49.328293461s
Mar 30 22:05:22.899: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Pending", Reason="", readiness=false. Elapsed: 51.366864s
Mar 30 22:05:24.938: INFO: Pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440": Phase="Failed", Reason="", readiness=false. Elapsed: 53.406186259s
Mar 30 22:05:34.184: INFO: Output of node "e2e-efc1d9eb24-6c91a-windows-node-group-g33z" pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440" container "client-container": runtime: VirtualAlloc of 8192 bytes failed with errno=1455
fatal error: out of memory

runtime stack:
runtime.throw(0x50f425, 0xd)
	/usr/local/go/src/runtime/panic.go:774 +0x79
runtime.sysUsed(0xc000094000, 0x2000)
	/usr/local/go/src/runtime/mem_windows.go:83 +0x230
... skipping 58 lines ...
runtime.goexit()
	/usr/local/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000075fe8 sp=0xc000075fe0 pc=0x457fb1

STEP: delete the pod
Mar 30 22:05:34.228: INFO: Waiting for pod downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440 to disappear
Mar 30 22:05:34.266: INFO: Pod downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440 no longer exists
Mar 30 22:05:34.266: FAIL: Unexpected error:
    <*errors.errorString | 0xc002d2a020>: {
        s: "expected pod \"downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440\" success: pod \"downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440\" failed with status: {Phase:Failed Conditions:[{Type:Initialized Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:} {Type:Ready Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:ContainersReady Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:PodScheduled Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:}] Message: Reason: NominatedNodeName: HostIP:10.40.0.5 PodIP:10.64.3.4 PodIPs:[{IP:10.64.3.4}] StartTime:2020-03-30 22:04:31 +0000 UTC InitContainerStatuses:[] ContainerStatuses:[{Name:client-container State:{Waiting:nil Running:nil Terminated:&ContainerStateTerminated{ExitCode:2,Signal:0,Reason:Error,Message:,StartedAt:2020-03-30 22:04:53 +0000 UTC,FinishedAt:2020-03-30 22:04:57 +0000 UTC,ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66,}} LastTerminationState:{Waiting:nil Running:nil Terminated:nil} Ready:false RestartCount:0 Image:e2eteam/mounttest:1.0 ImageID:docker-pullable://e2eteam/mounttest@sha256:1d6eaf26a98b5324496fe5a43116742417c46d8bf30100e214f9ff27e56460b2 ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66 Started:0xc000988d5e}] QOSClass:Burstable EphemeralContainerStatuses:[]}",
    }
    expected pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440" success: pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440" failed with status: {Phase:Failed Conditions:[{Type:Initialized Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:} {Type:Ready Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:ContainersReady Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:PodScheduled Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:}] Message: Reason: NominatedNodeName: HostIP:10.40.0.5 PodIP:10.64.3.4 PodIPs:[{IP:10.64.3.4}] StartTime:2020-03-30 22:04:31 +0000 UTC InitContainerStatuses:[] ContainerStatuses:[{Name:client-container State:{Waiting:nil Running:nil Terminated:&ContainerStateTerminated{ExitCode:2,Signal:0,Reason:Error,Message:,StartedAt:2020-03-30 22:04:53 +0000 UTC,FinishedAt:2020-03-30 22:04:57 +0000 UTC,ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66,}} LastTerminationState:{Waiting:nil Running:nil Terminated:nil} Ready:false RestartCount:0 Image:e2eteam/mounttest:1.0 ImageID:docker-pullable://e2eteam/mounttest@sha256:1d6eaf26a98b5324496fe5a43116742417c46d8bf30100e214f9ff27e56460b2 ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66 Started:0xc000988d5e}] QOSClass:Burstable EphemeralContainerStatuses:[]}
occurred
[AfterEach] [sig-storage] Projected downwardAPI
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "projected-7791".
STEP: Found 4 events.
Mar 30 22:05:34.305: INFO: At 2020-03-30 22:04:31 +0000 UTC - event for downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440: {default-scheduler } Scheduled: Successfully assigned projected-7791/downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440 to e2e-efc1d9eb24-6c91a-windows-node-group-g33z
Mar 30 22:05:34.305: INFO: At 2020-03-30 22:04:47 +0000 UTC - event for downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440: {kubelet e2e-efc1d9eb24-6c91a-windows-node-group-g33z} Pulled: Container image "e2eteam/mounttest:1.0" already present on machine
Mar 30 22:05:34.305: INFO: At 2020-03-30 22:04:48 +0000 UTC - event for downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440: {kubelet e2e-efc1d9eb24-6c91a-windows-node-group-g33z} Created: Created container client-container
Mar 30 22:05:34.305: INFO: At 2020-03-30 22:04:54 +0000 UTC - event for downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440: {kubelet e2e-efc1d9eb24-6c91a-windows-node-group-g33z} Started: Started container client-container
Mar 30 22:05:34.342: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Mar 30 22:05:34.342: INFO: 
Mar 30 22:05:34.382: INFO: 
Logging node info for node e2e-efc1d9eb24-6c91a-master
Mar 30 22:05:34.420: INFO: Node Info: &Node{ObjectMeta:{e2e-efc1d9eb24-6c91a-master   /api/v1/nodes/e2e-efc1d9eb24-6c91a-master 8c82184e-e954-4692-9f2b-52e06fd4a475 22518 0 2020-03-30 20:13:43 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:n1-standard-1 beta.kubernetes.io/os:linux cloud.google.com/metadata-proxy-ready:true failure-domain.beta.kubernetes.io/region:us-west1 failure-domain.beta.kubernetes.io/zone:us-west1-b kubernetes.io/arch:amd64 kubernetes.io/hostname:e2e-efc1d9eb24-6c91a-master kubernetes.io/os:linux node.kubernetes.io/instance-type:n1-standard-1 topology.kubernetes.io/region:us-west1 topology.kubernetes.io/zone:us-west1-b] map[node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:10.64.0.0/24,DoNotUseExternalID:,ProviderID:gce://kubernetes-petset/us-west1-b/e2e-efc1d9eb24-6c91a-master,Unschedulable:true,Taints:[]Taint{Taint{Key:node-under-test,Value:false,Effect:NoSchedule,TimeAdded:<nil>,},Taint{Key:node-role.kubernetes.io/master,Value:,Effect:NoSchedule,TimeAdded:<nil>,},Taint{Key:node.kubernetes.io/unschedulable,Value:,Effect:NoSchedule,TimeAdded:<nil>,},},ConfigSource:nil,PodCIDRs:[10.64.0.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{16684785664 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3876802560 0} {<nil>} 3785940Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{15016307073 0} {<nil>} 15016307073 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3614658560 0} {<nil>} 3529940Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:NetworkUnavailable,Status:False,LastHeartbeatTime:2020-03-30 20:13:44 +0000 UTC,LastTransitionTime:2020-03-30 20:13:44 +0000 UTC,Reason:RouteCreated,Message:NodeController create implicit route,},NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-03-30 22:04:54 +0000 UTC,LastTransitionTime:2020-03-30 20:13:43 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-03-30 22:04:54 +0000 UTC,LastTransitionTime:2020-03-30 20:13:43 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-03-30 22:04:54 +0000 UTC,LastTransitionTime:2020-03-30 20:13:43 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-03-30 22:04:54 +0000 UTC,LastTransitionTime:2020-03-30 20:13:53 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:10.40.0.2,},NodeAddress{Type:ExternalIP,Address:34.83.227.10,},NodeAddress{Type:InternalDNS,Address:e2e-efc1d9eb24-6c91a-master.c.kubernetes-petset.internal,},NodeAddress{Type:Hostname,Address:e2e-efc1d9eb24-6c91a-master.c.kubernetes-petset.internal,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:a79709500c35335f390a925a3cbd4c48,SystemUUID:a7970950-0c35-335f-390a-925a3cbd4c48,BootID:a70c0894-eadc-45e0-910b-c1e35aaa60ec,KernelVersion:4.19.102+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://19.3.1,KubeletVersion:v1.17.5-beta.0.22+ea3eeb494a528e,KubeProxyVersion:v1.17.5-beta.0.22+ea3eeb494a528e,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[k8s.gcr.io/etcd@sha256:4afb99b4690b418ffc2ceb67e1a17376457e441c1f09ab55447f0aaf992fa646 k8s.gcr.io/etcd:3.4.3-0],SizeBytes:288426917,},ContainerImage{Names:[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:810aadac042cee96db99d94cec2436482b886774681b44bb141254edda5e3cdf gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.17],SizeBytes:238318516,},ContainerImage{Names:[k8s.gcr.io/kube-apiserver-amd64:v1.17.5-beta.0.22_ea3eeb494a528e],SizeBytes:212274306,},ContainerImage{Names:[k8s.gcr.io/kube-controller-manager-amd64:v1.17.5-beta.0.22_ea3eeb494a528e],SizeBytes:200708589,},ContainerImage{Names:[k8s.gcr.io/kube-scheduler-amd64:v1.17.5-beta.0.22_ea3eeb494a528e],SizeBytes:110390861,},ContainerImage{Names:[k8s.gcr.io/kube-addon-manager@sha256:3e315022a842d782a28e729720f21091dde21f1efea28868d65ec595ad871616 k8s.gcr.io/kube-addon-manager:v9.0.2],SizeBytes:83076028,},ContainerImage{Names:[k8s.gcr.io/etcd-empty-dir-cleanup@sha256:484662e55e0705caed26c6fb8632097457f43ce685756531da7a76319a7dcee1 k8s.gcr.io/etcd-empty-dir-cleanup:3.4.3.0],SizeBytes:77408900,},ContainerImage{Names:[k8s.gcr.io/ingress-gce-glbc-amd64@sha256:1a859d138b4874642e9a8709e7ab04324669c77742349b5b21b1ef8a25fef55f k8s.gcr.io/ingress-gce-glbc-amd64:v1.6.1],SizeBytes:76121176,},ContainerImage{Names:[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0],SizeBytes:41861013,},ContainerImage{Names:[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12],SizeBytes:11337839,},ContainerImage{Names:[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1],SizeBytes:742472,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Mar 30 22:05:34.426: INFO: 
Logging kubelet events for node e2e-efc1d9eb24-6c91a-master
Mar 30 22:05:34.463: INFO: 
Logging pods the kubelet thinks is on node e2e-efc1d9eb24-6c91a-master
Mar 30 22:05:34.514: INFO: etcd-server-e2e-efc1d9eb24-6c91a-master started at 2020-03-30 20:12:51 +0000 UTC (0+1 container statuses recorded)
Mar 30 22:05:34.514: INFO: 	Container etcd-container ready: true, restart count 0
... skipping 18 lines ...
Mar 30 22:05:34.514: INFO: kube-controller-manager-e2e-efc1d9eb24-6c91a-master started at 2020-03-30 20:12:51 +0000 UTC (0+1 container statuses recorded)
Mar 30 22:05:34.514: INFO: 	Container kube-controller-manager ready: true, restart count 0
Mar 30 22:05:34.660: INFO: 
Latency metrics for node e2e-efc1d9eb24-6c91a-master
Mar 30 22:05:34.660: INFO: 
Logging node info for node e2e-efc1d9eb24-6c91a-minion-group-4c9f
Mar 30 22:05:34.699: INFO: Node Info: &Node{ObjectMeta:{e2e-efc1d9eb24-6c91a-minion-group-4c9f   /api/v1/nodes/e2e-efc1d9eb24-6c91a-minion-group-4c9f 0f5f7539-6773-496b-8f91-ec618998e971 22485 0 2020-03-30 20:13:49 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:n1-standard-2 beta.kubernetes.io/os:linux cloud.google.com/metadata-proxy-ready:true failure-domain.beta.kubernetes.io/region:us-west1 failure-domain.beta.kubernetes.io/zone:us-west1-b kubernetes.io/arch:amd64 kubernetes.io/hostname:e2e-efc1d9eb24-6c91a-minion-group-4c9f kubernetes.io/os:linux node.kubernetes.io/instance-type:n1-standard-2 topology.kubernetes.io/region:us-west1 topology.kubernetes.io/zone:us-west1-b] map[node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:10.64.4.0/24,DoNotUseExternalID:,ProviderID:gce://kubernetes-petset/us-west1-b/e2e-efc1d9eb24-6c91a-minion-group-4c9f,Unschedulable:false,Taints:[]Taint{Taint{Key:node-under-test,Value:false,Effect:NoSchedule,TimeAdded:<nil>,},},ConfigSource:nil,PodCIDRs:[10.64.4.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7840256000 0} {<nil>} 7656500Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7578112000 0} {<nil>} 7400500Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:KernelDeadlock,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:KernelHasNoDeadlock,Message:kernel has no deadlock,},NodeCondition{Type:ReadonlyFilesystem,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:FilesystemIsNotReadOnly,Message:Filesystem is not read-only,},NodeCondition{Type:FrequentKubeletRestart,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:NoFrequentKubeletRestart,Message:kubelet is functioning properly,},NodeCondition{Type:FrequentDockerRestart,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:NoFrequentDockerRestart,Message:docker is functioning properly,},NodeCondition{Type:FrequentContainerdRestart,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:NoFrequentContainerdRestart,Message:containerd is functioning properly,},NodeCondition{Type:CorruptDockerOverlay2,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:NoCorruptDockerOverlay2,Message:docker overlay2 is functioning properly,},NodeCondition{Type:FrequentUnregisterNetDevice,Status:False,LastHeartbeatTime:2020-03-30 22:04:09 +0000 UTC,LastTransitionTime:2020-03-30 20:13:52 +0000 UTC,Reason:NoFrequentUnregisterNetDevice,Message:node is functioning properly,},NodeCondition{Type:NetworkUnavailable,Status:False,LastHeartbeatTime:2020-03-30 20:13:50 +0000 UTC,LastTransitionTime:2020-03-30 20:13:50 +0000 UTC,Reason:RouteCreated,Message:NodeController create implicit route,},NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-03-30 22:04:46 +0000 UTC,LastTransitionTime:2020-03-30 20:13:49 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-03-30 22:04:46 +0000 UTC,LastTransitionTime:2020-03-30 20:13:49 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-03-30 22:04:46 +0000 UTC,LastTransitionTime:2020-03-30 20:13:49 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-03-30 22:04:46 +0000 UTC,LastTransitionTime:2020-03-30 20:14:00 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:10.40.0.6,},NodeAddress{Type:ExternalIP,Address:34.82.165.227,},NodeAddress{Type:InternalDNS,Address:e2e-efc1d9eb24-6c91a-minion-group-4c9f.c.kubernetes-petset.internal,},NodeAddress{Type:Hostname,Address:e2e-efc1d9eb24-6c91a-minion-group-4c9f.c.kubernetes-petset.internal,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:abcbdad3618f6caa0e47334019135a43,SystemUUID:abcbdad3-618f-6caa-0e47-334019135a43,BootID:f94a4c6b-53ff-4e80-a4f3-7b7ae9adb9df,KernelVersion:4.19.102+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://19.3.1,KubeletVersion:v1.17.5-beta.0.22+ea3eeb494a528e,KubeProxyVersion:v1.17.5-beta.0.22+ea3eeb494a528e,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:810aadac042cee96db99d94cec2436482b886774681b44bb141254edda5e3cdf gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.17],SizeBytes:238318516,},ContainerImage{Names:[k8s.gcr.io/kube-proxy-amd64:v1.17.5-beta.0.22_ea3eeb494a528e],SizeBytes:130116116,},ContainerImage{Names:[k8s.gcr.io/heapster-amd64@sha256:9fae0af136ce0cf4f88393b3670f7139ffc464692060c374d2ae748e13144521 k8s.gcr.io/heapster-amd64:v1.6.0-beta.1],SizeBytes:76016169,},ContainerImage{Names:[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0],SizeBytes:41861013,},ContainerImage{Names:[k8s.gcr.io/coredns@sha256:7ec975f167d815311a7136c32e70735f0d00b73781365df1befd46ed35bd4fe7 k8s.gcr.io/coredns:1.6.5],SizeBytes:41578211,},ContainerImage{Names:[k8s.gcr.io/cluster-proportional-autoscaler-amd64@sha256:a2db01cfd2ae1a16f0feef274160c659c1ac5aa433e1c514de20e334cb66c674 k8s.gcr.io/cluster-proportional-autoscaler-amd64:1.7.1],SizeBytes:40067731,},ContainerImage{Names:[k8s.gcr.io/metrics-server-amd64@sha256:c9c4e95068b51d6b33a9dccc61875df07dc650abbf4ac1a19d58b4628f89288b k8s.gcr.io/metrics-server-amd64:v0.3.6],SizeBytes:39944451,},ContainerImage{Names:[k8s.gcr.io/addon-resizer@sha256:30b3b12e471c534949e12d2da958fdf33848d153f2a0a88565bdef7ca999b5ad k8s.gcr.io/addon-resizer:1.8.7],SizeBytes:37930718,},ContainerImage{Names:[k8s.gcr.io/ingress-gce-404-server-with-metrics-amd64@sha256:d83d8a481145d0eb71f8bd71ae236d1c6a931dd3bdcaf80919a8ec4a4d8aff74 k8s.gcr.io/ingress-gce-404-server-with-metrics-amd64:v1.6.0],SizeBytes:13513083,},ContainerImage{Names:[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12],SizeBytes:11337839,},ContainerImage{Names:[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1],SizeBytes:742472,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Mar 30 22:05:34.699: INFO: 
Logging kubelet events for node e2e-efc1d9eb24-6c91a-minion-group-4c9f
Mar 30 22:05:34.737: INFO: 
Logging pods the kubelet thinks is on node e2e-efc1d9eb24-6c91a-minion-group-4c9f
Mar 30 22:05:34.791: INFO: l7-default-backend-678889f899-qwn5w started at 2020-03-30 20:14:03 +0000 UTC (0+1 container statuses recorded)
Mar 30 22:05:34.791: INFO: 	Container default-http-backend ready: true, restart count 0
... skipping 16 lines ...
Mar 30 22:05:34.791: INFO: 	Container metrics-server ready: true, restart count 0
Mar 30 22:05:34.791: INFO: 	Container metrics-server-nanny ready: true, restart count 0
Mar 30 22:05:34.940: INFO: 
Latency metrics for node e2e-efc1d9eb24-6c91a-minion-group-4c9f
Mar 30 22:05:34.940: INFO: 
Logging node info for node e2e-efc1d9eb24-6c91a-minion-group-h2bp
Mar 30 22:05:34.980: INFO: Node Info: &Node{ObjectMeta:{e2e-efc1d9eb24-6c91a-minion-group-h2bp   /api/v1/nodes/e2e-efc1d9eb24-6c91a-minion-group-h2bp 604136cf-f93a-4c85-9d83-cf2346f6d371 22669 0 2020-03-30 20:13:44 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:n1-standard-2 beta.kubernetes.io/os:linux cloud.google.com/metadata-proxy-ready:true failure-domain.beta.kubernetes.io/region:us-west1 failure-domain.beta.kubernetes.io/zone:us-west1-b kubernetes.io/arch:amd64 kubernetes.io/hostname:e2e-efc1d9eb24-6c91a-minion-group-h2bp kubernetes.io/os:linux node.kubernetes.io/instance-type:n1-standard-2 topology.kubernetes.io/region:us-west1 topology.kubernetes.io/zone:us-west1-b] map[node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:10.64.5.0/24,DoNotUseExternalID:,ProviderID:gce://kubernetes-petset/us-west1-b/e2e-efc1d9eb24-6c91a-minion-group-h2bp,Unschedulable:false,Taints:[]Taint{Taint{Key:node-under-test,Value:false,Effect:NoSchedule,TimeAdded:<nil>,},},ConfigSource:nil,PodCIDRs:[10.64.5.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7840256000 0} {<nil>} 7656500Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7578112000 0} {<nil>} 7400500Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:CorruptDockerOverlay2,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:NoCorruptDockerOverlay2,Message:docker overlay2 is functioning properly,},NodeCondition{Type:FrequentUnregisterNetDevice,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:NoFrequentUnregisterNetDevice,Message:node is functioning properly,},NodeCondition{Type:KernelDeadlock,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:KernelHasNoDeadlock,Message:kernel has no deadlock,},NodeCondition{Type:ReadonlyFilesystem,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:FilesystemIsNotReadOnly,Message:Filesystem is not read-only,},NodeCondition{Type:FrequentKubeletRestart,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:NoFrequentKubeletRestart,Message:kubelet is functioning properly,},NodeCondition{Type:FrequentDockerRestart,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:NoFrequentDockerRestart,Message:docker is functioning properly,},NodeCondition{Type:FrequentContainerdRestart,Status:False,LastHeartbeatTime:2020-03-30 22:04:01 +0000 UTC,LastTransitionTime:2020-03-30 20:13:48 +0000 UTC,Reason:NoFrequentContainerdRestart,Message:containerd is functioning properly,},NodeCondition{Type:NetworkUnavailable,Status:False,LastHeartbeatTime:2020-03-30 20:13:45 +0000 UTC,LastTransitionTime:2020-03-30 20:13:45 +0000 UTC,Reason:RouteCreated,Message:NodeController create implicit route,},NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-03-30 22:05:13 +0000 UTC,LastTransitionTime:2020-03-30 20:13:44 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-03-30 22:05:13 +0000 UTC,LastTransitionTime:2020-03-30 20:13:44 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-03-30 22:05:13 +0000 UTC,LastTransitionTime:2020-03-30 20:13:44 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-03-30 22:05:13 +0000 UTC,LastTransitionTime:2020-03-30 20:13:55 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:10.40.0.7,},NodeAddress{Type:ExternalIP,Address:34.83.228.77,},NodeAddress{Type:InternalDNS,Address:e2e-efc1d9eb24-6c91a-minion-group-h2bp.c.kubernetes-petset.internal,},NodeAddress{Type:Hostname,Address:e2e-efc1d9eb24-6c91a-minion-group-h2bp.c.kubernetes-petset.internal,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:a48ba968cb185f26eb5a64e1aeb2201d,SystemUUID:a48ba968-cb18-5f26-eb5a-64e1aeb2201d,BootID:9e618e31-8464-4565-b643-c45ed0f19d97,KernelVersion:4.19.102+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://19.3.1,KubeletVersion:v1.17.5-beta.0.22+ea3eeb494a528e,KubeProxyVersion:v1.17.5-beta.0.22+ea3eeb494a528e,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:810aadac042cee96db99d94cec2436482b886774681b44bb141254edda5e3cdf gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.17],SizeBytes:238318516,},ContainerImage{Names:[k8s.gcr.io/kube-proxy-amd64:v1.17.5-beta.0.22_ea3eeb494a528e],SizeBytes:130116116,},ContainerImage{Names:[k8s.gcr.io/kubernetes-dashboard-amd64@sha256:0ae6b69432e78069c5ce2bcde0fe409c5c4d6f0f4d9cd50a17974fea38898747 k8s.gcr.io/kubernetes-dashboard-amd64:v1.10.1],SizeBytes:121711221,},ContainerImage{Names:[k8s.gcr.io/fluentd-gcp-scaler@sha256:4f28f10fb89506768910b858f7a18ffb996824a16d70d5ac895e49687df9ff58 k8s.gcr.io/fluentd-gcp-scaler:0.5.2],SizeBytes:90498960,},ContainerImage{Names:[k8s.gcr.io/event-exporter@sha256:ab71028f7cbc851d273bb00449e30ab743d4e3be21ed2093299f718b42df0748 k8s.gcr.io/event-exporter:v0.3.1],SizeBytes:51445475,},ContainerImage{Names:[quay.io/k8scsi/snapshot-controller@sha256:b3c1c484ffe4f0bbf000bda93fb745e1b3899b08d605a08581426a1963dd3e8a quay.io/k8scsi/snapshot-controller:v2.0.0-rc2],SizeBytes:47222712,},ContainerImage{Names:[k8s.gcr.io/prometheus-to-sd@sha256:1d49fb3b108e6b42542e4a9b056dee308f06f88824326cde1636eea0472b799d k8s.gcr.io/prometheus-to-sd:v0.7.2],SizeBytes:42314030,},ContainerImage{Names:[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0],SizeBytes:41861013,},ContainerImage{Names:[k8s.gcr.io/coredns@sha256:7ec975f167d815311a7136c32e70735f0d00b73781365df1befd46ed35bd4fe7 k8s.gcr.io/coredns:1.6.5],SizeBytes:41578211,},ContainerImage{Names:[k8s.gcr.io/metrics-server-amd64@sha256:c9c4e95068b51d6b33a9dccc61875df07dc650abbf4ac1a19d58b4628f89288b k8s.gcr.io/metrics-server-amd64:v0.3.6],SizeBytes:39944451,},ContainerImage{Names:[k8s.gcr.io/addon-resizer@sha256:30b3b12e471c534949e12d2da958fdf33848d153f2a0a88565bdef7ca999b5ad k8s.gcr.io/addon-resizer:1.8.7],SizeBytes:37930718,},ContainerImage{Names:[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12],SizeBytes:11337839,},ContainerImage{Names:[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1],SizeBytes:742472,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Mar 30 22:05:34.981: INFO: 
Logging kubelet events for node e2e-efc1d9eb24-6c91a-minion-group-h2bp
Mar 30 22:05:35.018: INFO: 
Logging pods the kubelet thinks is on node e2e-efc1d9eb24-6c91a-minion-group-h2bp
Mar 30 22:05:35.074: INFO: kube-proxy-e2e-efc1d9eb24-6c91a-minion-group-h2bp started at 2020-03-30 20:13:44 +0000 UTC (0+1 container statuses recorded)
Mar 30 22:05:35.074: INFO: 	Container kube-proxy ready: true, restart count 0
... skipping 97 lines ...
• Failure [148.155 seconds]
[sig-storage] Projected downwardAPI
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/common/projected_downwardapi.go:34
  should provide container's cpu request [NodeConformance] [Conformance] [It]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721

  Mar 30 22:05:34.266: Unexpected error:
      <*errors.errorString | 0xc002d2a020>: {
          s: "expected pod \"downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440\" success: pod \"downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440\" failed with status: {Phase:Failed Conditions:[{Type:Initialized Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:} {Type:Ready Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:ContainersReady Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:PodScheduled Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:}] Message: Reason: NominatedNodeName: HostIP:10.40.0.5 PodIP:10.64.3.4 PodIPs:[{IP:10.64.3.4}] StartTime:2020-03-30 22:04:31 +0000 UTC InitContainerStatuses:[] ContainerStatuses:[{Name:client-container State:{Waiting:nil Running:nil Terminated:&ContainerStateTerminated{ExitCode:2,Signal:0,Reason:Error,Message:,StartedAt:2020-03-30 22:04:53 +0000 UTC,FinishedAt:2020-03-30 22:04:57 +0000 UTC,ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66,}} LastTerminationState:{Waiting:nil Running:nil Terminated:nil} Ready:false RestartCount:0 Image:e2eteam/mounttest:1.0 ImageID:docker-pullable://e2eteam/mounttest@sha256:1d6eaf26a98b5324496fe5a43116742417c46d8bf30100e214f9ff27e56460b2 ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66 Started:0xc000988d5e}] QOSClass:Burstable EphemeralContainerStatuses:[]}",
      }
      expected pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440" success: pod "downwardapi-volume-26d9df13-0d10-4625-90cb-0edbcb311440" failed with status: {Phase:Failed Conditions:[{Type:Initialized Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:} {Type:Ready Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:ContainersReady Status:False LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason:ContainersNotReady Message:containers with unready status: [client-container]} {Type:PodScheduled Status:True LastProbeTime:0001-01-01 00:00:00 +0000 UTC LastTransitionTime:2020-03-30 22:04:31 +0000 UTC Reason: Message:}] Message: Reason: NominatedNodeName: HostIP:10.40.0.5 PodIP:10.64.3.4 PodIPs:[{IP:10.64.3.4}] StartTime:2020-03-30 22:04:31 +0000 UTC InitContainerStatuses:[] ContainerStatuses:[{Name:client-container State:{Waiting:nil Running:nil Terminated:&ContainerStateTerminated{ExitCode:2,Signal:0,Reason:Error,Message:,StartedAt:2020-03-30 22:04:53 +0000 UTC,FinishedAt:2020-03-30 22:04:57 +0000 UTC,ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66,}} LastTerminationState:{Waiting:nil Running:nil Terminated:nil} Ready:false RestartCount:0 Image:e2eteam/mounttest:1.0 ImageID:docker-pullable://e2eteam/mounttest@sha256:1d6eaf26a98b5324496fe5a43116742417c46d8bf30100e214f9ff27e56460b2 ContainerID:docker://48e6c350c93ee61eee1fa8abe3795bd7794062c6e8baaf0de255a2d9e16a3e66 Started:0xc000988d5e}] QOSClass:Burstable EphemeralContainerStatuses:[]}
  occurred

  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/util.go:894
------------------------------
{"msg":"FAILED [sig-storage] Projected downwardAPI should provide container's cpu request [NodeConformance] [Conformance]","total":-1,"completed":0,"skipped":8,"failed":1,"failures":["[sig-storage] Projected downwardAPI should provide container's cpu request [NodeConformance] [Conformance]"]}

SSSS
------------------------------
[BeforeEach] [sig-api-machinery] Servers with support for Table transformation
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
... skipping 8 lines ...
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Mar 30 22:06:59.716: INFO: Waiting up to 3m0s for all (but 3) nodes to be ready
STEP: Destroying namespace "tables-281" for this suite.

•
------------------------------
{"msg":"PASSED [sig-api-machinery] Servers with support for Table transformation should return a 406 for a backend which does not implement metadata [Conformance]","total":-1,"completed":1,"skipped":12,"failed":1,"failures":["[sig-storage] Projected downwardAPI should provide container's cpu request [NodeConformance] [Conformance]"]}

SSSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:06:59.808: INFO: Driver cinder doesn't support ntfs -- skipping
... skipping 30 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
  [Driver: local][LocalVolumeType: dir-link-bindmounted]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:70
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:100
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:235

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 52 lines ...
• [SLOW TEST:11.027 seconds]
[sig-windows] DNS
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/windows/framework.go:27
  should support configurable pod DNS servers
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/windows/dns.go:42
------------------------------
{"msg":"PASSED [sig-windows] DNS should support configurable pod DNS servers","total":-1,"completed":2,"skipped":36,"failed":1,"failures":["[sig-storage] Projected downwardAPI should provide container's cpu request [NodeConformance] [Conformance]"]}

SSSSSSSSSSSSSS{"component":"entrypoint","file":"prow/entrypoint/run.go:164","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Process did not finish before 2h0m0s timeout","time":"2020-03-30T22:07:36Z"}

------------------------------
[BeforeEach] [sig-storage] Projected downwardAPI
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Mar 30 22:05:38.553: INFO: >>> kubeConfig: /workspace/.kube/config
... skipping 79 lines ...
• [SLOW TEST:119.031 seconds]
[sig-storage] Projected downwardAPI
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/common/projected_downwardapi.go:34
  should provide node allocatable (cpu) as default cpu limit if the limit is not set [NodeConformance] [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:721
------------------------------
{"msg":"PASSED [sig-storage] Projected downwardAPI should provide node allocatable (cpu) as default cpu limit if the limit is not set [NodeConformance] [Conformance]","total":-1,"completed":3,"skipped":51,"failed":0}

SSSS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ntfs)][sig-windows] provisioning
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:101
Mar 30 22:07:37.595: INFO: Driver hostPathSymlink doesn't support DynamicPV -- skipping
... skipping 54 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:23
  [Driver: local][LocalVolumeType: dir-bindmounted]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:70
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:100
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:273

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 193 lines ...
Received interrupt.  Running AfterSuite...
^C again to terminate immediately
Mar 30 22:07:38.164: INFO: Running AfterSuite actions on all nodes
Mar 30 22:07:38.170: INFO: Waiting up to 3m0s for all (but 3) nodes to be ready
STEP: Destroying namespace "container-probe-6519" for this suite.

{"component":"entrypoint","file":"prow/entrypoint/run.go:245","func":"k8s.io/test-infra/prow/entrypoint.gracefullyTerminate","level":"error","msg":"Process did not exit before 15s grace period","time":"2020-03-30T22:07:51Z"}