This job view page is being replaced by Spyglass soon. Check out the new job view.
PRbeautytiger: fix shellcheck in cluster/gce/config-common.sh
ResultFAILURE
Tests 1 failed / 478 succeeded
Started2019-09-11 18:03
Elapsed20m47s
Revision
Buildergke-prow-ssd-pool-1a225945-fgds
Refs master:001f2cd2
82357:e08742d8
pod692e6fec-d4be-11e9-a652-96f48f068c55
infra-commit069bf1fee
job-versionv1.17.0-alpha.0.1269+b6d6d1069e472c
pod692e6fec-d4be-11e9-a652-96f48f068c55
repok8s.io/kubernetes
repo-commitb6d6d1069e472cb8833b2ebf2eb9cc55f049fd94
repos{u'k8s.io/kubernetes': u'master:001f2cd2b553d06028c8542c8817820ee05d657f,82357:e08742d8b2b6922f94785a600266f5e399d2b3b2'}
revisionv1.17.0-alpha.0.1269+b6d6d1069e472c

Test Failures


Node Tests 19m23s

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 260 lines ...
I0911 18:05:41.787] make[1]: Entering directory '/go/src/k8s.io/kubernetes'
W0911 18:05:41.887] I0911 18:05:41.793447    4680 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:0xc0003ae690 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:05:41.888] I0911 18:05:41.798525    4680 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:0xc00044a0e0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:05:41.889] I0911 18:05:41.817666    4680 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:0xc0001541c0 machine: tests:[]} with service account "1046294573453-compute@developer.gserviceaccount.com"
W0911 18:05:41.889] I0911 18:05:41.825550    4680 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:05:42.293] I0911 18:05:42.293490    4680 run_remote.go:742] Deleting instance ""
W0911 18:05:42.299] E0911 18:05:42.298820    4680 run_remote.go:745] Error deleting instance "": googleapi: got HTTP response code 404 with body: Not Found
I0911 18:05:42.399] 
I0911 18:05:42.400] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 18:05:42.401] >                              START TEST                                >
I0911 18:05:42.401] >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
I0911 18:05:42.402] Start Test Suite on Host 
I0911 18:05:42.402] 
I0911 18:05:42.402] Failure Finished Test Suite on Host 
I0911 18:05:42.403] unable to create gce instance with running docker daemon for image coreos-beta-1911-1-1-v20181011.  could not create instance tmp-node-e2e-c47b345b-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:05:42.403] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 18:05:42.403] <                              FINISH TEST                               <
I0911 18:05:42.403] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
I0911 18:05:42.404] 
I0911 18:05:51.040] +++ [0911 18:05:51] Building go targets for linux/amd64:
I0911 18:05:51.041]     ./vendor/k8s.io/code-generator/cmd/deepcopy-gen
... skipping 456 lines ...
I0911 18:21:46.175] STEP: submitting the pod to kubernetes
I0911 18:21:46.175] STEP: verifying the pod is in kubernetes
I0911 18:21:46.175] STEP: updating the pod
I0911 18:21:46.175] Sep 11 18:14:02.534: INFO: Successfully updated pod "pod-update-activedeadlineseconds-34d6a81f-dea1-4408-9943-59ae654732f5"
I0911 18:21:46.175] Sep 11 18:14:02.534: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-34d6a81f-dea1-4408-9943-59ae654732f5" in namespace "pods-2241" to be "terminated due to deadline exceeded"
I0911 18:21:46.176] Sep 11 18:14:02.536: INFO: Pod "pod-update-activedeadlineseconds-34d6a81f-dea1-4408-9943-59ae654732f5": Phase="Running", Reason="", readiness=true. Elapsed: 1.511357ms
I0911 18:21:46.176] Sep 11 18:14:04.554: INFO: Pod "pod-update-activedeadlineseconds-34d6a81f-dea1-4408-9943-59ae654732f5": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 2.019636761s
I0911 18:21:46.176] Sep 11 18:14:04.554: INFO: Pod "pod-update-activedeadlineseconds-34d6a81f-dea1-4408-9943-59ae654732f5" satisfied condition "terminated due to deadline exceeded"
I0911 18:21:46.176] [AfterEach] [k8s.io] Pods
I0911 18:21:46.176]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:21:46.176] Sep 11 18:14:04.554: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:21:46.176] STEP: Destroying namespace "pods-2241" for this suite.
I0911 18:21:46.177] Sep 11 18:14:10.561: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 776 lines ...
I0911 18:21:46.309]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:21:46.309] STEP: Creating a kubernetes client
I0911 18:21:46.309] STEP: Building a namespace api object, basename init-container
I0911 18:21:46.310] Sep 11 18:14:51.501: INFO: Skipping waiting for service account
I0911 18:21:46.310] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:21:46.310]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:21:46.310] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:21:46.310]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:21:46.311] STEP: creating the pod
I0911 18:21:46.311] Sep 11 18:14:51.501: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:21:46.311] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:21:46.311]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:21:46.311] Sep 11 18:14:53.371: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:21:46.312] Sep 11 18:14:59.638: INFO: namespace init-container-3991 deletion completed in 6.234766616s
I0911 18:21:46.312] 
I0911 18:21:46.312] 
I0911 18:21:46.313] • [SLOW TEST:8.140 seconds]
I0911 18:21:46.313] [k8s.io] InitContainer [NodeConformance]
I0911 18:21:46.313] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:21:46.313]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:21:46.314]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:21:46.314] ------------------------------
I0911 18:21:46.314] [BeforeEach] [sig-storage] ConfigMap
I0911 18:21:46.314]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:21:46.314] STEP: Creating a kubernetes client
I0911 18:21:46.314] STEP: Building a namespace api object, basename configmap
... skipping 2360 lines ...
I0911 18:21:46.706] STEP: Creating a kubernetes client
I0911 18:21:46.706] STEP: Building a namespace api object, basename container-runtime
I0911 18:21:46.706] Sep 11 18:18:34.411: INFO: Skipping waiting for service account
I0911 18:21:46.706] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:21:46.706]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:21:46.706] STEP: create the container
I0911 18:21:46.706] STEP: wait for the container to reach Failed
I0911 18:21:46.707] STEP: get the container status
I0911 18:21:46.707] STEP: the container should be terminated
I0911 18:21:46.707] STEP: the termination message should be set
I0911 18:21:46.707] Sep 11 18:18:36.474: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:21:46.707] STEP: delete the container
I0911 18:21:46.707] [AfterEach] [k8s.io] Container Runtime
... skipping 864 lines ...
I0911 18:21:46.888] [BeforeEach] [k8s.io] Security Context
I0911 18:21:46.889]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:21:46.889] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:21:46.889]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:21:46.889] Sep 11 18:19:34.571: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-17b3a4d6-2a3f-491c-a2a2-c1ce26bc9162" in namespace "security-context-test-7998" to be "success or failure"
I0911 18:21:46.890] Sep 11 18:19:34.597: INFO: Pod "busybox-readonly-true-17b3a4d6-2a3f-491c-a2a2-c1ce26bc9162": Phase="Pending", Reason="", readiness=false. Elapsed: 25.309268ms
I0911 18:21:46.890] Sep 11 18:19:36.598: INFO: Pod "busybox-readonly-true-17b3a4d6-2a3f-491c-a2a2-c1ce26bc9162": Phase="Failed", Reason="", readiness=false. Elapsed: 2.027048638s
I0911 18:21:46.891] Sep 11 18:19:36.598: INFO: Pod "busybox-readonly-true-17b3a4d6-2a3f-491c-a2a2-c1ce26bc9162" satisfied condition "success or failure"
I0911 18:21:46.891] [AfterEach] [k8s.io] Security Context
I0911 18:21:46.892]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:21:46.892] Sep 11 18:19:36.598: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:21:46.892] STEP: Destroying namespace "security-context-test-7998" for this suite.
I0911 18:21:46.892] Sep 11 18:19:42.645: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 664 lines ...
I0911 18:21:47.022]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:21:47.022] STEP: Creating a kubernetes client
I0911 18:21:47.022] STEP: Building a namespace api object, basename init-container
I0911 18:21:47.022] Sep 11 18:19:54.467: INFO: Skipping waiting for service account
I0911 18:21:47.022] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:21:47.023]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:21:47.023] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:21:47.023]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:21:47.023] STEP: creating the pod
I0911 18:21:47.024] Sep 11 18:19:54.467: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:21:47.030] Sep 11 18:20:39.364: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-1b0aceec-db08-4339-8be3-85368a0b8d37", GenerateName:"", Namespace:"init-container-4798", SelfLink:"/api/v1/namespaces/init-container-4798/pods/pod-init-1b0aceec-db08-4339-8be3-85368a0b8d37", UID:"dbb0a3cb-440f-419e-95a6-2ad040d02e8e", ResourceVersion:"3329", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703822794, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"467718964"}, 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)(0xc0005ed9e0), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-c47b345b-cos-stable-60-9592-84-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000e6e120), 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)(0xc0005eda60)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0005eda80)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0005eda90), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0005eda94), 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:63703822794, 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:63703822794, 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:63703822794, 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:63703822794, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.99", PodIP:"10.100.0.173", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.173"}}, StartTime:(*v1.Time)(0xc0008df6e0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0008d9f10)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0008d9f80)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://efe6b68151be9b3bc534a615a6c0e92f18ac4c6244634948f7f7f1fd71f5525f", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc0008df700), 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)(0xc0008df720), 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)(0xc0005edb8c)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:21:47.030] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:21:47.030]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:21:47.031] Sep 11 18:20:39.364: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:21:47.031] STEP: Destroying namespace "init-container-4798" for this suite.
I0911 18:21:47.031] Sep 11 18:21:07.384: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:21:47.031] Sep 11 18:21:07.425: INFO: namespace init-container-4798 deletion completed in 28.051416034s
I0911 18:21:47.032] 
I0911 18:21:47.032] 
I0911 18:21:47.032] • [SLOW TEST:72.960 seconds]
I0911 18:21:47.032] [k8s.io] InitContainer [NodeConformance]
I0911 18:21:47.032] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:21:47.033]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:21:47.033]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:21:47.033] ------------------------------
I0911 18:21:47.033] [BeforeEach] [sig-storage] ConfigMap
I0911 18:21:47.033]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:21:47.034] STEP: Creating a kubernetes client
I0911 18:21:47.034] STEP: Building a namespace api object, basename configmap
... skipping 19 lines ...
I0911 18:21:47.037]   updates should be reflected in volume [NodeConformance] [Conformance]
I0911 18:21:47.037]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:21:47.038] ------------------------------
I0911 18:21:47.038] I0911 18:21:39.110665    1299 e2e_node_suite_test.go:196] Stopping node services...
I0911 18:21:47.038] I0911 18:21:39.110683    1299 server.go:257] Kill server "services"
I0911 18:21:47.038] I0911 18:21:39.110692    1299 server.go:294] Killing process 1933 (services) with -TERM
I0911 18:21:47.038] E0911 18:21:39.237226    1299 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 18:21:47.039] I0911 18:21:39.237262    1299 server.go:257] Kill server "kubelet"
I0911 18:21:47.039] I0911 18:21:39.245962    1299 services.go:147] Fetching log files...
I0911 18:21:47.039] I0911 18:21:39.246018    1299 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T181138.service].
I0911 18:21:47.039] I0911 18:21:40.104998    1299 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 18:21:47.039] I0911 18:21:40.140994    1299 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 18:21:47.040] I0911 18:21:40.571804    1299 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:21:47.040] I0911 18:21:40.597136    1299 e2e_node_suite_test.go:201] Tests Finished
I0911 18:21:47.040] 
I0911 18:21:47.040] 
I0911 18:21:47.040] Ran 157 of 313 Specs in 573.705 seconds
I0911 18:21:47.041] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:21:47.041] 
I0911 18:21:47.041] 
I0911 18:21:47.041] Ginkgo ran 1 suite in 9m37.283768156s
I0911 18:21:47.041] Test Suite Passed
I0911 18:21:47.041] 
I0911 18:21:47.042] Success Finished Test Suite on Host tmp-node-e2e-c47b345b-cos-stable-60-9592-84-0
... skipping 1608 lines ...
I0911 18:23:10.945]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:23:10.945] STEP: Creating a kubernetes client
I0911 18:23:10.945] STEP: Building a namespace api object, basename init-container
I0911 18:23:10.945] Sep 11 18:14:06.617: INFO: Skipping waiting for service account
I0911 18:23:10.945] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:23:10.945]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:23:10.945] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:23:10.945]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:23:10.945] STEP: creating the pod
I0911 18:23:10.946] Sep 11 18:14:06.617: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:23:10.951] Sep 11 18:14:58.903: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-6ff18cad-aa39-4694-9ce2-b4339cef1b0e", GenerateName:"", Namespace:"init-container-1235", SelfLink:"/api/v1/namespaces/init-container-1235/pods/pod-init-6ff18cad-aa39-4694-9ce2-b4339cef1b0e", UID:"18a36238-1458-4844-bccb-bfd304104191", ResourceVersion:"833", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703822446, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"617306615"}, 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)(0xc0010a2af0), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-c47b345b-cos-stable-63-10032-71-0", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000e42780), 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)(0xc0010a2ba0)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0010a2c00)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc0010a2c10), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0010a2c14), 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:63703822446, 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:63703822446, 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:63703822446, 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:63703822446, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.100", PodIP:"10.100.0.19", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.19"}}, StartTime:(*v1.Time)(0xc000d6b2a0), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0007d8310)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0007d8380)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:e004c2cc521c95383aebb1fb5893719aa7a8eae2e7a71f316a4410784edb00a9", ContainerID:"docker://7f53fa8022b185387d11642d224be626ef1ea81700a9fe715f1ec1cd2a8b0501", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc000d6b2c0), 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)(0xc000d6b2e0), 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)(0xc0010a2dfc)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:23:10.952] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:23:10.952]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:23:10.952] Sep 11 18:14:58.904: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:23:10.952] STEP: Destroying namespace "init-container-1235" for this suite.
I0911 18:23:10.952] Sep 11 18:15:26.910: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:23:10.952] Sep 11 18:15:26.949: INFO: namespace init-container-1235 deletion completed in 28.042831055s
I0911 18:23:10.952] 
I0911 18:23:10.952] 
I0911 18:23:10.953] • [SLOW TEST:80.337 seconds]
I0911 18:23:10.953] [k8s.io] InitContainer [NodeConformance]
I0911 18:23:10.953] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:23:10.953]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:23:10.953]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:23:10.953] ------------------------------
I0911 18:23:10.953] S
I0911 18:23:10.953] ------------------------------
I0911 18:23:10.953] [BeforeEach] [sig-storage] EmptyDir volumes
I0911 18:23:10.954]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 124 lines ...
I0911 18:23:10.972]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:23:10.972] STEP: Creating a kubernetes client
I0911 18:23:10.972] STEP: Building a namespace api object, basename init-container
I0911 18:23:10.973] Sep 11 18:15:27.613: INFO: Skipping waiting for service account
I0911 18:23:10.973] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:23:10.973]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:23:10.973] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:23:10.973]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:23:10.974] STEP: creating the pod
I0911 18:23:10.974] Sep 11 18:15:27.613: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:23:10.974] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:23:10.974]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:23:10.974] Sep 11 18:15:29.847: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:23:10.975] Sep 11 18:15:39.930: INFO: namespace init-container-3419 deletion completed in 10.048680163s
I0911 18:23:10.975] 
I0911 18:23:10.975] 
I0911 18:23:10.975] • [SLOW TEST:12.319 seconds]
I0911 18:23:10.975] [k8s.io] InitContainer [NodeConformance]
I0911 18:23:10.976] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:23:10.976]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:23:10.976]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:23:10.976] ------------------------------
I0911 18:23:10.976] SS
I0911 18:23:10.977] ------------------------------
I0911 18:23:10.977] [BeforeEach] [k8s.io] Security Context
I0911 18:23:10.977]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 3 lines ...
I0911 18:23:10.978] [BeforeEach] [k8s.io] Security Context
I0911 18:23:10.978]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:23:10.978] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:23:10.978]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:23:10.979] Sep 11 18:15:38.184: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-45fa4223-c17b-47a9-92eb-4f611feb9c04" in namespace "security-context-test-1824" to be "success or failure"
I0911 18:23:10.979] Sep 11 18:15:38.210: INFO: Pod "busybox-readonly-true-45fa4223-c17b-47a9-92eb-4f611feb9c04": Phase="Pending", Reason="", readiness=false. Elapsed: 26.529373ms
I0911 18:23:10.979] Sep 11 18:15:40.212: INFO: Pod "busybox-readonly-true-45fa4223-c17b-47a9-92eb-4f611feb9c04": Phase="Failed", Reason="", readiness=false. Elapsed: 2.028418804s
I0911 18:23:10.979] Sep 11 18:15:40.212: INFO: Pod "busybox-readonly-true-45fa4223-c17b-47a9-92eb-4f611feb9c04" satisfied condition "success or failure"
I0911 18:23:10.980] [AfterEach] [k8s.io] Security Context
I0911 18:23:10.980]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:23:10.980] Sep 11 18:15:40.212: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:23:10.980] STEP: Destroying namespace "security-context-test-1824" for this suite.
I0911 18:23:10.980] Sep 11 18:15:46.218: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 1931 lines ...
I0911 18:23:11.303] STEP: verifying the pod is in kubernetes
I0911 18:23:11.303] STEP: updating the pod
I0911 18:23:11.304] Sep 11 18:18:23.991: INFO: Successfully updated pod "pod-update-activedeadlineseconds-cae72934-72c9-4a1e-ab04-50e53a39b76d"
I0911 18:23:11.304] Sep 11 18:18:23.991: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-cae72934-72c9-4a1e-ab04-50e53a39b76d" in namespace "pods-7762" to be "terminated due to deadline exceeded"
I0911 18:23:11.304] Sep 11 18:18:23.992: INFO: Pod "pod-update-activedeadlineseconds-cae72934-72c9-4a1e-ab04-50e53a39b76d": Phase="Running", Reason="", readiness=true. Elapsed: 1.55088ms
I0911 18:23:11.304] Sep 11 18:18:25.994: INFO: Pod "pod-update-activedeadlineseconds-cae72934-72c9-4a1e-ab04-50e53a39b76d": Phase="Running", Reason="", readiness=true. Elapsed: 2.003661171s
I0911 18:23:11.304] Sep 11 18:18:27.996: INFO: Pod "pod-update-activedeadlineseconds-cae72934-72c9-4a1e-ab04-50e53a39b76d": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.005413814s
I0911 18:23:11.305] Sep 11 18:18:27.996: INFO: Pod "pod-update-activedeadlineseconds-cae72934-72c9-4a1e-ab04-50e53a39b76d" satisfied condition "terminated due to deadline exceeded"
I0911 18:23:11.305] [AfterEach] [k8s.io] Pods
I0911 18:23:11.305]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:23:11.305] Sep 11 18:18:27.996: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:23:11.305] STEP: Destroying namespace "pods-7762" for this suite.
I0911 18:23:11.305] Sep 11 18:18:34.007: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 831 lines ...
I0911 18:23:11.416] Sep 11 18:15:15.216: INFO: Skipping waiting for service account
I0911 18:23:11.416] [It] should be able to pull from private registry with credential provider [NodeConformance]
I0911 18:23:11.416]   _output/local/go/src/k8s.io/kubernetes/test/e2e_node/runtime_conformance_test.go:68
I0911 18:23:11.416] STEP: create the container
I0911 18:23:11.416] STEP: check the container status
I0911 18:23:11.416] STEP: delete the container
I0911 18:23:11.416] Sep 11 18:20:15.363: INFO: No.1 attempt failed: expected container state: Running, got: "Waiting", retrying...
I0911 18:23:11.416] STEP: create the container
I0911 18:23:11.416] STEP: check the container status
I0911 18:23:11.417] STEP: delete the container
I0911 18:23:11.417] [AfterEach] [k8s.io] Container Runtime Conformance Test
I0911 18:23:11.417]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:23:11.417] Sep 11 18:20:18.432: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 212 lines ...
I0911 18:23:11.445] STEP: Creating a kubernetes client
I0911 18:23:11.445] STEP: Building a namespace api object, basename container-runtime
I0911 18:23:11.445] Sep 11 18:20:54.996: INFO: Skipping waiting for service account
I0911 18:23:11.446] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:23:11.446]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:23:11.446] STEP: create the container
I0911 18:23:11.446] STEP: wait for the container to reach Failed
I0911 18:23:11.446] STEP: get the container status
I0911 18:23:11.446] STEP: the container should be terminated
I0911 18:23:11.446] STEP: the termination message should be set
I0911 18:23:11.446] Sep 11 18:20:56.006: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:23:11.446] STEP: delete the container
I0911 18:23:11.447] [AfterEach] [k8s.io] Container Runtime
... skipping 425 lines ...
I0911 18:23:11.502]   should *not* be restarted with a exec "cat /tmp/health" liveness probe [NodeConformance] [Conformance]
I0911 18:23:11.503]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:23:11.503] ------------------------------
I0911 18:23:11.503] I0911 18:23:02.684826    1315 e2e_node_suite_test.go:196] Stopping node services...
I0911 18:23:11.503] I0911 18:23:02.684854    1315 server.go:257] Kill server "services"
I0911 18:23:11.503] I0911 18:23:02.684869    1315 server.go:294] Killing process 1911 (services) with -TERM
I0911 18:23:11.503] E0911 18:23:02.894124    1315 services.go:88] Failed to stop services: error stopping "services": waitid: no child processes
I0911 18:23:11.503] I0911 18:23:02.894142    1315 server.go:257] Kill server "kubelet"
I0911 18:23:11.503] I0911 18:23:02.904031    1315 services.go:147] Fetching log files...
I0911 18:23:11.504] I0911 18:23:02.904089    1315 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T181138.service].
I0911 18:23:11.504] I0911 18:23:04.247393    1315 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 18:23:11.504] I0911 18:23:04.299122    1315 services.go:156] Get log file "cloud-init.log" with journalctl command [-u cloud*].
I0911 18:23:11.504] I0911 18:23:04.896905    1315 services.go:156] Get log file "docker.log" with journalctl command [-u docker].
I0911 18:23:11.504] I0911 18:23:04.932321    1315 e2e_node_suite_test.go:201] Tests Finished
I0911 18:23:11.504] 
I0911 18:23:11.504] 
I0911 18:23:11.504] Ran 157 of 313 Specs in 671.205 seconds
I0911 18:23:11.505] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:23:11.505] 
I0911 18:23:11.505] 
I0911 18:23:11.505] Ginkgo ran 1 suite in 11m14.332182446s
I0911 18:23:11.505] Test Suite Passed
I0911 18:23:11.505] 
I0911 18:23:11.505] Success Finished Test Suite on Host tmp-node-e2e-c47b345b-cos-stable-63-10032-71-0
... skipping 2419 lines ...
I0911 18:24:21.203]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:24:21.203] STEP: Creating a kubernetes client
I0911 18:24:21.203] STEP: Building a namespace api object, basename init-container
I0911 18:24:21.203] Sep 11 18:16:32.426: INFO: Skipping waiting for service account
I0911 18:24:21.203] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:24:21.203]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:24:21.204] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:24:21.204]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:24:21.204] STEP: creating the pod
I0911 18:24:21.204] Sep 11 18:16:32.426: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:24:21.204] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:24:21.204]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:24:21.204] Sep 11 18:16:34.975: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 2 lines ...
I0911 18:24:21.205] Sep 11 18:16:43.063: INFO: namespace init-container-6540 deletion completed in 8.08294195s
I0911 18:24:21.205] 
I0911 18:24:21.205] 
I0911 18:24:21.205] • [SLOW TEST:10.641 seconds]
I0911 18:24:21.205] [k8s.io] InitContainer [NodeConformance]
I0911 18:24:21.205] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:24:21.206]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I0911 18:24:21.206]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:24:21.206] ------------------------------
I0911 18:24:21.206] S
I0911 18:24:21.206] ------------------------------
I0911 18:24:21.206] [BeforeEach] [k8s.io] Probing container
I0911 18:24:21.206]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 533 lines ...
I0911 18:24:21.305] STEP: verifying the pod is in kubernetes
I0911 18:24:21.305] STEP: updating the pod
I0911 18:24:21.305] Sep 11 18:17:29.047: INFO: Successfully updated pod "pod-update-activedeadlineseconds-44729c05-c8e8-4b50-8a20-5acc93cc1aa5"
I0911 18:24:21.306] Sep 11 18:17:29.047: INFO: Waiting up to 5m0s for pod "pod-update-activedeadlineseconds-44729c05-c8e8-4b50-8a20-5acc93cc1aa5" in namespace "pods-1596" to be "terminated due to deadline exceeded"
I0911 18:24:21.306] Sep 11 18:17:29.067: INFO: Pod "pod-update-activedeadlineseconds-44729c05-c8e8-4b50-8a20-5acc93cc1aa5": Phase="Running", Reason="", readiness=true. Elapsed: 19.768982ms
I0911 18:24:21.306] Sep 11 18:17:31.069: INFO: Pod "pod-update-activedeadlineseconds-44729c05-c8e8-4b50-8a20-5acc93cc1aa5": Phase="Running", Reason="", readiness=true. Elapsed: 2.021662359s
I0911 18:24:21.306] Sep 11 18:17:33.071: INFO: Pod "pod-update-activedeadlineseconds-44729c05-c8e8-4b50-8a20-5acc93cc1aa5": Phase="Failed", Reason="DeadlineExceeded", readiness=false. Elapsed: 4.023187083s
I0911 18:24:21.307] Sep 11 18:17:33.071: INFO: Pod "pod-update-activedeadlineseconds-44729c05-c8e8-4b50-8a20-5acc93cc1aa5" satisfied condition "terminated due to deadline exceeded"
I0911 18:24:21.307] [AfterEach] [k8s.io] Pods
I0911 18:24:21.307]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:24:21.307] Sep 11 18:17:33.071: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:24:21.307] STEP: Destroying namespace "pods-1596" for this suite.
I0911 18:24:21.308] Sep 11 18:17:39.077: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 10 lines ...
I0911 18:24:21.310]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
I0911 18:24:21.310] STEP: Creating a kubernetes client
I0911 18:24:21.310] STEP: Building a namespace api object, basename init-container
I0911 18:24:21.310] Sep 11 18:16:26.428: INFO: Skipping waiting for service account
I0911 18:24:21.310] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:24:21.311]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/init_container.go:44
I0911 18:24:21.311] [It] should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:24:21.311]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:24:21.311] STEP: creating the pod
I0911 18:24:21.312] Sep 11 18:16:26.428: INFO: PodSpec: initContainers in spec.initContainers
I0911 18:24:21.319] Sep 11 18:17:16.011: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-1c609a8a-f8a4-4a2c-ad0a-9d5c26c47603", GenerateName:"", Namespace:"init-container-5351", SelfLink:"/api/v1/namespaces/init-container-5351/pods/pod-init-1c609a8a-f8a4-4a2c-ad0a-9d5c26c47603", UID:"fca76929-393d-4959-973b-dddc48fda210", ResourceVersion:"1875", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63703822586, loc:(*time.Location)(0xbe81a00)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"428297979"}, 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)(0xc00090e610), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string(nil), ServiceAccountName:"", DeprecatedServiceAccount:"", AutomountServiceAccountToken:(*bool)(nil), NodeName:"tmp-node-e2e-c47b345b-ubuntu-gke-1804-d1703-0-v20181113", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc000f36c60), 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)(0xc00090e690)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc00090e6b0)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(0xc00090e6c0), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc00090e6c4), 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:63703822586, 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:63703822586, 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:63703822586, 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:63703822586, loc:(*time.Location)(0xbe81a00)}}, Reason:"", Message:""}}, Message:"", Reason:"", NominatedNodeName:"", HostIP:"10.138.0.101", PodIP:"10.100.0.86", PodIPs:[]v1.PodIP{v1.PodIP{IP:"10.100.0.86"}}, StartTime:(*v1.Time)(0xc0008c6f40), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0001f80e0)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc0001f8150)}, Ready:false, RestartCount:3, Image:"busybox:1.29", ImageID:"docker-pullable://busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796", ContainerID:"docker://c50535553e61fe7abf8692a7e244be1d829bdc0f25e3e844ef9f36d31e96015d", Started:(*bool)(nil)}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc0008c6f60), 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)(0xc0008c6f80), 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)(0xc00090e7ac)}}, QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}
I0911 18:24:21.319] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I0911 18:24:21.319]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:24:21.320] Sep 11 18:17:16.011: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:24:21.320] STEP: Destroying namespace "init-container-5351" for this suite.
I0911 18:24:21.320] Sep 11 18:17:44.027: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
I0911 18:24:21.320] Sep 11 18:17:44.062: INFO: namespace init-container-5351 deletion completed in 28.04422361s
I0911 18:24:21.320] 
I0911 18:24:21.321] 
I0911 18:24:21.321] • [SLOW TEST:77.637 seconds]
I0911 18:24:21.321] [k8s.io] InitContainer [NodeConformance]
I0911 18:24:21.321] /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:693
I0911 18:24:21.321]   should not start app containers if init containers fail on a RestartAlways pod [Conformance]
I0911 18:24:21.322]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:24:21.322] ------------------------------
I0911 18:24:21.322] S
I0911 18:24:21.322] ------------------------------
I0911 18:24:21.322] [BeforeEach] [sig-storage] EmptyDir volumes
I0911 18:24:21.323]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 385 lines ...
I0911 18:24:21.393] [BeforeEach] [k8s.io] Security Context
I0911 18:24:21.393]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:40
I0911 18:24:21.393] [It] should run the container with readonly rootfs when readOnlyRootFilesystem=true [LinuxOnly] [NodeConformance]
I0911 18:24:21.394]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/security_context.go:211
I0911 18:24:21.394] Sep 11 18:18:06.300: INFO: Waiting up to 5m0s for pod "busybox-readonly-true-99b37640-3bf6-4d7e-8100-b7fa62d25f1f" in namespace "security-context-test-5559" to be "success or failure"
I0911 18:24:21.394] Sep 11 18:18:06.301: INFO: Pod "busybox-readonly-true-99b37640-3bf6-4d7e-8100-b7fa62d25f1f": Phase="Pending", Reason="", readiness=false. Elapsed: 1.073459ms
I0911 18:24:21.394] Sep 11 18:18:08.303: INFO: Pod "busybox-readonly-true-99b37640-3bf6-4d7e-8100-b7fa62d25f1f": Phase="Failed", Reason="", readiness=false. Elapsed: 2.002604639s
I0911 18:24:21.395] Sep 11 18:18:08.303: INFO: Pod "busybox-readonly-true-99b37640-3bf6-4d7e-8100-b7fa62d25f1f" satisfied condition "success or failure"
I0911 18:24:21.395] [AfterEach] [k8s.io] Security Context
I0911 18:24:21.395]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0911 18:24:21.395] Sep 11 18:18:08.303: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0911 18:24:21.396] STEP: Destroying namespace "security-context-test-5559" for this suite.
I0911 18:24:21.396] Sep 11 18:18:16.314: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
... skipping 618 lines ...
I0911 18:24:21.508] STEP: Creating a kubernetes client
I0911 18:24:21.508] STEP: Building a namespace api object, basename container-runtime
I0911 18:24:21.508] Sep 11 18:18:52.980: INFO: Skipping waiting for service account
I0911 18:24:21.509] [It] should report termination message [LinuxOnly] from log output if TerminationMessagePolicy FallbackToLogsOnError is set [NodeConformance] [Conformance]
I0911 18:24:21.509]   /go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0911 18:24:21.509] STEP: create the container
I0911 18:24:21.509] STEP: wait for the container to reach Failed
I0911 18:24:21.509] STEP: get the container status
I0911 18:24:21.509] STEP: the container should be terminated
I0911 18:24:21.509] STEP: the termination message should be set
I0911 18:24:21.510] Sep 11 18:18:55.076: INFO: Expected: &{DONE} to match Container's Termination Message: DONE --
I0911 18:24:21.510] STEP: delete the container
I0911 18:24:21.510] [AfterEach] [k8s.io] Container Runtime
... skipping 917 lines ...
I0911 18:24:21.689] Sep 11 18:20:09.644: INFO: Pod "pod86935525-96ed-4eac-b3d9-1f28bc9d0c48": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.007935122s
I0911 18:24:21.689] STEP: Saw pod success
I0911 18:24:21.689] Sep 11 18:20:09.644: INFO: Pod "pod86935525-96ed-4eac-b3d9-1f28bc9d0c48" satisfied condition "success or failure"
I0911 18:24:21.690] STEP: Verifying the memory backed volume was removed from node
I0911 18:24:21.690] Sep 11 18:20:09.648: INFO: Waiting up to 5m0s for pod "podc5211547-c156-4c11-a4ed-21757da3fc1a" in namespace "kubelet-volume-manager-3810" to be "success or failure"
I0911 18:24:21.690] Sep 11 18:20:09.653: INFO: Pod "podc5211547-c156-4c11-a4ed-21757da3fc1a": Phase="Pending", Reason="", readiness=false. Elapsed: 5.068496ms
I0911 18:24:21.691] Sep 11 18:20:11.655: INFO: Pod "podc5211547-c156-4c11-a4ed-21757da3fc1a": Phase="Failed", Reason="", readiness=false. Elapsed: 2.00656729s
I0911 18:24:21.691] Sep 11 18:20:21.669: INFO: Waiting up to 5m0s for pod "pod4313418e-9967-4e4f-bb9e-bc0de0d1b734" in namespace "kubelet-volume-manager-3810" to be "success or failure"
I0911 18:24:21.691] Sep 11 18:20:21.675: INFO: Pod "pod4313418e-9967-4e4f-bb9e-bc0de0d1b734": Phase="Pending", Reason="", readiness=false. Elapsed: 6.040413ms
I0911 18:24:21.691] Sep 11 18:20:23.676: INFO: Pod "pod4313418e-9967-4e4f-bb9e-bc0de0d1b734": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.007577365s
I0911 18:24:21.692] STEP: Saw pod success
I0911 18:24:21.692] Sep 11 18:20:23.676: INFO: Pod "pod4313418e-9967-4e4f-bb9e-bc0de0d1b734" satisfied condition "success or failure"
I0911 18:24:21.692] [AfterEach] [k8s.io] Kubelet Volume Manager
... skipping 247 lines ...
I0911 18:24:21.740] I0911 18:24:14.330457    2647 services.go:156] Get log file "kubelet.log" with journalctl command [-u kubelet-20190911T181138.service].
I0911 18:24:21.740] I0911 18:24:14.912607    2647 services.go:156] Get log file "kern.log" with journalctl command [-k].
I0911 18:24:21.740] I0911 18:24:14.934477    2647 e2e_node_suite_test.go:201] Tests Finished
I0911 18:24:21.740] 
I0911 18:24:21.741] 
I0911 18:24:21.741] Ran 157 of 313 Specs in 741.734 seconds
I0911 18:24:21.741] SUCCESS! -- 157 Passed | 0 Failed | 0 Flaked | 0 Pending | 156 Skipped
I0911 18:24:21.741] 
I0911 18:24:21.741] 
I0911 18:24:21.741] Ginkgo ran 1 suite in 12m23.412129042s
I0911 18:24:21.741] Test Suite Passed
I0911 18:24:21.742] 
I0911 18:24:21.742] Success Finished Test Suite on Host tmp-node-e2e-c47b345b-ubuntu-gke-1804-d1703-0-v20181113
... skipping 6 lines ...
W0911 18:24:21.844] 2019/09/11 18:24:21 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 19m23.688963302s
W0911 18:24:21.844] 2019/09/11 18:24:21 node.go:42: Noop - Node DumpClusterLogs() - /workspace/_artifacts: 
W0911 18:24:21.844] 2019/09/11 18:24:21 node.go:52: Noop - Node Down()
W0911 18:24:21.845] 2019/09/11 18:24:21 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0911 18:24:21.845] 2019/09/11 18:24:21 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0911 18:24:23.280] 2019/09/11 18:24:23 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.506614315s
W0911 18:24:23.281] 2019/09/11 18:24:23 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:24:23.283] Traceback (most recent call last):
W0911 18:24:23.283]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0911 18:24:23.285]     main(parse_args())
W0911 18:24:23.285]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0911 18:24:23.285]     mode.start(runner_args)
W0911 18:24:23.286]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0911 18:24:23.286]     check_env(env, self.command, *args)
W0911 18:24:23.286]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0911 18:24:23.286]     subprocess.check_call(cmd, env=env)
W0911 18:24:23.286]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0911 18:24:23.290]     raise CalledProcessError(retcode, cmd)
W0911 18:24:23.290] 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:24:23.298] Command failed
I0911 18:24:23.298] process 490 exited with code 1 after 19.5m
E0911 18:24:23.298] FAIL: pull-kubernetes-node-e2e
I0911 18:24:23.298] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0911 18:24:23.855] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0911 18:24:23.904] process 39151 exited with code 0 after 0.0m
I0911 18:24:23.905] Call:  gcloud config get-value account
I0911 18:24:24.231] process 39163 exited with code 0 after 0.0m
I0911 18:24:24.232] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0911 18:24:24.232] Upload result and artifacts...
I0911 18:24:24.232] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/82357/pull-kubernetes-node-e2e/1171846717012709376
I0911 18:24:24.232] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/82357/pull-kubernetes-node-e2e/1171846717012709376/artifacts
W0911 18:24:25.306] CommandException: One or more URLs matched no objects.
E0911 18:24:25.419] Command failed
I0911 18:24:25.419] process 39175 exited with code 1 after 0.0m
W0911 18:24:25.419] Remote dir gs://kubernetes-jenkins/pr-logs/pull/82357/pull-kubernetes-node-e2e/1171846717012709376/artifacts not exist yet
I0911 18:24:25.420] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/82357/pull-kubernetes-node-e2e/1171846717012709376/artifacts
I0911 18:24:28.919] process 39317 exited with code 0 after 0.1m
I0911 18:24:28.920] Call:  git rev-parse HEAD
I0911 18:24:28.924] process 39959 exited with code 0 after 0.0m
... skipping 21 lines ...