This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 265 succeeded
Started2020-09-28 14:00
Elapsed1h13m
Revisionmaster

Test Failures


task-06-e2e 35m0s

timeout. The task did not complete in less than 35m0s as expected
				from junit_runner.xml

Filter through log files


Show 265 Passed Tests

Show 4211 Skipped Tests

Error lines from build-log.txt

... skipping 191 lines ...
Created symlink /etc/systemd/system/multi-user.target.wants/kubelet.service → /kind/systemd/kubelet.service.
Created symlink /etc/systemd/system/kubelet.service → /kind/systemd/kubelet.service.
time="14:08:17" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 mkdir -p /etc/systemd/system/kubelet.service.d]"
time="14:08:19" level=info msg="Adding /etc/systemd/system/kubelet.service.d/10-kubeadm.conf to the image"
time="14:08:19" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 cp /alter/bits/systemd/10-kubeadm.conf /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="14:08:20" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 chown -R root:root /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="14:08:22" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 /bin/sh -c echo \"KUBELET_EXTRA_ARGS=--fail-swap-on=false\" >> /etc/default/kubelet]"
time="14:08:23" level=info msg="Start CRI ..."
time="14:08:23" level=info msg="starting dockerd"
time="14:08:23" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 dockerd]"
time="14:08:23" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 bash -c docker info &> /dev/null]"
time="2020-09-28T14:08:25.551759585Z" level=info msg="libcontainerd: started new containerd process" pid=212
time="2020-09-28T14:08:25.551870350Z" level=info msg="parsed scheme: \"unix\"" module=grpc
time="2020-09-28T14:08:25.551883295Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
time="2020-09-28T14:08:25.551919333Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}]" module=grpc
time="2020-09-28T14:08:25.551935352Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
time="2020-09-28T14:08:25.552703638Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4207479c0, CONNECTING" module=grpc
time="2020-09-28T14:08:26.151164414Z" level=info msg="starting containerd" revision=bb71b10fd8f58240ca47fbb579b9d1028eea7c84 version=1.2.5 
time="2020-09-28T14:08:26.156531307Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
time="2020-09-28T14:08:26.158234220Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
time="2020-09-28T14:08:26.159251366Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.btrfs" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" 
time="2020-09-28T14:08:26.159293679Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
time="2020-09-28T14:08:26.192316307Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.aufs" error="modprobe aufs failed: "modprobe: ERROR: ../libkmod/libkmod.c:586 kmod_search_moddep() could not open moddep file '/lib/modules/4.15.0-1044-gke/modules.dep.bin'\nmodprobe: FATAL: Module aufs not found in directory /lib/modules/4.15.0-1044-gke\n": exit status 1" 
time="2020-09-28T14:08:26.192392106Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
time="2020-09-28T14:08:26.192552555Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
time="2020-09-28T14:08:26.192774809Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
time="2020-09-28T14:08:26.195483024Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.zfs" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter" 
time="2020-09-28T14:08:26.195512824Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
time="2020-09-28T14:08:26.195611605Z" level=warning msg="could not use snapshotter zfs in metadata plugin" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter" 
time="2020-09-28T14:08:26.195621955Z" level=warning msg="could not use snapshotter btrfs in metadata plugin" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" 
time="2020-09-28T14:08:26.195630401Z" level=warning msg="could not use snapshotter aufs in metadata plugin" error="modprobe aufs failed: "modprobe: ERROR: ../libkmod/libkmod.c:586 kmod_search_moddep() could not open moddep file '/lib/modules/4.15.0-1044-gke/modules.dep.bin'\nmodprobe: FATAL: Module aufs not found in directory /lib/modules/4.15.0-1044-gke\n": exit status 1" 
time="2020-09-28T14:08:26.213118206Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
time="2020-09-28T14:08:26.213180106Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
time="2020-09-28T14:08:26.213263546Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
time="2020-09-28T14:08:26.213284689Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
time="2020-09-28T14:08:26.213309686Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
time="2020-09-28T14:08:26.213340871Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
... skipping 18 lines ...
time="2020-09-28T14:08:26.232680223Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
time="2020-09-28T14:08:26.232691155Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
time="2020-09-28T14:08:26.232706964Z" level=info msg="loading plugin "io.containerd.grpc.v1.cri"..." type=io.containerd.grpc.v1 
time="2020-09-28T14:08:26.232782277Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntime:{Type:io.containerd.runtime.v1.linux Engine: Root: Options:<nil>} UntrustedWorkloadRuntime:{Type: Engine: Root: Options:<nil>} Runtimes:map[] NoPivot:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginConfTemplate:} Registry:{Mirrors:map[docker.io:{Endpoints:[https://registry-1.docker.io]}] Auths:map[]} StreamServerAddress:127.0.0.1 StreamServerPort:0 EnableSelinux:false SandboxImage:k8s.gcr.io/pause:3.1 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384} ContainerdRootDir:/var/lib/docker/containerd/daemon ContainerdEndpoint:/var/run/docker/containerd/containerd.sock RootDir:/var/lib/docker/containerd/daemon/io.containerd.grpc.v1.cri StateDir:/var/run/docker/containerd/daemon/io.containerd.grpc.v1.cri}" 
time="2020-09-28T14:08:26.233836690Z" level=info msg="Connect containerd service" 
time="2020-09-28T14:08:26.235210355Z" level=info msg="Get image filesystem path "/var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.overlayfs"" 
time="2020-09-28T14:08:26.237096493Z" level=error msg="Failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" 
time="2020-09-28T14:08:26.237601003Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
time="2020-09-28T14:08:26.239660871Z" level=info msg="Start subscribing containerd event" 
time="2020-09-28T14:08:26.240161777Z" level=info msg="Start recovering state" 
time="2020-09-28T14:08:26.241239185Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
time="2020-09-28T14:08:26.241365625Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
time="2020-09-28T14:08:26.241384583Z" level=info msg="containerd successfully booted in 0.091586s" 
... skipping 15 lines ...
time="2020-09-28T14:08:26.297531636Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4208f6360, READY" module=grpc
time="2020-09-28T14:08:26.380404673Z" level=info msg="Graph migration to content-addressability took 0.00 seconds"
time="2020-09-28T14:08:26.380803431Z" level=warning msg="Your kernel does not support swap memory limit"
time="2020-09-28T14:08:26.380882000Z" level=warning msg="Your kernel does not support cgroup rt period"
time="2020-09-28T14:08:26.380897410Z" level=warning msg="Your kernel does not support cgroup rt runtime"
time="2020-09-28T14:08:26.383273645Z" level=info msg="Loading containers: start."
time="2020-09-28T14:08:26.389572640Z" level=warning msg="Running modprobe bridge br_netfilter failed with message: modprobe: ERROR: ../libkmod/libkmod.c:586 kmod_search_moddep() could not open moddep file '/lib/modules/4.15.0-1044-gke/modules.dep.bin'\nmodprobe: WARNING: Module bridge not found in directory /lib/modules/4.15.0-1044-gke\nmodprobe: ERROR: ../libkmod/libkmod.c:586 kmod_search_moddep() could not open moddep file '/lib/modules/4.15.0-1044-gke/modules.dep.bin'\nmodprobe: WARNING: Module br_netfilter not found in directory /lib/modules/4.15.0-1044-gke\n, error: exit status 1"
time="2020-09-28T14:08:26.401329713Z" level=warning msg="Running modprobe nf_nat failed with message: `modprobe: ERROR: ../libkmod/libkmod.c:586 kmod_search_moddep() could not open moddep file '/lib/modules/4.15.0-1044-gke/modules.dep.bin'\nmodprobe: WARNING: Module nf_nat not found in directory /lib/modules/4.15.0-1044-gke`, error: exit status 1"
time="2020-09-28T14:08:26.412913313Z" level=warning msg="Running modprobe xt_conntrack failed with message: `modprobe: ERROR: ../libkmod/libkmod.c:586 kmod_search_moddep() could not open moddep file '/lib/modules/4.15.0-1044-gke/modules.dep.bin'\nmodprobe: WARNING: Module xt_conntrack not found in directory /lib/modules/4.15.0-1044-gke`, error: exit status 1"
time="2020-09-28T14:08:27.236223877Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.18.0.0/16. Daemon option --bip can be used to set a preferred IP address"
time="2020-09-28T14:08:27.823374326Z" level=info msg="Loading containers: done."
time="2020-09-28T14:08:29.046195417Z" level=info msg="Docker daemon" commit=d14af54 graphdriver(s)=overlay2 version=18.09.4
time="2020-09-28T14:08:29.046611778Z" level=info msg="Daemon has completed initialization"
time="2020-09-28T14:08:29.121094368Z" level=info msg="API listen on /var/run/docker.sock"
time="14:08:29" level=info msg="dockerd started"
... skipping 11 lines ...
time="14:09:48" level=debug msg="Running: [docker save -o=/tmp/kinder-image-path921305347/coredns.tar k8s.gcr.io/coredns:1.7.0]"
time="14:09:54" level=debug msg="Running: [docker cp /tmp/kinder-image-path921305347/coredns.tar kind-build-032b7250-d499-42e5-8084-4e43b7c9d664:/kind/images]"
time="14:10:03" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 bash -c cat /kinder/upgrade/version 2> /dev/null]"
time="14:10:05" level=info msg="Stop CRI ..."
time="14:10:05" level=debug msg="Running: [docker exec kind-build-032b7250-d499-42e5-8084-4e43b7c9d664 pkill -f dockerd]"
time="2020-09-28T14:10:06.139977567Z" level=info msg="Processing signal 'terminated'"
time="2020-09-28T14:10:06.144073120Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
time="2020-09-28T14:10:06.147670986Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
time="2020-09-28T14:10:06.147729833Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
time="2020-09-28T14:10:06.153478850Z" level=info msg="Stop CRI service" 
time="2020-09-28T14:10:06.153644695Z" level=info msg="Stop CRI service" 
time="2020-09-28T14:10:06.160460341Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4208f6360, TRANSIENT_FAILURE" module=grpc
time="2020-09-28T14:10:06.160538598Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4208f6360, CONNECTING" module=grpc
time="14:10:06" level=info msg="Commit to kindest/node:test ..."
sha256:37edd10284e5e27dbcb1ff7c932ca48b43b02a9950aa26f29eb0ab0f5bdce778
... skipping 208 lines ...
time="14:14:57" level=debug msg="Running: [docker exec kinder-external-etcd-control-plane-1 kubeadm version -o=short]"
time="14:15:00" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:15:00" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:15:00" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:15:00" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-etcd]"
time="14:15:00" level=debug msg="Preparing externalEtcdPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:15:00" level=debug msg="generated config:\napiServer:\n  certSANs:\n  - localhost\n  - 172.17.0.3\napiVersion: kubeadm.k8s.io/v1beta2\nclusterName: kinder-external-etcd\ncontrolPlaneEndpoint: 172.17.0.7:6443\ncontrollerManager:\n  extraArgs:\n    enable-hostpath-provisioner: \"true\"\netcd:\n  external:\n    endpoints:\n    - http://172.17.0.8:2379\nkind: ClusterConfiguration\nkubernetesVersion: v1.20.0-alpha.1.171+4b24dca228d61f\nnetworking:\n  podSubnet: 192.168.0.0/16\n  serviceSubnet: \"\"\nscheduler:\n  extraArgs: null\n---\napiVersion: kubeadm.k8s.io/v1beta2\nbootstrapTokens:\n- token: abcdef.0123456789abcdef\ncertificateKey: \"0123456789012345678901234567890123456789012345678901234567890123\"\nkind: InitConfiguration\nlocalAPIEndpoint:\n  advertiseAddress: 172.17.0.3\n  bindPort: 6443\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.3\n---\napiVersion: kubelet.config.k8s.io/v1beta1\nevictionHard:\n  imagefs.available: 0%\n  nodefs.available: 0%\n  nodefs.inodesFree: 0%\nimageGCHighThresholdPercent: 100\nkind: KubeletConfiguration\n---\napiVersion: kubeproxy.config.k8s.io/v1alpha1\nkind: KubeProxyConfiguration\n"
time="14:15:00" level=debug msg="Running: [docker cp /tmp/kinder-external-etcd-control-plane-1-014137770 kinder-external-etcd-control-plane-1:/kind/kubeadm.conf]"

kinder-external-etcd-lb:$ Updating load balancer configuration with 1 control plane backends
time="14:15:02" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-control-plane-1]"
time="14:15:03" level=debug msg="Writing loadbalancer config on kinder-external-etcd-lb..."
time="14:15:03" level=debug msg="Running: [docker cp /tmp/kinder-external-etcd-lb-394199297 kinder-external-etcd-lb:/usr/local/etc/haproxy/haproxy.cfg]"
... skipping 31 lines ...
I0928 14:15:10.279629     916 checks.go:376] validating the presence of executable ebtables
I0928 14:15:10.279681     916 checks.go:376] validating the presence of executable ethtool
I0928 14:15:10.279732     916 checks.go:376] validating the presence of executable socat
I0928 14:15:10.279775     916 checks.go:376] validating the presence of executable tc
I0928 14:15:10.279824     916 checks.go:376] validating the presence of executable touch
I0928 14:15:10.279890     916 checks.go:520] running all checks
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
CGROUPS_DEVICES: enabled
CGROUPS_FREEZER: enabled
CGROUPS_MEMORY: enabled
CGROUPS_PIDS: enabled
CGROUPS_HUGETLB: enabled
	[WARNING SystemVerification]: failed to parse kernel config: unable to load kernel module: "configs", output: "modprobe: FATAL: Module configs not found in directory /lib/modules/4.15.0-1044-gke\n", err: exit status 1
I0928 14:15:11.052445     916 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0928 14:15:11.052742     916 checks.go:618] validating kubelet version
I0928 14:15:11.700719     916 checks.go:128] validating if the "kubelet" service is enabled and active
I0928 14:15:11.805813     916 checks.go:201] validating availability of port 10250
I0928 14:15:11.807783     916 checks.go:690] validating the external etcd version
[preflight] Pulling images required for setting up a Kubernetes cluster
... skipping 364 lines ...
time="14:17:31" level=debug msg="Running: [docker exec kinder-external-etcd-control-plane-2 kubeadm version -o=short]"
time="14:17:34" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:17:34" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:17:34" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:17:34" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-etcd]"
time="14:17:34" level=debug msg="Preparing externalEtcdPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:17:34" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ncontrolPlane:\n  certificateKey: \"0123456789012345678901234567890123456789012345678901234567890123\"\n  localAPIEndpoint:\n    advertiseAddress: 172.17.0.6\n    bindPort: 6443\ndiscovery:\n  bootstrapToken:\n    apiServerEndpoint: 172.17.0.7:6443\n    token: abcdef.0123456789abcdef\n    unsafeSkipCAVerification: true\nkind: JoinConfiguration\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.6\n"
time="14:17:34" level=debug msg="Running: [docker cp /tmp/kinder-external-etcd-control-plane-2-892278615 kinder-external-etcd-control-plane-2:/kind/kubeadm.conf]"

kinder-external-etcd-control-plane-2:$ kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables
time="14:17:37" level=debug msg="Running: [docker exec kinder-external-etcd-control-plane-2 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
I0928 14:17:38.960800    1008 join.go:395] [preflight] found NodeName empty; using OS hostname as NodeName
I0928 14:17:38.960881    1008 joinconfiguration.go:74] loading configuration from "/kind/kubeadm.conf"
... skipping 17 lines ...
I0928 14:17:40.875355    1008 checks.go:376] validating the presence of executable ebtables
I0928 14:17:40.875395    1008 checks.go:376] validating the presence of executable ethtool
I0928 14:17:40.875432    1008 checks.go:376] validating the presence of executable socat
I0928 14:17:40.875466    1008 checks.go:376] validating the presence of executable tc
I0928 14:17:40.875504    1008 checks.go:376] validating the presence of executable touch
I0928 14:17:40.875552    1008 checks.go:520] running all checks
	[WARNING SystemVerification]: failed to parse kernel config: unable to load kernel module: "configs", output: "modprobe: FATAL: Module configs not found in directory /lib/modules/4.15.0-1044-gke\n", err: exit status 1
I0928 14:17:41.788028    1008 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0928 14:17:41.789194    1008 checks.go:618] validating kubelet version
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 233 lines ...
time="14:19:33" level=debug msg="Running: [docker exec kinder-external-etcd-control-plane-3 kubeadm version -o=short]"
time="14:19:35" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:19:35" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:19:35" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:19:35" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-etcd]"
time="14:19:36" level=debug msg="Preparing externalEtcdPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:19:36" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ncontrolPlane:\n  certificateKey: \"0123456789012345678901234567890123456789012345678901234567890123\"\n  localAPIEndpoint:\n    advertiseAddress: 172.17.0.2\n    bindPort: 6443\ndiscovery:\n  bootstrapToken:\n    apiServerEndpoint: 172.17.0.7:6443\n    token: abcdef.0123456789abcdef\n    unsafeSkipCAVerification: true\nkind: JoinConfiguration\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.2\n"
time="14:19:36" level=debug msg="Running: [docker cp /tmp/kinder-external-etcd-control-plane-3-704538017 kinder-external-etcd-control-plane-3:/kind/kubeadm.conf]"

kinder-external-etcd-control-plane-3:$ kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables
time="14:19:39" level=debug msg="Running: [docker exec kinder-external-etcd-control-plane-3 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
I0928 14:19:40.656207    1131 join.go:395] [preflight] found NodeName empty; using OS hostname as NodeName
I0928 14:19:40.656653    1131 joinconfiguration.go:74] loading configuration from "/kind/kubeadm.conf"
... skipping 17 lines ...
I0928 14:19:42.487913    1131 checks.go:376] validating the presence of executable ebtables
I0928 14:19:42.487960    1131 checks.go:376] validating the presence of executable ethtool
I0928 14:19:42.488007    1131 checks.go:376] validating the presence of executable socat
I0928 14:19:42.488054    1131 checks.go:376] validating the presence of executable tc
I0928 14:19:42.488091    1131 checks.go:376] validating the presence of executable touch
I0928 14:19:42.488166    1131 checks.go:520] running all checks
	[WARNING SystemVerification]: failed to parse kernel config: unable to load kernel module: "configs", output: "modprobe: FATAL: Module configs not found in directory /lib/modules/4.15.0-1044-gke\n", err: exit status 1
I0928 14:19:43.471170    1131 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0928 14:19:43.471519    1131 checks.go:618] validating kubelet version
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 238 lines ...
time="14:21:45" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-worker-1]"
time="14:21:46" level=debug msg="Running: [docker exec kinder-external-etcd-worker-1 kubeadm version -o=short]"
time="14:21:49" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:21:49" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:21:49" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-etcd]"
time="14:21:49" level=debug msg="Preparing externalEtcdPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:21:49" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ndiscovery:\n  bootstrapToken:\n    apiServerEndpoint: 172.17.0.7:6443\n    token: abcdef.0123456789abcdef\n    unsafeSkipCAVerification: true\nkind: JoinConfiguration\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.4\n"
time="14:21:49" level=debug msg="Running: [docker cp /tmp/kinder-external-etcd-worker-1-220966011 kinder-external-etcd-worker-1:/kind/kubeadm.conf]"

kinder-external-etcd-worker-1:$ kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables
time="14:21:52" level=debug msg="Running: [docker exec kinder-external-etcd-worker-1 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
[preflight] Running pre-flight checks
I0928 14:21:53.661115    1249 join.go:395] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 17 lines ...
I0928 14:21:55.540442    1249 checks.go:376] validating the presence of executable ebtables
I0928 14:21:55.540480    1249 checks.go:376] validating the presence of executable ethtool
I0928 14:21:55.540520    1249 checks.go:376] validating the presence of executable socat
I0928 14:21:55.540561    1249 checks.go:376] validating the presence of executable tc
I0928 14:21:55.540598    1249 checks.go:376] validating the presence of executable touch
I0928 14:21:55.540646    1249 checks.go:520] running all checks
	[WARNING SystemVerification]: failed to parse kernel config: unable to load kernel module: "configs", output: "modprobe: FATAL: Module configs not found in directory /lib/modules/4.15.0-1044-gke\n", err: exit status 1
I0928 14:21:56.556449    1249 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0928 14:21:56.557067    1249 checks.go:618] validating kubelet version
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 97 lines ...
time="14:23:12" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-worker-2]"
time="14:23:13" level=debug msg="Running: [docker exec kinder-external-etcd-worker-2 kubeadm version -o=short]"
time="14:23:15" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:23:15" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:23:15" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-external-etcd-etcd]"
time="14:23:16" level=debug msg="Preparing externalEtcdPatch for kubeadm config v1beta2 (kubeadm version 1.20.0-alpha.1.171+4b24dca228d61f)"
time="14:23:16" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ndiscovery:\n  bootstrapToken:\n    apiServerEndpoint: 172.17.0.7:6443\n    token: abcdef.0123456789abcdef\n    unsafeSkipCAVerification: true\nkind: JoinConfiguration\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.5\n"
time="14:23:16" level=debug msg="Running: [docker cp /tmp/kinder-external-etcd-worker-2-478504350 kinder-external-etcd-worker-2:/kind/kubeadm.conf]"

kinder-external-etcd-worker-2:$ kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables
time="14:23:18" level=debug msg="Running: [docker exec kinder-external-etcd-worker-2 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
I0928 14:23:20.465449    1327 join.go:395] [preflight] found NodeName empty; using OS hostname as NodeName
I0928 14:23:20.465518    1327 joinconfiguration.go:74] loading configuration from "/kind/kubeadm.conf"
... skipping 17 lines ...
I0928 14:23:22.212380    1327 checks.go:376] validating the presence of executable ebtables
I0928 14:23:22.212418    1327 checks.go:376] validating the presence of executable ethtool
I0928 14:23:22.212482    1327 checks.go:376] validating the presence of executable socat
I0928 14:23:22.212514    1327 checks.go:376] validating the presence of executable tc
I0928 14:23:22.212550    1327 checks.go:376] validating the presence of executable touch
I0928 14:23:22.212595    1327 checks.go:520] running all checks
	[WARNING SystemVerification]: failed to parse kernel config: unable to load kernel module: "configs", output: "modprobe: FATAL: Module configs not found in directory /lib/modules/4.15.0-1044-gke\n", err: exit status 1
I0928 14:23:23.121889    1327 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0928 14:23:23.133571    1327 checks.go:618] validating kubelet version
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 674 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 28 14:33:19.763: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
•
JUnit report was created: /logs/artifacts/junit_e2e-kubeadm_01.xml

Ran 31 of 37 Specs in 1.625 seconds
SUCCESS! -- 31 Passed | 0 Failed | 0 Pending | 6 Skipped
PASS

Ginkgo ran 1 suite in 2.045720581s
Test Suite Passed
[--skip=\[copy-certs\] /home/prow/go/src/k8s.io/kubernetes/_output/bin/e2e_kubeadm.test -- --report-dir=/logs/artifacts --report-prefix=e2e-kubeadm --kubeconfig=/root/.kube/kind-config-kinder-external-etcd]
 completed!
... skipping 512 lines ...
• [SLOW TEST:31.372 seconds]
[sig-network] Service endpoints latency
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/network/framework.go:23
  should not be very high  [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-network] Service endpoints latency should not be very high  [Conformance]","total":-1,"completed":1,"skipped":41,"failed":0}

SSSS
------------------------------
[BeforeEach] [k8s.io] Pods
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 14 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 28 14:40:30.390: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "pods-1841" for this suite.

•
------------------------------
{"msg":"PASSED [k8s.io] Pods should delete a collection of pods [Conformance]","total":-1,"completed":2,"skipped":45,"failed":0}

SSSSSSSS
------------------------------
[BeforeEach] [sig-storage] ConfigMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 2 lines ...
Sep 28 14:39:58.580: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should be consumable from pods in volume with mappings as non-root [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating configMap with name configmap-test-volume-map-6c40e4c6-d7b0-4513-93e4-58d9e319fe46
STEP: Creating a pod to test consume configMaps
Sep 28 14:39:58.769: INFO: Waiting up to 5m0s for pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c" in namespace "configmap-5880" to be "Succeeded or Failed"
Sep 28 14:39:58.810: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 41.275052ms
Sep 28 14:40:00.857: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 2.088266338s
Sep 28 14:40:02.876: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 4.106865202s
Sep 28 14:40:04.903: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 6.133957006s
Sep 28 14:40:06.919: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 8.150386638s
Sep 28 14:40:08.957: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 10.187600332s
... skipping 12 lines ...
Sep 28 14:40:35.307: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 36.537746693s
Sep 28 14:40:37.325: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 38.55632378s
Sep 28 14:40:39.378: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 40.608768132s
Sep 28 14:40:41.401: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Pending", Reason="", readiness=false. Elapsed: 42.632144818s
Sep 28 14:40:43.439: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c": Phase="Succeeded", Reason="", readiness=false. Elapsed: 44.670368889s
STEP: Saw pod success
Sep 28 14:40:43.439: INFO: Pod "pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c" satisfied condition "Succeeded or Failed"
Sep 28 14:40:43.460: INFO: Trying to get logs from node kinder-external-etcd-worker-2 pod pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c container configmap-volume-test: <nil>
STEP: delete the pod
Sep 28 14:40:43.609: INFO: Waiting for pod pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c to disappear
Sep 28 14:40:43.620: INFO: Pod pod-configmaps-26b4d018-1ba9-46d8-9779-a5d2e1a7ab4c no longer exists
[AfterEach] [sig-storage] ConfigMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:45.428 seconds]
[sig-storage] ConfigMap
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/configmap_volume.go:36
  should be consumable from pods in volume with mappings as non-root [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] ConfigMap should be consumable from pods in volume with mappings as non-root [NodeConformance] [Conformance]","total":-1,"completed":1,"skipped":22,"failed":0}

SSSS
------------------------------
[BeforeEach] [sig-apps] ReplicationController
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 14 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 28 14:40:47.229: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "replication-controller-5402" for this suite.

•
------------------------------
{"msg":"PASSED [sig-apps] ReplicationController should surface a failure condition on a common issue like exceeded quota [Conformance]","total":-1,"completed":2,"skipped":26,"failed":0}

SSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] EmptyDir volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 28 14:40:30.550: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-external-etcd
STEP: Building a namespace api object, basename emptydir
STEP: Waiting for a default service account to be provisioned in namespace
[It] volume on default medium should have the correct mode [LinuxOnly] [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating a pod to test emptydir volume type on node default medium
Sep 28 14:40:30.735: INFO: Waiting up to 5m0s for pod "pod-326fce67-6014-46dc-8523-393471e999e7" in namespace "emptydir-8856" to be "Succeeded or Failed"
Sep 28 14:40:30.826: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 90.927691ms
Sep 28 14:40:32.852: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116628688s
Sep 28 14:40:34.872: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.136364683s
Sep 28 14:40:36.916: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.181001426s
Sep 28 14:40:38.941: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 8.205934695s
Sep 28 14:40:40.959: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 10.223611688s
Sep 28 14:40:42.984: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Pending", Reason="", readiness=false. Elapsed: 12.248285929s
Sep 28 14:40:45.023: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Running", Reason="", readiness=true. Elapsed: 14.287192388s
Sep 28 14:40:47.063: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.327723594s
STEP: Saw pod success
Sep 28 14:40:47.063: INFO: Pod "pod-326fce67-6014-46dc-8523-393471e999e7" satisfied condition "Succeeded or Failed"
Sep 28 14:40:47.073: INFO: Trying to get logs from node kinder-external-etcd-worker-1 pod pod-326fce67-6014-46dc-8523-393471e999e7 container test-container: <nil>
STEP: delete the pod
Sep 28 14:40:47.332: INFO: Waiting for pod pod-326fce67-6014-46dc-8523-393471e999e7 to disappear
Sep 28 14:40:47.386: INFO: Pod pod-326fce67-6014-46dc-8523-393471e999e7 no longer exists
[AfterEach] [sig-storage] EmptyDir volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:16.898 seconds]
[sig-storage] EmptyDir volumes
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/empty_dir.go:42
  volume on default medium should have the correct mode [LinuxOnly] [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] EmptyDir volumes volume on default medium should have the correct mode [LinuxOnly] [NodeConformance] [Conformance]","total":-1,"completed":3,"skipped":53,"failed":0}

SSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-api-machinery] CustomResourceDefinition resources [Privileged:ClusterAdmin]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 13 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 28 14:40:47.825: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "custom-resource-definition-6155" for this suite.

•
------------------------------
{"msg":"PASSED [sig-api-machinery] CustomResourceDefinition resources [Privileged:ClusterAdmin] should include custom resource definition resources in discovery documents [Conformance]","total":-1,"completed":4,"skipped":65,"failed":0}

SSSSSSSSSSSSS
------------------------------
[BeforeEach] [k8s.io] Container Runtime
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 22 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/runtime.go:41
    on terminated container
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/runtime.go:134
      should report termination message [LinuxOnly] if TerminationMessagePath is set as non-root user and at a non-default path [NodeConformance] [Conformance]
      /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [k8s.io] Container Runtime blackbox test on terminated container should report termination message [LinuxOnly] if TerminationMessagePath is set as non-root user and at a non-default path [NodeConformance] [Conformance]","total":-1,"completed":3,"skipped":35,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-apps] Job
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 27 lines ...
• [SLOW TEST:31.627 seconds]
[sig-apps] Job
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/apps/framework.go:23
  should adopt matching orphans and release non-matching pods [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-apps] Job should adopt matching orphans and release non-matching pods [Conformance]","total":-1,"completed":5,"skipped":78,"failed":0}

SSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] Projected configMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 28 14:41:10.617: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-external-etcd
STEP: Building a namespace api object, basename projected
STEP: Waiting for a default service account to be provisioned in namespace
[It] should be consumable in multiple volumes in the same pod [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating configMap with name projected-configmap-test-volume-2c1b5db2-d035-4b24-895a-3d0445181b2f
STEP: Creating a pod to test consume configMaps
Sep 28 14:41:10.869: INFO: Waiting up to 5m0s for pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c" in namespace "projected-9321" to be "Succeeded or Failed"
Sep 28 14:41:10.908: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 38.585464ms
Sep 28 14:41:12.929: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 2.059450362s
Sep 28 14:41:14.964: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 4.094306477s
Sep 28 14:41:16.983: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 6.114094416s
Sep 28 14:41:19.045: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 8.17514262s
Sep 28 14:41:21.052: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 10.182846281s
Sep 28 14:41:23.108: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Pending", Reason="", readiness=false. Elapsed: 12.238476568s
Sep 28 14:41:25.142: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.273054538s
STEP: Saw pod success
Sep 28 14:41:25.144: INFO: Pod "pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c" satisfied condition "Succeeded or Failed"
Sep 28 14:41:25.160: INFO: Trying to get logs from node kinder-external-etcd-worker-2 pod pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c container projected-configmap-volume-test: <nil>
STEP: delete the pod
Sep 28 14:41:25.536: INFO: Waiting for pod pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c to disappear
Sep 28 14:41:25.549: INFO: Pod pod-projected-configmaps-e20fc7f4-7201-48e0-bef3-3118cd27f57c no longer exists
[AfterEach] [sig-storage] Projected configMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:15.021 seconds]
[sig-storage] Projected configMap
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/projected_configmap.go:36
  should be consumable in multiple volumes in the same pod [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] Projected configMap should be consumable in multiple volumes in the same pod [NodeConformance] [Conformance]","total":-1,"completed":4,"skipped":52,"failed":0}

SSSS
------------------------------
[BeforeEach] [k8s.io] Container Lifecycle Hook
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 66 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:624
  when create a pod with lifecycle hook
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/lifecycle_hook.go:42
    should execute poststart http hook properly [NodeConformance] [Conformance]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [k8s.io] Container Lifecycle Hook when create a pod with lifecycle hook should execute poststart http hook properly [NodeConformance] [Conformance]","total":-1,"completed":1,"skipped":15,"failed":0}

SSSSS
------------------------------
[BeforeEach] [k8s.io] Pods
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 28 14:41:25.646: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-external-etcd
STEP: Building a namespace api object, basename pods
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [k8s.io] Pods
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/pods.go:181
[It] should contain environment variables for services [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
Sep 28 14:41:35.902: INFO: Waiting up to 5m0s for pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1" in namespace "pods-5213" to be "Succeeded or Failed"
Sep 28 14:41:35.936: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1": Phase="Pending", Reason="", readiness=false. Elapsed: 34.309458ms
Sep 28 14:41:37.951: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1": Phase="Pending", Reason="", readiness=false. Elapsed: 2.048598487s
Sep 28 14:41:39.964: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1": Phase="Pending", Reason="", readiness=false. Elapsed: 4.06175457s
Sep 28 14:41:41.980: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1": Phase="Pending", Reason="", readiness=false. Elapsed: 6.078213532s
Sep 28 14:41:44.005: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1": Phase="Pending", Reason="", readiness=false. Elapsed: 8.102798697s
Sep 28 14:41:46.032: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.129803368s
STEP: Saw pod success
Sep 28 14:41:46.032: INFO: Pod "client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1" satisfied condition "Succeeded or Failed"
Sep 28 14:41:46.041: INFO: Trying to get logs from node kinder-external-etcd-worker-2 pod client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1 container env3cont: <nil>
STEP: delete the pod
Sep 28 14:41:46.121: INFO: Waiting for pod client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1 to disappear
Sep 28 14:41:46.140: INFO: Pod client-envvars-4aca31e7-b9e0-464f-9d41-82d7e035faf1 no longer exists
[AfterEach] [k8s.io] Pods
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:20.522 seconds]
[k8s.io] Pods
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:624
  should contain environment variables for services [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [k8s.io] Pods should contain environment variables for services [NodeConformance] [Conformance]","total":-1,"completed":5,"skipped":56,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] Downward API volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 2 lines ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-storage] Downward API volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:42
[It] should provide node allocatable (cpu) as default cpu limit if the limit is not set [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating a pod to test downward API volume plugin
Sep 28 14:41:46.548: INFO: Waiting up to 5m0s for pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7" in namespace "downward-api-7599" to be "Succeeded or Failed"
Sep 28 14:41:46.572: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Pending", Reason="", readiness=false. Elapsed: 24.509678ms
Sep 28 14:41:48.589: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Pending", Reason="", readiness=false. Elapsed: 2.041635121s
Sep 28 14:41:50.601: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.053143904s
Sep 28 14:41:52.629: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.080821522s
Sep 28 14:41:54.661: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Pending", Reason="", readiness=false. Elapsed: 8.112925435s
Sep 28 14:41:56.667: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Pending", Reason="", readiness=false. Elapsed: 10.118944415s
Sep 28 14:41:58.683: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.135254093s
STEP: Saw pod success
Sep 28 14:41:58.683: INFO: Pod "downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7" satisfied condition "Succeeded or Failed"
Sep 28 14:41:58.688: INFO: Trying to get logs from node kinder-external-etcd-worker-1 pod downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7 container client-container: <nil>
STEP: delete the pod
Sep 28 14:41:58.754: INFO: Waiting for pod downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7 to disappear
Sep 28 14:41:58.769: INFO: Pod downwardapi-volume-badeb41e-8296-4e84-8662-31c7ba59cdc7 no longer exists
[AfterEach] [sig-storage] Downward API volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:12.436 seconds]
[sig-storage] Downward API volume
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:37
  should provide node allocatable (cpu) as default cpu limit if the limit is not set [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] Downward API volume should provide node allocatable (cpu) as default cpu limit if the limit is not set [NodeConformance] [Conformance]","total":-1,"completed":6,"skipped":85,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-node] Downward API
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 28 14:41:59.104: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-external-etcd
STEP: Building a namespace api object, basename downward-api
STEP: Waiting for a default service account to be provisioned in namespace
[It] should provide container's limits.cpu/memory and requests.cpu/memory as env vars [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating a pod to test downward api env vars
Sep 28 14:41:59.330: INFO: Waiting up to 5m0s for pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534" in namespace "downward-api-5453" to be "Succeeded or Failed"
Sep 28 14:41:59.348: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534": Phase="Pending", Reason="", readiness=false. Elapsed: 18.819382ms
Sep 28 14:42:01.380: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534": Phase="Pending", Reason="", readiness=false. Elapsed: 2.050487628s
Sep 28 14:42:03.392: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534": Phase="Pending", Reason="", readiness=false. Elapsed: 4.062415851s
Sep 28 14:42:05.404: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534": Phase="Pending", Reason="", readiness=false. Elapsed: 6.074005524s
Sep 28 14:42:07.416: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534": Phase="Pending", Reason="", readiness=false. Elapsed: 8.08644606s
Sep 28 14:42:09.426: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.096525697s
STEP: Saw pod success
Sep 28 14:42:09.426: INFO: Pod "downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534" satisfied condition "Succeeded or Failed"
Sep 28 14:42:09.434: INFO: Trying to get logs from node kinder-external-etcd-worker-2 pod downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534 container dapi-container: <nil>
STEP: delete the pod
Sep 28 14:42:09.496: INFO: Waiting for pod downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534 to disappear
Sep 28 14:42:09.529: INFO: Pod downward-api-e2a1bc10-316c-40ed-b2f2-aaa9226a0534 no longer exists
[AfterEach] [sig-node] Downward API
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:10.471 seconds]
[sig-node] Downward API
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/downward_api.go:34
  should provide container's limits.cpu/memory and requests.cpu/memory as env vars [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-node] Downward API should provide container's limits.cpu/memory and requests.cpu/memory as env vars [NodeConformance] [Conformance]","total":-1,"completed":7,"skipped":124,"failed":0}

S
------------------------------
[BeforeEach] [sig-api-machinery] CustomResourceDefinition resources [Privileged:ClusterAdmin]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 14 lines ...
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  Simple CustomResourceDefinition
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/apimachinery/custom_resource_definition.go:48
    listing custom resource definition objects works  [Conformance]
    /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-api-machinery] CustomResourceDefinition resources [Privileged:ClusterAdmin] Simple CustomResourceDefinition listing custom resource definition objects works  [Conformance]","total":-1,"completed":8,"skipped":125,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-network] Services
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 46 lines ...
• [SLOW TEST:148.479 seconds]
[sig-network] Services
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/network/framework.go:23
  should be able to change the type from NodePort to ExternalName [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-network] Services should be able to change the type from NodePort to ExternalName [Conformance]","total":-1,"completed":1,"skipped":7,"failed":0}

SSSSSSSSSSS
------------------------------
[BeforeEach] [sig-network] Services
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 66 lines ...
• [SLOW TEST:148.419 seconds]
[sig-network] Services
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/network/framework.go:23
  should have session affinity work for service with type clusterIP [LinuxOnly] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-network] Services should have session affinity work for service with type clusterIP [LinuxOnly] [Conformance]","total":-1,"completed":1,"skipped":15,"failed":0}

SSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] Projected downwardAPI
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 2 lines ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-storage] Projected downwardAPI
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/projected_downwardapi.go:42
[It] should set mode on item file [LinuxOnly] [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating a pod to test downward API volume plugin
Sep 28 14:42:17.060: INFO: Waiting up to 5m0s for pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e" in namespace "projected-4018" to be "Succeeded or Failed"
Sep 28 14:42:17.089: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Pending", Reason="", readiness=false. Elapsed: 28.782309ms
Sep 28 14:42:19.095: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Pending", Reason="", readiness=false. Elapsed: 2.035058917s
Sep 28 14:42:21.125: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Pending", Reason="", readiness=false. Elapsed: 4.064651127s
Sep 28 14:42:23.136: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Pending", Reason="", readiness=false. Elapsed: 6.075922359s
Sep 28 14:42:25.151: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Pending", Reason="", readiness=false. Elapsed: 8.090909482s
Sep 28 14:42:27.220: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Pending", Reason="", readiness=false. Elapsed: 10.159653207s
Sep 28 14:42:29.234: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.173132447s
STEP: Saw pod success
Sep 28 14:42:29.234: INFO: Pod "downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e" satisfied condition "Succeeded or Failed"
Sep 28 14:42:29.255: INFO: Trying to get logs from node kinder-external-etcd-worker-1 pod downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e container client-container: <nil>
STEP: delete the pod
Sep 28 14:42:29.321: INFO: Waiting for pod downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e to disappear
Sep 28 14:42:29.333: INFO: Pod downwardapi-volume-31ddc196-77b1-471d-964e-07172c2e437e no longer exists
[AfterEach] [sig-storage] Projected downwardAPI
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:12.464 seconds]
[sig-storage] Projected downwardAPI
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/projected_downwardapi.go:36
  should set mode on item file [LinuxOnly] [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] Projected downwardAPI should set mode on item file [LinuxOnly] [NodeConformance] [Conformance]","total":-1,"completed":9,"skipped":189,"failed":0}

S
------------------------------
[BeforeEach] [sig-storage] Downward API volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 2 lines ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-storage] Downward API volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:42
[It] should provide container's memory limit [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating a pod to test downward API volume plugin
Sep 28 14:42:26.856: INFO: Waiting up to 5m0s for pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f" in namespace "downward-api-1373" to be "Succeeded or Failed"
Sep 28 14:42:26.868: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 11.945901ms
Sep 28 14:42:28.890: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 2.033146783s
Sep 28 14:42:30.923: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 4.066609828s
Sep 28 14:42:32.932: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 6.07540248s
Sep 28 14:42:34.993: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 8.136474113s
Sep 28 14:42:37.040: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 10.183606027s
Sep 28 14:42:39.077: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Pending", Reason="", readiness=false. Elapsed: 12.220028853s
Sep 28 14:42:41.105: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.248975061s
STEP: Saw pod success
Sep 28 14:42:41.108: INFO: Pod "downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f" satisfied condition "Succeeded or Failed"
Sep 28 14:42:41.142: INFO: Trying to get logs from node kinder-external-etcd-worker-1 pod downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f container client-container: <nil>
STEP: delete the pod
Sep 28 14:42:41.291: INFO: Waiting for pod downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f to disappear
Sep 28 14:42:41.316: INFO: Pod downwardapi-volume-8c5905d4-c063-48e4-ab25-3af34206137f no longer exists
[AfterEach] [sig-storage] Downward API volume
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:14.748 seconds]
[sig-storage] Downward API volume
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:37
  should provide container's memory limit [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] Downward API volume should provide container's memory limit [NodeConformance] [Conformance]","total":-1,"completed":2,"skipped":21,"failed":0}

SSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] EmptyDir volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 28 14:42:29.401: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-external-etcd
STEP: Building a namespace api object, basename emptydir
STEP: Waiting for a default service account to be provisioned in namespace
[It] should support (root,0777,tmpfs) [LinuxOnly] [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
STEP: Creating a pod to test emptydir 0777 on tmpfs
Sep 28 14:42:29.575: INFO: Waiting up to 5m0s for pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4" in namespace "emptydir-6866" to be "Succeeded or Failed"
Sep 28 14:42:29.635: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Pending", Reason="", readiness=false. Elapsed: 60.135921ms
Sep 28 14:42:31.660: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.08502519s
Sep 28 14:42:33.676: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Pending", Reason="", readiness=false. Elapsed: 4.100755455s
Sep 28 14:42:35.695: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Pending", Reason="", readiness=false. Elapsed: 6.119996408s
Sep 28 14:42:37.709: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Pending", Reason="", readiness=false. Elapsed: 8.133724821s
Sep 28 14:42:39.735: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Pending", Reason="", readiness=false. Elapsed: 10.159786472s
Sep 28 14:42:41.751: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.175994554s
STEP: Saw pod success
Sep 28 14:42:41.751: INFO: Pod "pod-6a0673e7-bc33-467a-90e9-289e101c59e4" satisfied condition "Succeeded or Failed"
Sep 28 14:42:41.772: INFO: Trying to get logs from node kinder-external-etcd-worker-1 pod pod-6a0673e7-bc33-467a-90e9-289e101c59e4 container test-container: <nil>
STEP: delete the pod
Sep 28 14:42:41.868: INFO: Waiting for pod pod-6a0673e7-bc33-467a-90e9-289e101c59e4 to disappear
Sep 28 14:42:41.872: INFO: Pod pod-6a0673e7-bc33-467a-90e9-289e101c59e4 no longer exists
[AfterEach] [sig-storage] EmptyDir volumes
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
... skipping 4 lines ...
• [SLOW TEST:12.528 seconds]
[sig-storage] EmptyDir volumes
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/empty_dir.go:42
  should support (root,0777,tmpfs) [LinuxOnly] [NodeConformance] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-storage] EmptyDir volumes should support (root,0777,tmpfs) [LinuxOnly] [NodeConformance] [Conformance]","total":-1,"completed":10,"skipped":190,"failed":0}

S
------------------------------
[BeforeEach] [sig-auth] ServiceAccounts
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 25 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:175
Sep 28 14:42:42.945: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "svcaccounts-2024" for this suite.

•
------------------------------
{"msg":"PASSED [sig-auth] ServiceAccounts should allow opting out of API token automount  [Conformance]","total":-1,"completed":11,"skipped":191,"failed":0}

SSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [k8s.io] Variable Expansion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 26 lines ...
• [SLOW TEST:165.911 seconds]
[k8s.io] Variable Expansion
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:624
  should succeed in writing subpaths in container [sig-storage][Slow] [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [k8s.io] Variable Expansion should succeed in writing subpaths in container [sig-storage][Slow] [Conformance]","total":-1,"completed":1,"skipped":10,"failed":0}

SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
... skipping 36 lines ...
• [SLOW TEST:18.754 seconds]
[sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  patching/updating a mutating webhook should work [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:629
------------------------------
{"msg":"PASSED [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin] patching/updating a mutating webhook should work [Conformance]","total":-1,"completed":2,"skipped":34,"failed":0}

SSSS
------------------------------
[BeforeEach] [k8s.io] Variable Expansion
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:174
STEP: Creating a kubernetes client
Sep 28 14:42:41.529: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-external-etcd
STEP: Building a namespace api object, basename var-expansion
STEP: Waiting for a default service account to be provision