This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 269 succeeded
Started2020-02-05 06:12
Elapsed44m51s
Revisionrelease-1.16
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/0d1bab4f-a1dd-4c63-ac1c-c4f89c748aea/targets/test'}}
resultstorehttps://source.cloud.google.com/results/invocations/0d1bab4f-a1dd-4c63-ac1c-c4f89c748aea/targets/test
uploadercrier

No Test Failures!


Show 269 Passed Tests

Show 4499 Skipped Tests

Error lines from build-log.txt

... skipping 286 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="06:19:18" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf mkdir -p /etc/systemd/system/kubelet.service.d]"
time="06:19:19" level=info msg="Adding /etc/systemd/system/kubelet.service.d/10-kubeadm.conf to the image"
time="06:19:19" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf cp /alter/bits/systemd/10-kubeadm.conf /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="06:19:20" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf chown -R root:root /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="06:19:21" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf /bin/sh -c echo \"KUBELET_EXTRA_ARGS=--fail-swap-on=false\" >> /etc/default/kubelet]"
time="06:19:22" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf cp /alter/bits/kubeadm /usr/bin/kubeadm]"
time="06:19:23" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf chown -R root:root /usr/bin/kubeadm]"
time="06:19:24" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf /bin/sh -c which docker || true]"
time="06:19:25" level=info msg="Detected docker as container runtime"
time="06:19:25" level=info msg="Pre loading images ..."
time="06:19:25" level=debug msg="Running: [docker exec kind-build-6c7a2178-c706-4fd6-b423-98fa8f4dbcbf mkdir -p /kind/images]"
... skipping 173 lines ...
kinder-xony-control-plane-1:$ Preparing /kind/kubeadm.conf
time="06:21:25" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-xony-control-plane-1]"
time="06:21:25" level=debug msg="Running: [docker exec kinder-xony-control-plane-1 kubeadm version -o=short]"
time="06:21:27" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:21:27" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:21:27" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:21:27" level=debug msg="generated config:\napiServer:\n  certSANs:\n  - localhost\n  - 172.17.0.6\napiVersion: kubeadm.k8s.io/v1beta2\nclusterName: kinder-xony\ncontrolPlaneEndpoint: 172.17.0.7:6443\ncontrollerManager:\n  extraArgs:\n    enable-hostpath-provisioner: \"true\"\nkind: ClusterConfiguration\nkubernetesVersion: v1.16.7-beta.0.23+0a70c2fa6d4642\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.6\n  bindPort: 6443\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.6\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="06:21:27" level=debug msg="Running: [docker cp /tmp/kinder-xony-control-plane-1-822983804 kinder-xony-control-plane-1:/kind/kubeadm.conf]"

kinder-xony-lb:$ Updating load balancer configuration with 1 control plane backends
time="06:21:28" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-xony-control-plane-1]"
time="06:21:28" level=debug msg="Writing loadbalancer config on kinder-xony-lb..."
time="06:21:28" level=debug msg="Running: [docker cp /tmp/kinder-xony-lb-145698731 kinder-xony-lb:/usr/local/etc/haproxy/haproxy.cfg]"
... skipping 33 lines ...
I0205 06:21:32.573330     569 checks.go:376] validating the presence of executable ebtables
I0205 06:21:32.573415     569 checks.go:376] validating the presence of executable ethtool
I0205 06:21:32.573508     569 checks.go:376] validating the presence of executable socat
I0205 06:21:32.574159     569 checks.go:376] validating the presence of executable tc
I0205 06:21:32.574437     569 checks.go:376] validating the presence of executable touch
I0205 06:21:32.574580     569 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
I0205 06:21:32.934897     569 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0205 06:21:32.935393     569 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 337 lines ...
kinder-xony-control-plane-2:$ Preparing /kind/kubeadm.conf
time="06:23:19" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-xony-control-plane-2]"
time="06:23:19" level=debug msg="Running: [docker exec kinder-xony-control-plane-2 kubeadm version -o=short]"
time="06:23:20" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:23:20" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:23:20" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:23:20" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta2\ncontrolPlane:\n  certificateKey: \"0123456789012345678901234567890123456789012345678901234567890123\"\n  localAPIEndpoint:\n    advertiseAddress: 172.17.0.4\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.4\n"
time="06:23:20" level=debug msg="Running: [docker cp /tmp/kinder-xony-control-plane-2-789937855 kinder-xony-control-plane-2:/kind/kubeadm.conf]"
time="06:23:21" level=debug msg="Running: [docker exec kinder-xony-control-plane-2 kubeadm version -o=short]"

kinder-xony-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="06:23:22" level=debug msg="Running: [docker exec kinder-xony-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]"
W0205 06:23:23.730440     712 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
... skipping 18 lines ...
I0205 06:23:24.617768     712 checks.go:376] validating the presence of executable ebtables
I0205 06:23:24.617829     712 checks.go:376] validating the presence of executable ethtool
I0205 06:23:24.617884     712 checks.go:376] validating the presence of executable socat
I0205 06:23:24.617916     712 checks.go:376] validating the presence of executable tc
I0205 06:23:24.618002     712 checks.go:376] validating the presence of executable touch
I0205 06:23:24.618045     712 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
I0205 06:23:24.999165     712 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
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 120 lines ...
I0205 06:23:51.321842     712 local.go:136] Adding etcd member: https://172.17.0.4:2380
[etcd] Announced new etcd member joining to the existing etcd cluster
[etcd] Creating static Pod manifest for "etcd"
I0205 06:23:51.409897     712 local.go:142] Updated etcd member list: [{kinder-xony-control-plane-2 https://172.17.0.4:2380} {kinder-xony-control-plane-1 https://172.17.0.6:2380}]
[etcd] Waiting for the new etcd member to join the cluster. This can take up to 40s
I0205 06:23:51.412994     712 etcd.go:408] [etcd] attempting to see if all cluster endpoints ([https://172.17.0.6:2379 https://172.17.0.4:2379]) are available 1/8
{"level":"warn","ts":"2020-02-05T06:24:07.149Z","caller":"clientv3/retry_interceptor.go:61","msg":"retrying of unary invoker failed","target":"passthrough:///https://172.17.0.4:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
I0205 06:24:07.149442     712 etcd.go:388] Failed to get etcd status for https://172.17.0.4:2379: context deadline exceeded
[upload-config] Storing the configuration used in ConfigMap "kubeadm-config" in the "kube-system" Namespace
I0205 06:24:08.576095     712 round_trippers.go:443] POST https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps?timeout=10s 409 Conflict in 1355 milliseconds
I0205 06:24:08.620693     712 round_trippers.go:443] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 43 milliseconds
I0205 06:24:08.653347     712 round_trippers.go:443] PUT https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 31 milliseconds
I0205 06:24:08.682044     712 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 27 milliseconds
I0205 06:24:08.713502     712 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 26 milliseconds
... skipping 67 lines ...
kinder-xony-control-plane-3:$ Preparing /kind/kubeadm.conf
time="06:24:30" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-xony-control-plane-3]"
time="06:24:31" level=debug msg="Running: [docker exec kinder-xony-control-plane-3 kubeadm version -o=short]"
time="06:24:32" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:24:32" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:24:32" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:24:32" 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="06:24:32" level=debug msg="Running: [docker cp /tmp/kinder-xony-control-plane-3-281633609 kinder-xony-control-plane-3:/kind/kubeadm.conf]"
time="06:24:33" level=debug msg="Running: [docker exec kinder-xony-control-plane-3 kubeadm version -o=short]"

kinder-xony-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="06:24:35" level=debug msg="Running: [docker exec kinder-xony-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]"
W0205 06:24:35.987799     786 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
... skipping 18 lines ...
I0205 06:24:36.843720     786 checks.go:376] validating the presence of executable ebtables
I0205 06:24:36.843753     786 checks.go:376] validating the presence of executable ethtool
I0205 06:24:36.843797     786 checks.go:376] validating the presence of executable socat
I0205 06:24:36.843835     786 checks.go:376] validating the presence of executable tc
I0205 06:24:36.843870     786 checks.go:376] validating the presence of executable touch
I0205 06:24:36.843910     786 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
I0205 06:24:37.210096     786 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
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 120 lines ...
I0205 06:25:08.237077     786 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"
I0205 06:25:08.317360     786 local.go:142] Updated etcd member list: [{kinder-xony-control-plane-2 https://172.17.0.4:2380} {kinder-xony-control-plane-3 https://172.17.0.2:2380} {kinder-xony-control-plane-1 https://172.17.0.6:2380}]
[etcd] Waiting for the new etcd member to join the cluster. This can take up to 40s
I0205 06:25:08.319820     786 etcd.go:408] [etcd] attempting to see if all cluster endpoints ([https://172.17.0.4:2379 https://172.17.0.6:2379 https://172.17.0.2:2379]) are available 1/8
{"level":"warn","ts":"2020-02-05T06:25:13.550Z","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"}
I0205 06:25:13.550998     786 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
I0205 06:25:13.638890     786 round_trippers.go:443] POST https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps?timeout=10s 409 Conflict in 19 milliseconds
I0205 06:25:13.643539     786 round_trippers.go:443] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 4 milliseconds
I0205 06:25:13.651221     786 round_trippers.go:443] PUT https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config?timeout=10s 200 OK in 6 milliseconds
I0205 06:25:13.659638     786 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 7 milliseconds
I0205 06:25:13.664122     786 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 4 milliseconds
... skipping 71 lines ...

kinder-xony-worker-1:$ Preparing /kind/kubeadm.conf
time="06:25:46" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-xony-worker-1]"
time="06:25:47" level=debug msg="Running: [docker exec kinder-xony-worker-1 kubeadm version -o=short]"
time="06:25:48" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:25:48" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:25:48" 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="06:25:48" level=debug msg="Running: [docker cp /tmp/kinder-xony-worker-1-749750883 kinder-xony-worker-1:/kind/kubeadm.conf]"

kinder-xony-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="06:25:49" level=debug msg="Running: [docker exec kinder-xony-worker-1 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
W0205 06:25:50.703160     833 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
I0205 06:25:50.703246     833 join.go:371] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 17 lines ...
I0205 06:25:51.557971     833 checks.go:376] validating the presence of executable ebtables
I0205 06:25:51.558052     833 checks.go:376] validating the presence of executable ethtool
I0205 06:25:51.558092     833 checks.go:376] validating the presence of executable socat
I0205 06:25:51.558130     833 checks.go:376] validating the presence of executable tc
I0205 06:25:51.558168     833 checks.go:376] validating the presence of executable touch
I0205 06:25:51.558222     833 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
I0205 06:25:51.835785     833 checks.go:406] checking whether the given node name is reachable using net.LookupHost
I0205 06:25:51.836037     833 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 93 lines ...

kinder-xony-worker-2:$ Preparing /kind/kubeadm.conf
time="06:26:33" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-xony-worker-2]"
time="06:26:33" level=debug msg="Running: [docker exec kinder-xony-worker-2 kubeadm version -o=short]"
time="06:26:34" level=debug msg="Preparing kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:26:34" level=debug msg="Preparing dockerPatch for kubeadm config v1beta2 (kubeadm version 1.17.3-beta.0.30+a4d80456373f8e)"
time="06:26:34" 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.3\n"
time="06:26:34" level=debug msg="Running: [docker cp /tmp/kinder-xony-worker-2-045165670 kinder-xony-worker-2:/kind/kubeadm.conf]"

kinder-xony-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="06:26:35" level=debug msg="Running: [docker exec kinder-xony-worker-2 kubeadm join --config=/kind/kubeadm.conf --v=6 --ignore-preflight-errors=Swap,SystemVerification,FileContent--proc-sys-net-bridge-bridge-nf-call-iptables]"
W0205 06:26:36.878724     881 join.go:346] [preflight] WARNING: JoinControlPane.controlPlane settings will be ignored when control-plane flag is not set.
I0205 06:26:36.878796     881 join.go:371] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 17 lines ...
I0205 06:26:37.680139     881 checks.go:376] validating the presence of executable ebtables
I0205 06:26:37.680682     881 checks.go:376] validating the presence of executable ethtool
I0205 06:26:37.680726     881 checks.go:376] validating the presence of executable socat
I0205 06:26:37.680759     881 checks.go:376] validating the presence of executable tc
I0205 06:26:37.680800     881 checks.go:376] validating the presence of executable touch
I0205 06:26:37.680847     881 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
I0205 06:26:38.157528     881 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
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
... skipping 763 lines ...
  _output/local/go/src/k8s.io/kubernetes/test/e2e_kubeadm/cluster_info_test.go:66
[AfterEach] [k8s.io] [sig-cluster-lifecycle] [area-kubeadm] cluster-info ConfigMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Feb  5 06:31:57.952: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
•
Ran 31 of 37 Specs in 0.574 seconds
SUCCESS! -- 31 Passed | 0 Failed | 0 Pending | 6 Skipped
PASS

Ginkgo ran 1 suite in 697.769775ms
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-xony]
 completed!
... skipping 191 lines ...
Feb  5 06:35:55.787: INFO: Running '/usr/local/bin/kubectl --kubeconfig=/root/.kube/kind-config-kinder-xony explain e2e-test-crd-publish-openapi-9766-crds.spec'
Feb  5 06:35:56.473: INFO: stderr: ""
Feb  5 06:35:56.473: INFO: stdout: "KIND:     E2e-test-crd-publish-openapi-9766-crd\nVERSION:  crd-publish-openapi-test-foo.example.com/v1\n\nRESOURCE: spec <Object>\n\nDESCRIPTION:\n     Specification of Foo\n\nFIELDS:\n   bars\t<[]Object>\n     List of Bars and their specs.\n\n"
Feb  5 06:35:56.474: INFO: Running '/usr/local/bin/kubectl --kubeconfig=/root/.kube/kind-config-kinder-xony explain e2e-test-crd-publish-openapi-9766-crds.spec.bars'
Feb  5 06:35:57.093: INFO: stderr: ""
Feb  5 06:35:57.093: INFO: stdout: "KIND:     E2e-test-crd-publish-openapi-9766-crd\nVERSION:  crd-publish-openapi-test-foo.example.com/v1\n\nRESOURCE: bars <[]Object>\n\nDESCRIPTION:\n     List of Bars and their specs.\n\nFIELDS:\n   age\t<string>\n     Age of Bar.\n\n   bazs\t<[]string>\n     List of Bazs.\n\n   name\t<string> -required-\n     Name of Bar.\n\n"
STEP: kubectl explain works to return error when explain is called on property that doesn't exist
Feb  5 06:35:57.094: INFO: Running '/usr/local/bin/kubectl --kubeconfig=/root/.kube/kind-config-kinder-xony explain e2e-test-crd-publish-openapi-9766-crds.spec.bars2'
Feb  5 06:35:57.869: INFO: rc: 1
[AfterEach] [sig-api-machinery] CustomResourcePublishOpenAPI [Privileged:ClusterAdmin]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Feb  5 06:36:02.366: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "crd-publish-openapi-5226" for this suite.
... skipping 279 lines ...
[BeforeEach] [sig-api-machinery] Secrets
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Feb  5 06:36:56.313: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-xony
STEP: Building a namespace api object, basename secrets
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to create secret due to empty secret key [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
STEP: Creating projection with secret that has name secret-emptykey-test-16ce07a8-cfd3-48d7-b855-efe9fc2bc84d
[AfterEach] [sig-api-machinery] Secrets
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Feb  5 06:36:56.475: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "secrets-4629" for this suite.
Feb  5 06:37:02.569: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb  5 06:37:02.969: INFO: namespace secrets-4629 deletion completed in 6.4839078s


• [SLOW TEST:6.657 seconds]
[sig-api-machinery] Secrets
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/secrets.go:32
  should fail to create secret due to empty secret key [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
------------------------------
SSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-storage] ConfigMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 264 lines ...
Feb  5 06:37:18.029: INFO: deployment "sample-webhook-deployment" doesn't have the required revision set
Feb  5 06:37:20.054: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"sample-webhook-deployment-86d95b659d\" is progressing."}}, CollisionCount:(*int32)(nil)}
Feb  5 06:37:22.059: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63716481438, loc:(*time.Location)(0x78686e0)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"sample-webhook-deployment-86d95b659d\" is progressing."}}, CollisionCount:(*int32)(nil)}
STEP: Deploying the webhook service
STEP: Verifying the service has paired with the endpoint
Feb  5 06:37:25.118: INFO: Waiting for amount of service:e2e-test-webhook endpoints to be 1
[It] should unconditionally reject operations on fail closed webhook [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
STEP: Registering a webhook that server cannot talk to, with fail closed policy, via the AdmissionRegistration API
STEP: create a namespace for the webhook
STEP: create a configmap should be unconditionally rejected by the webhook
[AfterEach] [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:152
Feb  5 06:37:25.297: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "webhook-643" for this suite.
... skipping 6 lines ...
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/apimachinery/webhook.go:103


• [SLOW TEST:25.266 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
  should unconditionally reject operations on fail closed webhook [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [sig-api-machinery] CustomResourceConversionWebhook [Privileged:ClusterAdmin]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 1173 lines ...
[BeforeEach] [sig-node] ConfigMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Feb  5 06:39:45.394: INFO: >>> kubeConfig: /root/.kube/kind-config-kinder-xony
STEP: Building a namespace api object, basename configmap
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to create ConfigMap with empty key [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
STEP: Creating configMap that has name configmap-test-emptyKey-1d940687-36a3-4002-94f1-a73036dcd63a
[AfterEach] [sig-node] ConfigMap
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
Feb  5 06:39:45.487: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "configmap-7731" for this suite.
Feb  5 06:39:51.553: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb  5 06:39:52.006: INFO: namespace configmap-7731 deletion completed in 6.511334959s


• [SLOW TEST:6.612 seconds]
[sig-node] ConfigMap
/home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/common/configmap.go:32
  should fail to create ConfigMap with empty key [Conformance]
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
------------------------------
SSSSSSSSSSSSSSSSSSSS
------------------------------
[BeforeEach] [k8s.io] Pods
  /home/prow/go/src/k8s.io/kubernetes/_output/local/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 1081 lines ...
Feb  5 06:41:00.079: INFO: Unable to read wheezy_udp@dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:00.098: INFO: Unable to read wheezy_tcp@dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:00.151: INFO: Unable to read jessie_udp@dns-querier-2.dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:00.173: INFO: Unable to read jessie_tcp@dns-querier-2.dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:00.221: INFO: Unable to read jessie_udp@dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:00.239: INFO: Unable to read jessie_tcp@dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:00.317: INFO: Lookups using dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49 failed for: [wheezy_udp@dns-querier-2.dns-test-service-2.dns-5862.svc.cluster.local wheezy_tcp@dns-querier-2.dns-test-service-2.dns-5862.svc.cluster.local wheezy_udp@dns-test-service-2.dns-5862.svc.cluster.local wheezy_tcp@dns-test-service-2.dns-5862.svc.cluster.local jessie_udp@dns-querier-2.dns-test-service-2.dns-5862.svc.cluster.local jessie_tcp@dns-querier-2.dns-test-service-2.dns-5862.svc.cluster.local jessie_udp@dns-test-service-2.dns-5862.svc.cluster.local jessie_tcp@dns-test-service-2.dns-5862.svc.cluster.local]

Feb  5 06:41:05.713: INFO: Unable to read jessie_udp@dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:05.748: INFO: Unable to read jessie_tcp@dns-test-service-2.dns-5862.svc.cluster.local from pod dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49: the server could not find the requested resource (get pods dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49)
Feb  5 06:41:05.839: INFO: Lookups using dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49 failed for: [jessie_udp@dns-test-service-2.dns-5862.svc.cluster.local jessie_tcp@dns-test-service-2.dns-5862.svc.cluster.local]

Feb  5 06:41:10.554: INFO: DNS probes using dns-5862/dns-test-e70e107d-becd-40ee-b80e-538f5b66ba49 succeeded

STEP: deleting the pod
STEP: deleting the test headless service
[AfterEach] [sig-network] DNS
... skipping 511 lines ...
Feb  5 06:41:27.094: INFO: Unable to read wheezy_udp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:27.101: INFO: Unable to read wheezy_tcp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:27.152: INFO: Unable to read jessie_udp@dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:27.163: INFO: Unable to read jessie_tcp@dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:27.169: INFO: Unable to read jessie_udp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:27.183: INFO: Unable to read jessie_tcp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:27.234: INFO: Lookups using dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07 failed for: [wheezy_udp@dns-test-service.dns-4174.svc.cluster.local wheezy_tcp@dns-test-service.dns-4174.svc.cluster.local wheezy_udp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local wheezy_tcp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local jessie_udp@dns-test-service.dns-4174.svc.cluster.local jessie_tcp@dns-test-service.dns-4174.svc.cluster.local jessie_udp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local jessie_tcp@_http._tcp.dns-test-service.dns-4174.svc.cluster.local]

Feb  5 06:41:32.241: INFO: Unable to read wheezy_udp@dns-test-service.dns-4174.svc.cluster.local from pod dns-4174/dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07: the server could not find the requested resource (get pods dns-test-3c410bad-f8f9-4825-bd28-f0af1669ce07)
Feb  5 06:41:32.251: INFO: Unable to read wheezy_tcp@dns-test-service.dns-4174.svc.cluster.loca