This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 5 succeeded
Started2020-09-26 21:47
Elapsed19m31s
Revisionmaster

Test Failures


task-02-create-cluster 5m0s

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

Filter through log files


Show 5 Passed Tests

Show 4 Skipped Tests

Error lines from build-log.txt

... skipping 196 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="21:53:49" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f mkdir -p /etc/systemd/system/kubelet.service.d]"
time="21:53:52" level=info msg="Adding /etc/systemd/system/kubelet.service.d/10-kubeadm.conf to the image"
time="21:53:52" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f cp /alter/bits/systemd/10-kubeadm.conf /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="21:53:55" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f chown -R root:root /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="21:53:58" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f /bin/sh -c echo \"KUBELET_EXTRA_ARGS=--fail-swap-on=false\" >> /etc/default/kubelet]"
time="21:54:01" level=info msg="Start CRI ..."
time="21:54:01" level=info msg="starting dockerd"
time="21:54:01" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f bash -c docker info &> /dev/null]"
time="21:54:01" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f dockerd]"
time="2020-09-26T21:54:04.840588546Z" level=info msg="libcontainerd: started new containerd process" pid=201
time="2020-09-26T21:54:04.842596863Z" level=info msg="parsed scheme: \"unix\"" module=grpc
time="2020-09-26T21:54:04.842735096Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
time="2020-09-26T21:54:04.842932400Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}]" module=grpc
time="2020-09-26T21:54:04.843049044Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
time="2020-09-26T21:54:04.843242356Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc420721aa0, CONNECTING" module=grpc
time="2020-09-26T21:54:05.529654325Z" level=info msg="starting containerd" revision=bb71b10fd8f58240ca47fbb579b9d1028eea7c84 version=1.2.5 
time="2020-09-26T21:54:05.532763896Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
time="2020-09-26T21:54:05.534934842Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
time="2020-09-26T21:54:05.535350150Z" 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-26T21:54:05.535375402Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
time="2020-09-26T21:54:05.553476929Z" 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-26T21:54:05.553530965Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
time="2020-09-26T21:54:05.553826862Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
time="2020-09-26T21:54:05.577691875Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
time="2020-09-26T21:54:05.578472800Z" 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-26T21:54:05.578506276Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
time="2020-09-26T21:54:05.578701786Z" 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-26T21:54:05.578726888Z" 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-26T21:54:05.578735010Z" 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-26T21:54:05.615267601Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
time="2020-09-26T21:54:05.615646533Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
time="2020-09-26T21:54:05.615737380Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
time="2020-09-26T21:54:05.618155175Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
time="2020-09-26T21:54:05.618861513Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
time="2020-09-26T21:54:05.618973493Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
... skipping 18 lines ...
time="2020-09-26T21:54:05.621991818Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
time="2020-09-26T21:54:05.622007104Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
time="2020-09-26T21:54:05.622020333Z" level=info msg="loading plugin "io.containerd.grpc.v1.cri"..." type=io.containerd.grpc.v1 
time="2020-09-26T21:54:05.622093708Z" 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-26T21:54:05.622122782Z" level=info msg="Connect containerd service" 
time="2020-09-26T21:54:05.622431722Z" level=info msg="Get image filesystem path "/var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.overlayfs"" 
time="2020-09-26T21:54:05.622629630Z" 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-26T21:54:05.675598882Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
time="2020-09-26T21:54:05.675971572Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
time="2020-09-26T21:54:05.679229454Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
time="2020-09-26T21:54:05.676329798Z" level=info msg="Start subscribing containerd event" 
time="2020-09-26T21:54:05.680416797Z" level=info msg="containerd successfully booted in 0.161076s" 
time="2020-09-26T21:54:05.680522702Z" level=info msg="Start recovering state" 
... skipping 15 lines ...
time="2020-09-26T21:54:05.742614308Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201c69a0, READY" module=grpc
time="2020-09-26T21:54:05.908050198Z" level=info msg="Graph migration to content-addressability took 0.00 seconds"
time="2020-09-26T21:54:05.914009039Z" level=warning msg="Your kernel does not support swap memory limit"
time="2020-09-26T21:54:05.914134650Z" level=warning msg="Your kernel does not support cgroup rt period"
time="2020-09-26T21:54:05.914159880Z" level=warning msg="Your kernel does not support cgroup rt runtime"
time="2020-09-26T21:54:05.916441096Z" level=info msg="Loading containers: start."
time="2020-09-26T21:54:05.942725364Z" 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-26T21:54:05.965260961Z" 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-26T21:54:05.970764258Z" 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-26T21:54:07.183214095Z" 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-26T21:54:08.127001336Z" level=info msg="Loading containers: done."
time="2020-09-26T21:54:08.487809608Z" level=info msg="Docker daemon" commit=d14af54 graphdriver(s)=overlay2 version=18.09.4
time="2020-09-26T21:54:08.489078784Z" level=info msg="Daemon has completed initialization"
time="2020-09-26T21:54:08.653293814Z" level=info msg="API listen on /var/run/docker.sock"
time="21:54:10" level=info msg="dockerd started"
... skipping 11 lines ...
time="21:56:30" level=debug msg="Running: [docker save -o=/tmp/kinder-image-path975412622/coredns.tar k8s.gcr.io/coredns:1.7.0]"
time="21:56:38" level=debug msg="Running: [docker cp /tmp/kinder-image-path975412622/coredns.tar kind-build-9f453d11-0718-4582-807a-6294a754c86f:/kind/images]"
time="21:56:47" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f bash -c cat /kinder/upgrade/version 2> /dev/null]"
time="21:56:49" level=info msg="Stop CRI ..."
time="21:56:49" level=debug msg="Running: [docker exec kind-build-9f453d11-0718-4582-807a-6294a754c86f pkill -f dockerd]"
time="2020-09-26T21:56:51.382222078Z" level=info msg="Processing signal 'terminated'"
time="2020-09-26T21:56:51.387876283Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
time="2020-09-26T21:56:51.389808081Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
time="2020-09-26T21:56:51.389925868Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
time="2020-09-26T21:56:51.392943078Z" level=info msg="Stop CRI service" 
time="2020-09-26T21:56:51.398672814Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201c69a0, TRANSIENT_FAILURE" module=grpc
time="2020-09-26T21:56:51.398747193Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201c69a0, CONNECTING" module=grpc
time="21:56:52" level=info msg="Commit to kindest/node:test ..."
sha256:71dd24faa7dce011af1b5e9ce13c2241e0f915dccb5dab7bfc9bd56435b5d438
... skipping 207 lines ...
I0926 22:05:06.146333     904 cleanupnode.go:57] [reset] Getting init system
[preflight] Running pre-flight checks
[reset] No etcd config found. Assuming external etcd
[reset] Please, manually reset etcd to prevent further issues
[reset] Stopping the kubelet service
[reset] Unmounting mounted directories in "/var/lib/kubelet"
W0926 22:05:06.202103     904 cleanupnode.go:99] [reset] Failed to evaluate the "/var/lib/kubelet" directory. Skipping its unmount and cleanup: lstat /var/lib/kubelet: no such file or directory
I0926 22:05:06.202153     904 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
[reset] Deleting contents of config directories: [/etc/kubernetes/manifests /etc/kubernetes/pki]
[reset] Deleting files: [/etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/bootstrap-kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf]
[reset] Deleting contents of stateful directories: [/var/lib/dockershim /var/run/kubernetes /var/lib/cni]
I0926 22:05:07.211110     904 cleanupnode.go:87] [reset] Removing contents from the config and pki directories
I0926 22:05:07.211308     904 reset.go:218] [reset] Deleting contents of /var/lib/dockershim
... skipping 20 lines ...
I0926 22:05:11.206754     875 cleanupnode.go:57] [reset] Getting init system
[preflight] Running pre-flight checks
[reset] No etcd config found. Assuming external etcd
[reset] Please, manually reset etcd to prevent further issues
[reset] Stopping the kubelet service
[reset] Unmounting mounted directories in "/var/lib/kubelet"
W0926 22:05:11.283336     875 cleanupnode.go:99] [reset] Failed to evaluate the "/var/lib/kubelet" directory. Skipping its unmount and cleanup: lstat /var/lib/kubelet: no such file or directory
I0926 22:05:11.283393     875 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
I0926 22:05:12.293306     875 cleanupnode.go:87] [reset] Removing contents from the config and pki directories
I0926 22:05:12.293437     875 reset.go:218] [reset] Deleting contents of /var/lib/dockershim
I0926 22:05:12.293469     875 reset.go:218] [reset] Deleting contents of /var/run/kubernetes
I0926 22:05:12.293483     875 reset.go:218] [reset] Deleting contents of /var/lib/cni
[reset] Deleting contents of config directories: [/etc/kubernetes/manifests /etc/kubernetes/pki]
... skipping 19 lines ...
W0926 22:05:15.709679     912 removeetcdmember.go:79] [reset] No kubeadm config, using etcd pod spec to get data directory
I0926 22:05:15.709735     912 cleanupnode.go:57] [reset] Getting init system
[preflight] Running pre-flight checks
[reset] No etcd config found. Assuming external etcd
[reset] Please, manually reset etcd to prevent further issues
[reset] Stopping the kubelet service
W0926 22:05:15.765772     912 cleanupnode.go:99] [reset] Failed to evaluate the "/var/lib/kubelet" directory. Skipping its unmount and cleanup: lstat /var/lib/kubelet: no such file or directory
I0926 22:05:15.765799     912 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
[reset] Unmounting mounted directories in "/var/lib/kubelet"
[reset] Deleting contents of config directories: [/etc/kubernetes/manifests /etc/kubernetes/pki]
[reset] Deleting files: [/etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/bootstrap-kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf]
[reset] Deleting contents of stateful directories: [/var/lib/dockershim /var/run/kubernetes /var/lib/cni]

... skipping 20 lines ...
W0926 22:05:20.035117     890 removeetcdmember.go:79] [reset] No kubeadm config, using etcd pod spec to get data directory
I0926 22:05:20.035246     890 cleanupnode.go:57] [reset] Getting init system
[preflight] Running pre-flight checks
[reset] No etcd config found. Assuming external etcd
[reset] Please, manually reset etcd to prevent further issues
[reset] Stopping the kubelet service
W0926 22:05:20.086520     890 cleanupnode.go:99] [reset] Failed to evaluate the "/var/lib/kubelet" directory. Skipping its unmount and cleanup: lstat /var/lib/kubelet: no such file or directory
I0926 22:05:20.086567     890 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
[reset] Unmounting mounted directories in "/var/lib/kubelet"
I0926 22:05:20.934639     890 cleanupnode.go:87] [reset] Removing contents from the config and pki directories
I0926 22:05:20.934748     890 reset.go:218] [reset] Deleting contents of /var/lib/dockershim
I0926 22:05:20.934772     890 reset.go:218] [reset] Deleting contents of /var/run/kubernetes
I0926 22:05:20.934785     890 reset.go:218] [reset] Deleting contents of /var/lib/cni
... skipping 21 lines ...
I0926 22:05:24.100545     900 reset.go:102] [reset] Could not obtain a client set from the kubeconfig file: /etc/kubernetes/admin.conf
I0926 22:05:24.100655     900 reset.go:120] [reset] Detected and using CRI socket: /var/run/dockershim.sock
I0926 22:05:24.100723     900 removeetcdmember.go:54] [reset] Checking for etcd config
W0926 22:05:24.100729     900 removeetcdmember.go:79] [reset] No kubeadm config, using etcd pod spec to get data directory
I0926 22:05:24.100786     900 cleanupnode.go:57] [reset] Getting init system
[reset] Unmounting mounted directories in "/var/lib/kubelet"
W0926 22:05:24.218138     900 cleanupnode.go:99] [reset] Failed to evaluate the "/var/lib/kubelet" directory. Skipping its unmount and cleanup: lstat /var/lib/kubelet: no such file or directory
I0926 22:05:24.218219     900 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
[reset] Deleting contents of config directories: [/etc/kubernetes/manifests /etc/kubernetes/pki]
[reset] Deleting files: [/etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/bootstrap-kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf]
[reset] Deleting contents of stateful directories: [/var/lib/dockershim /var/run/kubernetes /var/lib/cni]

The reset process does not clean CNI configuration. To do so, you must remove /etc/cni/net.d
... skipping 19 lines ...
Deleting cluster "kinder-external-etcd" ...
time="22:05:27" level=debug msg="Running: /usr/bin/docker [docker ps -q -a --no-trunc --filter label=io.k8s.sigs.kind.cluster --format {{.Names}}\\t{{.Label \"io.k8s.sigs.kind.cluster\"}} --filter label=io.k8s.sigs.kind.cluster=kinder-external-etcd]"
time="22:05:28" level=debug msg="Running: /usr/bin/docker [docker rm -f -v kinder-external-etcd-lb kinder-external-etcd-worker-1 kinder-external-etcd-control-plane-1 kinder-external-etcd-control-plane-2 kinder-external-etcd-control-plane-3 kinder-external-etcd-worker-2]"
 completed!

Ran 6 of 10 tasks in 0.000 seconds
FAIL! -- 5 tasks Passed | 1 Failed | 4 Skipped

see junit-runner.xml and task logs files for more details

Error: failed executing the workflow
+ EXIT_VALUE=1
+ set +o xtrace
Cleaning up after docker in docker.
================================================================================
Cleaning up after docker
Stopping Docker: dockerProgram process in pidfile '/var/run/docker-ssd.pid', 1 process(es), refused to die.
... skipping 3 lines ...