=== RUN TestMountStart/serial/StartWithMountSecond
mount_start_test.go:98: (dbg) Run: out/minikube-darwin-amd64 start -p mount-start-2-576000 --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-576000 --memory=2048 --mount --mount-gid 0 --mount-msize 6543 --mount-port 46465 --mount-uid 0 --no-kubernetes --driver=hyperkit : exit status 90 (1m15.774997783s)
-- stdout --
* [mount-start-2-576000] minikube v1.33.1 on Darwin 14.5
- MINIKUBE_LOCATION=19302
- KUBECONFIG=/Users/jenkins/minikube-integration/19302-1032/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/19302-1032/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting minikube without Kubernetes in cluster mount-start-2-576000
* 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 14:55:33 mount-start-2-576000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 14:55:33 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:33.251587807Z" level=info msg="Starting up"
Jul 19 14:55:33 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:33.252413371Z" level=info msg="containerd not running, starting managed containerd"
Jul 19 14:55:33 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:33.253083635Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=527
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.268224350Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283505435Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283569425Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283632804Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283667833Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283748428Z" 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 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283785182Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283927922Z" 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 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.283968924Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.284002371Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.284030884Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.284112221Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.284319612Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.285895748Z" 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 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.285952395Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.286086834Z" 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 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.286157725Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.286249822Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.286313827Z" level=info msg="metadata content store policy set" policy=shared
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.288964042Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289048235Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289128094Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289173400Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289216934Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289308469Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289517701Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289620205Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289657472Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289690216Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289721707Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289755713Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289785610Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289816695Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289853154Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289886147Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289914495Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289941956Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.289979095Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290010092Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290038978Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290068750Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290100566Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290129096Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290157744Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290223480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290259231Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290290628Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290319053Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290347037Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290374965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290405455Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290440595Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290470513Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290499430Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290569898Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290615370Z" 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 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290645052Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290673396Z" 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 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290700573Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290727791Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290755657Z" level=info msg="NRI interface is disabled by configuration."
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.290925093Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.291034379Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.291119381Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jul 19 14:55:33 mount-start-2-576000 dockerd[527]: time="2024-07-19T14:55:33.291184603Z" level=info msg="containerd successfully booted in 0.023594s"
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.275445599Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.281056164Z" level=info msg="Loading containers: start."
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.382199426Z" 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 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.466594067Z" level=info msg="Loading containers: done."
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.473360638Z" level=info msg="Docker daemon" commit=662f78c containerd-snapshotter=false storage-driver=overlay2 version=27.0.3
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.473444152Z" level=info msg="Daemon has completed initialization"
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.503959516Z" level=info msg="API listen on /var/run/docker.sock"
Jul 19 14:55:34 mount-start-2-576000 systemd[1]: Started Docker Application Container Engine.
Jul 19 14:55:34 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:34.504189827Z" level=info msg="API listen on [::]:2376"
Jul 19 14:55:35 mount-start-2-576000 systemd[1]: Stopping Docker Application Container Engine...
Jul 19 14:55:35 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:35.445404396Z" level=info msg="Processing signal 'terminated'"
Jul 19 14:55:35 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:35.446505085Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jul 19 14:55:35 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:35.446596594Z" level=info msg="Daemon shutdown complete"
Jul 19 14:55:35 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:35.446632912Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jul 19 14:55:35 mount-start-2-576000 dockerd[521]: time="2024-07-19T14:55:35.446666952Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jul 19 14:55:36 mount-start-2-576000 systemd[1]: docker.service: Deactivated successfully.
Jul 19 14:55:36 mount-start-2-576000 systemd[1]: Stopped Docker Application Container Engine.
Jul 19 14:55:36 mount-start-2-576000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 14:55:36 mount-start-2-576000 dockerd[865]: time="2024-07-19T14:55:36.486392644Z" level=info msg="Starting up"
Jul 19 14:56:36 mount-start-2-576000 dockerd[865]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jul 19 14:56:36 mount-start-2-576000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 14:56:36 mount-start-2-576000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jul 19 14:56:36 mount-start-2-576000 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-576000 --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-576000 -n mount-start-2-576000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p mount-start-2-576000 -n mount-start-2-576000: exit status 6 (160.281819ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0719 07:56:36.662831 4132 status.go:417] kubeconfig endpoint: get endpoint: "mount-start-2-576000" does not appear in /Users/jenkins/minikube-integration/19302-1032/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "mount-start-2-576000" 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 (75.94s)