This job view page is being replaced by Spyglass soon. Check out the new job view.
PRpjbgf: Harden kube-dns to run with less privileges.
ResultFAILURE
Tests 1 failed / 478 succeeded
Started2019-09-11 19:46
Elapsed22m59s
Revision
Buildergke-prow-ssd-pool-1a225945-86t9
Refs master:001f2cd2
82347:2545cbaf
podc2125ab5-d4cc-11e9-b541-82f14e5fe11d
infra-commit72663f1bb
job-versionv1.17.0-alpha.0.1273+44be8e05febf31
podc2125ab5-d4cc-11e9-b541-82f14e5fe11d
repok8s.io/kubernetes
repo-commit44be8e05febf31781c2fd4f54b3bc4107540b351
repos{u'k8s.io/kubernetes': u'master:001f2cd2b553d06028c8542c8817820ee05d657f,82347:2545cbafd9b8e776a84c76dfb94f99fba9e5b6f1'}
revisionv1.17.0-alpha.0.1273+44be8e05febf31

Test Failures


Node Tests 21m17s

error during go run /go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go --cleanup --logtostderr --vmodule=*=4 --ssh-env=gce --results-dir=/workspace/_artifacts --project=k8s-jkns-pr-node-e2e --zone=us-west1-b --ssh-user=prow --ssh-key=/workspace/.ssh/google_compute_engine --ginkgo-flags=--nodes=8 --focus="\[NodeConformance\]" --skip="\[Flaky\]|\[Slow\]|\[Serial\]" --flakeAttempts=2 --test_args=--kubelet-flags="--cgroups-per-qos=true --cgroup-root=/" --test-timeout=1h5m0s --image-config-file=/workspace/test-infra/jobs/e2e_node/image-config.yaml: exit status 1
				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 478 Passed Tests

Show 468 Skipped Tests

Error lines from build-log.txt

... skipping 263 lines ...
I0911 19:49:12.217] make[1]: Entering directory '/go/src/k8s.io/kubernetes'
W0911 19:49:12.317] I0911 19:49:12.222878    4634 run_remote.go:567] Creating instance {image:ubuntu-gke-1804-d1703-0-v20181113 imageDesc:ubuntu-gke-1804-d1703-0-v20181113 project:ubuntu-os-gke-cloud resources:{Accelerators:[]} metadata:<nil> machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 19:49:12.320] I0911 19:49:12.240387    4634 run_remote.go:567] Creating instance {image:coreos-beta-1911-1-1-v20181011 imageDesc:coreos-beta-1911-1-1-v20181011 project:coreos-cloud resources:{Accelerators:[]} metadata:0xc0004ccc40 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 19:49:12.321] I0911 19:49:12.242932    4634 run_remote.go:567] Creating instance {image:cos-stable-60-9592-84-0 imageDesc:cos-stable-60-9592-84-0 project:cos-cloud resources:{Accelerators:[]} metadata:0xc0002143f0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 19:49:12.322] I0911 19:49:12.280702    4634 run_remote.go:567] Creating instance {image:cos-stable-63-10032-71-0 imageDesc:cos-stable-63-10032-71-0 project:cos-cloud resources:{Accelerators:[]} metadata:0xc0004ea0e0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 19:49:12.712] I0911 19:49:12.712359    4634 run_remote.go:742] Deleting instance ""
W0911 19:49:12.723] E0911 19:49:12.723233    4634 run_remote.go:745] Error deleting instance "": googleapi: got HTTP response code 404 with body: <!DOCTYPE html>
W0911 19:49:12.723] <html lang=en>
W0911 19:49:12.724]   <meta charset=utf-8>
W0911 19:49:12.724]   <meta name=viewport content="initial-scale=1, minimum-scale=1, width=device-width">
W0911 19:49:12.724]   <title>Error 404 (Not Found)!!1</title>
W0911 19:49:12.725]   <style>
W0911 19:49:12.726]     *{margin:0;padding:0}html,code{font:15px/22px arial,sans-serif}html{background:#fff;color:#222;padding:15px}body{margin:7% auto 0;max-width:390px;min-height:180px;padding:30px 0 15px}* > body{background:url(//www.google.com/images/errors/robot.png) 100% 5px no-repeat;padding-right:205px}p{margin:11px 0 22px;overflow:hidden}ins{color:#777;text-decoration:none}a img{border:0}@media screen and (max-width:772px){body{background:none;margin-top:0;max-width:none;padding-right:0}}#logo{background:url(//www.google.com/images/branding/googlelogo/1x/googlelogo_color_150x54dp.png) no-repeat;margin-left:-5px}@media only screen and (min-resolution:192dpi){#logo{background:url(//www.google.com/images/branding/googlelogo/2x/googlelogo_color_150x54dp.png) no-repeat 0% 0%/100% 100%;-moz-border-image:url(//www.google.com/images/branding/googlelogo/2x/googlelogo_color_150x54dp.png) 0}}@media only screen and (-webkit-min-device-pixel-ratio:2){#logo{background:url(//www.google.com/images/branding/googlelogo/2x/googlelogo_color_150x54dp.png) no-repeat;-webkit-background-size:100% 100%}}#logo{display:inline-block;height:54px;width:150px}
W0911 19:49:12.726]   </style>
W0911 19:49:12.727]   <a href=//www.google.com/><span id=logo aria-label=Google></span></a>
W0911 19:49:12.727]   <p><b>404.</b> <ins>That’s an error.</ins>
W0911 19:49:12.727]   <p>The requested URL <code>/compute/beta/projects/k8s-jkns-pr-node-e2e/zones/us-west1-b/instances/?alt=json&amp;prettyPrint=false</code> was not found on this server.  <ins>That’s all we know.</ins>
I0911 19:49:12.828] 
I0911 19:49:12.828] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 19:49:12.828] >                              START TEST                                >
I0911 19:49:12.829] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 19:49:12.829] Start Test Suite on Host 
I0911 19:49:12.829] 
I0911 19:49:12.829] Failure Finished Test Suite on Host 
I0911 19:49:12.830] unable to create gce instance with running docker daemon for image coreos-beta-1911-1-1-v20181011.  could not create instance tmp-node-e2e-3a2ef4f3-coreos-beta-1911-1-1-v20181011: API error: googleapi: Error 400: Invalid value for field 'resource.disks[0].initializeParams.sourceImage': 'projects/coreos-cloud/global/images/coreos-beta-1911-1-1-v20181011'. The referenced image resource cannot be found., invalid
I0911 19:49:12.830] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 19:49:12.830] <                              FINISH TEST                               <
I0911 19:49:12.830] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 19:49:12.831] 
I0911 19:49:27.239] +++ [0911 19:49:27] Building go targets for linux/amd64:
I0911 19:49:27.239]     ./vendor/k8s.io/code-generator/cmd/deepcopy-gen
... skipping 1738 lines ...
I0911 20:07:50.070] STEP: Creating a kubernetes client
I0911 20:07:50.071] STEP: Building a namespace api object, basename container-runtime
I0911 20:07:50.071] Sep 11 20:00:51.969: INFO: Skipping waiting for service account
I0911 20:07:50.071] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 20:07:50.071]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:07:50.071] STEP: create the container
I0911 20:07:50.071] STEP: wait for the container to reach Failed
I0911 20:07:50.071] STEP: get the container status
I0911 20:07:50.071] STEP: the container should be terminated
I0911 20:07:50.072] STEP: the termination message should be set
I0911 20:07:50.072] Sep 11 20:00:55.047: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 20:07:50.072] STEP: delete the container
I0911 20:07:50.072] [AfterEach] [k8s.io] Container Runtime
... skipping 684 lines ...
I0911 20:07:50.188]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:07:50.188] STEP: Creating a kubernetes client
I0911 20:07:50.188] STEP: Building a namespace api object, basename init-container
I0911 20:07:50.188] Sep 11 20:01:38.496: INFO: Skipping waiting for service account
I0911 20:07:50.188] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:07:50.189]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 20:07:50.189] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 20:07:50.189]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:07:50.189] STEP: creating the pod
I0911 20:07:50.190] Sep 11 20:01:38.496: INFO: PodSpec: initContainers in spec.initContainers
I0911 20:07:50.190] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:07:50.190]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:07:50.190] Sep 11 20:01:40.197: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 20:07:50.191] Sep 11 20:01:46.247: INFO: namespace init-container-3323 deletion completed in 6.043140981s
I0911 20:07:50.191] 
I0911 20:07:50.191] 
I0911 20:07:50.191] • [SLOW TEST:7.754 seconds]
I0911 20:07:50.191] [k8s.io] InitContainer [NodeConformance]
I0911 20:07:50.192] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 20:07:50.192]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 20:07:50.192]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:07:50.192] ------------------------------
I0911 20:07:50.193] [BeforeEach] [sig-storage] Secrets
I0911 20:07:50.193]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:07:50.193] STEP: Creating a kubernetes client
I0911 20:07:50.193] STEP: Building a namespace api object, basename secrets
... skipping 300 lines ...
I0911 20:07:50.240]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:07:50.240] STEP: Creating a kubernetes client
I0911 20:07:50.240] STEP: Building a namespace api object, basename init-container
I0911 20:07:50.240] Sep 11 20:01:14.204: INFO: Skipping waiting for service account
I0911 20:07:50.240] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:07:50.240]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 20:07:50.240] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 20:07:50.240]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:07:50.240] STEP: creating the pod
I0911 20:07:50.241] Sep 11 20:01:14.204: INFO: PodSpec: initContainers in spec.initContainers
I0911 20:07:50.246] Sep 11 20:01:55.735: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-23821567-e4a9-4ce2-93e5-e1bbfebd777c", GenerateName:"", Namespace:"init-container-9051", SelfLink:"/api/v1/namespaces/init-container-9051/pods/pod-init-23821567-e4a9-4ce2-93e5-e1bbfebd777c", UID:"ddccafc9-68e8-4bfc-b78f-3d3a2ca7f893", ResourceVersion:"1792", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703828874, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"204286793"}, Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ClusterName:"", ManagedFields:[]v1.ManagedFieldsEntry(nil)}, Spec:v1.PodSpec{Volumes:[]v1.Volume(nil), InitContainers:[]v1.Container{v1.Container{Name:"init1", Image:"docker.io/library/busybox:1.29", Command:[]string{"/bin/false"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}, v1.Container{Name:"init2", Image:"docker.io/library/busybox:1.29", Command:[]string{"/bin/true"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}}, Containers:[]v1.Container{v1.Container{Name:"run1", Image:"k8s.gcr.io/pause:3.1", Command:[]string(nil), Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:52428800, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"52428800", Format:"DecimalSI"}}, Requests:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:52428800, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"52428800", Format:"DecimalSI"}}}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"Never", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}}, EphemeralContainers:[]v1.EphemeralContainer(nil), RestartPolicy:"Always", TerminationGracePeriodSeconds:(*int64)(0xc000f7bf20), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-3a2ef4f3-ubuntu-gke-1804-d1703-0-v20181113", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc00108e360), ImagePullSecrets:[]v1.LocalObjectReference(nil), Hostname:"", Subdomain:"", Affinity:(*v1.Affinity)(nil), SchedulerName:"default-scheduler", Tolerations:[]v1.Toleration{v1.Toleration{Key:"node.kubernetes.io/not-ready", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc000f7bf90)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc000f7bfb0)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc000f7bfc0), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc000f7bfc4), PreemptionPolicy:(*v1.PreemptionPolicy)(nil), Overhead:v1.ResourceList(nil), TopologySpreadConstraints:[]v1.TopologySpreadConstraint(nil)}, Status:v1.PodStatus{Phase:"Pending", Conditions:[]v1.PodCondition{v1.PodCondition{Type:"Initialized", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828874, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotInitialized", Message:"containers with incomplete status: [init1 init2]"}, v1.PodCondition{Type:"Ready", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828874, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"ContainersReady", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828874, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"PodScheduled", Status:"True", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828874, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.125", PodIP:"10.100.0.82", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.82"}}, StartTime:(*v1.Time)(0xc00105c300), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000986150)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0009861c0)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796", ContainerID:"docker://1bb7806da49517351a7489ca62413f118a365d6a91254440931e30fb4219ca61", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc00105c320), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"docker.io/library/busybox:1.29", ImageID:"", ContainerID:"", Started:(*bool)(nil)}}, ContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"run1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc00105c340), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"k8s.gcr.io/pause:3.1", ImageID:"", ContainerID:"", Started:(*bool)(0xc000b1a0cc)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 20:07:50.247] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:07:50.247]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:07:50.247] Sep 11 20:01:55.736: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:07:50.247] STEP: Destroying namespace "init-container-9051" for this suite.
I0911 20:07:50.247] Sep 11 20:02:07.783: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 20:07:50.247] Sep 11 20:02:07.894: INFO: namespace init-container-9051 deletion completed in 12.140508408s
I0911 20:07:50.247] 
I0911 20:07:50.247] 
I0911 20:07:50.248] • [SLOW TEST:53.694 seconds]
I0911 20:07:50.248] [k8s.io] InitContainer [NodeConformance]
I0911 20:07:50.248] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 20:07:50.248]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 20:07:50.248]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:07:50.248] ------------------------------
I0911 20:07:50.248] S
I0911 20:07:50.248] ------------------------------
I0911 20:07:50.248] [BeforeEach] [sig-storage] Secrets
I0911 20:07:50.249]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 1842 lines ...
I0911 20:07:50.489] STEP: verifying the pod is in kubernetes
I0911 20:07:50.489] STEP: updating the pod
I0911 20:07:50.489] Sep 11 20:05:14.357: INFO: Successfully updated pod "pod-update-activedeadlineseconds-8278df49-f948-4e30-a7c4-1cbde3e4bd01"
I0911 20:07:50.490] Sep 11 20:05:14.357: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-8278df49-f948-4e30-a7c4-1cbde3e4bd01" in namespace "pods-5746" to be "terminated due to deadline exceeded"
I0911 20:07:50.490] Sep 11 20:05:14.363: INFO: Pod "pod-update-activedeadlineseconds-8278df49-f948-4e30-a7c4-1cbde3e4bd01": Phase="Running", Reason="", readiness=true. Elapsed: 6.448188ms
I0911 20:07:50.490] Sep 11 20:05:16.365: INFO: Pod "pod-update-activedeadlineseconds-8278df49-f948-4e30-a7c4-1cbde3e4bd01": Phase="Running", Reason="", readiness=true. Elapsed: 2.00806366s
I0911 20:07:50.490] Sep 11 20:05:18.367: INFO: Pod "pod-update-activedeadlineseconds-8278df49-f948-4e30-a7c4-1cbde3e4bd01": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.009732142s
I0911 20:07:50.490] Sep 11 20:05:18.367: INFO: Pod "pod-update-activedeadlineseconds-8278df49-f948-4e30-a7c4-1cbde3e4bd01" satisfied condition "terminated due to deadline exceeded"
I0911 20:07:50.490] [AfterEach] [k8s.io] Pods
I0911 20:07:50.491]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:07:50.491] Sep 11 20:05:18.367: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:07:50.491] STEP: Destroying namespace "pods-5746" for this suite.
I0911 20:07:50.491] Sep 11 20:05:24.373: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 146 lines ...
I0911 20:07:50.510] [BeforeEach] [k8s.io] Security Context
I0911 20:07:50.510]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 20:07:50.510] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 20:07:50.511]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 20:07:50.511] Sep 11 20:05:26.975: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-a72b02d8-2520-4ccb-a33b-0306df86893e" in namespace "security-context-test-4514" to be "success or failure"
I0911 20:07:50.511] Sep 11 20:05:26.979: INFO: Pod "busybox-readonly-true-a72b02d8-2520-4ccb-a33b-0306df86893e": Phase="Pending", Reason="", readiness=false. Elapsed: 3.840059ms
I0911 20:07:50.511] Sep 11 20:05:28.981: INFO: Pod "busybox-readonly-true-a72b02d8-2520-4ccb-a33b-0306df86893e": Phase="Failed", Reason="", readiness=false. Elapsed: 2.005447162s
I0911 20:07:50.511] Sep 11 20:05:28.981: INFO: Pod "busybox-readonly-true-a72b02d8-2520-4ccb-a33b-0306df86893e" satisfied condition "success or failure"
I0911 20:07:50.511] [AfterEach] [k8s.io] Security Context
I0911 20:07:50.512]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:07:50.512] Sep 11 20:05:28.981: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:07:50.512] STEP: Destroying namespace "security-context-test-4514" for this suite.
I0911 20:07:50.512] Sep 11 20:05:36.998: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 486 lines ...
I0911 20:07:50.591] I0911 20:07:43.939416    2610 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 20:07:50.591] I0911 20:07:43.946539    2610 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 20:07:50.592] I0911 20:07:43.965967    2610 e2e_node_suite_test.go:201] Tests Finished
I0911 20:07:50.592] 
I0911 20:07:50.592] 
I0911 20:07:50.592] Ran 157 of 313 Specs in 628.981 seconds
I0911 20:07:50.592] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 20:07:50.592] 
I0911 20:07:50.592] 
I0911 20:07:50.592] Ginkgo ran 1 suite in 10m30.922243225s
I0911 20:07:50.592] Test Suite Passed
I0911 20:07:50.592] 
I0911 20:07:50.593] Success Finished Test Suite on Host tmp-node-e2e-3a2ef4f3-ubuntu-gke-1804-d1703-0-v20181113
... skipping 887 lines ...
I0911 20:09:22.711] Sep 11 19:59:32.472: INFO: Pod "poda05adafd-4fe0-400d-bc12-ce9c11d4c5bc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.009444029s
I0911 20:09:22.711] STEP: Saw pod success
I0911 20:09:22.711] Sep 11 19:59:32.472: INFO: Pod "poda05adafd-4fe0-400d-bc12-ce9c11d4c5bc" satisfied condition "success or failure"
I0911 20:09:22.711] STEP: Verifying the memory backed volume was removed from node
I0911 20:09:22.711] Sep 11 19:59:32.482: INFO: Waiting up to 5m0s for pod "pod646adb24-d49b-4c5a-8632-d5607319f17c" in namespace "kubelet-volume-manager-4570" to be "success or failure"
I0911 20:09:22.711] Sep 11 19:59:32.493: INFO: Pod "pod646adb24-d49b-4c5a-8632-d5607319f17c": Phase="Pending", Reason="", readiness=false. Elapsed: 10.314564ms
I0911 20:09:22.711] Sep 11 19:59:34.494: INFO: Pod "pod646adb24-d49b-4c5a-8632-d5607319f17c": Phase="Failed", Reason="", readiness=false. Elapsed: 2.012220529s
I0911 20:09:22.712] Sep 11 19:59:44.508: INFO: Waiting up to 5m0s for pod "pod9c9f0be4-86a5-4e84-a355-34f2753c386d" in namespace "kubelet-volume-manager-4570" to be "success or failure"
I0911 20:09:22.712] Sep 11 19:59:44.515: INFO: Pod "pod9c9f0be4-86a5-4e84-a355-34f2753c386d": Phase="Pending", Reason="", readiness=false. Elapsed: 6.997301ms
I0911 20:09:22.712] Sep 11 19:59:46.516: INFO: Pod "pod9c9f0be4-86a5-4e84-a355-34f2753c386d": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.008617161s
I0911 20:09:22.712] STEP: Saw pod success
I0911 20:09:22.712] Sep 11 19:59:46.516: INFO: Pod "pod9c9f0be4-86a5-4e84-a355-34f2753c386d" satisfied condition "success or failure"
I0911 20:09:22.712] [AfterEach] [k8s.io] Kubelet Volume Manager
... skipping 536 lines ...
I0911 20:09:22.782] [BeforeEach] [k8s.io] Security Context
I0911 20:09:22.783]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 20:09:22.783] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 20:09:22.783]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 20:09:22.783] Sep 11 20:00:24.461: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-5222a999-e955-4843-9225-54f2c1dac9b5" in namespace "security-context-test-5125" to be "success or failure"
I0911 20:09:22.783] Sep 11 20:00:24.463: INFO: Pod "busybox-readonly-true-5222a999-e955-4843-9225-54f2c1dac9b5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.196168ms
I0911 20:09:22.783] Sep 11 20:00:26.465: INFO: Pod "busybox-readonly-true-5222a999-e955-4843-9225-54f2c1dac9b5": Phase="Failed", Reason="", readiness=false. Elapsed: 2.003869689s
I0911 20:09:22.784] Sep 11 20:00:26.465: INFO: Pod "busybox-readonly-true-5222a999-e955-4843-9225-54f2c1dac9b5" satisfied condition "success or failure"
I0911 20:09:22.784] [AfterEach] [k8s.io] Security Context
I0911 20:09:22.784]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:22.784] Sep 11 20:00:26.465: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:09:22.784] STEP: Destroying namespace "security-context-test-5125" for this suite.
I0911 20:09:22.784] Sep 11 20:00:32.471: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 479 lines ...
I0911 20:09:22.859] STEP: submitting the pod to kubernetes
I0911 20:09:22.860] STEP: verifying the pod is in kubernetes
I0911 20:09:22.860] STEP: updating the pod
I0911 20:09:22.860] Sep 11 20:00:49.462: INFO: Successfully updated pod "pod-update-activedeadlineseconds-fb1777f3-9f41-4a16-bf04-446732a7a317"
I0911 20:09:22.860] Sep 11 20:00:49.462: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-fb1777f3-9f41-4a16-bf04-446732a7a317" in namespace "pods-6236" to be "terminated due to deadline exceeded"
I0911 20:09:22.861] Sep 11 20:00:49.466: INFO: Pod "pod-update-activedeadlineseconds-fb1777f3-9f41-4a16-bf04-446732a7a317": Phase="Running", Reason="", readiness=true. Elapsed: 4.369722ms
I0911 20:09:22.861] Sep 11 20:00:51.469: INFO: Pod "pod-update-activedeadlineseconds-fb1777f3-9f41-4a16-bf04-446732a7a317": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 2.007181452s
I0911 20:09:22.861] Sep 11 20:00:51.469: INFO: Pod "pod-update-activedeadlineseconds-fb1777f3-9f41-4a16-bf04-446732a7a317" satisfied condition "terminated due to deadline exceeded"
I0911 20:09:22.862] [AfterEach] [k8s.io] Pods
I0911 20:09:22.862]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:22.862] Sep 11 20:00:51.469: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:09:22.862] STEP: Destroying namespace "pods-6236" for this suite.
I0911 20:09:22.862] Sep 11 20:00:57.475: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 1392 lines ...
I0911 20:09:23.084]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:09:23.084] STEP: Creating a kubernetes client
I0911 20:09:23.085] STEP: Building a namespace api object, basename init-container
I0911 20:09:23.085] Sep 11 20:02:21.563: INFO: Skipping waiting for service account
I0911 20:09:23.085] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:23.085]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 20:09:23.086] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 20:09:23.086]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:23.086] STEP: creating the pod
I0911 20:09:23.086] Sep 11 20:02:21.563: INFO: PodSpec: initContainers in spec.initContainers
I0911 20:09:23.086] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:23.087]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:23.087] Sep 11 20:02:23.495: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 20:09:23.088] Sep 11 20:02:31.567: INFO: namespace init-container-1811 deletion completed in 8.070320547s
I0911 20:09:23.088] 
I0911 20:09:23.088] 
I0911 20:09:23.088] • [SLOW TEST:10.006 seconds]
I0911 20:09:23.088] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:23.089] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 20:09:23.089]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 20:09:23.089]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:23.089] ------------------------------
I0911 20:09:23.089] S
I0911 20:09:23.090] ------------------------------
I0911 20:09:23.090] [BeforeEach] [sig-storage] Downward API volume
I0911 20:09:23.090]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 907 lines ...
I0911 20:09:23.249]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:09:23.249] STEP: Creating a kubernetes client
I0911 20:09:23.250] STEP: Building a namespace api object, basename init-container
I0911 20:09:23.250] Sep 11 20:03:11.806: INFO: Skipping waiting for service account
I0911 20:09:23.250] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:23.250]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 20:09:23.250] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 20:09:23.251]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:23.251] STEP: creating the pod
I0911 20:09:23.251] Sep 11 20:03:11.806: INFO: PodSpec: initContainers in spec.initContainers
I0911 20:09:23.259] Sep 11 20:03:55.454: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-4ff4b5c3-14f1-46a0-9a05-db0828736942", GenerateName:"", Namespace:"init-container-1424", SelfLink:"/api/v1/namespaces/init-container-1424/pods/pod-init-4ff4b5c3-14f1-46a0-9a05-db0828736942", UID:"77c9df98-23f2-48cb-81a7-a827c0ea55e8", ResourceVersion:"2754", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703828991, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"806671352"}, Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ClusterName:"", ManagedFields:[]v1.ManagedFieldsEntry(nil)}, Spec:v1.PodSpec{Volumes:[]v1.Volume(nil), InitContainers:[]v1.Container{v1.Container{Name:"init1", Image:"docker.io/library/busybox:1.29", Command:[]string{"/bin/false"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}, v1.Container{Name:"init2", Image:"docker.io/library/busybox:1.29", Command:[]string{"/bin/true"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}}, Containers:[]v1.Container{v1.Container{Name:"run1", Image:"k8s.gcr.io/pause:3.1", Command:[]string(nil), Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:52428800, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"52428800", Format:"DecimalSI"}}, Requests:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:52428800, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"52428800", Format:"DecimalSI"}}}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"Never", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}}, EphemeralContainers:[]v1.EphemeralContainer(nil), RestartPolicy:"Always", TerminationGracePeriodSeconds:(*int64)(0xc0008d7e40), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-3a2ef4f3-cos-stable-60-9592-84-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc001156000), ImagePullSecrets:[]v1.LocalObjectReference(nil), Hostname:"", Subdomain:"", Affinity:(*v1.Affinity)(nil), SchedulerName:"default-scheduler", Tolerations:[]v1.Toleration{v1.Toleration{Key:"node.kubernetes.io/not-ready", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0008d7eb0)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0008d7ee0)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0008d7ef0), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0008d7ef4), PreemptionPolicy:(*v1.PreemptionPolicy)(nil), Overhead:v1.ResourceList(nil), TopologySpreadConstraints:[]v1.TopologySpreadConstraint(nil)}, Status:v1.PodStatus{Phase:"Pending", Conditions:[]v1.PodCondition{v1.PodCondition{Type:"Initialized", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828991, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotInitialized", Message:"containers with incomplete status: [init1 init2]"}, v1.PodCondition{Type:"Ready", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828991, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"ContainersReady", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828991, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"PodScheduled", Status:"True", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703828991, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.124", PodIP:"10.100.0.151", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.151"}}, StartTime:(*v1.Time)(0xc000bdd2c0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0002feee0)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0002fef50)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://42c1171485670422480f8d46aae32b51a9b3c2f34d2e3920597b95fef9afd602", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000bdd2e0), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"docker.io/library/busybox:1.29", ImageID:"", ContainerID:"", Started:(*bool)(nil)}}, ContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"run1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000bdd300), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"k8s.gcr.io/pause:3.1", ImageID:"", ContainerID:"", Started:(*bool)(0xc00125a03c)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 20:09:23.260] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:23.260]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:23.260] Sep 11 20:03:55.456: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:09:23.261] STEP: Destroying namespace "init-container-1424" for this suite.
I0911 20:09:23.261] Sep 11 20:04:23.496: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 20:09:23.261] Sep 11 20:04:23.541: INFO: namespace init-container-1424 deletion completed in 28.082853862s
I0911 20:09:23.261] 
I0911 20:09:23.262] 
I0911 20:09:23.262] • [SLOW TEST:71.751 seconds]
I0911 20:09:23.262] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:23.262] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 20:09:23.262]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 20:09:23.263]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:23.263] ------------------------------
I0911 20:09:23.263] S
I0911 20:09:23.263] ------------------------------
I0911 20:09:23.263] [BeforeEach] [sig-storage] Downward API volume
I0911 20:09:23.263]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 458 lines ...
I0911 20:09:23.350] STEP: Creating a kubernetes client
I0911 20:09:23.350] STEP: Building a namespace api object, basename container-runtime
I0911 20:09:23.351] Sep 11 20:05:14.047: INFO: Skipping waiting for service account
I0911 20:09:23.351] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 20:09:23.351]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:23.351] STEP: create the container
I0911 20:09:23.351] STEP: wait for the container to reach Failed
I0911 20:09:23.352] STEP: get the container status
I0911 20:09:23.352] STEP: the container should be terminated
I0911 20:09:23.352] STEP: the termination message should be set
I0911 20:09:23.352] Sep 11 20:05:15.069: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 20:09:23.352] STEP: delete the container
I0911 20:09:23.352] [AfterEach] [k8s.io] Container Runtime
... skipping 474 lines ...
I0911 20:09:23.434]   should *not* be restarted with a exec "cat /tmp/health" liveness probe [NodeConformance] [Conformance]
I0911 20:09:23.434]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:23.434] ------------------------------
I0911 20:09:23.434] I0911 20:09:14.904854    1295 e2e_node_suite_test.go:196] Stopping node services...
I0911 20:09:23.434] I0911 20:09:14.904879    1295 server.go:257] Kill server "services"
I0911 20:09:23.435] I0911 20:09:14.904888    1295 server.go:294] Killing process 1941 (services) with -TERM
I0911 20:09:23.435] E0911 20:09:15.050053    1295 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 20:09:23.435] I0911 20:09:15.050072    1295 server.go:257] Kill server "kubelet"
I0911 20:09:23.435] I0911 20:09:15.058042    1295 services.go:147] Fetching log files...
I0911 20:09:23.435] I0911 20:09:15.058093    1295 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 20:09:23.435] I0911 20:09:15.192515    1295 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 20:09:23.435] I0911 20:09:15.688532    1295 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 20:09:23.436] I0911 20:09:15.718060    1295 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T195700.service].
I0911 20:09:23.436] I0911 20:09:16.712771    1295 e2e_node_suite_test.go:201] Tests Finished
I0911 20:09:23.436] 
I0911 20:09:23.436] 
I0911 20:09:23.436] Ran 157 of 313 Specs in 720.568 seconds
I0911 20:09:23.436] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 20:09:23.436] 
I0911 20:09:23.436] 
I0911 20:09:23.436] Ginkgo ran 1 suite in 12m4.431863424s
I0911 20:09:23.436] Test Suite Passed
I0911 20:09:23.436] 
I0911 20:09:23.437] Success Finished Test Suite on Host tmp-node-e2e-3a2ef4f3-cos-stable-60-9592-84-0
... skipping 1401 lines ...
I0911 20:09:25.981] [BeforeEach] [k8s.io] Security Context
I0911 20:09:25.981]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 20:09:25.982] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 20:09:25.982]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 20:09:25.982] Sep 11 20:00:22.949: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-edd15da2-8842-4b6d-9e3d-6aca3198c0ae" in namespace "security-context-test-6689" to be "success or failure"
I0911 20:09:25.982] Sep 11 20:00:22.957: INFO: Pod "busybox-readonly-true-edd15da2-8842-4b6d-9e3d-6aca3198c0ae": Phase="Pending", Reason="", readiness=false. Elapsed: 8.296476ms
I0911 20:09:25.982] Sep 11 20:00:24.959: INFO: Pod "busybox-readonly-true-edd15da2-8842-4b6d-9e3d-6aca3198c0ae": Phase="Failed", Reason="", readiness=false. Elapsed: 2.009955076s
I0911 20:09:25.983] Sep 11 20:00:24.959: INFO: Pod "busybox-readonly-true-edd15da2-8842-4b6d-9e3d-6aca3198c0ae" satisfied condition "success or failure"
I0911 20:09:25.983] [AfterEach] [k8s.io] Security Context
I0911 20:09:25.983]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:25.983] Sep 11 20:00:24.959: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:09:25.983] STEP: Destroying namespace "security-context-test-6689" for this suite.
I0911 20:09:25.983] Sep 11 20:00:30.970: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 471 lines ...
I0911 20:09:26.055] STEP: verifying the pod is in kubernetes
I0911 20:09:26.055] STEP: updating the pod
I0911 20:09:26.055] Sep 11 20:00:54.326: INFO: Successfully updated pod "pod-update-activedeadlineseconds-3177727e-6a2f-4023-90dc-f870c89f032a"
I0911 20:09:26.056] Sep 11 20:00:54.326: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-3177727e-6a2f-4023-90dc-f870c89f032a" in namespace "pods-8613" to be "terminated due to deadline exceeded"
I0911 20:09:26.056] Sep 11 20:00:54.331: INFO: Pod "pod-update-activedeadlineseconds-3177727e-6a2f-4023-90dc-f870c89f032a": Phase="Running", Reason="", readiness=true. Elapsed: 5.387439ms
I0911 20:09:26.056] Sep 11 20:00:56.369: INFO: Pod "pod-update-activedeadlineseconds-3177727e-6a2f-4023-90dc-f870c89f032a": Phase="Running", Reason="", readiness=true. Elapsed: 2.042780983s
I0911 20:09:26.057] Sep 11 20:00:58.371: INFO: Pod "pod-update-activedeadlineseconds-3177727e-6a2f-4023-90dc-f870c89f032a": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.044618514s
I0911 20:09:26.057] Sep 11 20:00:58.371: INFO: Pod "pod-update-activedeadlineseconds-3177727e-6a2f-4023-90dc-f870c89f032a" satisfied condition "terminated due to deadline exceeded"
I0911 20:09:26.057] [AfterEach] [k8s.io] Pods
I0911 20:09:26.057]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:26.057] Sep 11 20:00:58.371: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:09:26.057] STEP: Destroying namespace "pods-8613" for this suite.
I0911 20:09:26.057] Sep 11 20:01:04.377: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 1437 lines ...
I0911 20:09:26.286]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:09:26.286] STEP: Creating a kubernetes client
I0911 20:09:26.286] STEP: Building a namespace api object, basename init-container
I0911 20:09:26.286] Sep 11 20:02:30.844: INFO: Skipping waiting for service account
I0911 20:09:26.286] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:26.287]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 20:09:26.287] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 20:09:26.287]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:26.287] STEP: creating the pod
I0911 20:09:26.287] Sep 11 20:02:30.844: INFO: PodSpec: initContainers in spec.initContainers
I0911 20:09:26.287] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:26.288]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:26.288] Sep 11 20:02:32.825: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 20:09:26.288] Sep 11 20:02:39.236: INFO: namespace init-container-9343 deletion completed in 6.404368266s
I0911 20:09:26.289] 
I0911 20:09:26.289] 
I0911 20:09:26.289] • [SLOW TEST:8.394 seconds]
I0911 20:09:26.289] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:26.289] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 20:09:26.289]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 20:09:26.290]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:26.290] ------------------------------
I0911 20:09:26.290] S
I0911 20:09:26.290] ------------------------------
I0911 20:09:26.290] [BeforeEach] [sig-storage] Downward API volume
I0911 20:09:26.290]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 964 lines ...
I0911 20:09:26.439]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:09:26.439] STEP: Creating a kubernetes client
I0911 20:09:26.439] STEP: Building a namespace api object, basename init-container
I0911 20:09:26.439] Sep 11 20:03:29.611: INFO: Skipping waiting for service account
I0911 20:09:26.439] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:26.440]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 20:09:26.440] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 20:09:26.440]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:26.440] STEP: creating the pod
I0911 20:09:26.440] Sep 11 20:03:29.611: INFO: PodSpec: initContainers in spec.initContainers
I0911 20:09:26.446] Sep 11 20:04:13.084: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-78ca06d1-bfca-4986-b708-81fb81f4fed8", GenerateName:"", Namespace:"init-container-7275", SelfLink:"/api/v1/namespaces/init-container-7275/pods/pod-init-78ca06d1-bfca-4986-b708-81fb81f4fed8", UID:"8d2a3b6c-c82a-4325-8367-2065248a652f", ResourceVersion:"2846", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703829009, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"611853190"}, Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ClusterName:"", ManagedFields:[]v1.ManagedFieldsEntry(nil)}, Spec:v1.PodSpec{Volumes:[]v1.Volume(nil), InitContainers:[]v1.Container{v1.Container{Name:"init1", Image:"docker.io/library/busybox:1.29", Command:[]string{"/bin/false"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}, v1.Container{Name:"init2", Image:"docker.io/library/busybox:1.29", Command:[]string{"/bin/true"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}}, Containers:[]v1.Container{v1.Container{Name:"run1", Image:"k8s.gcr.io/pause:3.1", Command:[]string(nil), Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:52428800, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"52428800", Format:"DecimalSI"}}, Requests:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:52428800, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"52428800", Format:"DecimalSI"}}}, VolumeMounts:[]v1.VolumeMount(nil), VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"Never", SecurityContext:(*v1.SecurityContext)(nil), Stdin:false, StdinOnce:false, TTY:false}}, EphemeralContainers:[]v1.EphemeralContainer(nil), RestartPolicy:"Always", TerminationGracePeriodSeconds:(*int64)(0xc000d08f80), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-3a2ef4f3-cos-stable-63-10032-71-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000e974a0), ImagePullSecrets:[]v1.LocalObjectReference(nil), Hostname:"", Subdomain:"", Affinity:(*v1.Affinity)(nil), SchedulerName:"default-scheduler", Tolerations:[]v1.Toleration{v1.Toleration{Key:"node.kubernetes.io/not-ready", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc000d09010)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc000d09060)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc000d09070), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc000d09074), PreemptionPolicy:(*v1.PreemptionPolicy)(nil), Overhead:v1.ResourceList(nil), TopologySpreadConstraints:[]v1.TopologySpreadConstraint(nil)}, Status:v1.PodStatus{Phase:"Pending", Conditions:[]v1.PodCondition{v1.PodCondition{Type:"Initialized", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703829009, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotInitialized", Message:"containers with incomplete status: [init1 init2]"}, v1.PodCondition{Type:"Ready", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703829009, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"ContainersReady", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703829009, loc:(*time.Location)(0xbe81a00)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"PodScheduled", Status:"True", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703829009, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.123", PodIP:"10.100.0.149", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.149"}}, StartTime:(*v1.Time)(0xc0010aaca0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000b32b60)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000b32d20)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://775d7e0bf8238965bc270ce4793664433720c5ea3bffcc2702853cd7470d7e16", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc0010aad20), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"docker.io/library/busybox:1.29", ImageID:"", ContainerID:"", Started:(*bool)(nil)}}, ContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"run1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc0010aada0), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"k8s.gcr.io/pause:3.1", ImageID:"", ContainerID:"", Started:(*bool)(0xc000d0921c)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 20:09:26.446] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:26.446]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 20:09:26.446] Sep 11 20:04:13.085: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 20:09:26.446] STEP: Destroying namespace "init-container-7275" for this suite.
I0911 20:09:26.447] Sep 11 20:04:27.101: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 20:09:26.447] Sep 11 20:04:27.225: INFO: namespace init-container-7275 deletion completed in 14.135134137s
I0911 20:09:26.447] 
I0911 20:09:26.447] 
I0911 20:09:26.447] • [SLOW TEST:57.620 seconds]
I0911 20:09:26.447] [k8s.io] InitContainer [NodeConformance]
I0911 20:09:26.447] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 20:09:26.447]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 20:09:26.448]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:26.448] ------------------------------
I0911 20:09:26.448] [BeforeEach] [k8s.io] Container Runtime
I0911 20:09:26.448]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 20:09:26.448] STEP: Creating a kubernetes client
I0911 20:09:26.448] STEP: Building a namespace api object, basename container-runtime
... skipping 403 lines ...
I0911 20:09:26.516] STEP: Creating a kubernetes client
I0911 20:09:26.516] STEP: Building a namespace api object, basename container-runtime
I0911 20:09:26.516] Sep 11 20:05:11.412: INFO: Skipping waiting for service account
I0911 20:09:26.516] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 20:09:26.516]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:26.517] STEP: create the container
I0911 20:09:26.517] STEP: wait for the container to reach Failed
I0911 20:09:26.517] STEP: get the container status
I0911 20:09:26.517] STEP: the container should be terminated
I0911 20:09:26.517] STEP: the termination message should be set
I0911 20:09:26.517] Sep 11 20:05:12.478: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 20:09:26.517] STEP: delete the container
I0911 20:09:26.517] [AfterEach] [k8s.io] Container Runtime
... skipping 474 lines ...
I0911 20:09:26.587]   should *not* be restarted with a exec "cat /tmp/health" liveness probe [NodeConformance] [Conformance]
I0911 20:09:26.588]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 20:09:26.588] ------------------------------
I0911 20:09:26.588] I0911 20:09:18.229459    1311 e2e_node_suite_test.go:196] Stopping node services...
I0911 20:09:26.588] I0911 20:09:18.229484    1311 server.go:257] Kill server "services"
I0911 20:09:26.588] I0911 20:09:18.229496    1311 server.go:294] Killing process 1916 (services) with -TERM
I0911 20:09:26.589] E0911 20:09:18.366162    1311 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 20:09:26.589] I0911 20:09:18.366183    1311 server.go:257] Kill server "kubelet"
I0911 20:09:26.589] I0911 20:09:18.374929    1311 services.go:147] Fetching log files...
I0911 20:09:26.589] I0911 20:09:18.374999    1311 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 20:09:26.589] I0911 20:09:18.507196    1311 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 20:09:26.589] I0911 20:09:19.107319    1311 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 20:09:26.590] I0911 20:09:19.140737    1311 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T195700.service].
I0911 20:09:26.590] I0911 20:09:20.265541    1311 e2e_node_suite_test.go:201] Tests Finished
I0911 20:09:26.590] 
I0911 20:09:26.590] 
I0911 20:09:26.590] Ran 157 of 313 Specs in 725.077 seconds
I0911 20:09:26.591] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 20:09:26.591] 
I0911 20:09:26.591] 
I0911 20:09:26.591] Ginkgo ran 1 suite in 12m8.208173668s
I0911 20:09:26.591] Test Suite Passed
I0911 20:09:26.591] 
I0911 20:09:26.591] Success Finished Test Suite on Host tmp-node-e2e-3a2ef4f3-cos-stable-63-10032-71-0
... skipping 6 lines ...
W0911 20:09:26.776] 2019/09/11 20:09:26 process.go:155: Step 'go run /go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go --cleanup --logtostderr --vmodule=*=4 --ssh-env=gce --results-dir=/workspace/_artifacts --project=k8s-jkns-pr-node-e2e --zone=us-west1-b --ssh-user=prow --ssh-key=/workspace/.ssh/google_compute_engine --ginkgo-flags=--nodes=8 --focus="\[NodeConformance\]" --skip="\[Flaky\]|\[Slow\]|\[Serial\]" --flakeAttempts=2 --test_args=--kubelet-flags="--cgroups-per-qos=true --cgroup-root=/" --test-timeout=1h5m0s --image-config-file=/workspace/test-infra/jobs/e2e_node/image-config.yaml' finished in 21m17.469740428s
W0911 20:09:26.776] 2019/09/11 20:09:26 node.go:42: Noop - Node DumpClusterLogs() - /workspace/_artifacts: 
W0911 20:09:26.776] 2019/09/11 20:09:26 node.go:52: Noop - Node Down()
W0911 20:09:26.777] 2019/09/11 20:09:26 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0911 20:09:26.777] 2019/09/11 20:09:26 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0911 20:09:27.150] 2019/09/11 20:09:27 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 373.435199ms
W0911 20:09:27.151] 2019/09/11 20:09:27 main.go:319: Something went wrong: encountered 1 errors: [error during go run /go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go --cleanup --logtostderr --vmodule=*=4 --ssh-env=gce --results-dir=/workspace/_artifacts --project=k8s-jkns-pr-node-e2e --zone=us-west1-b --ssh-user=prow --ssh-key=/workspace/.ssh/google_compute_engine --ginkgo-flags=--nodes=8 --focus="\[NodeConformance\]" --skip="\[Flaky\]|\[Slow\]|\[Serial\]" --flakeAttempts=2 --test_args=--kubelet-flags="--cgroups-per-qos=true --cgroup-root=/" --test-timeout=1h5m0s --image-config-file=/workspace/test-infra/jobs/e2e_node/image-config.yaml: exit status 1]
W0911 20:09:27.156] Traceback (most recent call last):
W0911 20:09:27.157]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0911 20:09:27.157]     main(parse_args())
W0911 20:09:27.158]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0911 20:09:27.158]     mode.start(runner_args)
W0911 20:09:27.158]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0911 20:09:27.158]     check_env(env, self.command, *args)
W0911 20:09:27.159]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0911 20:09:27.159]     subprocess.check_call(cmd, env=env)
W0911 20:09:27.159]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0911 20:09:27.159]     raise CalledProcessError(retcode, cmd)
W0911 20:09:27.160] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--deployment=node', '--provider=gce', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--gcp-project=k8s-jkns-pr-node-e2e', '--gcp-zone=us-west1-b', '--node-test-args=--kubelet-flags="--cgroups-per-qos=true --cgroup-root=/"', '--node-tests=true', '--test_args=--nodes=8 --focus="\\[NodeConformance\\]" --skip="\\[Flaky\\]|\\[Slow\\]|\\[Serial\\]" --flakeAttempts=2', '--timeout=65m', '--node-args=--image-config-file=/workspace/test-infra/jobs/e2e_node/image-config.yaml')' returned non-zero exit status 1
E0911 20:09:27.173] Command failed
I0911 20:09:27.174] process 490 exited with code 1 after 21.3m
E0911 20:09:27.174] FAIL: pull-kubernetes-node-e2e
I0911 20:09:27.175] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0911 20:09:27.725] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0911 20:09:27.792] process 39237 exited with code 0 after 0.0m
I0911 20:09:27.793] Call:  gcloud config get-value account
I0911 20:09:28.182] process 39249 exited with code 0 after 0.0m
I0911 20:09:28.183] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0911 20:09:28.183] Upload result and artifacts...
I0911 20:09:28.183] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/82347/pull-kubernetes-node-e2e/1171872652814454784
I0911 20:09:28.184] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/82347/pull-kubernetes-node-e2e/1171872652814454784/artifacts
W0911 20:09:29.388] CommandException: One or more URLs matched no objects.
E0911 20:09:29.521] Command failed
I0911 20:09:29.522] process 39261 exited with code 1 after 0.0m
W0911 20:09:29.522] Remote dir gs://kubernetes-jenkins/pr-logs/pull/82347/pull-kubernetes-node-e2e/1171872652814454784/artifacts not exist yet
I0911 20:09:29.522] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/82347/pull-kubernetes-node-e2e/1171872652814454784/artifacts
I0911 20:09:33.068] process 39403 exited with code 0 after 0.1m
I0911 20:09:33.069] Call:  git rev-parse HEAD
I0911 20:09:33.074] process 40045 exited with code 0 after 0.0m
... skipping 21 lines ...