This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 478 succeeded
Started2019-09-11 18:26
Elapsed21m35s
Revision
Buildergke-prow-ssd-pool-1a225945-fgds
Refs master:001f2cd2
82053:07ba65df
82261:d78b1a22
82279:e8b57814
82307:6e0878fb
82317:99430f51
82324:4c58dc3a
82329:b4638519
82330:4105fea6
82342:8cd98fbd
82360:eff4f79e
82367:6a433ee5
82391:637badc1
82411:7bdb66f8
82414:c53f2f30
82416:bd0691d1
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/10ee7d39-487b-4afe-adb0-a19bd4cfb84d/targets/test'}}
pod79f84519-d4c1-11e9-ad08-968d9a0b984c
resultstorehttps://source.cloud.google.com/results/invocations/10ee7d39-487b-4afe-adb0-a19bd4cfb84d/targets/test
infra-commit069bf1fee
job-versionv1.17.0-alpha.0.1298+637993d80ef8c3
pod79f84519-d4c1-11e9-ad08-968d9a0b984c
repok8s.io/kubernetes
repo-commit637993d80ef8c3cb3b9fd24f20b5321d1ae265b1
repos{u'k8s.io/kubernetes': u'master:001f2cd2b553d06028c8542c8817820ee05d657f,82053:07ba65df6d69cf951063f08e52eafe218be21481,82261:d78b1a22271bca507343af6ccb1f338140ed1e31,82279:e8b5781499dfb35809c4ede091fc9718f142265e,82307:6e0878fbcc12ba6dd534d68165f7074274644986,82317:99430f51fd406607529a188ddae30b333bd59667,82324:4c58dc3a567513aea9f3db204e71de65a3fd1ef1,82329:b4638519a9d82e2f92421f43dd2f881529c64864,82330:4105fea6eb0cf1e5dd56146ccf94755445988b9a,82342:8cd98fbd60d7cd69bd47187c3b6bcddf27a32d22,82360:eff4f79eda1399d9c10941d7bb591614723c40bc,82367:6a433ee5ba375e6f33fba4d17f8198ff9b7647f0,82391:637badc1f0de46915ec10808b6654ddfd042abea,82411:7bdb66f8d15a3521e1fa308131f530118136b533,82414:c53f2f306ae6f1ff4501720e1f1019fe8cbe4488,82416:bd0691d15915e35303c867d98d74f3e89aafeb75'}
revisionv1.17.0-alpha.0.1298+637993d80ef8c3

Test Failures


Node Tests 19m25s

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


Show 478 Passed Tests

Show 468 Skipped Tests

Error lines from build-log.txt

... skipping 360 lines ...
I0911 18:28:55.070] make[1]: Entering directory '/go/src/k8s.io/kubernetes'
W0911 18:28:55.171] I0911 18:28:55.082835    4648 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:0xc00048e1c0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:28:55.172] I0911 18:28:55.094592    4648 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:0xc000412fc0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:28:55.173] I0911 18:28:55.133916    4648 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:28:55.174] I0911 18:28:55.136782    4648 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:0xc0001d3030 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:28:55.990] I0911 18:28:55.990499    4648 run_remote.go:742] Deleting instance ""
W0911 18:28:55.996] E0911 18:28:55.995737    4648 run_remote.go:745] Error deleting instance "": googleapi: got HTTP response code 404 with body: Not Found
I0911 18:28:56.096] 
I0911 18:28:56.097] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 18:28:56.097] >                              START TEST                                >
I0911 18:28:56.097] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 18:28:56.097] Start Test Suite on Host 
I0911 18:28:56.098] 
I0911 18:28:56.098] Failure Finished Test Suite on Host 
I0911 18:28:56.098] unable to create gce instance with running docker daemon for image coreos-beta-1911-1-1-v20181011.  could not create instance tmp-node-e2e-02f1d27a-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:28:56.099] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 18:28:56.099] <                              FINISH TEST                               <
I0911 18:28:56.099] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 18:28:56.099] 
I0911 18:29:05.673] +++ [0911 18:29:05] Building go targets for linux/amd64:
I0911 18:29:05.674]     ./vendor/k8s.io/code-generator/cmd/deepcopy-gen
... skipping 153 lines ...
I0911 18:43:47.641]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:43:47.641] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:43:47.641]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:43:47.642] Sep 11 18:35:54.629: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-d87a7f85-af0e-422e-a0c0-c2f0b0c73d49" in namespace "security-context-test-2314" to be "success or failure"
I0911 18:43:47.642] Sep 11 18:35:57.404: INFO: Pod "busybox-readonly-true-d87a7f85-af0e-422e-a0c0-c2f0b0c73d49": Phase="Pending", Reason="", readiness=false. Elapsed: 2.774806516s
I0911 18:43:47.642] Sep 11 18:35:59.405: INFO: Pod "busybox-readonly-true-d87a7f85-af0e-422e-a0c0-c2f0b0c73d49": Phase="Pending", Reason="", readiness=false. Elapsed: 4.776430008s
I0911 18:43:47.643] Sep 11 18:36:01.426: INFO: Pod "busybox-readonly-true-d87a7f85-af0e-422e-a0c0-c2f0b0c73d49": Phase="Failed", Reason="", readiness=false. Elapsed: 6.796906092s
I0911 18:43:47.643] Sep 11 18:36:01.426: INFO: Pod "busybox-readonly-true-d87a7f85-af0e-422e-a0c0-c2f0b0c73d49" satisfied condition "success or failure"
I0911 18:43:47.643] [AfterEach] [k8s.io] Security Context
I0911 18:43:47.643]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:43:47.643] Sep 11 18:36:01.426: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:43:47.644] STEP: Destroying namespace "security-context-test-2314" for this suite.
I0911 18:43:47.644] Sep 11 18:36:07.492: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 803 lines ...
I0911 18:43:47.813] STEP: Creating a kubernetes client
I0911 18:43:47.814] STEP: Building a namespace api object, basename init-container
I0911 18:43:47.814] Sep 11 18:35:54.559: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
I0911 18:43:47.814] Sep 11 18:35:54.559: INFO: Skipping waiting for service account
I0911 18:43:47.814] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:43:47.814]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:43:47.815] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:43:47.815]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:43:47.815] STEP: creating the pod
I0911 18:43:47.815] Sep 11 18:35:54.559: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:43:47.824] Sep 11 18:36:46.944: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-6ca4dab4-dddc-48d8-9ac6-cf08c234cf9d", GenerateName:"", Namespace:"init-container-9219", SelfLink:"/api/v1/namespaces/init-container-9219/pods/pod-init-6ca4dab4-dddc-48d8-9ac6-cf08c234cf9d", UID:"4f5cf4a0-b905-43cd-83d3-a7025bed78bb", ResourceVersion:"541", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703823754, loc:(*time.Location)(0xbe82a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"559272646"}, 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)(0xc001137300), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-02f1d27a-ubuntu-gke-1804-d1703-0-v20181113", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc001087920), 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)(0xc001137370)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc001137390)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0011373a0), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0011373a4), 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:63703823757, loc:(*time.Location)(0xbe82a00)}}, 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:63703823757, loc:(*time.Location)(0xbe82a00)}}, 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:63703823757, loc:(*time.Location)(0xbe82a00)}}, 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:63703823757, loc:(*time.Location)(0xbe82a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.34", PodIP:"10.100.0.5", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.5"}}, StartTime:(*v1.Time)(0xc000c071e0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000a85ea0)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000a85f10)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796", ContainerID:"docker://08ff72531bfbdb7553116dba5b1b814fc7a9cea697442358bcc7ae24d5b69803", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000c07200), 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)(0xc000c07220), 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)(0xc0011374a4)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:43:47.825] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:43:47.825]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:43:47.825] Sep 11 18:36:46.944: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:43:47.825] STEP: Destroying namespace "init-container-9219" for this suite.
I0911 18:43:47.825] Sep 11 18:37:14.967: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:43:47.826] Sep 11 18:37:15.005: INFO: namespace init-container-9219 deletion completed in 28.047911898s
I0911 18:43:47.826] 
I0911 18:43:47.826] 
I0911 18:43:47.826] • [SLOW TEST:80.545 seconds]
I0911 18:43:47.826] [k8s.io] InitContainer [NodeConformance]
I0911 18:43:47.827] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:43:47.827]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:43:47.827]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:43:47.827] ------------------------------
I0911 18:43:47.828] [BeforeEach] [k8s.io] MirrorPod
I0911 18:43:47.828]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:43:47.828] STEP: Creating a kubernetes client
I0911 18:43:47.828] STEP: Building a namespace api object, basename mirror-pod
... skipping 3100 lines ...
I0911 18:43:48.427]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:43:48.427] STEP: Creating a kubernetes client
I0911 18:43:48.428] STEP: Building a namespace api object, basename init-container
I0911 18:43:48.428] Sep 11 18:41:32.573: INFO: Skipping waiting for service account
I0911 18:43:48.428] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:43:48.428]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:43:48.429] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:43:48.429]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:43:48.429] STEP: creating the pod
I0911 18:43:48.429] Sep 11 18:41:32.574: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:43:48.429] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:43:48.430]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:43:48.430] Sep 11 18:41:34.387: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:43:48.431] Sep 11 18:41:40.588: INFO: namespace init-container-1 deletion completed in 6.195009443s
I0911 18:43:48.431] 
I0911 18:43:48.431] 
I0911 18:43:48.431] • [SLOW TEST:8.018 seconds]
I0911 18:43:48.431] [k8s.io] InitContainer [NodeConformance]
I0911 18:43:48.432] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:43:48.432]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:43:48.432]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:43:48.432] ------------------------------
I0911 18:43:48.433] [BeforeEach] [sig-storage] Projected downwardAPI
I0911 18:43:48.433]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:43:48.433] STEP: Creating a kubernetes client
I0911 18:43:48.433] STEP: Building a namespace api object, basename projected
... skipping 106 lines ...
I0911 18:43:48.465] STEP: Creating a kubernetes client
I0911 18:43:48.466] STEP: Building a namespace api object, basename container-runtime
I0911 18:43:48.466] Sep 11 18:41:39.964: INFO: Skipping waiting for service account
I0911 18:43:48.466] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:43:48.466]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:43:48.466] STEP: create the container
I0911 18:43:48.467] STEP: wait for the container to reach Failed
I0911 18:43:48.467] STEP: get the container status
I0911 18:43:48.467] STEP: the container should be terminated
I0911 18:43:48.467] STEP: the termination message should be set
I0911 18:43:48.467] Sep 11 18:41:42.034: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:43:48.468] STEP: delete the container
I0911 18:43:48.468] [AfterEach] [k8s.io] Container Runtime
... skipping 605 lines ...
I0911 18:43:48.586] STEP: verifying the pod is in kubernetes
I0911 18:43:48.586] STEP: updating the pod
I0911 18:43:48.586] Sep 11 18:42:28.871: INFO: Successfully updated pod "pod-update-activedeadlineseconds-4f4b6bb1-c53f-47d1-b759-0efa55081e53"
I0911 18:43:48.587] Sep 11 18:42:28.871: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-4f4b6bb1-c53f-47d1-b759-0efa55081e53" in namespace "pods-7431" to be "terminated due to deadline exceeded"
I0911 18:43:48.587] Sep 11 18:42:28.874: INFO: Pod "pod-update-activedeadlineseconds-4f4b6bb1-c53f-47d1-b759-0efa55081e53": Phase="Running", Reason="", readiness=true. Elapsed: 3.810818ms
I0911 18:43:48.587] Sep 11 18:42:30.876: INFO: Pod "pod-update-activedeadlineseconds-4f4b6bb1-c53f-47d1-b759-0efa55081e53": Phase="Running", Reason="", readiness=true. Elapsed: 2.005540398s
I0911 18:43:48.588] Sep 11 18:42:32.878: INFO: Pod "pod-update-activedeadlineseconds-4f4b6bb1-c53f-47d1-b759-0efa55081e53": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.007192604s
I0911 18:43:48.588] Sep 11 18:42:32.878: INFO: Pod "pod-update-activedeadlineseconds-4f4b6bb1-c53f-47d1-b759-0efa55081e53" satisfied condition "terminated due to deadline exceeded"
I0911 18:43:48.588] [AfterEach] [k8s.io] Pods
I0911 18:43:48.588]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:43:48.589] Sep 11 18:42:32.878: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:43:48.589] STEP: Destroying namespace "pods-7431" for this suite.
I0911 18:43:48.589] Sep 11 18:42:38.909: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 417 lines ...
I0911 18:43:48.678] I0911 18:43:40.947663    2656 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:43:48.678] I0911 18:43:40.967489    2656 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T183354.service].
I0911 18:43:48.679] I0911 18:43:41.496024    2656 e2e_node_suite_test.go:201] Tests Finished
I0911 18:43:48.679] 
I0911 18:43:48.679] 
I0911 18:43:48.679] Ran 157 of 313 Specs in 571.441 seconds
I0911 18:43:48.679] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:43:48.679] 
I0911 18:43:48.680] 
I0911 18:43:48.680] Ginkgo ran 1 suite in 9m33.551267121s
I0911 18:43:48.680] Test Suite Passed
I0911 18:43:48.680] 
I0911 18:43:48.680] Success Finished Test Suite on Host tmp-node-e2e-02f1d27a-ubuntu-gke-1804-d1703-0-v20181113
... skipping 122 lines ...
I0911 18:44:22.437]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:44:22.437] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:44:22.437]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:44:22.437] Sep 11 18:35:48.231: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-e5708f97-30e4-4986-abbf-b7452b38bde0" in namespace "security-context-test-4069" to be "success or failure"
I0911 18:44:22.438] Sep 11 18:35:48.287: INFO: Pod "busybox-readonly-true-e5708f97-30e4-4986-abbf-b7452b38bde0": Phase="Pending", Reason="", readiness=false. Elapsed: 55.740945ms
I0911 18:44:22.438] Sep 11 18:35:50.290: INFO: Pod "busybox-readonly-true-e5708f97-30e4-4986-abbf-b7452b38bde0": Phase="Pending", Reason="", readiness=false. Elapsed: 2.05904836s
I0911 18:44:22.438] Sep 11 18:35:52.294: INFO: Pod "busybox-readonly-true-e5708f97-30e4-4986-abbf-b7452b38bde0": Phase="Failed", Reason="", readiness=false. Elapsed: 4.063312374s
I0911 18:44:22.439] Sep 11 18:35:52.295: INFO: Pod "busybox-readonly-true-e5708f97-30e4-4986-abbf-b7452b38bde0" satisfied condition "success or failure"
I0911 18:44:22.439] [AfterEach] [k8s.io] Security Context
I0911 18:44:22.439]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:44:22.439] Sep 11 18:35:52.295: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:44:22.439] STEP: Destroying namespace "security-context-test-4069" for this suite.
I0911 18:44:22.440] Sep 11 18:35:58.307: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 643 lines ...
I0911 18:44:22.561] STEP: Creating a kubernetes client
I0911 18:44:22.561] STEP: Building a namespace api object, basename init-container
I0911 18:44:22.562] Sep 11 18:35:47.991: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
I0911 18:44:22.562] Sep 11 18:35:47.991: INFO: Skipping waiting for service account
I0911 18:44:22.562] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:44:22.562]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:44:22.563] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:44:22.563]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:44:22.563] STEP: creating the pod
I0911 18:44:22.563] Sep 11 18:35:47.991: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:44:22.572] Sep 11 18:36:30.522: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-05f10099-3030-4c90-b70d-afc133ce4ef4", GenerateName:"", Namespace:"init-container-9432", SelfLink:"/api/v1/namespaces/init-container-9432/pods/pod-init-05f10099-3030-4c90-b70d-afc133ce4ef4", UID:"1c100dc3-c9af-4908-828c-ee3b7a7a2497", ResourceVersion:"492", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703823748, loc:(*time.Location)(0xbe82a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"991723372"}, 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)(0xc000a2be70), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-02f1d27a-cos-stable-60-9592-84-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000b73680), 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)(0xc000a2bee0)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc000a2bf10)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc000a2bf20), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc000a2bf24), 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:63703823748, loc:(*time.Location)(0xbe82a00)}}, 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:63703823748, loc:(*time.Location)(0xbe82a00)}}, 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:63703823748, loc:(*time.Location)(0xbe82a00)}}, 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:63703823748, loc:(*time.Location)(0xbe82a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.38", PodIP:"10.100.0.4", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.4"}}, StartTime:(*v1.Time)(0xc0008b21e0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000a02d20)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0000e7f80)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://b762c93f92543a3e2c83a614d285a4050ba583a2cba48b79879e40b7e3c43465", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc0008b2200), 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)(0xc0008b2220), 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)(0xc0008e2034)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:44:22.573] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:44:22.573]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:44:22.573] Sep 11 18:36:30.524: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:44:22.573] STEP: Destroying namespace "init-container-9432" for this suite.
I0911 18:44:22.573] Sep 11 18:36:42.540: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:44:22.574] Sep 11 18:36:42.623: INFO: namespace init-container-9432 deletion completed in 12.095371176s
I0911 18:44:22.574] 
I0911 18:44:22.574] 
I0911 18:44:22.574] • [SLOW TEST:54.758 seconds]
I0911 18:44:22.574] [k8s.io] InitContainer [NodeConformance]
I0911 18:44:22.574] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:44:22.575]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:44:22.575]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:44:22.575] ------------------------------
I0911 18:44:22.575] [BeforeEach] [sig-storage] Downward API volume
I0911 18:44:22.576]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:44:22.576] STEP: Creating a kubernetes client
I0911 18:44:22.576] STEP: Building a namespace api object, basename downward-api
... skipping 3286 lines ...
I0911 18:44:23.345]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:44:23.345] STEP: Creating a kubernetes client
I0911 18:44:23.345] STEP: Building a namespace api object, basename init-container
I0911 18:44:23.346] Sep 11 18:41:35.764: INFO: Skipping waiting for service account
I0911 18:44:23.346] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:44:23.346]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:44:23.346] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:44:23.346]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:44:23.347] STEP: creating the pod
I0911 18:44:23.347] Sep 11 18:41:35.764: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:44:23.347] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:44:23.347]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:44:23.347] Sep 11 18:41:38.463: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:44:23.348] Sep 11 18:41:44.600: INFO: namespace init-container-1029 deletion completed in 6.107681728s
I0911 18:44:23.348] 
I0911 18:44:23.348] 
I0911 18:44:23.349] • [SLOW TEST:8.839 seconds]
I0911 18:44:23.349] [k8s.io] InitContainer [NodeConformance]
I0911 18:44:23.349] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:44:23.349]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:44:23.350]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:44:23.350] ------------------------------
I0911 18:44:23.350] S
I0911 18:44:23.350] ------------------------------
I0911 18:44:23.350] [BeforeEach] [k8s.io] Container Runtime
I0911 18:44:23.351]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:44:23.351] STEP: Creating a kubernetes client
I0911 18:44:23.351] STEP: Building a namespace api object, basename container-runtime
I0911 18:44:23.351] Sep 11 18:41:37.912: INFO: Skipping waiting for service account
I0911 18:44:23.352] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:44:23.352]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:44:23.352] STEP: create the container
I0911 18:44:23.352] STEP: wait for the container to reach Failed
I0911 18:44:23.352] STEP: get the container status
I0911 18:44:23.353] STEP: the container should be terminated
I0911 18:44:23.353] STEP: the termination message should be set
I0911 18:44:23.353] Sep 11 18:41:38.980: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:44:23.353] STEP: delete the container
I0911 18:44:23.353] [AfterEach] [k8s.io] Container Runtime
... skipping 746 lines ...
I0911 18:44:23.510] STEP: verifying the pod is in kubernetes
I0911 18:44:23.510] STEP: updating the pod
I0911 18:44:23.511] Sep 11 18:42:39.047: INFO: Successfully updated pod "pod-update-activedeadlineseconds-5fc1605f-4330-4e05-9c13-a9ca2b166a33"
I0911 18:44:23.511] Sep 11 18:42:39.047: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-5fc1605f-4330-4e05-9c13-a9ca2b166a33" in namespace "pods-4351" to be "terminated due to deadline exceeded"
I0911 18:44:23.511] Sep 11 18:42:39.048: INFO: Pod "pod-update-activedeadlineseconds-5fc1605f-4330-4e05-9c13-a9ca2b166a33": Phase="Running", Reason="", readiness=true. Elapsed: 1.530414ms
I0911 18:44:23.512] Sep 11 18:42:41.050: INFO: Pod "pod-update-activedeadlineseconds-5fc1605f-4330-4e05-9c13-a9ca2b166a33": Phase="Running", Reason="", readiness=true. Elapsed: 2.003283306s
I0911 18:44:23.512] Sep 11 18:42:43.052: INFO: Pod "pod-update-activedeadlineseconds-5fc1605f-4330-4e05-9c13-a9ca2b166a33": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.004958044s
I0911 18:44:23.513] Sep 11 18:42:43.052: INFO: Pod "pod-update-activedeadlineseconds-5fc1605f-4330-4e05-9c13-a9ca2b166a33" satisfied condition "terminated due to deadline exceeded"
I0911 18:44:23.513] [AfterEach] [k8s.io] Pods
I0911 18:44:23.513]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:44:23.513] Sep 11 18:42:43.052: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:44:23.513] STEP: Destroying namespace "pods-4351" for this suite.
I0911 18:44:23.514] Sep 11 18:42:49.058: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 333 lines ...
I0911 18:44:23.584]   should have monotonically increasing restart count [NodeConformance] [Conformance]
I0911 18:44:23.584]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:44:23.584] ------------------------------
I0911 18:44:23.584] I0911 18:44:14.824694    1300 e2e_node_suite_test.go:196] Stopping node services...
I0911 18:44:23.585] I0911 18:44:14.824722    1300 server.go:257] Kill server "services"
I0911 18:44:23.585] I0911 18:44:14.824736    1300 server.go:294] Killing process 1939 (services) with -TERM
I0911 18:44:23.585] E0911 18:44:14.972474    1300 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 18:44:23.585] I0911 18:44:14.972492    1300 server.go:257] Kill server "kubelet"
I0911 18:44:23.586] I0911 18:44:14.981610    1300 services.go:147] Fetching log files...
I0911 18:44:23.586] I0911 18:44:14.981675    1300 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 18:44:23.586] I0911 18:44:15.070877    1300 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 18:44:23.587] I0911 18:44:15.538382    1300 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:44:23.587] I0911 18:44:15.565064    1300 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T183354.service].
I0911 18:44:23.587] I0911 18:44:16.418508    1300 e2e_node_suite_test.go:201] Tests Finished
I0911 18:44:23.587] 
I0911 18:44:23.587] 
I0911 18:44:23.588] Ran 157 of 313 Specs in 605.479 seconds
I0911 18:44:23.588] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:44:23.588] 
I0911 18:44:23.588] 
I0911 18:44:23.588] Ginkgo ran 1 suite in 10m9.379004236s
I0911 18:44:23.589] Test Suite Passed
I0911 18:44:23.589] 
I0911 18:44:23.589] Success Finished Test Suite on Host tmp-node-e2e-02f1d27a-cos-stable-60-9592-84-0
... skipping 792 lines ...
I0911 18:47:32.654] [BeforeEach] [k8s.io] Security Context
I0911 18:47:32.654]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:47:32.655] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:47:32.655]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:47:32.655] Sep 11 18:36:53.531: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-f2fb7e28-e83a-4b95-bea5-dde6f57b5984" in namespace "security-context-test-8548" to be "success or failure"
I0911 18:47:32.655] Sep 11 18:36:53.532: INFO: Pod "busybox-readonly-true-f2fb7e28-e83a-4b95-bea5-dde6f57b5984": Phase="Pending", Reason="", readiness=false. Elapsed: 1.601958ms
I0911 18:47:32.656] Sep 11 18:36:55.534: INFO: Pod "busybox-readonly-true-f2fb7e28-e83a-4b95-bea5-dde6f57b5984": Phase="Failed", Reason="", readiness=false. Elapsed: 2.003568621s
I0911 18:47:32.656] Sep 11 18:36:55.534: INFO: Pod "busybox-readonly-true-f2fb7e28-e83a-4b95-bea5-dde6f57b5984" satisfied condition "success or failure"
I0911 18:47:32.656] [AfterEach] [k8s.io] Security Context
I0911 18:47:32.656]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:47:32.656] Sep 11 18:36:55.534: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:47:32.656] STEP: Destroying namespace "security-context-test-8548" for this suite.
I0911 18:47:32.656] Sep 11 18:37:01.546: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 832 lines ...
I0911 18:47:32.794]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:47:32.794] STEP: Creating a kubernetes client
I0911 18:47:32.794] STEP: Building a namespace api object, basename init-container
I0911 18:47:32.794] Sep 11 18:37:11.264: INFO: Skipping waiting for service account
I0911 18:47:32.794] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:47:32.795]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:47:32.795] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:47:32.795]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:47:32.795] STEP: creating the pod
I0911 18:47:32.795] Sep 11 18:37:11.264: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:47:32.802] Sep 11 18:38:03.693: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-64eabcd4-8035-45cb-b1f5-9634cca47579", GenerateName:"", Namespace:"init-container-529", SelfLink:"/api/v1/namespaces/init-container-529/pods/pod-init-64eabcd4-8035-45cb-b1f5-9634cca47579", UID:"eaf38dd6-78cc-4e53-9ac8-29983bb77d99", ResourceVersion:"1103", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703823831, loc:(*time.Location)(0xbe82a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"264982654"}, 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)(0xc0012a22e0), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-02f1d27a-cos-stable-63-10032-71-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc00127bec0), 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)(0xc0012a2350)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0012a2370)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0012a2380), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0012a2384), 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:63703823831, loc:(*time.Location)(0xbe82a00)}}, 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:63703823831, loc:(*time.Location)(0xbe82a00)}}, 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:63703823831, loc:(*time.Location)(0xbe82a00)}}, 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:63703823831, loc:(*time.Location)(0xbe82a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.32", PodIP:"10.100.0.38", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.38"}}, StartTime:(*v1.Time)(0xc00091e780), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc000307650)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0003076c0)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://0a3181be04850518de6c9d640232f4176abc5691264d3751bd80c4422dab0149", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc00091e7c0), 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)(0xc00091e7e0), 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)(0xc0012a246c)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:47:32.802] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:47:32.802]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:47:32.802] Sep 11 18:38:03.694: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:47:32.802] STEP: Destroying namespace "init-container-529" for this suite.
I0911 18:47:32.803] Sep 11 18:38:17.701: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:47:32.803] Sep 11 18:38:17.746: INFO: namespace init-container-529 deletion completed in 14.049655801s
I0911 18:47:32.803] 
I0911 18:47:32.803] 
I0911 18:47:32.803] • [SLOW TEST:66.485 seconds]
I0911 18:47:32.803] [k8s.io] InitContainer [NodeConformance]
I0911 18:47:32.804] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:47:32.804]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:47:32.804]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:47:32.804] ------------------------------
I0911 18:47:32.804] SSS
I0911 18:47:32.805] ------------------------------
I0911 18:47:32.805] [BeforeEach] [sig-storage] Projected downwardAPI
I0911 18:47:32.805]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 150 lines ...
I0911 18:47:32.826] STEP: Creating a kubernetes client
I0911 18:47:32.826] STEP: Building a namespace api object, basename container-runtime
I0911 18:47:32.826] Sep 11 18:38:24.202: INFO: Skipping waiting for service account
I0911 18:47:32.827] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:47:32.827]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:47:32.827] STEP: create the container
I0911 18:47:32.827] STEP: wait for the container to reach Failed
I0911 18:47:32.827] STEP: get the container status
I0911 18:47:32.827] STEP: the container should be terminated
I0911 18:47:32.827] STEP: the termination message should be set
I0911 18:47:32.828] Sep 11 18:38:26.216: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:47:32.828] STEP: delete the container
I0911 18:47:32.828] [AfterEach] [k8s.io] Container Runtime
... skipping 2648 lines ...
I0911 18:47:33.315] STEP: verifying the pod is in kubernetes
I0911 18:47:33.315] STEP: updating the pod
I0911 18:47:33.315] Sep 11 18:42:06.068: INFO: Successfully updated pod "pod-update-activedeadlineseconds-6c5e6aea-16ca-4cf0-a952-ff221979ec3d"
I0911 18:47:33.316] Sep 11 18:42:06.068: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-6c5e6aea-16ca-4cf0-a952-ff221979ec3d" in namespace "pods-855" to be "terminated due to deadline exceeded"
I0911 18:47:33.316] Sep 11 18:42:06.069: INFO: Pod "pod-update-activedeadlineseconds-6c5e6aea-16ca-4cf0-a952-ff221979ec3d": Phase="Running", Reason="", readiness=true. Elapsed: 1.439463ms
I0911 18:47:33.316] Sep 11 18:42:08.071: INFO: Pod "pod-update-activedeadlineseconds-6c5e6aea-16ca-4cf0-a952-ff221979ec3d": Phase="Running", Reason="", readiness=true. Elapsed: 2.003193043s
I0911 18:47:33.317] Sep 11 18:42:10.073: INFO: Pod "pod-update-activedeadlineseconds-6c5e6aea-16ca-4cf0-a952-ff221979ec3d": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.00557117s
I0911 18:47:33.317] Sep 11 18:42:10.073: INFO: Pod "pod-update-activedeadlineseconds-6c5e6aea-16ca-4cf0-a952-ff221979ec3d" satisfied condition "terminated due to deadline exceeded"
I0911 18:47:33.317] [AfterEach] [k8s.io] Pods
I0911 18:47:33.318]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:47:33.318] Sep 11 18:42:10.073: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:47:33.318] STEP: Destroying namespace "pods-855" for this suite.
I0911 18:47:33.318] Sep 11 18:42:16.081: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 459 lines ...
I0911 18:47:33.433]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:47:33.434] STEP: Creating a kubernetes client
I0911 18:47:33.434] STEP: Building a namespace api object, basename init-container
I0911 18:47:33.434] Sep 11 18:43:02.161: INFO: Skipping waiting for service account
I0911 18:47:33.434] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:47:33.434]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:47:33.435] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:47:33.435]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:47:33.435] STEP: creating the pod
I0911 18:47:33.435] Sep 11 18:43:02.161: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:47:33.435] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:47:33.436]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:47:33.436] Sep 11 18:43:05.096: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:47:33.436] Sep 11 18:43:11.405: INFO: namespace init-container-599 deletion completed in 6.292683721s
I0911 18:47:33.437] 
I0911 18:47:33.437] 
I0911 18:47:33.437] • [SLOW TEST:9.246 seconds]
I0911 18:47:33.437] [k8s.io] InitContainer [NodeConformance]
I0911 18:47:33.437] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:47:33.437]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:47:33.438]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:47:33.438] ------------------------------
I0911 18:47:33.438] [BeforeEach] [k8s.io] Probing container
I0911 18:47:33.438]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:47:33.439] STEP: Creating a kubernetes client
I0911 18:47:33.439] STEP: Building a namespace api object, basename container-probe
... skipping 248 lines ...
I0911 18:47:33.491] Sep 11 18:42:15.296: INFO: Skipping waiting for service account
I0911 18:47:33.491] [It] should be able to pull from private registry with credential provider [NodeConformance]
I0911 18:47:33.492]   _output/local/go/src/k8s.io/kubernetes/test/e2e_node/runtime_conformance_test.go:68
I0911 18:47:33.492] STEP: create the container
I0911 18:47:33.492] STEP: check the container status
I0911 18:47:33.492] STEP: delete the container
I0911 18:47:33.492] Sep 11 18:47:16.033: INFO: No.1 attempt failed: expected container state: Running, got: "Waiting", retrying...
I0911 18:47:33.492] STEP: create the container
I0911 18:47:33.493] STEP: check the container status
I0911 18:47:33.493] STEP: delete the container
I0911 18:47:33.493] [AfterEach] [k8s.io] Container Runtime Conformance Test
I0911 18:47:33.493]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:47:33.493] Sep 11 18:47:18.095: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 12 lines ...
I0911 18:47:33.496]       should be able to pull from private registry with credential provider [NodeConformance]
I0911 18:47:33.497]       _output/local/go/src/k8s.io/kubernetes/test/e2e_node/runtime_conformance_test.go:68
I0911 18:47:33.497] ------------------------------
I0911 18:47:33.497] I0911 18:47:24.192038    1296 e2e_node_suite_test.go:196] Stopping node services...
I0911 18:47:33.497] I0911 18:47:24.192072    1296 server.go:257] Kill server "services"
I0911 18:47:33.497] I0911 18:47:24.192090    1296 server.go:294] Killing process 1895 (services) with -TERM
I0911 18:47:33.498] E0911 18:47:24.376611    1296 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 18:47:33.498] I0911 18:47:24.376629    1296 server.go:257] Kill server "kubelet"
I0911 18:47:33.498] I0911 18:47:24.387231    1296 services.go:147] Fetching log files...
I0911 18:47:33.498] I0911 18:47:24.387292    1296 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 18:47:33.499] I0911 18:47:24.504490    1296 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 18:47:33.499] I0911 18:47:25.104996    1296 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:47:33.499] I0911 18:47:25.138347    1296 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T183354.service].
I0911 18:47:33.499] I0911 18:47:26.267320    1296 e2e_node_suite_test.go:201] Tests Finished
I0911 18:47:33.499] 
I0911 18:47:33.499] 
I0911 18:47:33.500] Ran 157 of 313 Specs in 787.968 seconds
I0911 18:47:33.500] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:47:33.500] 
I0911 18:47:33.500] 
I0911 18:47:33.500] Ginkgo ran 1 suite in 13m11.136071618s
I0911 18:47:33.501] Test Suite Passed
I0911 18:47:33.501] 
I0911 18:47:33.501] Success Finished Test Suite on Host tmp-node-e2e-02f1d27a-cos-stable-63-10032-71-0
... skipping 6 lines ...
W0911 18:47:33.625] 2019/09/11 18:47: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 19m25.228878031s
W0911 18:47:33.626] 2019/09/11 18:47:33 node.go:42: Noop - Node DumpClusterLogs() - /workspace/_artifacts: 
W0911 18:47:33.626] 2019/09/11 18:47:33 node.go:52: Noop - Node Down()
W0911 18:47:33.626] 2019/09/11 18:47:33 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0911 18:47:33.627] 2019/09/11 18:47:33 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0911 18:47:34.076] 2019/09/11 18:47:34 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 449.550466ms
W0911 18:47:34.077] 2019/09/11 18:47:34 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:47:34.083] Traceback (most recent call last):
W0911 18:47:34.084]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0911 18:47:34.084]     main(parse_args())
W0911 18:47:34.084]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0911 18:47:34.084]     mode.start(runner_args)
W0911 18:47:34.084]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0911 18:47:34.085]     check_env(env, self.command, *args)
W0911 18:47:34.085]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0911 18:47:34.085]     subprocess.check_call(cmd, env=env)
W0911 18:47:34.085]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0911 18:47:34.093]     raise CalledProcessError(retcode, cmd)
W0911 18:47:34.093] 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:47:34.115] Command failed
I0911 18:47:34.116] process 517 exited with code 1 after 19.5m
E0911 18:47:34.116] FAIL: pull-kubernetes-node-e2e
I0911 18:47:34.116] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0911 18:47:34.998] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0911 18:47:35.067] process 39267 exited with code 0 after 0.0m
I0911 18:47:35.067] Call:  gcloud config get-value account
I0911 18:47:35.649] process 39279 exited with code 0 after 0.0m
I0911 18:47:35.650] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0911 18:47:35.650] Upload result and artifacts...
I0911 18:47:35.650] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/batch/pull-kubernetes-node-e2e/1171852250075631616
I0911 18:47:35.651] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/batch/pull-kubernetes-node-e2e/1171852250075631616/artifacts
W0911 18:47:37.600] CommandException: One or more URLs matched no objects.
E0911 18:47:37.817] Command failed
I0911 18:47:37.818] process 39291 exited with code 1 after 0.0m
W0911 18:47:37.818] Remote dir gs://kubernetes-jenkins/pr-logs/pull/batch/pull-kubernetes-node-e2e/1171852250075631616/artifacts not exist yet
I0911 18:47:37.818] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/batch/pull-kubernetes-node-e2e/1171852250075631616/artifacts
I0911 18:47:43.889] process 39433 exited with code 0 after 0.1m
I0911 18:47:43.890] Call:  git rev-parse HEAD
I0911 18:47:43.896] process 40075 exited with code 0 after 0.0m
... skipping 21 lines ...