This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 7 succeeded
Started2020-02-05 14:20
Elapsed10m20s
Revisionrelease-1.15
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/57cdb183-52eb-4abc-8e76-cb91bfa56356/targets/test'}}
resultstorehttps://source.cloud.google.com/results/invocations/57cdb183-52eb-4abc-8e76-cb91bfa56356/targets/test

Test Failures


task-04-join 1m43s

exit status 1
				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 7 Passed Tests

Show 5 Skipped Tests

Error lines from build-log.txt

... skipping 336 lines ...
Created symlink /etc/systemd/system/multi-user.target.wants/kubelet.service → /kind/systemd/kubelet.service.
Created symlink /etc/systemd/system/kubelet.service → /kind/systemd/kubelet.service.
time="14:22:48" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed mkdir -p /etc/systemd/system/kubelet.service.d]"
time="14:22:49" level=info msg="Adding /etc/systemd/system/kubelet.service.d/10-kubeadm.conf to the image"
time="14:22:49" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed cp /alter/bits/systemd/10-kubeadm.conf /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="14:22:50" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed chown -R root:root /etc/systemd/system/kubelet.service.d/10-kubeadm.conf]"
time="14:22:51" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed /bin/sh -c echo \"KUBELET_EXTRA_ARGS=--fail-swap-on=false\" >> /etc/default/kubelet]"
time="14:22:53" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed mkdir -p /kinder]"
time="14:22:53" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed rsync -r /alter/bits/upgrade /kinder]"
time="14:23:13" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed chown -R root:root /kinder/upgrade]"
time="14:23:14" level=debug msg="Running: [docker exec kind-build-6df7f5db-f3d9-4eef-aad9-d2c1c2905bed /bin/sh -c which docker || true]"
time="14:23:15" level=info msg="Detected docker as container runtime"
time="14:23:15" level=info msg="Pre loading images ..."
... skipping 173 lines ...
kinder-upgrade-control-plane-1:$ Preparing /kind/kubeadm.conf
time="14:25:40" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-upgrade-control-plane-1]"
time="14:25:40" level=debug msg="Running: [docker exec kinder-upgrade-control-plane-1 kubeadm version -o=short]"
time="14:25:41" level=debug msg="Preparing kubeadm config v1beta1 (kubeadm version 1.14.11-beta.1.2+c8b135d0b49c44)"
time="14:25:41" level=debug msg="Preparing dockerPatch for kubeadm config v1beta1 (kubeadm version 1.14.11-beta.1.2+c8b135d0b49c44)"
time="14:25:41" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta1 (kubeadm version 1.14.11-beta.1.2+c8b135d0b49c44)"
time="14:25:41" level=debug msg="generated config:\napiServer:\n  certSANs:\n  - localhost\n  - 172.17.0.3\napiVersion: kubeadm.k8s.io/v1beta1\nclusterName: kinder-upgrade\ncontrolPlaneEndpoint: 172.17.0.7:6443\ncontrollerManager:\n  extraArgs:\n    enable-hostpath-provisioner: \"true\"\nkind: ClusterConfiguration\nkubernetesVersion: v1.14.11-beta.1.2+c8b135d0b49c44\nnetworking:\n  podSubnet: 192.168.0.0/16\n  serviceSubnet: \"\"\nscheduler:\n  extraArgs: null\n---\napiVersion: kubeadm.k8s.io/v1beta1\nbootstrapTokens:\n- token: abcdef.0123456789abcdef\nkind: InitConfiguration\nlocalAPIEndpoint:\n  advertiseAddress: 172.17.0.3\n  bindPort: 6443\nnodeRegistration:\n  criSocket: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.3\n---\napiVersion: kubelet.config.k8s.io/v1beta1\nevictionHard:\n  imagefs.available: 0%\n  nodefs.available: 0%\n  nodefs.inodesFree: 0%\nimageGCHighThresholdPercent: 100\nkind: KubeletConfiguration\n---\napiVersion: kubeproxy.config.k8s.io/v1alpha1\nkind: KubeProxyConfiguration\n"
time="14:25:41" level=debug msg="Running: [docker cp /tmp/kinder-upgrade-control-plane-1-006244004 kinder-upgrade-control-plane-1:/kind/kubeadm.conf]"

kinder-upgrade-lb:$ Updating load balancer configuration with 1 control plane backends
time="14:25:42" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-upgrade-control-plane-1]"
time="14:25:42" level=debug msg="Writing loadbalancer config on kinder-upgrade-lb..."
time="14:25:42" level=debug msg="Running: [docker cp /tmp/kinder-upgrade-lb-702340531 kinder-upgrade-lb:/usr/local/etc/haproxy/haproxy.cfg]"
... skipping 32 lines ...
I0205 14:25:46.101637     559 checks.go:382] validating the presence of executable ebtables
I0205 14:25:46.101683     559 checks.go:382] validating the presence of executable ethtool
I0205 14:25:46.101738     559 checks.go:382] validating the presence of executable socat
I0205 14:25:46.101786     559 checks.go:382] validating the presence of executable tc
I0205 14:25:46.101838     559 checks.go:382] validating the presence of executable touch
I0205 14:25:46.101893     559 checks.go:524] 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 14:25:46.187661     559 checks.go:412] checking whether the given node name is reachable using net.LookupHost
I0205 14:25:46.187922     559 checks.go:622] 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 399 lines ...
kinder-upgrade-control-plane-2:$ Preparing /kind/kubeadm.conf
time="14:28:04" level=debug msg="Running: /usr/bin/docker [docker inspect -f {{range .NetworkSettings.Networks}}{{.IPAddress}},{{.GlobalIPv6Address}}{{end}} kinder-upgrade-control-plane-2]"
time="14:28:04" level=debug msg="Running: [docker exec kinder-upgrade-control-plane-2 kubeadm version -o=short]"
time="14:28:05" level=debug msg="Preparing kubeadm config v1beta1 (kubeadm version 1.14.11-beta.1.2+c8b135d0b49c44)"
time="14:28:05" level=debug msg="Preparing dockerPatch for kubeadm config v1beta1 (kubeadm version 1.14.11-beta.1.2+c8b135d0b49c44)"
time="14:28:05" level=debug msg="Preparing automaticCopyCertsPatches for kubeadm config v1beta1 (kubeadm version 1.14.11-beta.1.2+c8b135d0b49c44)"
time="14:28:05" level=debug msg="generated config:\napiVersion: kubeadm.k8s.io/v1beta1\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: /var/run/dockershim.sock\n  kubeletExtraArgs:\n    fail-swap-on: \"false\"\n    node-ip: 172.17.0.2\n"
time="14:28:05" level=debug msg="Running: [docker cp /tmp/kinder-upgrade-control-plane-2-467779851 kinder-upgrade-control-plane-2:/kind/kubeadm.conf]"
time="14:28:06" level=debug msg="Running: [docker exec kinder-upgrade-control-plane-2 kubeadm version -o=short]"

kinder-upgrade-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 --certificate-key=0123456789012345678901234567890123456789012345678901234567890123
time="14:28:07" level=debug msg="Running: [docker exec kinder-upgrade-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 --certificate-key=0123456789012345678901234567890123456789012345678901234567890123]"
I0205 14:28:07.928092     704 join.go:367] [preflight] found NodeName empty; using OS hostname as NodeName
... skipping 17 lines ...
I0205 14:28:08.561720     704 checks.go:382] validating the presence of executable ebtables
I0205 14:28:08.561759     704 checks.go:382] validating the presence of executable ethtool
I0205 14:28:08.561794     704 checks.go:382] validating the presence of executable socat
I0205 14:28:08.561829     704 checks.go:382] validating the presence of executable tc
I0205 14:28:08.561865     704 checks.go:382] validating the presence of executable touch
I0205 14:28:08.561911     704 checks.go:524] running all checks
[preflight] The system verification failed. Printing the output from the verification:
KERNEL_VERSION: 4.15.0-1044-gke
DOCKER_VERSION: 18.09.4
DOCKER_GRAPH_DRIVER: overlay2
OS: Linux
CGROUPS_CPU: enabled
CGROUPS_CPUACCT: enabled
CGROUPS_CPUSET: enabled
CGROUPS_DEVICES: enabled
CGROUPS_FREEZER: enabled
CGROUPS_MEMORY: enabled
	[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 14:28:08.643013     704 checks.go:412] checking whether the given node name is reachable using net.LookupHost
I0205 14:28:08.648086     704 checks.go:622] validating kubelet version
I0205 14:28:08.812123     704 checks.go:131] validating if the service is enabled and active
I0205 14:28:08.839370     704 checks.go:209] validating availability of port 10250
I0205 14:28:08.839621     704 checks.go:439] validating if the connectivity type is via proxy or direct
I0205 14:28:08.839671     704 join.go:427] [preflight] Discovering cluster-info
... skipping 134 lines ...
I0205 14:29:30.103702     704 etcd.go:370] [etcd] attempting to see if all cluster endpoints ([https://172.17.0.3:2379 https://172.17.0.2:2379]) are available 7/8
I0205 14:29:35.130601     704 etcd.go:375] [etcd] Attempt timed out
I0205 14:29:35.130636     704 etcd.go:367] [etcd] Waiting 5s until next retry
I0205 14:29:40.130852     704 etcd.go:370] [etcd] attempting to see if all cluster endpoints ([https://172.17.0.3:2379 https://172.17.0.2:2379]) are available 8/8
[upload-config] storing the configuration used in ConfigMap "kubeadm-config" in the "kube-system" Namespace
I0205 14:29:40.173511     704 round_trippers.go:438] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config  in 0 milliseconds
error execution phase control-plane-join/update-status: error uploading configuration: Get https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config: EOF
Error: failed to exec action kubeadm-join: exit status 1
 exit status 1

# task-05-cluster-info-before
kinder do cluster-info --name=kinder-upgrade --loglevel=debug

 skipping because a predecessor task failed

# task-06-upgrade
kinder do kubeadm-upgrade --upgrade-version=v1.15.10-beta.0.17+2e0c2c47211680 --name=kinder-upgrade --loglevel=debug --kubeadm-verbosity=6

 skipping because a predecessor task failed

# task-07-e2e-kubeadm-after
kinder test e2e-kubeadm --test-flags=--report-dir=/logs/artifacts --report-prefix=e2e-kubeadm --name=kinder-upgrade --loglevel=debug

 skipping because a predecessor task failed

# task-08-cluster-info-after
kinder do cluster-info --name=kinder-upgrade --loglevel=debug

 skipping because a predecessor task failed

# task-09-e2e-after
kinder test e2e --test-flags=--report-dir=/logs/artifacts --report-prefix=e2e --parallel --name=kinder-upgrade --loglevel=debug

 skipping because a predecessor task failed

# task-10-get-logs
kinder export logs --loglevel=debug --name=kinder-upgrade /logs/artifacts

time="14:29:40" 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\"}}]"
time="14:29:41" 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-upgrade]"
... skipping 84 lines ...
I0205 14:29:52.556850    8537 reset.go:151] [reset] Checking for etcd config
I0205 14:29:52.556872    8537 local.go:88] [etcd] creating etcd client that connects to etcd pods
I0205 14:29:52.556909    8537 etcd.go:88] checking etcd manifest
I0205 14:29:52.597543    8537 round_trippers.go:438] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config 200 OK in 3 milliseconds
[reset] Stopping the kubelet service
I0205 14:29:52.598916    8537 etcd.go:139] etcd endpoints read from pods: 
W0205 14:29:52.599395    8537 reset.go:158] [reset] failed to remove etcd member: error syncing endpoints with etc: etcdclient: no available endpoints
.Please manually remove this etcd member using etcdctl
I0205 14:29:52.599428    8537 reset.go:167] [reset] Getting init system
I0205 14:29:52.690179    8537 reset.go:184] [reset] Executing command "awk '$2 ~ path {print $2}' path=/var/lib/kubelet/ /proc/mounts | xargs -r umount"
[reset] unmounting mounted directories in "/var/lib/kubelet"
I0205 14:29:52.863408    8537 reset.go:190] [reset] Removing Kubernetes-managed containers
I0205 14:30:05.234679    8537 reset.go:200] [reset] Deleting content of /var/lib/etcd
... skipping 20 lines ...
I0205 14:30:05.861776    3146 loader.go:359] Config loaded from file /etc/kubernetes/admin.conf
[reset] Reading configuration from the cluster...
[reset] FYI: You can look at this config file with 'kubectl -n kube-system get cm kubeadm-config -oyaml'
I0205 14:30:05.862706    3146 reset.go:70] [reset] Loaded client set from kubeconfig file: /etc/kubernetes/admin.conf
[preflight] Running pre-flight checks
I0205 14:30:08.867372    3146 round_trippers.go:438] GET https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config  in 3004 milliseconds
W0205 14:30:08.867501    3146 reset.go:73] [reset] Unable to fetch the kubeadm-config ConfigMap from cluster: failed to get config map: Get https://172.17.0.7:6443/api/v1/namespaces/kube-system/configmaps/kubeadm-config: EOF
I0205 14:30:08.867558    3146 reset.go:82] [reset] Detected and using CRI socket: /var/run/dockershim.sock
I0205 14:30:08.867676    3146 reset.go:151] [reset] Checking for etcd config
W0205 14:30:08.867691    3146 reset.go:234] [reset] No kubeadm config, using etcd pod spec to get data directory
[reset] Stopping the kubelet service
I0205 14:30:08.879590    3146 reset.go:167] [reset] Getting init system
I0205 14:30:08.978100    3146 reset.go:184] [reset] Executing command "awk '$2 ~ path {print $2}' path=/var/lib/kubelet/ /proc/mounts | xargs -r umount"
... skipping 122 lines ...
Deleting cluster "kinder-upgrade" ...
time="14:30:18" 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-upgrade]"
time="14:30:18" level=debug msg="Running: /usr/bin/docker [docker rm -f -v kinder-upgrade-lb kinder-upgrade-worker-1 kinder-upgrade-control-plane-3 kinder-upgrade-worker-2 kinder-upgrade-control-plane-2 kinder-upgrade-control-plane-1]"
 completed!

Ran 8 of 13 tasks in 0.000 seconds
FAIL! -- 7 tasks Passed | 1 Failed | 5 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.
================================================================================
[Barnacle] 2020/02/05 14:30:25 Cleaning up Docker data root...
[Barnacle] 2020/02/05 14:30:25 Removing all containers.
... skipping 15 lines ...