This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 9 succeeded
Started2020-03-15 23:12
Elapsed41m55s
Revisionrelease-1.17
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/35965ebe-ce71-437b-858f-190cd0c97f21/targets/test'}}
resultstorehttps://source.cloud.google.com/results/invocations/35965ebe-ce71-437b-858f-190cd0c97f21/targets/test
uploadercrier

Test Failures


task-06-e2e-kubeadm 5m0s

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

Filter through log files | View test history on testgrid


Show 9 Passed Tests

Show 1 Skipped Tests

Error lines from build-log.txt

... skipping 238 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="23:26:48" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 mkdir -p /etc/systemd/system/kubelet.service.d]"
time="23:26:50" level=info msg="Adding /etc/systemd/system/kubelet.service.d/10-kubeadm.conf to the image"
time="23:26:50" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 cp /alter/bits/systemd/10-kubeadm.conf /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="23:26:52" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 chown -R root:root /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="23:26:55" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 /bin/sh -c echo \"KUBELET_EXTRA_ARGS=--fail-swap-on=false\" >> /etc/default/kubelet]"
time="23:26:58" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 /bin/sh -c which docker || true]"
time="23:27:01" level=info msg="Detected containerd as container runtime"
time="23:27:01" level=info msg="Pre loading images ..."
time="23:27:01" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 mkdir -p /kind/images]"
time="23:27:03" level=debug msg="Running: [docker exec kind-build-ffc58913-6dda-41fd-ba45-5a6a00871a97 bash -c containerd & find /kind/images -name *.tar -print0 | xargs -r -0 -n 1 -P $(nproc) ctr --namespace=k8s.io images import --no-unpack && kill %1 && rm -rf /kind/images/*]"
time="2020-03-15T23:27:06.315075666Z" level=info msg="starting containerd" revision=7af311b4200b464a79c340b4e3a2799f8906ee8d version=v1.3.0-20-g7af311b4
time="2020-03-15T23:27:06.396471033Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
time="2020-03-15T23:27:06.397084295Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
time="2020-03-15T23:27:06.397166026Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
time="2020-03-15T23:27:06.397198197Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
time="2020-03-15T23:27:06.397366034Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
time="2020-03-15T23:27:06.408148336Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
time="2020-03-15T23:27:06.408586209Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
time="2020-03-15T23:27:06.408613352Z" level=info msg="metadata content store policy set" policy=shared
time="2020-03-15T23:27:06.433889967Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
time="2020-03-15T23:27:06.433987036Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
time="2020-03-15T23:27:06.435417779Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
time="2020-03-15T23:27:06.436504105Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
time="2020-03-15T23:27:06.436552135Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
... skipping 20 lines ...
time="2020-03-15T23:27:06.442819845Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
time="2020-03-15T23:27:06.442858918Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
time="2020-03-15T23:27:06.443207720Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type:io.containerd.runc.v2 Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false} UntrustedWorkloadRuntime:{Type: Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false} Runtimes:map[runc:{Type:io.containerd.runc.v1 Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false} test-handler:{Type:io.containerd.runc.v2 Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false}] NoPivot:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate:} Registry:{Mirrors:map[docker.io:{Endpoints:[https://registry-1.docker.io]}] Configs:map[] Auths:map[]} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SandboxImage:k8s.gcr.io/pause:3.1 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}"
time="2020-03-15T23:27:06.444512864Z" level=warning msg="`default_runtime` is deprecated, please use `default_runtime_name` to reference the default configuration you have defined in `runtimes`"
time="2020-03-15T23:27:06.444697556Z" level=info msg="Connect containerd service"
time="2020-03-15T23:27:06.445250784Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
time="2020-03-15T23:27:06.445737447Z" 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-03-15T23:27:06.446402891Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
time="2020-03-15T23:27:06.446962725Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
time="2020-03-15T23:27:06.448103493Z" level=info msg=serving... address=/run/containerd/containerd.sock
time="2020-03-15T23:27:06.448134568Z" level=info msg="containerd successfully booted in 0.140721s"
time="2020-03-15T23:27:06.449702001Z" level=info msg="Start subscribing containerd event"
time="2020-03-15T23:27:06.449805729Z" level=info msg="Start recovering state"
time="2020-03-15T23:27:06.450053972Z" level=info msg="Start event monitor"
time="2020-03-15T23:27:06.451373987Z" level=info msg="Start snapshots syncer"
time="2020-03-15T23:27:06.451396820Z" level=info msg="Start streaming server"
time="2020-03-15T23:27:18.265720645Z" level=info msg="ImageCreate event &ImageCreate{Name:k8s.gcr.io/kube-scheduler:v1.17.5-beta.0.1_106c255ad7ab80,Labels:map[string]string{},XXX_unrecognized:[],}"
time="2020-03-15T23:27:18.315383617Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:f2998888ee28e18966ea1fb33e8582ab94f6b86225f2a0e0edb604ed919bbe36,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}"
time="2020-03-15T23:27:18.319039982Z" level=info msg="ImageUpdate event &ImageUpdate{Name:k8s.gcr.io/kube-scheduler:v1.17.5-beta.0.1_106c255ad7ab80,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}"
time="2020-03-15T23:27:21.520769395Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/VERSION locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/VERSION total=3
time="2020-03-15T23:27:22.354317205Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:22.677053625Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:23.213968468Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:23.838600469Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:24.612333829Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:25.349054162Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:25.952735857Z" level=error msg="(*service).Write failed" error="rpc error: code = Unavailable desc = ref k8s.io/1/tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar locked: unavailable" ref=tar-7642396919f26df99df1ff78e89004e3ef88e4c470e96082598862bcd55d68d2/layer.tar total=53882368
time="2020-03-15T23:27:26.762269246Z" level=info msg="ImageCreate event &ImageCreate{Name:k8s.gcr.io/kube-proxy:v1.17.5-beta.0.1_106c255ad7ab80,Labels:map[string]string{},XXX_unrecognized:[],}"
time="2020-03-15T23:27:26.862872736Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:886254797c6654ad2178a87f723c11a699124a7049d53fdf6e0d33b770b13b89,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}"
time="2020-03-15T23:27:26.872586347Z" level=info msg="ImageUpdate event &ImageUpdate{Name:k8s.gcr.io/kube-proxy:v1.17.5-beta.0.1_106c255ad7ab80,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}"
time="2020-03-15T23:27:26.937719287Z" level=info msg="ImageCreate event &ImageCreate{Name:k8s.gcr.io/kube-controller-manager:v1.17.5-beta.0.1_106c255ad7ab80,Labels:map[string]string{},XXX_unrecognized:[],}"
time="2020-03-15T23:27:26.966740453Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5e4a08957ce99564cd68d95a5791a3a6dfcbba4bc071e4347d7a79ad7604129d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}"
time="2020-03-15T23:27:26.969638067Z" level=info msg="ImageUpdate event &ImageUpdate{Name:k8s.gcr.io/kube-controller-manager:v1.17.5-beta.0.1_106c255ad7ab80,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}"
... skipping 106 lines ...
time="23:31:05" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-lb]"

kinder-regular-control-plane-1:$ Preparing /kind/kubeadm.conf
time="23:31:05" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-control-plane-1]"
time="23:31:06" level=debug msg="Running: [docker exec kinder-regular-control-plane-1 kubeadm version -o=short]"
time="23:31:09" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.5-beta.0.1+106c255ad7ab80)"
time="23:31:09" level=debug msg="generated config:\napiServer:\n  certSANs:\n  - localhost\n  - 172.17.0.5\napiVersion: kubeadm.k8s.io/v1beta2\nclusterName: kinder-regular\ncontrolPlaneEndpoint: 172.17.0.7:6443\ncontrollerManager:\n  extraArgs:\n    enable-hostpath-provisioner: \"true\"\nkind: ClusterConfiguration\nkubernetesVersion: v1.17.5-beta.0.1+106c255ad7ab80\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\nkind: InitConfiguration\nlocalAPIEndpoint:\n  advertiseAddress: 172.17.0.5\n  bindPort: 6443\nnodeRegistration:\n  criSocket: /run/containerd/containerd.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.5\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="23:31:09" level=debug msg="Running: [docker cp /tmp/kinder-regular-control-plane-1-711993064 kinder-regular-control-plane-1:/kind/kubeadm.conf]"

kinder-regular-lb:$ Updating load balancer configuration with 1 control plane backends
time="23:31:12" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-control-plane-1]"
time="23:31:13" level=debug msg="Writing loadbalancer config on kinder-regular-lb..."
time="23:31:13" level=debug msg="Running: [docker cp /tmp/kinder-regular-lb-302437671 kinder-regular-lb:/usr/local/etc/haproxy/haproxy.cfg]"
... skipping 31 lines ...
I0315 23:31:18.857469     219 checks.go:376] validating the presence of executable ebtables
I0315 23:31:18.857509     219 checks.go:376] validating the presence of executable ethtool
I0315 23:31:18.857590     219 checks.go:376] validating the presence of executable socat
I0315 23:31:18.857665     219 checks.go:376] validating the presence of executable tc
I0315 23:31:18.857694     219 checks.go:376] validating the presence of executable touch
I0315 23:31:18.857738     219 checks.go:520] running all checks
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
CGROUPS_DEVICES: enabled
CGROUPS_FREEZER: enabled
CGROUPS_MEMORY: 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
I0315 23:31:18.908733     219 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0315 23:31:18.927273     219 checks.go:618] validating kubelet version
I0315 23:31:19.575317     219 checks.go:128] validating if the service is enabled and active
[preflight] Pulling images required for setting up a Kubernetes cluster
[preflight] This might take a minute or two, depending on the speed of your internet connection
[preflight] You can also perform this action in beforehand using 'kubeadm config images pull'
... skipping 345 lines ...
time="23:34:18" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-lb]"

kinder-regular-control-plane-2:$ Preparing /kind/kubeadm.conf
time="23:34:19" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-control-plane-2]"
time="23:34:20" level=debug msg="Running: [docker exec kinder-regular-control-plane-2 kubeadm version -o=short]"
time="23:34:23" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.5-beta.0.1+106c255ad7ab80)"
time="23:34:23" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ncontrolPlane:\n  localAPIEndpoint:\n    advertiseAddress: 172.17.0.3\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: /run/containerd/containerd.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.3\n"
time="23:34:23" level=debug msg="Running: [docker cp /tmp/kinder-regular-control-plane-2-325945134 kinder-regular-control-plane-2:/kind/kubeadm.conf]"

kinder-regular-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="23:34:26" level=debug msg="Running: [docker exec kinder-regular-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]"
W0315 23:34:28.278849     399 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
I0315 23:34:28.279249     399 join.go:371] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 16 lines ...
I0315 23:34:28.486616     399 checks.go:376] validating the presence of executable ebtables
I0315 23:34:28.486651     399 checks.go:376] validating the presence of executable ethtool
I0315 23:34:28.486676     399 checks.go:376] validating the presence of executable socat
I0315 23:34:28.486720     399 checks.go:376] validating the presence of executable tc
I0315 23:34:28.486745     399 checks.go:376] validating the presence of executable touch
I0315 23:34:28.486780     399 checks.go:520] running all checks
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
CGROUPS_DEVICES: enabled
CGROUPS_FREEZER: enabled
CGROUPS_MEMORY: 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
I0315 23:34:28.539731     399 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0315 23:34:28.540096     399 checks.go:618] validating kubelet version
I0315 23:34:29.086645     399 checks.go:128] validating if the service is enabled and active
I0315 23:34:29.161347     399 checks.go:201] validating availability of port 10250
I0315 23:34:29.162528     399 checks.go:432] validating if the connectivity type is via proxy or direct
I0315 23:34:29.164088     399 join.go:441] [preflight] Discovering cluster-info
... skipping 140 lines ...
I0315 23:35:13.575734     399 local.go:136] Adding etcd member: https://172.17.0.3:2380
I0315 23:35:13.787592     399 local.go:142] Updated etcd member list: [{kinder-regular-control-plane-1 https://172.17.0.5:2380} {kinder-regular-control-plane-2 https://172.17.0.3:2380}]
I0315 23:35:13.790376     399 etcd.go:408] [etcd] attempting to see if all cluster endpoints ([https://172.17.0.5:2379 https://172.17.0.3:2379]) are available 1/8
[etcd] Announced new etcd member joining to the existing etcd cluster
[etcd] Creating static Pod manifest for "etcd"
[etcd] Waiting for the new etcd member to join the cluster. This can take up to 40s
{"level":"warn","ts":"2020-03-15T23:35:29.640Z","caller":"clientv3/retry_interceptor.go:61","msg":"retrying of unary invoker failed","target":"passthrough:///https://172.17.0.3:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
I0315 23:35:29.640464     399 etcd.go:388] Failed to get etcd status for https://172.17.0.3:2379: context deadline exceeded
[upload-config] Storing the configuration used in ConfigMap "kubeadm-config" in the "kube-system" Namespace
I0315 23:35:33.096153     399 round_trippers.go:443] POST https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps?timeout=10s 409 Conflict in 3132 milliseconds
I0315 23:35:33.186246     399 round_trippers.go:443] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 89 milliseconds
I0315 23:35:33.236195     399 round_trippers.go:443] PUT https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 48 milliseconds
I0315 23:35:33.338052     399 round_trippers.go:443] POST https://172.17.0.7:6443/apis/rbac.authorization.k8s.io/v1/namespaces/kube-system/roles?timeout=10s 409 Conflict in 100 milliseconds
I0315 23:35:33.368527     399 round_trippers.go:443] PUT https://172.17.0.7:6443/apis/rbac.authorization.k8s.io/v1/namespaces/kube-system/roles/kubeadm:nodes-kubeadm-config?timeout=10s 200 OK in 29 milliseconds
... skipping 118 lines ...
time="23:37:51" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-lb]"

kinder-regular-control-plane-3:$ Preparing /kind/kubeadm.conf
time="23:37:52" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-control-plane-3]"
time="23:37:53" level=debug msg="Running: [docker exec kinder-regular-control-plane-3 kubeadm version -o=short]"
time="23:37:56" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.5-beta.0.1+106c255ad7ab80)"
time="23:37:56" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ncontrolPlane:\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: /run/containerd/containerd.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.2\n"
time="23:37:56" level=debug msg="Running: [docker cp /tmp/kinder-regular-control-plane-3-681700920 kinder-regular-control-plane-3:/kind/kubeadm.conf]"

kinder-regular-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="23:37:59" level=debug msg="Running: [docker exec kinder-regular-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]"
[preflight] Running pre-flight checks
W0315 23:38:01.302620     593 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
... skipping 16 lines ...
I0315 23:38:01.466074     593 checks.go:376] validating the presence of executable ebtables
I0315 23:38:01.466177     593 checks.go:376] validating the presence of executable ethtool
I0315 23:38:01.466273     593 checks.go:376] validating the presence of executable socat
I0315 23:38:01.466375     593 checks.go:376] validating the presence of executable tc
I0315 23:38:01.466480     593 checks.go:376] validating the presence of executable touch
I0315 23:38:01.466586     593 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
I0315 23:38:01.506131     593 checks.go:406] checking whether the given node name is reachable using net.LookupHost
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
CGROUPS_DEVICES: enabled
... skipping 146 lines ...
I0315 23:38:46.334438     593 local.go:136] Adding etcd member: https://172.17.0.2:2380
[etcd] Announced new etcd member joining to the existing etcd cluster
[etcd] Creating static Pod manifest for "etcd"
[etcd] Waiting for the new etcd member to join the cluster. This can take up to 40s
I0315 23:38:46.629763     593 local.go:142] Updated etcd member list: [{kinder-regular-control-plane-3 https://172.17.0.2:2380} {kinder-regular-control-plane-1 https://172.17.0.5:2380} {kinder-regular-control-plane-2 https://172.17.0.3:2380}]
I0315 23:38:46.631020     593 etcd.go:408] [etcd] attempting to see if all cluster endpoints ([https://172.17.0.5:2379 https://172.17.0.3:2379 https://172.17.0.2:2379]) are available 1/8
{"level":"warn","ts":"2020-03-15T23:38:52.408Z","caller":"clientv3/retry_interceptor.go:61","msg":"retrying of unary invoker failed","target":"passthrough:///https://172.17.0.2:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
I0315 23:38:52.409937     593 etcd.go:388] Failed to get etcd status for https://172.17.0.2:2379: context deadline exceeded
[upload-config] Storing the configuration used in ConfigMap "kubeadm-config" in the "kube-system" Namespace
I0315 23:38:52.723191     593 round_trippers.go:443] POST https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps?timeout=10s 409 Conflict in 36 milliseconds
I0315 23:38:52.730593     593 round_trippers.go:443] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 6 milliseconds
I0315 23:38:52.890704     593 round_trippers.go:443] PUT https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 157 milliseconds
I0315 23:38:53.012787     593 round_trippers.go:443] POST https://172.17.0.7:6443/apis/rbac.authorization.k8s.io/v1/namespaces/kube-system/roles?timeout=10s 409 Conflict in 121 milliseconds
I0315 23:38:53.079334     593 round_trippers.go:443] PUT https://172.17.0.7:6443/apis/rbac.authorization.k8s.io/v1/namespaces/kube-system/roles/kubeadm:nodes-kubeadm-config?timeout=10s 200 OK in 66 milliseconds
... skipping 90 lines ...
time="23:40:19" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-lb]"

kinder-regular-worker-1:$ Preparing /kind/kubeadm.conf
time="23:40:20" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-worker-1]"
time="23:40:21" level=debug msg="Running: [docker exec kinder-regular-worker-1 kubeadm version -o=short]"
time="23:40:24" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.5-beta.0.1+106c255ad7ab80)"
time="23:40:24" 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: /run/containerd/containerd.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.6\n"
time="23:40:24" level=debug msg="Running: [docker cp /tmp/kinder-regular-worker-1-880263722 kinder-regular-worker-1:/kind/kubeadm.conf]"

kinder-regular-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="23:40:26" level=debug msg="Running: [docker exec kinder-regular-worker-1 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
W0315 23:40:29.045508     723 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
I0315 23:40:29.045614     723 join.go:371] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 16 lines ...
I0315 23:40:29.213161     723 checks.go:376] validating the presence of executable ebtables
I0315 23:40:29.213275     723 checks.go:376] validating the presence of executable ethtool
I0315 23:40:29.213394     723 checks.go:376] validating the presence of executable socat
I0315 23:40:29.213524     723 checks.go:376] validating the presence of executable tc
I0315 23:40:29.213632     723 checks.go:376] validating the presence of executable touch
I0315 23:40:29.213796     723 checks.go:520] running all checks
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
CGROUPS_DEVICES: enabled
CGROUPS_FREEZER: enabled
CGROUPS_MEMORY: 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
I0315 23:40:29.302771     723 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0315 23:40:29.303200     723 checks.go:618] validating kubelet version
I0315 23:40:30.064649     723 checks.go:128] validating if the service is enabled and active
I0315 23:40:30.169469     723 checks.go:201] validating availability of port 10250
I0315 23:40:30.174052     723 checks.go:286] validating the existence of file /etc/kubernetes/pki/ca.crt
I0315 23:40:30.176937     723 checks.go:432] validating if the connectivity type is via proxy or direct
... skipping 109 lines ...
time="23:41:45" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-lb]"

kinder-regular-worker-2:$ Preparing /kind/kubeadm.conf
time="23:41:46" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-regular-worker-2]"
time="23:41:47" level=debug msg="Running: [docker exec kinder-regular-worker-2 kubeadm version -o=short]"
time="23:41:50" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.5-beta.0.1+106c255ad7ab80)"
time="23:41:50" 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: /run/containerd/containerd.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.4\n"
time="23:41:50" level=debug msg="Running: [docker cp /tmp/kinder-regular-worker-2-022203777 kinder-regular-worker-2:/kind/kubeadm.conf]"

kinder-regular-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="23:41:53" level=debug msg="Running: [docker exec kinder-regular-worker-2 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
W0315 23:41:55.340285     800 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
I0315 23:41:55.340536     800 join.go:371] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 16 lines ...
I0315 23:41:55.484434     800 checks.go:376] validating the presence of executable ebtables
I0315 23:41:55.484466     800 checks.go:376] validating the presence of executable ethtool
I0315 23:41:55.484491     800 checks.go:376] validating the presence of executable socat
I0315 23:41:55.484525     800 checks.go:376] validating the presence of executable tc
I0315 23:41:55.484552     800 checks.go:376] validating the presence of executable touch
I0315 23:41:55.484588     800 checks.go:520] running all checks
[preflight] The system verification failed. Printing the output from the verification:
	[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
I0315 23:41:55.545483     800 checks.go:406] checking whether the given node name is reachable using net.LookupHost
KERNEL_VERSION: 4.15.0-1044-gke
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
... skipping 333 lines ...
I0315 23:49:35.349825   14975 local.go:117] [etcd] Updated etcd member list: [{kinder-regular-control-plane-3 https://172.17.0.2:2380} {kinder-regular-control-plane-2 https://172.17.0.3:2380}]
I0315 23:49:35.351955   14975 cleanupnode.go:57] [reset] Getting init system
[reset] Stopping the kubelet service
[reset] Unmounting mounted directories in "/var/lib/kubelet"
I0315 23:49:37.139690   14975 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
make: Leaving directory '/home/prow/go/src/k8s.io/kubernetes'
W0315 23:51:23.841816   14975 cleanupnode.go:81] [reset] Failed to remove containers: failed to stop running pod 6bd90737ac81817792359f8f3e98725b13cab3a0d026365454a3bdcd06bda529: output: time="2020-03-15T23:50:52Z" level=fatal msg="stopping the pod sandbox \"6bd90737ac81817792359f8f3e98725b13cab3a0d026365454a3bdcd06bda529\" failed: rpc error: code = Unknown desc = failed to destroy network for sandbox \"6bd90737ac81817792359f8f3e98725b13cab3a0d026365454a3bdcd06bda529\": error getting ClusterInformation: the server was unable to return a response in the time allotted, but may still be processing the request (get ClusterInformations.crd.projectcalico.org default)"
, error: exit status 1
I0315 23:51:23.843290   14975 cleanupnode.go:87] [reset] Removing contents from the config and pki directories
[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/etcd /var/lib/kubelet /var/lib/dockershim /var/run/kubernetes /var/lib/cni]
I0315 23:51:23.856643   14975 reset.go:218] [reset] Deleting content of /var/lib/etcd
I0315 23:51:23.989905   14975 reset.go:218] [reset] Deleting content of /var/lib/kubelet
... skipping 82 lines ...
I0315 23:51:44.195493    7086 round_trippers.go:443] PUT https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 18 milliseconds
I0315 23:51:44.195938    7086 removeetcdmember.go:54] [reset] Checking for etcd config
I0315 23:51:44.195985    7086 local.go:97] [etcd] creating etcd client that connects to etcd pods
I0315 23:51:44.221708    7086 round_trippers.go:443] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 25 milliseconds
[reset] Stopping the kubelet service
I0315 23:51:44.225105    7086 etcd.go:107] etcd endpoints read from pods: 
W0315 23:51:44.225891    7086 removeetcdmember.go:61] [reset] failed to remove etcd member: error syncing endpoints with etc: etcdclient: no available endpoints
.Please manually remove this etcd member using etcdctl
I0315 23:51:44.225923    7086 cleanupnode.go:57] [reset] Getting init system
[reset] Unmounting mounted directories in "/var/lib/kubelet"
I0315 23:51:44.589204    7086 cleanupnode.go:79] [reset] Removing Kubernetes-managed containers
I0315 23:51:57.558675    7086 cleanupnode.go:87] [reset] Removing contents from the config and pki directories
I0315 23:51:57.562150    7086 reset.go:218] [reset] Deleting content of /var/lib/etcd
... skipping 90 lines ...
Deleting cluster "kinder-regular" ...
time="23:52:14" 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-regular]"
time="23:52:15" level=debug msg="Running: /usr/bin/docker [docker rm -f -v kinder-regular-lb kinder-regular-worker-2 kinder-regular-control-plane-1 kinder-regular-control-plane-3 kinder-regular-worker-1 kinder-regular-control-plane-2]"
 completed!

Ran 10 of 11 tasks in 0.000 seconds
FAIL! -- 9 tasks Passed | 1 Failed | 1 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 binfmt_misc ...
================================================================================
... skipping 2 lines ...