<div class="snippet-clipboard-content notranslate position-relative overflow-auto" data-snippet-clipboard-copy-content="[Fail] Port forwarding With a server that expects a client request [It] should support a client that connects, sends data, and disconnects [Conformance] 
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:163

â€¢ Failure [311.018 seconds]
Port forwarding
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:268
  With a server that expects a client request
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:216
    should support a client that connects, sends data, and disconnects [Conformance] [It]
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:215

    Feb 25 08:07:11.060: Pod did not start running: gave up waiting for pod 'pfpod' to be 'running' after 5m0s

    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:163"><pre class="notranslate"><code class="notranslate">[Fail] Port forwarding With a server that expects a client request [It] should support a client that connects, sends data, and disconnects [Conformance] 
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:163

â€¢ Failure [311.018 seconds]
Port forwarding
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:268
  With a server that expects a client request
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:216
    should support a client that connects, sends data, and disconnects [Conformance] [It]
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:215

    Feb 25 08:07:11.060: Pod did not start running: gave up waiting for pod 'pfpod' to be 'running' after 5m0s

    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:163
</code></pre></div>
<p dir="auto">I couldn't find any issue for this one, thus it must be a new regression we are having here. The log can be accessed at: <a href="http://kubekins.dls.corp.google.com/view/Critical%20Builds/job/kubernetes-e2e-gce/12127/consoleFull" rel="nofollow">http://kubekins.dls.corp.google.com/view/Critical%20Builds/job/kubernetes-e2e-gce/12127/consoleFull</a></p>
<div class="snippet-clipboard-content notranslate position-relative overflow-auto" data-snippet-clipboard-copy-content="07:17:09 [It] should support a client that connects, sends no data, and disconnects [Conformance]
07:17:09   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:156
07:17:09 STEP: creating the target pod
07:17:09 Feb 23 07:16:55.427: INFO: Waiting up to 5m0s for pod pfpod status to be running
07:17:09 Feb 23 07:16:55.447: INFO: Waiting for pod pfpod in namespace 'e2e-tests-port-forwarding-vep4r' status to be 'running'(found phase: &quot;Pending&quot;, readiness: false) (19.842797ms elapsed)
07:17:09 Feb 23 07:16:57.450: INFO: Waiting for pod pfpod in namespace 'e2e-tests-port-forwarding-vep4r' status to be 'running'(found phase: &quot;Pending&quot;, readiness: false) (2.023387632s elapsed)
07:17:09 Feb 23 07:16:59.454: INFO: Waiting for pod pfpod in namespace 'e2e-tests-port-forwarding-vep4r' status to be 'running'(found phase: &quot;Pending&quot;, readiness: false) (4.026805448s elapsed)
07:17:09 Feb 23 07:17:01.461: INFO: Found pod 'pfpod' on node 'jenkins-e2e-minion-ajjm'
07:17:09 STEP: Running 'kubectl port-forward'
07:17:09 Feb 23 07:17:01.461: INFO: starting port-forward command and streaming output
07:17:09 Feb 23 07:17:01.461: INFO: Asynchronously running '/jenkins-master-data/jobs/kubernetes-e2e-gce/workspace/kubernetes/platforms/linux/amd64/kubectl kubectl --server=https://104.197.61.15 --kubeconfig=/var/lib/jenkins/jobs/kubernetes-e2e-gce/workspace/.kube/config port-forward --namespace=e2e-tests-port-forwarding-vep4r pfpod :80'
07:17:09 Feb 23 07:17:01.463: INFO: reading from `kubectl port-forward` command's stderr
07:17:09 STEP: Dialing the local port
07:17:09 STEP: Closing the connection to the local port
07:17:09 Feb 23 07:17:01.988: INFO: Running '/jenkins-master-data/jobs/kubernetes-e2e-gce/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://104.197.61.15 --kubeconfig=/var/lib/jenkins/jobs/kubernetes-e2e-gce/workspace/.kube/config logs --namespace=e2e-tests-port-forwarding-vep4r -f pfpod'
07:17:09 Feb 23 07:17:02.920: INFO: stdout: &quot;&quot;
07:17:09 Feb 23 07:17:02.920: INFO: stderr: &quot;&quot;
07:17:09 Feb 23 07:17:02.920: FAIL: Missing &quot;Accepted client connection&quot; from log: 
07:17:09 [AfterEach] Port forwarding
07:17:09   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:84
07:17:09 STEP: Collecting events from namespace &quot;e2e-tests-port-forwarding-vep4r&quot;.
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {default-scheduler } Scheduled: Successfully assigned pfpod to jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Pulling: pulling image &quot;gcr.io/google_containers/portforwardtester:1.0&quot;
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Pulled: Successfully pulled image &quot;gcr.io/google_containers/portforwardtester:1.0&quot;
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Created: Created container with docker id 4ad03609ba81
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Started: Started container with docker id 4ad03609ba81
07:17:09 Feb 23 07:17:02.987: INFO: POD                                                             NODE                     PHASE    GRACE  CONDITIONS
07:17:09 Feb 23 07:17:02.987: INFO: pod780e3fb2-da40-11e5-b787-42010af01555                         jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-configmaps-795a4b3f-da40-11e5-88c3-42010af01555             jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [configmap-volume-test]}]
07:17:09 Feb 23 07:17:02.987: INFO: client-containers-7825e4f5-da40-11e5-917d-42010af01555          jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [test-container]}]
07:17:09 Feb 23 07:17:02.987: INFO: client-containers-7dc2c528-da40-11e5-988c-42010af01555          jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:02 -0800 PST ContainersNotReady containers with unready status: [test-container]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-gzcqa                                          jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-t6txl                                          jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-ugxfw                                          jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-bwora                                          jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-79647c34-da40-11e5-9e63-42010af01555                        jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [test-container]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-host-path-test                                              jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST ContainersNotReady containers with unready status: [test-container-1 test-container-2]}]
07:17:09 Feb 23 07:17:02.987: INFO: foo-gso08                                                       jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:59 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: foo-oapzs                                                       jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:57 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: nginx                                                           jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: redis-master-kje8q                                              jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:01 -0800 PST ContainersNotReady containers with unready status: [redis-master]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx                                                           jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-logs-websocket-7c5c3108-da40-11e5-87e0-42010af01555         jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST ContainersNotReady containers with unready status: [main]}]
07:17:09 Feb 23 07:17:02.987: INFO: pfpod                                                           jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [portforwardtester]}]
07:17:09 Feb 23 07:17:02.987: INFO: pfpod                                                           jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-kwky7  jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:02 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-tlu7z  jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: svc-latency-rc-ur1wo                                            jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: pod-service-account-787267e3-da40-11e5-b259-42010af01555        jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:01 -0800 PST ContainersNotReady containers with unready status: [token-test root-ca-test]}]
07:17:09 Feb 23 07:17:02.988: INFO: elasticsearch-logging-v1-nxkc6                                  jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:19 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: elasticsearch-logging-v1-yse0k                                  jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:20 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: fluentd-elasticsearch-jenkins-e2e-minion-ajjm                   jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:10 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: fluentd-elasticsearch-jenkins-e2e-minion-gjop                   jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:16 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: fluentd-elasticsearch-jenkins-e2e-minion-w7f5                   jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:03 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: heapster-v14-pcau0                                              jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:20 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kibana-logging-v1-x0fzh                                         jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:41 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-dns-v10-x0rod                                              jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:28 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-proxy-jenkins-e2e-minion-ajjm                              jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:15:33 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-proxy-jenkins-e2e-minion-gjop                              jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:02 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-proxy-jenkins-e2e-minion-w7f5                              jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:15:35 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kubernetes-dashboard-v0.1.0-09u97                               jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:10 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: l7-lb-controller-v0.5.2-xu332                                   jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:29 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: monitoring-influxdb-grafana-v3-s4zvp                            jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:41 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: 
07:17:09 Feb 23 07:17:02.994: INFO: 
07:17:09 Logging node info for node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.001: INFO: Node Info: &amp;{{ } {jenkins-e2e-minion-ajjm   /api/v1/nodes/jenkins-e2e-minion-ajjm 2b5320f8-da40-11e5-bb5a-42010af00002 367 0 2016-02-23 07:14:44 -0800 PST &lt;nil&gt; &lt;nil&gt; map[beta.kubernetes.io/instance-type:n1-standard-2 failure-domain.beta.kubernetes.io/region:us-central1 failure-domain.beta.kubernetes.io/zone:us-central1-f kubernetes.io/hostname:jenkins-e2e-minion-ajjm master:] map[]} {10.245.0.0/24 14548348975076778713 gce://k8s-jkns-e2e-gce/us-central1-f/jenkins-e2e-minion-ajjm false} {map[memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI} cpu:{2.000 DecimalSI}] map[cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI}]  [{OutOfDisk False 2016-02-23 07:16:53 -0800 PST 2016-02-23 07:14:44 -0800 PST KubeletHasSufficientDisk kubelet has sufficient disk space available} {Ready True 2016-02-23 07:16:53 -0800 PST 2016-02-23 07:15:32 -0800 PST KubeletReady kubelet is posting ready status}] [{InternalIP 10.240.0.3} {ExternalIP 104.154.98.97}] {{10250}} { 10C07727-2011-F0F9-CA44-80C069F0F32D b76f4a86-6123-4fd9-9236-6b2d3cb80e7d 3.16.0-4-amd64 Debian GNU/Linux 7 (wheezy) docker://1.9.1 v1.2.0-alpha.8.200+7f1b699880a3d4 v1.2.0-alpha.8.200+7f1b699880a3d4} [{[gcr.io/google_containers/kube-proxy:0a7bf1781f033fc04e110c3cd8505785] 165640277} {[gcr.io/google_containers/fluentd-elasticsearch:1.14] 562034622} {[gcr.io/google_containers/kubernetes-dashboard-amd64:v0.1.0] 35409795} {[gcr.io/google_containers/glbc:0.5.2] 226351998} {[gcr.io/google_containers/elasticsearch:1.8] 410989305} {[gcr.io/google_containers/defaultbackend:1.0] 7513643} {[gcr.io/google_containers/pause:2.0] 350164} {[gcr.io/google_containers/pause:0.8.0] 241656}]}}
07:17:09 Feb 23 07:17:03.002: INFO: 
07:17:09 Logging kubelet events for node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.035: INFO: 
07:17:09 Logging pods the kubelet thinks is on node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.052: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-tlu7z started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: svc-latency-rc-ur1wo started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: nginx started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: fluentd-elasticsearch-jenkins-e2e-minion-ajjm started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: pod-logs-websocket-7c5c3108-da40-11e5-87e0-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: nginx-controller-gzcqa started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: foo-oapzs started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: l7-lb-controller-v0.5.2-xu332 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: elasticsearch-logging-v1-yse0k started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: kubernetes-dashboard-v0.1.0-09u97 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: kube-proxy-jenkins-e2e-minion-ajjm started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: pfpod started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.446: INFO: ERROR kubelet_docker_errors{operation_type=&quot;info&quot;} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.446: INFO: ERROR kubelet_docker_errors{operation_type=&quot;inspect_image&quot;} =&gt; 15 @[0]
07:17:09 Feb 23 07:17:03.446: INFO: ERROR kubelet_docker_errors{operation_type=&quot;list_containers&quot;} =&gt; 32 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: ERROR kubelet_docker_errors{operation_type=&quot;list_images&quot;} =&gt; 5 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: ERROR kubelet_docker_errors{operation_type=&quot;stop_container&quot;} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: ERROR kubelet_docker_errors{operation_type=&quot;version&quot;} =&gt; 22 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: 
07:17:09 Latency metrics for node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.447: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:41.49339s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:40.027169s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation: Method:pod_worker_start_latency_microseconds Quantile:0.99 Latency:40.02661s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:37.502548s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.99 Latency:37.480865s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:30.454951s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:29.950011s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:29.001099s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.9 Latency:27.51048s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:10.221818s}
07:17:09 Feb 23 07:17:03.447: INFO: 
07:17:09 Logging node info for node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.451: INFO: Node Info: &amp;{{ } {jenkins-e2e-minion-gjop   /api/v1/nodes/jenkins-e2e-minion-gjop 2c6a9bda-da40-11e5-bb5a-42010af00002 373 0 2016-02-23 07:14:46 -0800 PST &lt;nil&gt; &lt;nil&gt; map[kubernetes.io/hostname:jenkins-e2e-minion-gjop master: beta.kubernetes.io/instance-type:n1-standard-2 failure-domain.beta.kubernetes.io/region:us-central1 failure-domain.beta.kubernetes.io/zone:us-central1-f] map[]} {10.245.1.0/24 10660228105066903695 gce://k8s-jkns-e2e-gce/us-central1-f/jenkins-e2e-minion-gjop false} {map[pods:{110.000 DecimalSI} cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI}] map[memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI} cpu:{2.000 DecimalSI}]  [{OutOfDisk False 2016-02-23 07:16:54 -0800 PST 2016-02-23 07:14:46 -0800 PST KubeletHasSufficientDisk kubelet has sufficient disk space available} {Ready True 2016-02-23 07:16:54 -0800 PST 2016-02-23 07:15:33 -0800 PST KubeletReady kubelet is posting ready status}] [{InternalIP 10.240.0.5} {ExternalIP 104.154.18.200}] {{10250}} { CEF2A9D6-8CE4-1670-1696-570023B29CC8 8635b608-0928-46fa-9252-424edf6eb21e 3.16.0-4-amd64 Debian GNU/Linux 7 (wheezy) docker://1.9.1 v1.2.0-alpha.8.200+7f1b699880a3d4 v1.2.0-alpha.8.200+7f1b699880a3d4} [{[gcr.io/google_containers/kube-proxy:0a7bf1781f033fc04e110c3cd8505785] 165640277} {[gcr.io/google_containers/heapster:v0.20.0-alpha6] 85929942} {[gcr.io/google_containers/fluentd-elasticsearch:1.14] 562034622} {[gcr.io/google_containers/mounttest:0.5] 1718853} {[gcr.io/google_containers/heapster_grafana:v2.1.1] 206641132} {[gcr.io/google_containers/pause:2.0] 350164} {[gcr.io/google_containers/heapster_influxdb:v0.5] 251005705} {[gcr.io/google_containers/kibana:1.3] 396897764} {[gcr.io/google_containers/pause:0.8.0] 241656}]}}
07:17:09 Feb 23 07:17:03.451: INFO: 
07:17:09 Logging kubelet events for node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.464: INFO: 
07:17:09 Logging pods the kubelet thinks is on node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.573: INFO: nginx-controller-ugxfw started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pfpod started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: client-containers-7825e4f5-da40-11e5-917d-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: monitoring-influxdb-grafana-v3-s4zvp started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: kube-proxy-jenkins-e2e-minion-gjop started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: heapster-v14-pcau0 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: fluentd-elasticsearch-jenkins-e2e-minion-gjop started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: foo-gso08 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pod-79647c34-da40-11e5-9e63-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pod-configmaps-795a4b3f-da40-11e5-88c3-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pod780e3fb2-da40-11e5-b787-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: kibana-logging-v1-x0fzh started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;info&quot;} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;inspect_image&quot;} =&gt; 20 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;list_containers&quot;} =&gt; 36 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;list_images&quot;} =&gt; 6 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;pull_image&quot;} =&gt; 2 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;stop_container&quot;} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type=&quot;version&quot;} =&gt; 24 @[0]
07:17:09 Feb 23 07:17:03.826: INFO: 
07:17:09 Latency metrics for node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.826: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:1m9.824686s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:52.910615s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:51.304079s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:51.304079s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.99 Latency:51.295383s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:47.065565s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:45.90737s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation: Method:pod_worker_start_latency_microseconds Quantile:0.99 Latency:35.210061s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.5 Latency:31.659999s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.9 Latency:31.646042s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:29.831946s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.9 Latency:29.088885s}
07:17:09 Feb 23 07:17:03.826: INFO: 
07:17:09 Logging node info for node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:03.831: INFO: Node Info: &amp;{{ } {jenkins-e2e-minion-w7f5   /api/v1/nodes/jenkins-e2e-minion-w7f5 2b781dda-da40-11e5-bb5a-42010af00002 927 0 2016-02-23 07:14:44 -0800 PST &lt;nil&gt; &lt;nil&gt; map[beta.kubernetes.io/instance-type:n1-standard-2 failure-domain.beta.kubernetes.io/region:us-central1 failure-domain.beta.kubernetes.io/zone:us-central1-f kubernetes.io/hostname:jenkins-e2e-minion-w7f5 master:] map[]} {10.245.2.0/24 5512211706074314555 gce://k8s-jkns-e2e-gce/us-central1-f/jenkins-e2e-minion-w7f5 false} {map[cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI}] map[cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI}]  [{OutOfDisk False 2016-02-23 07:17:03 -0800 PST 2016-02-23 07:14:44 -0800 PST KubeletHasSufficientDisk kubelet has sufficient disk space available} {Ready True 2016-02-23 07:17:03 -0800 PST 2016-02-23 07:15:32 -0800 PST KubeletReady kubelet is posting ready status}] [{InternalIP 10.240.0.4} {ExternalIP 104.154.49.202}] {{10250}} { 03BE766C-FE24-8851-6BB8-D6E3075909E4 ccc4ea9f-66cf-465a-8fe6-58b76a524f88 3.16.0-4-amd64 Debian GNU/Linux 7 (wheezy) docker://1.9.1 v1.2.0-alpha.8.200+7f1b699880a3d4 v1.2.0-alpha.8.200+7f1b699880a3d4} [{[gcr.io/google_containers/kube-proxy:0a7bf1781f033fc04e110c3cd8505785] 165640277} {[gcr.io/google_containers/fluentd-elasticsearch:1.14] 562034622} {[gcr.io/google_containers/busybox:1.24] 1113554} {[gcr.io/google_containers/kube2sky:1.12] 24482187} {[gcr.io/google_containers/elasticsearch:1.8] 410989305} {[gcr.io/google_containers/mounttest:0.6] 2084693} {[gcr.io/google_containers/mounttest:0.5] 1718853} {[gcr.io/google_containers/skydns:2015-10-13-8c72f8c] 40551394} {[gcr.io/google_containers/pause:2.0] 350164} {[gcr.io/google_containers/exechealthz:1.0] 7099444} {[gcr.io/google_containers/mounttest:0.2] 1752375} {[gcr.io/google_containers/etcd:2.0.9] 12819040} {[gcr.io/google_containers/pause:0.8.0] 241656} {[b.gcr.io/k8s_authenticated_test/serve_hostname:1.1] 4522409}]}}
07:17:09 Feb 23 07:17:03.832: INFO: 
07:17:09 Logging kubelet events for node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:03.846: INFO: 
07:17:09 Logging pods the kubelet thinks is on node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:03.863: INFO: elasticsearch-logging-v1-nxkc6 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: fluentd-elasticsearch-jenkins-e2e-minion-w7f5 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: redis-master-kje8q started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: pod-host-path-test started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: nginx-controller-t6txl started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: nginx started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-kwky7 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: nginx-controller-bwora started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: kube-dns-v10-x0rod started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: kube-proxy-jenkins-e2e-minion-w7f5 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type=&quot;info&quot;} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type=&quot;inspect_image&quot;} =&gt; 17 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type=&quot;list_containers&quot;} =&gt; 32 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type=&quot;list_images&quot;} =&gt; 5 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type=&quot;stop_container&quot;} =&gt; 5 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type=&quot;version&quot;} =&gt; 22 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: 
07:17:09 Latency metrics for node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:04.343: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:43.696016s}
07:17:09 Feb 23 07:17:04.343: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:40.017824s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation: Method:pod_worker_start_latency_microseconds Quantile:0.99 Latency:40.017388s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:31.494667s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:31.494667s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.99 Latency:31.484782s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:29.420866s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:29.189764s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.9 Latency:28.506516s}
07:17:09 Feb 23 07:17:04.344: INFO: Waiting up to 1m0s for all nodes to be ready
07:17:09 STEP: Destroying namespace &quot;e2e-tests-port-forwarding-vep4r&quot; for this suite.
07:17:09 W0223 07:17:04.418253   31107 request.go:627] Throttling request took 58.08125ms, request: https://104.197.61.15/api/v1/namespaces/e2e-tests-port-forwarding-vep4r
07:17:09 
07:17:09 
07:17:09 • Failure [18.563 seconds]
07:17:09 Port forwarding
07:17:09 /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:240
07:17:09   With a server that expects a client request
07:17:09   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:203
07:17:09     should support a client that connects, sends no data, and disconnects [Conformance] [It]
07:17:09     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:156
07:17:09 
07:17:09     Feb 23 07:17:02.920: Missing &quot;Accepted client connection&quot; from log: 
07:17:09 
07:17:09     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:250
07:17:09 ------------------------------"><pre class="notranslate"><code class="notranslate">07:17:09 [It] should support a client that connects, sends no data, and disconnects [Conformance]
07:17:09   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:156
07:17:09 STEP: creating the target pod
07:17:09 Feb 23 07:16:55.427: INFO: Waiting up to 5m0s for pod pfpod status to be running
07:17:09 Feb 23 07:16:55.447: INFO: Waiting for pod pfpod in namespace 'e2e-tests-port-forwarding-vep4r' status to be 'running'(found phase: "Pending", readiness: false) (19.842797ms elapsed)
07:17:09 Feb 23 07:16:57.450: INFO: Waiting for pod pfpod in namespace 'e2e-tests-port-forwarding-vep4r' status to be 'running'(found phase: "Pending", readiness: false) (2.023387632s elapsed)
07:17:09 Feb 23 07:16:59.454: INFO: Waiting for pod pfpod in namespace 'e2e-tests-port-forwarding-vep4r' status to be 'running'(found phase: "Pending", readiness: false) (4.026805448s elapsed)
07:17:09 Feb 23 07:17:01.461: INFO: Found pod 'pfpod' on node 'jenkins-e2e-minion-ajjm'
07:17:09 STEP: Running 'kubectl port-forward'
07:17:09 Feb 23 07:17:01.461: INFO: starting port-forward command and streaming output
07:17:09 Feb 23 07:17:01.461: INFO: Asynchronously running '/jenkins-master-data/jobs/kubernetes-e2e-gce/workspace/kubernetes/platforms/linux/amd64/kubectl kubectl --server=https://104.197.61.15 --kubeconfig=/var/lib/jenkins/jobs/kubernetes-e2e-gce/workspace/.kube/config port-forward --namespace=e2e-tests-port-forwarding-vep4r pfpod :80'
07:17:09 Feb 23 07:17:01.463: INFO: reading from `kubectl port-forward` command's stderr
07:17:09 STEP: Dialing the local port
07:17:09 STEP: Closing the connection to the local port
07:17:09 Feb 23 07:17:01.988: INFO: Running '/jenkins-master-data/jobs/kubernetes-e2e-gce/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://104.197.61.15 --kubeconfig=/var/lib/jenkins/jobs/kubernetes-e2e-gce/workspace/.kube/config logs --namespace=e2e-tests-port-forwarding-vep4r -f pfpod'
07:17:09 Feb 23 07:17:02.920: INFO: stdout: ""
07:17:09 Feb 23 07:17:02.920: INFO: stderr: ""
07:17:09 Feb 23 07:17:02.920: FAIL: Missing "Accepted client connection" from log: 
07:17:09 [AfterEach] Port forwarding
07:17:09   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:84
07:17:09 STEP: Collecting events from namespace "e2e-tests-port-forwarding-vep4r".
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {default-scheduler } Scheduled: Successfully assigned pfpod to jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Pulling: pulling image "gcr.io/google_containers/portforwardtester:1.0"
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Pulled: Successfully pulled image "gcr.io/google_containers/portforwardtester:1.0"
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Created: Created container with docker id 4ad03609ba81
07:17:09 Feb 23 07:17:02.929: INFO: event for pfpod: {kubelet jenkins-e2e-minion-ajjm} Started: Started container with docker id 4ad03609ba81
07:17:09 Feb 23 07:17:02.987: INFO: POD                                                             NODE                     PHASE    GRACE  CONDITIONS
07:17:09 Feb 23 07:17:02.987: INFO: pod780e3fb2-da40-11e5-b787-42010af01555                         jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-configmaps-795a4b3f-da40-11e5-88c3-42010af01555             jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [configmap-volume-test]}]
07:17:09 Feb 23 07:17:02.987: INFO: client-containers-7825e4f5-da40-11e5-917d-42010af01555          jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [test-container]}]
07:17:09 Feb 23 07:17:02.987: INFO: client-containers-7dc2c528-da40-11e5-988c-42010af01555          jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:02 -0800 PST ContainersNotReady containers with unready status: [test-container]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-gzcqa                                          jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-t6txl                                          jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-ugxfw                                          jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx-controller-bwora                                          jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-79647c34-da40-11e5-9e63-42010af01555                        jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [test-container]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-host-path-test                                              jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST ContainersNotReady containers with unready status: [test-container-1 test-container-2]}]
07:17:09 Feb 23 07:17:02.987: INFO: foo-gso08                                                       jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:59 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: foo-oapzs                                                       jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:57 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: nginx                                                           jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: redis-master-kje8q                                              jenkins-e2e-minion-w7f5  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:01 -0800 PST ContainersNotReady containers with unready status: [redis-master]}]
07:17:09 Feb 23 07:17:02.987: INFO: nginx                                                           jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:53 -0800 PST ContainersNotReady containers with unready status: [nginx]}]
07:17:09 Feb 23 07:17:02.987: INFO: pod-logs-websocket-7c5c3108-da40-11e5-87e0-42010af01555         jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST ContainersNotReady containers with unready status: [main]}]
07:17:09 Feb 23 07:17:02.987: INFO: pfpod                                                           jenkins-e2e-minion-gjop  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST ContainersNotReady containers with unready status: [portforwardtester]}]
07:17:09 Feb 23 07:17:02.987: INFO: pfpod                                                           jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-kwky7  jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:02 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-tlu7z  jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:00 -0800 PST  }]
07:17:09 Feb 23 07:17:02.987: INFO: svc-latency-rc-ur1wo                                            jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:55 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: pod-service-account-787267e3-da40-11e5-b259-42010af01555        jenkins-e2e-minion-ajjm  Pending         [{Ready False 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:17:01 -0800 PST ContainersNotReady containers with unready status: [token-test root-ca-test]}]
07:17:09 Feb 23 07:17:02.988: INFO: elasticsearch-logging-v1-nxkc6                                  jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:19 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: elasticsearch-logging-v1-yse0k                                  jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:20 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: fluentd-elasticsearch-jenkins-e2e-minion-ajjm                   jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:10 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: fluentd-elasticsearch-jenkins-e2e-minion-gjop                   jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:16 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: fluentd-elasticsearch-jenkins-e2e-minion-w7f5                   jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:03 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: heapster-v14-pcau0                                              jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:20 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kibana-logging-v1-x0fzh                                         jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:41 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-dns-v10-x0rod                                              jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:28 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-proxy-jenkins-e2e-minion-ajjm                              jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:15:33 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-proxy-jenkins-e2e-minion-gjop                              jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:02 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kube-proxy-jenkins-e2e-minion-w7f5                              jenkins-e2e-minion-w7f5  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:15:35 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: kubernetes-dashboard-v0.1.0-09u97                               jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:10 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: l7-lb-controller-v0.5.2-xu332                                   jenkins-e2e-minion-ajjm  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:29 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: monitoring-influxdb-grafana-v3-s4zvp                            jenkins-e2e-minion-gjop  Running         [{Ready True 0001-01-01 00:00:00 +0000 UTC 2016-02-23 07:16:41 -0800 PST  }]
07:17:09 Feb 23 07:17:02.988: INFO: 
07:17:09 Feb 23 07:17:02.994: INFO: 
07:17:09 Logging node info for node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.001: INFO: Node Info: &amp;{{ } {jenkins-e2e-minion-ajjm   /api/v1/nodes/jenkins-e2e-minion-ajjm 2b5320f8-da40-11e5-bb5a-42010af00002 367 0 2016-02-23 07:14:44 -0800 PST &lt;nil&gt; &lt;nil&gt; map[beta.kubernetes.io/instance-type:n1-standard-2 failure-domain.beta.kubernetes.io/region:us-central1 failure-domain.beta.kubernetes.io/zone:us-central1-f kubernetes.io/hostname:jenkins-e2e-minion-ajjm master:] map[]} {10.245.0.0/24 14548348975076778713 gce://k8s-jkns-e2e-gce/us-central1-f/jenkins-e2e-minion-ajjm false} {map[memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI} cpu:{2.000 DecimalSI}] map[cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI}]  [{OutOfDisk False 2016-02-23 07:16:53 -0800 PST 2016-02-23 07:14:44 -0800 PST KubeletHasSufficientDisk kubelet has sufficient disk space available} {Ready True 2016-02-23 07:16:53 -0800 PST 2016-02-23 07:15:32 -0800 PST KubeletReady kubelet is posting ready status}] [{InternalIP 10.240.0.3} {ExternalIP 104.154.98.97}] {{10250}} { 10C07727-2011-F0F9-CA44-80C069F0F32D b76f4a86-6123-4fd9-9236-6b2d3cb80e7d 3.16.0-4-amd64 Debian GNU/Linux 7 (wheezy) docker://1.9.1 v1.2.0-alpha.8.200+7f1b699880a3d4 v1.2.0-alpha.8.200+7f1b699880a3d4} [{[gcr.io/google_containers/kube-proxy:0a7bf1781f033fc04e110c3cd8505785] 165640277} {[gcr.io/google_containers/fluentd-elasticsearch:1.14] 562034622} {[gcr.io/google_containers/kubernetes-dashboard-amd64:v0.1.0] 35409795} {[gcr.io/google_containers/glbc:0.5.2] 226351998} {[gcr.io/google_containers/elasticsearch:1.8] 410989305} {[gcr.io/google_containers/defaultbackend:1.0] 7513643} {[gcr.io/google_containers/pause:2.0] 350164} {[gcr.io/google_containers/pause:0.8.0] 241656}]}}
07:17:09 Feb 23 07:17:03.002: INFO: 
07:17:09 Logging kubelet events for node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.035: INFO: 
07:17:09 Logging pods the kubelet thinks is on node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.052: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-tlu7z started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: svc-latency-rc-ur1wo started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: nginx started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: fluentd-elasticsearch-jenkins-e2e-minion-ajjm started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: pod-logs-websocket-7c5c3108-da40-11e5-87e0-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: nginx-controller-gzcqa started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: foo-oapzs started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: l7-lb-controller-v0.5.2-xu332 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: elasticsearch-logging-v1-yse0k started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: kubernetes-dashboard-v0.1.0-09u97 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: kube-proxy-jenkins-e2e-minion-ajjm started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.052: INFO: pfpod started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.446: INFO: ERROR kubelet_docker_errors{operation_type="info"} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.446: INFO: ERROR kubelet_docker_errors{operation_type="inspect_image"} =&gt; 15 @[0]
07:17:09 Feb 23 07:17:03.446: INFO: ERROR kubelet_docker_errors{operation_type="list_containers"} =&gt; 32 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: ERROR kubelet_docker_errors{operation_type="list_images"} =&gt; 5 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: ERROR kubelet_docker_errors{operation_type="stop_container"} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: ERROR kubelet_docker_errors{operation_type="version"} =&gt; 22 @[0]
07:17:09 Feb 23 07:17:03.447: INFO: 
07:17:09 Latency metrics for node jenkins-e2e-minion-ajjm
07:17:09 Feb 23 07:17:03.447: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:41.49339s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:40.027169s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation: Method:pod_worker_start_latency_microseconds Quantile:0.99 Latency:40.02661s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:37.502548s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.99 Latency:37.480865s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:30.454951s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:29.950011s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:29.001099s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.9 Latency:27.51048s}
07:17:09 Feb 23 07:17:03.447: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:10.221818s}
07:17:09 Feb 23 07:17:03.447: INFO: 
07:17:09 Logging node info for node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.451: INFO: Node Info: &amp;{{ } {jenkins-e2e-minion-gjop   /api/v1/nodes/jenkins-e2e-minion-gjop 2c6a9bda-da40-11e5-bb5a-42010af00002 373 0 2016-02-23 07:14:46 -0800 PST &lt;nil&gt; &lt;nil&gt; map[kubernetes.io/hostname:jenkins-e2e-minion-gjop master: beta.kubernetes.io/instance-type:n1-standard-2 failure-domain.beta.kubernetes.io/region:us-central1 failure-domain.beta.kubernetes.io/zone:us-central1-f] map[]} {10.245.1.0/24 10660228105066903695 gce://k8s-jkns-e2e-gce/us-central1-f/jenkins-e2e-minion-gjop false} {map[pods:{110.000 DecimalSI} cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI}] map[memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI} cpu:{2.000 DecimalSI}]  [{OutOfDisk False 2016-02-23 07:16:54 -0800 PST 2016-02-23 07:14:46 -0800 PST KubeletHasSufficientDisk kubelet has sufficient disk space available} {Ready True 2016-02-23 07:16:54 -0800 PST 2016-02-23 07:15:33 -0800 PST KubeletReady kubelet is posting ready status}] [{InternalIP 10.240.0.5} {ExternalIP 104.154.18.200}] {{10250}} { CEF2A9D6-8CE4-1670-1696-570023B29CC8 8635b608-0928-46fa-9252-424edf6eb21e 3.16.0-4-amd64 Debian GNU/Linux 7 (wheezy) docker://1.9.1 v1.2.0-alpha.8.200+7f1b699880a3d4 v1.2.0-alpha.8.200+7f1b699880a3d4} [{[gcr.io/google_containers/kube-proxy:0a7bf1781f033fc04e110c3cd8505785] 165640277} {[gcr.io/google_containers/heapster:v0.20.0-alpha6] 85929942} {[gcr.io/google_containers/fluentd-elasticsearch:1.14] 562034622} {[gcr.io/google_containers/mounttest:0.5] 1718853} {[gcr.io/google_containers/heapster_grafana:v2.1.1] 206641132} {[gcr.io/google_containers/pause:2.0] 350164} {[gcr.io/google_containers/heapster_influxdb:v0.5] 251005705} {[gcr.io/google_containers/kibana:1.3] 396897764} {[gcr.io/google_containers/pause:0.8.0] 241656}]}}
07:17:09 Feb 23 07:17:03.451: INFO: 
07:17:09 Logging kubelet events for node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.464: INFO: 
07:17:09 Logging pods the kubelet thinks is on node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.573: INFO: nginx-controller-ugxfw started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pfpod started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: client-containers-7825e4f5-da40-11e5-917d-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: monitoring-influxdb-grafana-v3-s4zvp started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: kube-proxy-jenkins-e2e-minion-gjop started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: heapster-v14-pcau0 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: fluentd-elasticsearch-jenkins-e2e-minion-gjop started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: foo-gso08 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pod-79647c34-da40-11e5-9e63-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pod-configmaps-795a4b3f-da40-11e5-88c3-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: pod780e3fb2-da40-11e5-b787-42010af01555 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.573: INFO: kibana-logging-v1-x0fzh started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="info"} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="inspect_image"} =&gt; 20 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="list_containers"} =&gt; 36 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="list_images"} =&gt; 6 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="pull_image"} =&gt; 2 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="stop_container"} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:03.825: INFO: ERROR kubelet_docker_errors{operation_type="version"} =&gt; 24 @[0]
07:17:09 Feb 23 07:17:03.826: INFO: 
07:17:09 Latency metrics for node jenkins-e2e-minion-gjop
07:17:09 Feb 23 07:17:03.826: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:1m9.824686s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:52.910615s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:51.304079s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:51.304079s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.99 Latency:51.295383s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:47.065565s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:45.90737s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation: Method:pod_worker_start_latency_microseconds Quantile:0.99 Latency:35.210061s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.5 Latency:31.659999s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.9 Latency:31.646042s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:29.831946s}
07:17:09 Feb 23 07:17:03.826: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.9 Latency:29.088885s}
07:17:09 Feb 23 07:17:03.826: INFO: 
07:17:09 Logging node info for node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:03.831: INFO: Node Info: &amp;{{ } {jenkins-e2e-minion-w7f5   /api/v1/nodes/jenkins-e2e-minion-w7f5 2b781dda-da40-11e5-bb5a-42010af00002 927 0 2016-02-23 07:14:44 -0800 PST &lt;nil&gt; &lt;nil&gt; map[beta.kubernetes.io/instance-type:n1-standard-2 failure-domain.beta.kubernetes.io/region:us-central1 failure-domain.beta.kubernetes.io/zone:us-central1-f kubernetes.io/hostname:jenkins-e2e-minion-w7f5 master:] map[]} {10.245.2.0/24 5512211706074314555 gce://k8s-jkns-e2e-gce/us-central1-f/jenkins-e2e-minion-w7f5 false} {map[cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI}] map[cpu:{2.000 DecimalSI} memory:{7864139776.000 BinarySI} pods:{110.000 DecimalSI}]  [{OutOfDisk False 2016-02-23 07:17:03 -0800 PST 2016-02-23 07:14:44 -0800 PST KubeletHasSufficientDisk kubelet has sufficient disk space available} {Ready True 2016-02-23 07:17:03 -0800 PST 2016-02-23 07:15:32 -0800 PST KubeletReady kubelet is posting ready status}] [{InternalIP 10.240.0.4} {ExternalIP 104.154.49.202}] {{10250}} { 03BE766C-FE24-8851-6BB8-D6E3075909E4 ccc4ea9f-66cf-465a-8fe6-58b76a524f88 3.16.0-4-amd64 Debian GNU/Linux 7 (wheezy) docker://1.9.1 v1.2.0-alpha.8.200+7f1b699880a3d4 v1.2.0-alpha.8.200+7f1b699880a3d4} [{[gcr.io/google_containers/kube-proxy:0a7bf1781f033fc04e110c3cd8505785] 165640277} {[gcr.io/google_containers/fluentd-elasticsearch:1.14] 562034622} {[gcr.io/google_containers/busybox:1.24] 1113554} {[gcr.io/google_containers/kube2sky:1.12] 24482187} {[gcr.io/google_containers/elasticsearch:1.8] 410989305} {[gcr.io/google_containers/mounttest:0.6] 2084693} {[gcr.io/google_containers/mounttest:0.5] 1718853} {[gcr.io/google_containers/skydns:2015-10-13-8c72f8c] 40551394} {[gcr.io/google_containers/pause:2.0] 350164} {[gcr.io/google_containers/exechealthz:1.0] 7099444} {[gcr.io/google_containers/mounttest:0.2] 1752375} {[gcr.io/google_containers/etcd:2.0.9] 12819040} {[gcr.io/google_containers/pause:0.8.0] 241656} {[b.gcr.io/k8s_authenticated_test/serve_hostname:1.1] 4522409}]}}
07:17:09 Feb 23 07:17:03.832: INFO: 
07:17:09 Logging kubelet events for node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:03.846: INFO: 
07:17:09 Logging pods the kubelet thinks is on node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:03.863: INFO: elasticsearch-logging-v1-nxkc6 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: fluentd-elasticsearch-jenkins-e2e-minion-w7f5 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: redis-master-kje8q started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: pod-host-path-test started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: nginx-controller-t6txl started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: nginx started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: my-hostname-private-78025aec-da40-11e5-a865-42010af01555-kwky7 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: nginx-controller-bwora started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: kube-dns-v10-x0rod started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:03.863: INFO: kube-proxy-jenkins-e2e-minion-w7f5 started at &lt;nil&gt; (0 container statuses recorded)
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type="info"} =&gt; 1 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type="inspect_image"} =&gt; 17 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type="list_containers"} =&gt; 32 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type="list_images"} =&gt; 5 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type="stop_container"} =&gt; 5 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: ERROR kubelet_docker_errors{operation_type="version"} =&gt; 22 @[0]
07:17:09 Feb 23 07:17:04.343: INFO: 
07:17:09 Latency metrics for node jenkins-e2e-minion-w7f5
07:17:09 Feb 23 07:17:04.343: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:43.696016s}
07:17:09 Feb 23 07:17:04.343: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:40.017824s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation: Method:pod_worker_start_latency_microseconds Quantile:0.99 Latency:40.017388s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:31.494667s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:31.494667s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:SyncPod Method:container_manager_latency_microseconds Quantile:0.99 Latency:31.484782s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:29.420866s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:29.189764s}
07:17:09 Feb 23 07:17:04.344: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.9 Latency:28.506516s}
07:17:09 Feb 23 07:17:04.344: INFO: Waiting up to 1m0s for all nodes to be ready
07:17:09 STEP: Destroying namespace "e2e-tests-port-forwarding-vep4r" for this suite.
07:17:09 W0223 07:17:04.418253   31107 request.go:627] Throttling request took 58.08125ms, request: https://104.197.61.15/api/v1/namespaces/e2e-tests-port-forwarding-vep4r
07:17:09 
07:17:09 
07:17:09 • Failure [18.563 seconds]
07:17:09 Port forwarding
07:17:09 /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:240
07:17:09   With a server that expects a client request
07:17:09   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:203
07:17:09     should support a client that connects, sends no data, and disconnects [Conformance] [It]
07:17:09     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:156
07:17:09 
07:17:09     Feb 23 07:17:02.920: Missing "Accepted client connection" from log: 
07:17:09 
07:17:09     /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/portforward.go:250
07:17:09 ------------------------------
</code></pre></div>
<p dir="auto">cc/ <a class="user-mention notranslate" data-hovercard-type="user" data-hovercard-url="/users/lavalamp/hovercard" data-octo-click="hovercard-link-click" data-octo-dimensions="link_type:self" href="https://github.com/lavalamp">@lavalamp</a></p>