This job view page is being replaced by Spyglass soon. Check out the new job view.
PRdanwinship: iptables: ugly hack to quickly fix kubernetes on RHEL 7
ResultFAILURE
Tests 1 failed / 478 succeeded
Started2019-09-11 18:18
Elapsed21m10s
Revision
Buildergke-prow-ssd-pool-1a225945-nk4t
Refs master:001f2cd2
82596:febbd768
pod6c6f2f88-d4c0-11e9-b767-f2268b853d97
infra-commit069bf1fee
job-versionv1.17.0-alpha.0.1269+200ebb7d532acc
pod6c6f2f88-d4c0-11e9-b767-f2268b853d97
repok8s.io/kubernetes
repo-commit200ebb7d532accd2f4fa1375b82cbc13f803fcac
repos{u'k8s.io/kubernetes': u'master:001f2cd2b553d06028c8542c8817820ee05d657f,82596:febbd768f758e8459fa7f8ccfcfb0ddec13bc07f'}
revisionv1.17.0-alpha.0.1269+200ebb7d532acc

Test Failures


Node Tests 18m42s

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 258 lines ...
W0911 18:20:50.702] I0911 18:20:50.560734    4613 build.go:42] Building k8s binaries...
W0911 18:20:50.703] I0911 18:20:50.673326    4613 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 18:20:50.705] I0911 18:20:50.678042    4613 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:0xc0005b4070 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:20:50.707] I0911 18:20:50.689079    4613 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:0xc0001b91f0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:20:50.708] I0911 18:20:50.691042    4613 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:0xc0005b40e0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:20:51.317] I0911 18:20:51.316921    4613 run_remote.go:742] Deleting instance ""
W0911 18:20:51.319] E0911 18:20:51.319420    4613 run_remote.go:745] Error deleting instance "": googleapi: got HTTP response code 404 with body: <!DOCTYPE html>
W0911 18:20:51.319] <html lang=en>
W0911 18:20:51.320]   <meta charset=utf-8>
W0911 18:20:51.320]   <meta name=viewport content="initial-scale=1, minimum-scale=1, width=device-width">
W0911 18:20:51.320]   <title>Error 404 (Not Found)!!1</title>
W0911 18:20:51.320]   <style>
W0911 18:20:51.322]     *{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 18:20:51.322]   </style>
W0911 18:20:51.322]   <a href=//www.google.com/><span id=logo aria-label=Google></span></a>
W0911 18:20:51.322]   <p><b>404.</b> <ins>That’s an error.</ins>
W0911 18:20:51.322]   <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 18:20:51.423] 
I0911 18:20:51.423] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 18:20:51.423] >                              START TEST                                >
I0911 18:20:51.423] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 18:20:51.424] Start Test Suite on Host 
I0911 18:20:51.424] 
I0911 18:20:51.424] Failure Finished Test Suite on Host 
I0911 18:20:51.424] unable to create gce instance with running docker daemon for image coreos-beta-1911-1-1-v20181011.  could not create instance tmp-node-e2e-e2376206-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 18:20:51.424] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 18:20:51.425] <                              FINISH TEST                               <
I0911 18:20:51.425] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 18:20:51.425] 
I0911 18:21:02.286] +++ [0911 18:21:02] Building go targets for linux/amd64:
I0911 18:21:02.287]     ./vendor/k8s.io/code-generator/cmd/deepcopy-gen
... skipping 470 lines ...
I0911 18:37:49.143] STEP: verifying the pod is in kubernetes
I0911 18:37:49.143] STEP: updating the pod
I0911 18:37:49.143] Sep 11 18:29:09.684: INFO: Successfully updated pod "pod-update-activedeadlineseconds-480e9da5-2be8-4ffb-afdf-776c93fb665f"
I0911 18:37:49.154] Sep 11 18:29:09.684: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-480e9da5-2be8-4ffb-afdf-776c93fb665f" in namespace "pods-97" to be "terminated due to deadline exceeded"
I0911 18:37:49.154] Sep 11 18:29:09.686: INFO: Pod "pod-update-activedeadlineseconds-480e9da5-2be8-4ffb-afdf-776c93fb665f": Phase="Running", Reason="", readiness=true. Elapsed: 1.287708ms
I0911 18:37:49.155] Sep 11 18:29:11.692: INFO: Pod "pod-update-activedeadlineseconds-480e9da5-2be8-4ffb-afdf-776c93fb665f": Phase="Running", Reason="", readiness=true. Elapsed: 2.00725941s
I0911 18:37:49.161] Sep 11 18:29:13.693: INFO: Pod "pod-update-activedeadlineseconds-480e9da5-2be8-4ffb-afdf-776c93fb665f": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.00854667s
I0911 18:37:49.162] Sep 11 18:29:13.693: INFO: Pod "pod-update-activedeadlineseconds-480e9da5-2be8-4ffb-afdf-776c93fb665f" satisfied condition "terminated due to deadline exceeded"
I0911 18:37:49.162] [AfterEach] [k8s.io] Pods
I0911 18:37:49.163]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:49.163] Sep 11 18:29:13.693: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:37:49.163] STEP: Destroying namespace "pods-97" for this suite.
I0911 18:37:49.163] Sep 11 18:29:19.704: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 379 lines ...
I0911 18:37:49.725] STEP: Creating a kubernetes client
I0911 18:37:49.726] STEP: Building a namespace api object, basename init-container
I0911 18:37:49.726] Sep 11 18:28:54.738: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
I0911 18:37:49.726] Sep 11 18:28:54.738: INFO: Skipping waiting for service account
I0911 18:37:49.726] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:49.726]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:37:49.727] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:37:49.727]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:49.727] STEP: creating the pod
I0911 18:37:49.727] Sep 11 18:28:54.738: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:37:49.780] Sep 11 18:29:41.298: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-02e064fc-8b18-4c2c-9bb0-bb08e0e04859", GenerateName:"", Namespace:"init-container-1444", SelfLink:"/api/v1/namespaces/init-container-1444/pods/pod-init-02e064fc-8b18-4c2c-9bb0-bb08e0e04859", UID:"e2c7e0cb-a69b-48f4-af55-5804d65922ac", ResourceVersion:"511", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703823334, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"738229132"}, 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)(0xc0007f6e00), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-e2376206-cos-stable-63-10032-71-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc00085eae0), 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)(0xc0007f6e80)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0007f6eb0)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0007f6ec0), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0007f6ec4), 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:63703823334, 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:63703823334, 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:63703823334, 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:63703823334, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.114", PodIP:"10.100.0.8", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.8"}}, StartTime:(*v1.Time)(0xc000931f20), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000b15c70)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000b15ce0)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://7172f8393901293ef3e56c31db644240e3537c05f7e7ae6ba758c21f3d0a0f0e", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000931f40), 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)(0xc000931f60), 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)(0xc0007f6fe4)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:37:49.780] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:49.780]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:49.780] Sep 11 18:29:41.328: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:37:49.781] STEP: Destroying namespace "init-container-1444" for this suite.
I0911 18:37:49.782] Sep 11 18:29:53.334: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:37:49.783] Sep 11 18:29:53.380: INFO: namespace init-container-1444 deletion completed in 12.050561386s
I0911 18:37:49.783] 
I0911 18:37:49.783] 
I0911 18:37:49.783] • [SLOW TEST:58.790 seconds]
I0911 18:37:49.783] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:49.783] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:37:49.784]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:37:49.784]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:49.784] ------------------------------
I0911 18:37:49.784] [BeforeEach] [sig-storage] Projected downwardAPI
I0911 18:37:49.784]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:37:49.784] STEP: Creating a kubernetes client
I0911 18:37:49.785] STEP: Building a namespace api object, basename projected
... skipping 476 lines ...
I0911 18:37:49.962] STEP: Creating a kubernetes client
I0911 18:37:49.962] STEP: Building a namespace api object, basename container-runtime
I0911 18:37:49.962] Sep 11 18:30:12.442: INFO: Skipping waiting for service account
I0911 18:37:49.962] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:37:49.962]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:49.963] STEP: create the container
I0911 18:37:49.963] STEP: wait for the container to reach Failed
I0911 18:37:49.963] STEP: get the container status
I0911 18:37:49.963] STEP: the container should be terminated
I0911 18:37:49.963] STEP: the termination message should be set
I0911 18:37:49.964] Sep 11 18:30:13.508: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:37:49.964] STEP: delete the container
I0911 18:37:49.965] [AfterEach] [k8s.io] Container Runtime
... skipping 941 lines ...
I0911 18:37:50.990]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:37:50.991] STEP: Creating a kubernetes client
I0911 18:37:50.991] STEP: Building a namespace api object, basename init-container
I0911 18:37:50.991] Sep 11 18:31:13.556: INFO: Skipping waiting for service account
I0911 18:37:50.991] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:50.991]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:37:50.992] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:37:50.992]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:50.992] STEP: creating the pod
I0911 18:37:50.992] Sep 11 18:31:13.556: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:37:50.993] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:50.993]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:50.993] Sep 11 18:31:15.507: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:37:50.994] Sep 11 18:31:21.586: INFO: namespace init-container-6719 deletion completed in 6.049286606s
I0911 18:37:50.994] 
I0911 18:37:50.994] 
I0911 18:37:50.995] • [SLOW TEST:8.032 seconds]
I0911 18:37:50.995] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:50.995] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:37:50.995]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:37:50.996]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:50.996] ------------------------------
I0911 18:37:50.996] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:50.997]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:37:50.997] STEP: Creating a kubernetes client
I0911 18:37:50.997] STEP: Building a namespace api object, basename init-container
... skipping 1713 lines ...
I0911 18:37:53.137] [BeforeEach] [k8s.io] Security Context
I0911 18:37:53.137]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:37:53.137] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:37:53.137]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:37:53.138] Sep 11 18:34:44.916: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-d6b88fbd-94b7-440b-ac04-b411cd8ec9e5" in namespace "security-context-test-345" to be "success or failure"
I0911 18:37:53.138] Sep 11 18:34:44.938: INFO: Pod "busybox-readonly-true-d6b88fbd-94b7-440b-ac04-b411cd8ec9e5": Phase="Pending", Reason="", readiness=false. Elapsed: 22.682572ms
I0911 18:37:53.138] Sep 11 18:34:46.940: INFO: Pod "busybox-readonly-true-d6b88fbd-94b7-440b-ac04-b411cd8ec9e5": Phase="Failed", Reason="", readiness=false. Elapsed: 2.024591017s
I0911 18:37:53.143] Sep 11 18:34:46.940: INFO: Pod "busybox-readonly-true-d6b88fbd-94b7-440b-ac04-b411cd8ec9e5" satisfied condition "success or failure"
I0911 18:37:53.144] [AfterEach] [k8s.io] Security Context
I0911 18:37:53.144]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:53.144] Sep 11 18:34:46.940: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:37:53.145] STEP: Destroying namespace "security-context-test-345" for this suite.
I0911 18:37:53.145] Sep 11 18:34:52.950: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 809 lines ...
I0911 18:37:53.867] Sep 11 18:31:22.671: INFO: Skipping waiting for service account
I0911 18:37:53.867] [It] should be able to pull from private registry with credential provider [NodeConformance]
I0911 18:37:53.867]   _output/local/go/src/k8s.io/kubernetes/test/e2e_node/runtime_conformance_test.go:68
I0911 18:37:53.867] STEP: create the container
I0911 18:37:53.867] STEP: check the container status
I0911 18:37:53.867] STEP: delete the container
I0911 18:37:53.868] Sep 11 18:36:23.664: INFO: No.1 attempt failed: expected container state: Running, got: "Waiting", retrying...
I0911 18:37:53.868] STEP: create the container
I0911 18:37:53.868] STEP: check the container status
I0911 18:37:53.868] STEP: delete the container
I0911 18:37:53.868] [AfterEach] [k8s.io] Container Runtime Conformance Test
I0911 18:37:53.868]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:53.868] Sep 11 18:36:25.723: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 355 lines ...
I0911 18:37:53.974]   optional updates should be reflected in volume [NodeConformance] [Conformance]
I0911 18:37:53.974]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:53.975] ------------------------------
I0911 18:37:53.975] I0911 18:37:39.665277    1288 e2e_node_suite_test.go:196] Stopping node services...
I0911 18:37:53.975] I0911 18:37:39.665303    1288 server.go:257] Kill server "services"
I0911 18:37:53.975] I0911 18:37:39.665315    1288 server.go:294] Killing process 1898 (services) with -TERM
I0911 18:37:53.976] E0911 18:37:39.863318    1288 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 18:37:53.976] I0911 18:37:39.863335    1288 server.go:257] Kill server "kubelet"
I0911 18:37:53.976] I0911 18:37:39.873312    1288 services.go:147] Fetching log files...
I0911 18:37:53.976] I0911 18:37:39.873385    1288 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 18:37:53.976] I0911 18:37:40.007297    1288 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 18:37:53.977] I0911 18:37:40.608436    1288 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:37:53.977] I0911 18:37:40.643322    1288 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T182656.service].
I0911 18:37:53.977] I0911 18:37:41.719676    1288 e2e_node_suite_test.go:201] Tests Finished
I0911 18:37:53.977] 
I0911 18:37:53.977] 
I0911 18:37:53.978] Ran 157 of 313 Specs in 630.054 seconds
I0911 18:37:53.978] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:37:53.978] 
I0911 18:37:53.978] 
I0911 18:37:53.978] Ginkgo ran 1 suite in 10m33.23669951s
I0911 18:37:53.978] Test Suite Passed
I0911 18:37:53.979] 
I0911 18:37:53.979] Success Finished Test Suite on Host tmp-node-e2e-e2376206-cos-stable-63-10032-71-0
... skipping 803 lines ...
I0911 18:37:55.379] STEP: Creating a kubernetes client
I0911 18:37:55.380] STEP: Building a namespace api object, basename init-container
I0911 18:37:55.380] Sep 11 18:28:54.502: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
I0911 18:37:55.380] Sep 11 18:28:54.502: INFO: Skipping waiting for service account
I0911 18:37:55.380] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:55.381]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:37:55.381] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:37:55.381]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:55.381] STEP: creating the pod
I0911 18:37:55.382] Sep 11 18:28:54.502: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:37:55.406] Sep 11 18:29:39.274: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-e18009ac-e4f6-45af-9df6-74ec6eadec04", GenerateName:"", Namespace:"init-container-2549", SelfLink:"/api/v1/namespaces/init-container-2549/pods/pod-init-e18009ac-e4f6-45af-9df6-74ec6eadec04", UID:"e8b29e76-7d63-42b9-abdd-1b8edea97a85", ResourceVersion:"491", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703823336, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"502567961"}, 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)(0xc00108bba0), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-e2376206-ubuntu-gke-1804-d1703-0-v20181113", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000fbe120), 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)(0xc00108bc10)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc00108bc30)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc00108bc40), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc00108bc44), 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:63703823337, 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:63703823337, 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:63703823337, 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:63703823337, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.43", PodIP:"10.100.0.5", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.5"}}, StartTime:(*v1.Time)(0xc000b6aee0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0007c9960)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0007c99d0)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796", ContainerID:"docker://cc94bb6f89db3f44c28c1b8cd321a35377d0f61252914f4a1d4375f3c2b5246b", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000b6af00), 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)(0xc000b6af20), 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)(0xc00108bd34)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:37:55.406] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:55.406]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:55.407] Sep 11 18:29:39.274: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:37:55.408] STEP: Destroying namespace "init-container-2549" for this suite.
I0911 18:37:55.409] Sep 11 18:29:53.303: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:37:55.409] Sep 11 18:29:53.449: INFO: namespace init-container-2549 deletion completed in 14.16588114s
I0911 18:37:55.409] 
I0911 18:37:55.409] 
I0911 18:37:55.409] • [SLOW TEST:59.091 seconds]
I0911 18:37:55.409] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:55.410] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:37:55.410]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:37:55.410]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:55.411] ------------------------------
I0911 18:37:55.411] S
I0911 18:37:55.411] ------------------------------
I0911 18:37:55.411] [BeforeEach] [k8s.io] Container Lifecycle Hook
I0911 18:37:55.411]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 640 lines ...
I0911 18:37:55.924] STEP: Creating a kubernetes client
I0911 18:37:55.924] STEP: Building a namespace api object, basename container-runtime
I0911 18:37:55.924] Sep 11 18:31:00.731: INFO: Skipping waiting for service account
I0911 18:37:55.925] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:37:55.926]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:55.926] STEP: create the container
I0911 18:37:55.926] STEP: wait for the container to reach Failed
I0911 18:37:55.926] STEP: get the container status
I0911 18:37:55.927] STEP: the container should be terminated
I0911 18:37:55.939] STEP: the termination message should be set
I0911 18:37:55.940] Sep 11 18:31:01.748: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:37:55.940] STEP: delete the container
I0911 18:37:55.940] [AfterEach] [k8s.io] Container Runtime
... skipping 933 lines ...
I0911 18:37:57.569]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:37:57.570] STEP: Creating a kubernetes client
I0911 18:37:57.570] STEP: Building a namespace api object, basename init-container
I0911 18:37:57.598] Sep 11 18:32:14.841: INFO: Skipping waiting for service account
I0911 18:37:57.603] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:57.604]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:37:57.605] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:37:57.605]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:57.605] STEP: creating the pod
I0911 18:37:57.606] Sep 11 18:32:14.841: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:37:57.606] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:57.607]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:57.607] Sep 11 18:32:16.803: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:37:57.608] Sep 11 18:32:22.923: INFO: namespace init-container-3078 deletion completed in 6.112750795s
I0911 18:37:57.608] 
I0911 18:37:57.609] 
I0911 18:37:57.609] • [SLOW TEST:8.086 seconds]
I0911 18:37:57.610] [k8s.io] InitContainer [NodeConformance]
I0911 18:37:57.610] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:37:57.610]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:37:57.612]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:37:57.612] ------------------------------
I0911 18:37:57.613] [BeforeEach] [sig-storage] Projected configMap
I0911 18:37:57.613]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:37:57.613] STEP: Creating a kubernetes client
I0911 18:37:57.614] STEP: Building a namespace api object, basename projected
... skipping 606 lines ...
I0911 18:37:58.158] STEP: verifying the pod is in kubernetes
I0911 18:37:58.158] STEP: updating the pod
I0911 18:37:58.159] Sep 11 18:32:54.674: INFO: Successfully updated pod "pod-update-activedeadlineseconds-9b862cc7-f06a-4cf0-8338-f475dfe6a80b"
I0911 18:37:58.159] Sep 11 18:32:54.674: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-9b862cc7-f06a-4cf0-8338-f475dfe6a80b" in namespace "pods-2715" to be "terminated due to deadline exceeded"
I0911 18:37:58.160] Sep 11 18:32:54.678: INFO: Pod "pod-update-activedeadlineseconds-9b862cc7-f06a-4cf0-8338-f475dfe6a80b": Phase="Running", Reason="", readiness=true. Elapsed: 3.610639ms
I0911 18:37:58.160] Sep 11 18:32:56.680: INFO: Pod "pod-update-activedeadlineseconds-9b862cc7-f06a-4cf0-8338-f475dfe6a80b": Phase="Running", Reason="", readiness=true. Elapsed: 2.005811425s
I0911 18:37:58.161] Sep 11 18:32:58.682: INFO: Pod "pod-update-activedeadlineseconds-9b862cc7-f06a-4cf0-8338-f475dfe6a80b": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.007439906s
I0911 18:37:58.161] Sep 11 18:32:58.682: INFO: Pod "pod-update-activedeadlineseconds-9b862cc7-f06a-4cf0-8338-f475dfe6a80b" satisfied condition "terminated due to deadline exceeded"
I0911 18:37:58.161] [AfterEach] [k8s.io] Pods
I0911 18:37:58.162]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:58.162] Sep 11 18:32:58.682: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:37:58.162] STEP: Destroying namespace "pods-2715" for this suite.
I0911 18:37:58.163] Sep 11 18:33:04.690: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 2034 lines ...
I0911 18:37:59.826]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:37:59.826] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:37:59.826]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:37:59.826] Sep 11 18:36:08.239: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-2bd2a716-0adf-4e52-90c6-060809c88233" in namespace "security-context-test-2280" to be "success or failure"
I0911 18:37:59.827] Sep 11 18:36:08.241: INFO: Pod "busybox-readonly-true-2bd2a716-0adf-4e52-90c6-060809c88233": Phase="Pending", Reason="", readiness=false. Elapsed: 1.743068ms
I0911 18:37:59.827] Sep 11 18:36:10.242: INFO: Pod "busybox-readonly-true-2bd2a716-0adf-4e52-90c6-060809c88233": Phase="Pending", Reason="", readiness=false. Elapsed: 2.003291573s
I0911 18:37:59.827] Sep 11 18:36:12.244: INFO: Pod "busybox-readonly-true-2bd2a716-0adf-4e52-90c6-060809c88233": Phase="Failed", Reason="", readiness=false. Elapsed: 4.004895623s
I0911 18:37:59.828] Sep 11 18:36:12.244: INFO: Pod "busybox-readonly-true-2bd2a716-0adf-4e52-90c6-060809c88233" satisfied condition "success or failure"
I0911 18:37:59.828] [AfterEach] [k8s.io] Security Context
I0911 18:37:59.828]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:37:59.828] Sep 11 18:36:12.244: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:37:59.828] STEP: Destroying namespace "security-context-test-2280" for this suite.
I0911 18:37:59.829] Sep 11 18:36:18.254: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 139 lines ...
I0911 18:37:59.921] I0911 18:37:42.573387    2602 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 18:37:59.921] I0911 18:37:42.580285    2602 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:37:59.922] I0911 18:37:42.599681    2602 e2e_node_suite_test.go:201] Tests Finished
I0911 18:37:59.922] 
I0911 18:37:59.922] 
I0911 18:37:59.922] Ran 157 of 313 Specs in 630.562 seconds
I0911 18:37:59.922] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:37:59.922] 
I0911 18:37:59.922] 
I0911 18:37:59.923] Ginkgo ran 1 suite in 10m32.621395307s
I0911 18:37:59.923] Test Suite Passed
I0911 18:37:59.923] 
I0911 18:37:59.923] Success Finished Test Suite on Host tmp-node-e2e-e2376206-ubuntu-gke-1804-d1703-0-v20181113
... skipping 401 lines ...
I0911 18:38:10.107] STEP: verifying the pod is in kubernetes
I0911 18:38:10.107] STEP: updating the pod
I0911 18:38:10.108] Sep 11 18:29:03.390: INFO: Successfully updated pod "pod-update-activedeadlineseconds-be371fa7-6837-4387-aa1e-d6c17362fae4"
I0911 18:38:10.254] Sep 11 18:29:03.390: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-be371fa7-6837-4387-aa1e-d6c17362fae4" in namespace "pods-571" to be "terminated due to deadline exceeded"
I0911 18:38:10.256] Sep 11 18:29:03.391: INFO: Pod "pod-update-activedeadlineseconds-be371fa7-6837-4387-aa1e-d6c17362fae4": Phase="Running", Reason="", readiness=true. Elapsed: 1.256487ms
I0911 18:38:10.257] Sep 11 18:29:05.393: INFO: Pod "pod-update-activedeadlineseconds-be371fa7-6837-4387-aa1e-d6c17362fae4": Phase="Running", Reason="", readiness=true. Elapsed: 2.002981001s
I0911 18:38:10.257] Sep 11 18:29:07.394: INFO: Pod "pod-update-activedeadlineseconds-be371fa7-6837-4387-aa1e-d6c17362fae4": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.004627303s
I0911 18:38:10.258] Sep 11 18:29:07.394: INFO: Pod "pod-update-activedeadlineseconds-be371fa7-6837-4387-aa1e-d6c17362fae4" satisfied condition "terminated due to deadline exceeded"
I0911 18:38:10.258] [AfterEach] [k8s.io] Pods
I0911 18:38:10.259]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:38:10.259] Sep 11 18:29:07.394: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:38:10.260] STEP: Destroying namespace "pods-571" for this suite.
I0911 18:38:10.260] Sep 11 18:29:13.400: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 689 lines ...
I0911 18:38:14.314] STEP: Creating a kubernetes client
I0911 18:38:14.315] STEP: Building a namespace api object, basename init-container
I0911 18:38:14.315] Sep 11 18:28:46.516: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
I0911 18:38:14.315] Sep 11 18:28:46.516: INFO: Skipping waiting for service account
I0911 18:38:14.315] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:38:14.316]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:38:14.316] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:38:14.317]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:38:14.317] STEP: creating the pod
I0911 18:38:14.317] Sep 11 18:28:46.516: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:38:14.325] Sep 11 18:29:35.379: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-428ae6bb-ae4e-491d-9fc8-d21c6cbffff8", GenerateName:"", Namespace:"init-container-4094", SelfLink:"/api/v1/namespaces/init-container-4094/pods/pod-init-428ae6bb-ae4e-491d-9fc8-d21c6cbffff8", UID:"7916d2e6-ed80-48ed-b47c-00741f02fcb9", ResourceVersion:"513", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703823326, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"516125464"}, 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)(0xc0005e0050), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-e2376206-cos-stable-60-9592-84-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000952f60), 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)(0xc0005e00f0)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0005e0110)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0005e0120), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0005e0124), 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:63703823326, 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:63703823326, 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:63703823326, 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:63703823326, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.107", PodIP:"10.100.0.6", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.6"}}, StartTime:(*v1.Time)(0xc000944100), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0004eca10)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0004eca80)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://2786380f9ac5393a1d84bb491806ee401559a24b05f58d1a6a95d0cf3eca860e", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000944120), 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)(0xc000944140), 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)(0xc0005e0224)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:38:14.326] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:38:14.326]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:38:14.333] Sep 11 18:29:35.380: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:38:14.333] STEP: Destroying namespace "init-container-4094" for this suite.
I0911 18:38:14.334] Sep 11 18:30:03.402: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:38:14.334] Sep 11 18:30:03.778: INFO: namespace init-container-4094 deletion completed in 28.395935844s
I0911 18:38:14.334] 
I0911 18:38:14.334] 
I0911 18:38:14.334] • [SLOW TEST:77.306 seconds]
I0911 18:38:14.334] [k8s.io] InitContainer [NodeConformance]
I0911 18:38:14.335] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:38:14.346]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:38:14.353]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:38:14.353] ------------------------------
I0911 18:38:14.354] [BeforeEach] [k8s.io] Kubelet
I0911 18:38:14.451]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:38:14.503] STEP: Creating a kubernetes client
I0911 18:38:14.503] STEP: Building a namespace api object, basename kubelet-test
... skipping 244 lines ...
I0911 18:38:20.229] STEP: Creating a kubernetes client
I0911 18:38:20.229] STEP: Building a namespace api object, basename container-runtime
I0911 18:38:20.229] Sep 11 18:30:05.828: INFO: Skipping waiting for service account
I0911 18:38:20.230] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:38:20.231]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:38:20.231] STEP: create the container
I0911 18:38:20.231] STEP: wait for the container to reach Failed
I0911 18:38:20.231] STEP: get the container status
I0911 18:38:20.231] STEP: the container should be terminated
I0911 18:38:20.232] STEP: the termination message should be set
I0911 18:38:20.232] Sep 11 18:30:07.894: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:38:20.232] STEP: delete the container
I0911 18:38:20.232] [AfterEach] [k8s.io] Container Runtime
... skipping 899 lines ...
I0911 18:38:23.010]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:38:23.010] STEP: Creating a kubernetes client
I0911 18:38:23.010] STEP: Building a namespace api object, basename init-container
I0911 18:38:23.011] Sep 11 18:31:12.744: INFO: Skipping waiting for service account
I0911 18:38:23.011] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:38:23.034]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:38:23.059] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:38:23.060]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:38:23.118] STEP: creating the pod
I0911 18:38:23.118] Sep 11 18:31:12.744: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:38:23.119] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:38:23.119]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:38:23.119] Sep 11 18:31:14.900: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:38:23.120] Sep 11 18:31:21.054: INFO: namespace init-container-2726 deletion completed in 6.112959708s
I0911 18:38:23.120] 
I0911 18:38:23.120] 
I0911 18:38:23.120] • [SLOW TEST:8.313 seconds]
I0911 18:38:23.120] [k8s.io] InitContainer [NodeConformance]
I0911 18:38:23.121] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:38:23.121]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:38:23.121]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:38:23.121] ------------------------------
I0911 18:38:23.121] [BeforeEach] [sig-storage] EmptyDir volumes
I0911 18:38:23.121]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:38:23.122] STEP: Creating a kubernetes client
I0911 18:38:23.122] STEP: Building a namespace api object, basename emptydir
... skipping 1685 lines ...
I0911 18:38:29.300] [BeforeEach] [k8s.io] Security Context
I0911 18:38:29.300]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:38:29.300] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:38:29.301]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:38:29.301] Sep 11 18:34:59.186: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-a4d1f5cb-e899-4d13-ad08-a5ac157f9bf5" in namespace "security-context-test-4912" to be "success or failure"
I0911 18:38:29.301] Sep 11 18:34:59.194: INFO: Pod "busybox-readonly-true-a4d1f5cb-e899-4d13-ad08-a5ac157f9bf5": Phase="Pending", Reason="", readiness=false. Elapsed: 7.846321ms
I0911 18:38:29.302] Sep 11 18:35:01.196: INFO: Pod "busybox-readonly-true-a4d1f5cb-e899-4d13-ad08-a5ac157f9bf5": Phase="Failed", Reason="", readiness=false. Elapsed: 2.009337633s
I0911 18:38:29.302] Sep 11 18:35:01.196: INFO: Pod "busybox-readonly-true-a4d1f5cb-e899-4d13-ad08-a5ac157f9bf5" satisfied condition "success or failure"
I0911 18:38:29.302] [AfterEach] [k8s.io] Security Context
I0911 18:38:29.303]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:38:29.303] Sep 11 18:35:01.196: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:38:29.303] STEP: Destroying namespace "security-context-test-4912" for this suite.
I0911 18:38:29.303] Sep 11 18:35:07.201: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 834 lines ...
I0911 18:38:35.423] Sep 11 18:31:21.361: INFO: Skipping waiting for service account
I0911 18:38:35.423] [It] should be able to pull from private registry with credential provider [NodeConformance]
I0911 18:38:35.424]   _output/local/go/src/k8s.io/kubernetes/test/e2e_node/runtime_conformance_test.go:68
I0911 18:38:35.424] STEP: create the container
I0911 18:38:35.425] STEP: check the container status
I0911 18:38:35.425] STEP: delete the container
I0911 18:38:35.425] Sep 11 18:36:21.401: INFO: No.1 attempt failed: expected container state: Running, got: "Waiting", retrying...
I0911 18:38:35.425] STEP: create the container
I0911 18:38:35.426] STEP: check the container status
I0911 18:38:35.426] STEP: delete the container
I0911 18:38:35.428] [AfterEach] [k8s.io] Container Runtime Conformance Test
I0911 18:38:35.428]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:38:35.428] Sep 11 18:36:24.450: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 381 lines ...
I0911 18:38:36.722] I0911 18:37:56.046078    1293 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:38:36.722] I0911 18:37:56.071757    1293 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T182656.service].
I0911 18:38:36.723] I0911 18:37:56.932399    1293 e2e_node_suite_test.go:201] Tests Finished
I0911 18:38:36.724] 
I0911 18:38:36.724] 
I0911 18:38:36.724] Ran 157 of 313 Specs in 644.723 seconds
I0911 18:38:36.725] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:38:36.725] 
I0911 18:38:36.727] 
I0911 18:38:36.728] Ginkgo ran 1 suite in 10m48.269519002s
I0911 18:38:36.728] Test Suite Passed
I0911 18:38:36.728] 
I0911 18:38:36.728] Success Finished Test Suite on Host tmp-node-e2e-e2376206-cos-stable-60-9592-84-0
... skipping 6 lines ...
W0911 18:38:36.854] 2019/09/11 18:38:33 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 18m42.261197161s
W0911 18:38:36.857] 2019/09/11 18:38:33 node.go:42: Noop - Node DumpClusterLogs() - /workspace/_artifacts: 
W0911 18:38:36.858] 2019/09/11 18:38:33 node.go:52: Noop - Node Down()
W0911 18:38:36.858] 2019/09/11 18:38:33 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0911 18:38:36.858] 2019/09/11 18:38:33 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0911 18:38:37.438] 2019/09/11 18:38:37 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 4.405605219s
W0911 18:38:37.443] 2019/09/11 18:38:37 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 18:38:37.443] Traceback (most recent call last):
W0911 18:38:37.443]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0911 18:38:37.443]     main(parse_args())
W0911 18:38:37.443]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0911 18:38:37.444]     mode.start(runner_args)
W0911 18:38:37.464]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0911 18:38:37.464]     check_env(env, self.command, *args)
W0911 18:38:37.465]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0911 18:38:37.564]     subprocess.check_call(cmd, env=env)
W0911 18:38:37.579]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0911 18:38:37.579]     raise CalledProcessError(retcode, cmd)
W0911 18:38:37.593] 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 18:38:37.617] Command failed
I0911 18:38:37.617] process 490 exited with code 1 after 18.8m
E0911 18:38:37.617] FAIL: pull-kubernetes-node-e2e
I0911 18:38:37.618] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0911 18:38:41.001] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0911 18:38:41.219] process 39006 exited with code 0 after 0.1m
I0911 18:38:41.219] Call:  gcloud config get-value account
I0911 18:38:42.396] process 39018 exited with code 0 after 0.0m
I0911 18:38:42.397] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0911 18:38:42.397] Upload result and artifacts...
I0911 18:38:42.398] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/82596/pull-kubernetes-node-e2e/1171850362924044288
I0911 18:38:42.398] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/82596/pull-kubernetes-node-e2e/1171850362924044288/artifacts
W0911 18:38:51.492] CommandException: One or more URLs matched no objects.
E0911 18:38:52.781] Command failed
I0911 18:38:52.781] process 39030 exited with code 1 after 0.2m
W0911 18:38:52.781] Remote dir gs://kubernetes-jenkins/pr-logs/pull/82596/pull-kubernetes-node-e2e/1171850362924044288/artifacts not exist yet
I0911 18:38:52.782] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/82596/pull-kubernetes-node-e2e/1171850362924044288/artifacts
I0911 18:39:17.321] process 39172 exited with code 0 after 0.4m
I0911 18:39:17.393] Call:  git rev-parse HEAD
I0911 18:39:17.447] process 39798 exited with code 0 after 0.0m
... skipping 20 lines ...