=== RUN TestMountStart/serial/StartWithMountSecond
mount_start_test.go:98: (dbg) Run: out/minikube-darwin-amd64 start -p mount-start-2-110000 --memory=2048 --mount --mount-gid 0 --mount-msize 6543 --mount-port 46465 --mount-uid 0 --no-kubernetes --driver=hyperkit
mount_start_test.go:98: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p mount-start-2-110000 --memory=2048 --mount --mount-gid 0 --mount-msize 6543 --mount-port 46465 --mount-uid 0 --no-kubernetes --driver=hyperkit : exit status 90 (1m15.846610715s)
-- stdout --
* [mount-start-2-110000] minikube v1.33.1 on Darwin 14.5
- MINIKUBE_LOCATION=19307
- KUBECONFIG=/Users/jenkins/minikube-integration/19307-1053/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/19307-1053/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting minikube without Kubernetes in cluster mount-start-2-110000
* Creating hyperkit VM (CPUs=2, Memory=2048MB, Disk=20000MB) ...
-- /stdout --
** stderr **
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
sudo journalctl --no-pager -u docker:
-- stdout --
Jul 19 18:53:22 mount-start-2-110000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 18:53:22 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:22.126713422Z" level=info msg="Starting up"
Jul 19 18:53:22 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:22.127156930Z" level=info msg="containerd not running, starting managed containerd"
Jul 19 18:53:22 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:22.127856258Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=516
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.145571790Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162000493Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162064454Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162125447Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162161273Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162269899Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162311850Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162457901Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162500014Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162533530Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162563638Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162644262Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.162820555Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.164393982Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.164490131Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.164631119Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.164674100Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.164758878Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.164824648Z" level=info msg="metadata content store policy set" policy=shared
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167112424Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167199534Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167285692Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167326442Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167359338Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167453254Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167681431Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167786812Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167826251Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167857177Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167891336Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167926939Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.167956721Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168002520Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168041499Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168075335Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168105520Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168134053Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168168764Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168207345Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168247200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168282582Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168314489Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168344157Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168373653Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168405367Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168435369Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168520029Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168552356Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168581934Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168611484Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168642604Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168677800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168709785Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168739627Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168830159Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168877190Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168907881Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168936501Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168964618Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.168993294Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.169024375Z" level=info msg="NRI interface is disabled by configuration."
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.169198370Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.169320122Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.169382956Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jul 19 18:53:22 mount-start-2-110000 dockerd[516]: time="2024-07-19T18:53:22.169419379Z" level=info msg="containerd successfully booted in 0.024536s"
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.169882380Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.178902480Z" level=info msg="Loading containers: start."
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.263990340Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.343213987Z" level=info msg="Loading containers: done."
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.353344423Z" level=info msg="Docker daemon" commit=662f78c containerd-snapshotter=false storage-driver=overlay2 version=27.0.3
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.353500887Z" level=info msg="Daemon has completed initialization"
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.378621774Z" level=info msg="API listen on /var/run/docker.sock"
Jul 19 18:53:23 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:23.378740276Z" level=info msg="API listen on [::]:2376"
Jul 19 18:53:23 mount-start-2-110000 systemd[1]: Started Docker Application Container Engine.
Jul 19 18:53:24 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:24.328659195Z" level=info msg="Processing signal 'terminated'"
Jul 19 18:53:24 mount-start-2-110000 systemd[1]: Stopping Docker Application Container Engine...
Jul 19 18:53:24 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:24.329917538Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jul 19 18:53:24 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:24.330030298Z" level=info msg="Daemon shutdown complete"
Jul 19 18:53:24 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:24.330093571Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jul 19 18:53:24 mount-start-2-110000 dockerd[509]: time="2024-07-19T18:53:24.330105127Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jul 19 18:53:25 mount-start-2-110000 systemd[1]: docker.service: Deactivated successfully.
Jul 19 18:53:25 mount-start-2-110000 systemd[1]: Stopped Docker Application Container Engine.
Jul 19 18:53:25 mount-start-2-110000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 18:53:25 mount-start-2-110000 dockerd[911]: time="2024-07-19T18:53:25.365677113Z" level=info msg="Starting up"
Jul 19 18:54:25 mount-start-2-110000 dockerd[911]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jul 19 18:54:25 mount-start-2-110000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 18:54:25 mount-start-2-110000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jul 19 18:54:25 mount-start-2-110000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
*
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
** /stderr **
mount_start_test.go:100: failed to start minikube with args: "out/minikube-darwin-amd64 start -p mount-start-2-110000 --memory=2048 --mount --mount-gid 0 --mount-msize 6543 --mount-port 46465 --mount-uid 0 --no-kubernetes --driver=hyperkit " : exit status 90
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p mount-start-2-110000 -n mount-start-2-110000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p mount-start-2-110000 -n mount-start-2-110000: exit status 6 (152.374182ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0719 11:54:25.479702 4175 status.go:417] kubeconfig endpoint: get endpoint: "mount-start-2-110000" does not appear in /Users/jenkins/minikube-integration/19307-1053/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "mount-start-2-110000" host is not running, skipping log retrieval (state="Running\nWARNING: Your kubectl is pointing to stale minikube-vm.\nTo fix the kubectl context, run `minikube update-context`")
--- FAIL: TestMountStart/serial/StartWithMountSecond (76.00s)