=== RUN TestAddons/Setup
addons_test.go:110: (dbg) Run: out/minikube-darwin-amd64 start -p addons-992000 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --addons=nvidia-device-plugin --addons=yakd --addons=volcano --driver=hyperkit --addons=ingress --addons=ingress-dns --addons=helm-tiller
addons_test.go:110: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p addons-992000 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --addons=nvidia-device-plugin --addons=yakd --addons=volcano --driver=hyperkit --addons=ingress --addons=ingress-dns --addons=helm-tiller: exit status 90 (1m16.468617101s)
-- stdout --
* [addons-992000] minikube v1.33.1 on Darwin 14.4.1
- MINIKUBE_LOCATION=19046
- KUBECONFIG=/Users/jenkins/minikube-integration/19046-5942/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/19046-5942/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting "addons-992000" primary control-plane node in "addons-992000" cluster
* Creating hyperkit VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I0610 18:52:40.201541 6599 out.go:291] Setting OutFile to fd 1 ...
I0610 18:52:40.202253 6599 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0610 18:52:40.202260 6599 out.go:304] Setting ErrFile to fd 2...
I0610 18:52:40.202264 6599 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0610 18:52:40.202809 6599 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/19046-5942/.minikube/bin
I0610 18:52:40.204355 6599 out.go:298] Setting JSON to false
I0610 18:52:40.226543 6599 start.go:129] hostinfo: {"hostname":"MacOS-Agent-1.local","uptime":22916,"bootTime":1718047844,"procs":455,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.4.1","kernelVersion":"23.4.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"b7610dcb-1435-5842-8d5a-b2388403fea3"}
W0610 18:52:40.226643 6599 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0610 18:52:40.248627 6599 out.go:177] * [addons-992000] minikube v1.33.1 on Darwin 14.4.1
I0610 18:52:40.290734 6599 out.go:177] - MINIKUBE_LOCATION=19046
I0610 18:52:40.290794 6599 notify.go:220] Checking for updates...
I0610 18:52:40.333477 6599 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/19046-5942/kubeconfig
I0610 18:52:40.354657 6599 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0610 18:52:40.375577 6599 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0610 18:52:40.396502 6599 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/19046-5942/.minikube
I0610 18:52:40.417705 6599 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0610 18:52:40.438474 6599 driver.go:392] Setting default libvirt URI to qemu:///system
I0610 18:52:40.467497 6599 out.go:177] * Using the hyperkit driver based on user configuration
I0610 18:52:40.509236 6599 start.go:297] selected driver: hyperkit
I0610 18:52:40.509263 6599 start.go:901] validating driver "hyperkit" against <nil>
I0610 18:52:40.509284 6599 start.go:912] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0610 18:52:40.513639 6599 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0610 18:52:40.513784 6599 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/19046-5942/.minikube/bin:/Users/jenkins/workspace/out/:/usr/bin:/bin:/usr/sbin:/sbin:/Users/jenkins/google-cloud-sdk/bin:/usr/local/bin/:/usr/local/go/bin/:/Users/jenkins/go/bin
I0610 18:52:40.522605 6599 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.33.1
I0610 18:52:40.526677 6599 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0610 18:52:40.526713 6599 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0610 18:52:40.526764 6599 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0610 18:52:40.526979 6599 start_flags.go:947] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0610 18:52:40.527033 6599 cni.go:84] Creating CNI manager for ""
I0610 18:52:40.527068 6599 cni.go:158] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0610 18:52:40.527076 6599 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0610 18:52:40.527192 6599 start.go:340] cluster config:
{Name:addons-992000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1717668449-19038@sha256:30d191eb345232f513c52f7ac036e7a34a8cc441d88353f92985384bcddf00d6 Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperkit HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:8443 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.30.1 ClusterName:addons-992000 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRunti
me:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.30.1 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHA
gentPID:0 GPUs: AutoPauseInterval:1m0s}
I0610 18:52:40.527280 6599 iso.go:125] acquiring lock: {Name:mk09656d383f321c39be8062546440df099fe7ff Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0610 18:52:40.548639 6599 out.go:177] * Starting "addons-992000" primary control-plane node in "addons-992000" cluster
I0610 18:52:40.569655 6599 preload.go:132] Checking if preload exists for k8s version v1.30.1 and runtime docker
I0610 18:52:40.569724 6599 preload.go:147] Found local preload: /Users/jenkins/minikube-integration/19046-5942/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.1-docker-overlay2-amd64.tar.lz4
I0610 18:52:40.569765 6599 cache.go:56] Caching tarball of preloaded images
I0610 18:52:40.570012 6599 preload.go:173] Found /Users/jenkins/minikube-integration/19046-5942/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.1-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0610 18:52:40.570034 6599 cache.go:59] Finished verifying existence of preloaded tar for v1.30.1 on docker
I0610 18:52:40.570535 6599 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19046-5942/.minikube/profiles/addons-992000/config.json ...
I0610 18:52:40.570582 6599 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/19046-5942/.minikube/profiles/addons-992000/config.json: {Name:mk5bfb42a6bae624e6af132a397072fa37536eb9 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0610 18:52:40.571912 6599 start.go:360] acquireMachinesLock for addons-992000: {Name:mkb49c28b47b51a1f649f8a2347c58a1e3abb012 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0610 18:52:40.572153 6599 start.go:364] duration metric: took 210.585µs to acquireMachinesLock for "addons-992000"
I0610 18:52:40.572226 6599 start.go:93] Provisioning new machine with config: &{Name:addons-992000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19038/minikube-v1.33.1-1717668912-19038-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1717668449-19038@sha256:30d191eb345232f513c52f7ac036e7a34a8cc441d88353f92985384bcddf00d6 Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperkit HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:8443 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{K
ubernetesVersion:v1.30.1 ClusterName:addons-992000 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.30.1 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0
MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.30.1 ContainerRuntime:docker ControlPlane:true Worker:true}
I0610 18:52:40.572297 6599 start.go:125] createHost starting for "" (driver="hyperkit")
I0610 18:52:40.593462 6599 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
I0610 18:52:40.593805 6599 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0610 18:52:40.593861 6599 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0610 18:52:40.603836 6599 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:50370
I0610 18:52:40.604186 6599 main.go:141] libmachine: () Calling .GetVersion
I0610 18:52:40.604591 6599 main.go:141] libmachine: Using API Version 1
I0610 18:52:40.604600 6599 main.go:141] libmachine: () Calling .SetConfigRaw
I0610 18:52:40.604819 6599 main.go:141] libmachine: () Calling .GetMachineName
I0610 18:52:40.604937 6599 main.go:141] libmachine: (addons-992000) Calling .GetMachineName
I0610 18:52:40.605039 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:40.605167 6599 start.go:159] libmachine.API.Create for "addons-992000" (driver="hyperkit")
I0610 18:52:40.605202 6599 client.go:168] LocalClient.Create starting
I0610 18:52:40.605262 6599 main.go:141] libmachine: Creating CA: /Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca.pem
I0610 18:52:40.665197 6599 main.go:141] libmachine: Creating client certificate: /Users/jenkins/minikube-integration/19046-5942/.minikube/certs/cert.pem
I0610 18:52:40.765492 6599 main.go:141] libmachine: Running pre-create checks...
I0610 18:52:40.765501 6599 main.go:141] libmachine: (addons-992000) Calling .PreCreateCheck
I0610 18:52:40.765681 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:40.765844 6599 main.go:141] libmachine: (addons-992000) Calling .GetConfigRaw
I0610 18:52:40.766358 6599 main.go:141] libmachine: Creating machine...
I0610 18:52:40.766373 6599 main.go:141] libmachine: (addons-992000) Calling .Create
I0610 18:52:40.766530 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:40.766696 6599 main.go:141] libmachine: (addons-992000) DBG | I0610 18:52:40.766494 6607 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/19046-5942/.minikube
I0610 18:52:40.766763 6599 main.go:141] libmachine: (addons-992000) Downloading /Users/jenkins/minikube-integration/19046-5942/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/19046-5942/.minikube/cache/iso/amd64/minikube-v1.33.1-1717668912-19038-amd64.iso...
I0610 18:52:40.957305 6599 main.go:141] libmachine: (addons-992000) DBG | I0610 18:52:40.957155 6607 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/id_rsa...
I0610 18:52:40.991481 6599 main.go:141] libmachine: (addons-992000) DBG | I0610 18:52:40.991382 6607 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/addons-992000.rawdisk...
I0610 18:52:40.991492 6599 main.go:141] libmachine: (addons-992000) DBG | Writing magic tar header
I0610 18:52:40.991500 6599 main.go:141] libmachine: (addons-992000) DBG | Writing SSH key tar header
I0610 18:52:40.991972 6599 main.go:141] libmachine: (addons-992000) DBG | I0610 18:52:40.991934 6607 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000 ...
I0610 18:52:41.363715 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:41.363732 6599 main.go:141] libmachine: (addons-992000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/hyperkit.pid
I0610 18:52:41.363760 6599 main.go:141] libmachine: (addons-992000) DBG | Using UUID 2a1ff4bd-47e8-42ab-9656-3e3baac47914
I0610 18:52:41.610642 6599 main.go:141] libmachine: (addons-992000) DBG | Generated MAC 9a:f8:ad:2:8c:c7
I0610 18:52:41.610677 6599 main.go:141] libmachine: (addons-992000) DBG | Starting with cmdline: loglevel=3 console=ttyS0 console=tty0 noembed nomodeset norestore waitusb=10 systemd.legacy_systemd_cgroup_controller=yes random.trust_cpu=on hw_rng_model=virtio base host=addons-992000
I0610 18:52:41.610730 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"2a1ff4bd-47e8-42ab-9656-3e3baac47914", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0001e2240)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/bzimage", Initrd:"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/initrd", Bootrom:"", CPUs:2, Memory:4000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0610 18:52:41.610765 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"2a1ff4bd-47e8-42ab-9656-3e3baac47914", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0001e2240)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/bzimage", Initrd:"/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/initrd", Bootrom:"", CPUs:2, Memory:4000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0610 18:52:41.610851 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/hyperkit.pid", "-c", "2", "-m", "4000M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "2a1ff4bd-47e8-42ab-9656-3e3baac47914", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/addons-992000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/tty,log=/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/bzimage,/Users/jenkins/minikube-integration/19046-5942/.minikube/machine
s/addons-992000/initrd,earlyprintk=serial loglevel=3 console=ttyS0 console=tty0 noembed nomodeset norestore waitusb=10 systemd.legacy_systemd_cgroup_controller=yes random.trust_cpu=on hw_rng_model=virtio base host=addons-992000"}
I0610 18:52:41.610891 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/hyperkit.pid -c 2 -m 4000M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 2a1ff4bd-47e8-42ab-9656-3e3baac47914 -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/addons-992000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/tty,log=/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/console-ring -f kexec,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/bzimage,/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/initrd,earlyprintk=serial loglevel=3 console=t
tyS0 console=tty0 noembed nomodeset norestore waitusb=10 systemd.legacy_systemd_cgroup_controller=yes random.trust_cpu=on hw_rng_model=virtio base host=addons-992000"
I0610 18:52:41.610908 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0610 18:52:41.613924 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 DEBUG: hyperkit: Pid is 6612
I0610 18:52:41.614371 6599 main.go:141] libmachine: (addons-992000) DBG | Attempt 0
I0610 18:52:41.614391 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:41.614442 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:41.615332 6599 main.go:141] libmachine: (addons-992000) DBG | Searching for 9a:f8:ad:2:8c:c7 in /var/db/dhcpd_leases ...
I0610 18:52:41.615381 6599 main.go:141] libmachine: (addons-992000) DBG | Found 4 entries in /var/db/dhcpd_leases!
I0610 18:52:41.615412 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x6668e8ae}
I0610 18:52:41.615427 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:26:f1:d1:5f:34:ec ID:1,26:f1:d1:5f:34:ec Lease:0x6668e6ac}
I0610 18:52:41.615435 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:de:e6:30:86:70:77 ID:1,de:e6:30:86:70:77 Lease:0x6668d03a}
I0610 18:52:41.615443 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:2e:1f:35:5e:30:7f ID:1,2e:1f:35:5e:30:7f Lease:0x6668b84e}
I0610 18:52:41.621393 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0610 18:52:41.674957 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0610 18:52:41.675600 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0610 18:52:41.675617 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0610 18:52:41.675625 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0610 18:52:41.675631 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:41 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0610 18:52:42.205326 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0610 18:52:42.205341 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0610 18:52:42.321771 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0610 18:52:42.321803 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0610 18:52:42.321838 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0610 18:52:42.321855 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0610 18:52:42.322713 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0610 18:52:42.322727 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:42 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0610 18:52:43.616595 6599 main.go:141] libmachine: (addons-992000) DBG | Attempt 1
I0610 18:52:43.616630 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:43.616788 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:43.617702 6599 main.go:141] libmachine: (addons-992000) DBG | Searching for 9a:f8:ad:2:8c:c7 in /var/db/dhcpd_leases ...
I0610 18:52:43.617776 6599 main.go:141] libmachine: (addons-992000) DBG | Found 4 entries in /var/db/dhcpd_leases!
I0610 18:52:43.617800 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x6668e8ae}
I0610 18:52:43.617810 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:26:f1:d1:5f:34:ec ID:1,26:f1:d1:5f:34:ec Lease:0x6668e6ac}
I0610 18:52:43.617848 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:de:e6:30:86:70:77 ID:1,de:e6:30:86:70:77 Lease:0x6668d03a}
I0610 18:52:43.617860 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:2e:1f:35:5e:30:7f ID:1,2e:1f:35:5e:30:7f Lease:0x6668b84e}
I0610 18:52:45.618595 6599 main.go:141] libmachine: (addons-992000) DBG | Attempt 2
I0610 18:52:45.618621 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:45.618794 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:45.619889 6599 main.go:141] libmachine: (addons-992000) DBG | Searching for 9a:f8:ad:2:8c:c7 in /var/db/dhcpd_leases ...
I0610 18:52:45.619958 6599 main.go:141] libmachine: (addons-992000) DBG | Found 4 entries in /var/db/dhcpd_leases!
I0610 18:52:45.619987 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x6668e8ae}
I0610 18:52:45.620031 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:26:f1:d1:5f:34:ec ID:1,26:f1:d1:5f:34:ec Lease:0x6668e6ac}
I0610 18:52:45.620053 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:de:e6:30:86:70:77 ID:1,de:e6:30:86:70:77 Lease:0x6668d03a}
I0610 18:52:45.620088 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:2e:1f:35:5e:30:7f ID:1,2e:1f:35:5e:30:7f Lease:0x6668b84e}
I0610 18:52:47.621581 6599 main.go:141] libmachine: (addons-992000) DBG | Attempt 3
I0610 18:52:47.621599 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:47.621725 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:47.622578 6599 main.go:141] libmachine: (addons-992000) DBG | Searching for 9a:f8:ad:2:8c:c7 in /var/db/dhcpd_leases ...
I0610 18:52:47.622591 6599 main.go:141] libmachine: (addons-992000) DBG | Found 4 entries in /var/db/dhcpd_leases!
I0610 18:52:47.622597 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x6668e8ae}
I0610 18:52:47.622603 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:26:f1:d1:5f:34:ec ID:1,26:f1:d1:5f:34:ec Lease:0x6668e6ac}
I0610 18:52:47.622609 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:de:e6:30:86:70:77 ID:1,de:e6:30:86:70:77 Lease:0x6668d03a}
I0610 18:52:47.622616 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:2e:1f:35:5e:30:7f ID:1,2e:1f:35:5e:30:7f Lease:0x6668b84e}
I0610 18:52:47.649060 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:47 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 0
I0610 18:52:47.649106 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:47 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 0
I0610 18:52:47.649118 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:47 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 0
I0610 18:52:47.671649 6599 main.go:141] libmachine: (addons-992000) DBG | 2024/06/10 18:52:47 INFO : hyperkit: stderr: rdmsr to register 0xc0011029 on vcpu 0
I0610 18:52:49.623243 6599 main.go:141] libmachine: (addons-992000) DBG | Attempt 4
I0610 18:52:49.623260 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:49.623347 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:49.624171 6599 main.go:141] libmachine: (addons-992000) DBG | Searching for 9a:f8:ad:2:8c:c7 in /var/db/dhcpd_leases ...
I0610 18:52:49.624236 6599 main.go:141] libmachine: (addons-992000) DBG | Found 4 entries in /var/db/dhcpd_leases!
I0610 18:52:49.624249 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x6668e8ae}
I0610 18:52:49.624258 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:26:f1:d1:5f:34:ec ID:1,26:f1:d1:5f:34:ec Lease:0x6668e6ac}
I0610 18:52:49.624277 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:de:e6:30:86:70:77 ID:1,de:e6:30:86:70:77 Lease:0x6668d03a}
I0610 18:52:49.624294 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:2e:1f:35:5e:30:7f ID:1,2e:1f:35:5e:30:7f Lease:0x6668b84e}
I0610 18:52:51.625049 6599 main.go:141] libmachine: (addons-992000) DBG | Attempt 5
I0610 18:52:51.625121 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:51.625244 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:51.626153 6599 main.go:141] libmachine: (addons-992000) DBG | Searching for 9a:f8:ad:2:8c:c7 in /var/db/dhcpd_leases ...
I0610 18:52:51.626241 6599 main.go:141] libmachine: (addons-992000) DBG | Found 5 entries in /var/db/dhcpd_leases!
I0610 18:52:51.626264 6599 main.go:141] libmachine: (addons-992000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:9a:f8:ad:2:8c:c7 ID:1,9a:f8:ad:2:8c:c7 Lease:0x6668ff72}
I0610 18:52:51.626282 6599 main.go:141] libmachine: (addons-992000) DBG | Found match: 9a:f8:ad:2:8c:c7
I0610 18:52:51.626294 6599 main.go:141] libmachine: (addons-992000) DBG | IP: 192.169.0.6
I0610 18:52:51.626395 6599 main.go:141] libmachine: (addons-992000) Calling .GetConfigRaw
I0610 18:52:51.627021 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:51.627170 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:51.627328 6599 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0610 18:52:51.627357 6599 main.go:141] libmachine: (addons-992000) Calling .GetState
I0610 18:52:51.627511 6599 main.go:141] libmachine: (addons-992000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 18:52:51.627585 6599 main.go:141] libmachine: (addons-992000) DBG | hyperkit pid from json: 6612
I0610 18:52:51.628559 6599 main.go:141] libmachine: Detecting operating system of created instance...
I0610 18:52:51.628592 6599 main.go:141] libmachine: Waiting for SSH to be available...
I0610 18:52:51.628613 6599 main.go:141] libmachine: Getting to WaitForSSH function...
I0610 18:52:51.628619 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:51.628807 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:51.628943 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:51.629115 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:51.629293 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:51.629957 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:51.630293 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:51.630316 6599 main.go:141] libmachine: About to run SSH command:
exit 0
I0610 18:52:52.689382 6599 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0610 18:52:52.689395 6599 main.go:141] libmachine: Detecting the provisioner...
I0610 18:52:52.689400 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:52.689551 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:52.689657 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.689761 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.689845 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:52.689973 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:52.690126 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:52.690134 6599 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0610 18:52:52.749026 6599 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2023.02.9-dirty
ID=buildroot
VERSION_ID=2023.02.9
PRETTY_NAME="Buildroot 2023.02.9"
I0610 18:52:52.749095 6599 main.go:141] libmachine: found compatible host: buildroot
I0610 18:52:52.749101 6599 main.go:141] libmachine: Provisioning with buildroot...
I0610 18:52:52.749106 6599 main.go:141] libmachine: (addons-992000) Calling .GetMachineName
I0610 18:52:52.749232 6599 buildroot.go:166] provisioning hostname "addons-992000"
I0610 18:52:52.749243 6599 main.go:141] libmachine: (addons-992000) Calling .GetMachineName
I0610 18:52:52.749340 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:52.749445 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:52.749541 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.749651 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.749748 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:52.749873 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:52.750008 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:52.750016 6599 main.go:141] libmachine: About to run SSH command:
sudo hostname addons-992000 && echo "addons-992000" | sudo tee /etc/hostname
I0610 18:52:52.818209 6599 main.go:141] libmachine: SSH cmd err, output: <nil>: addons-992000
I0610 18:52:52.818228 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:52.818352 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:52.818449 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.818528 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.818621 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:52.818759 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:52.818908 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:52.818920 6599 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\saddons-992000' /etc/hosts; then
if grep -xq '127.0.1.1\s.*' /etc/hosts; then
sudo sed -i 's/^127.0.1.1\s.*/127.0.1.1 addons-992000/g' /etc/hosts;
else
echo '127.0.1.1 addons-992000' | sudo tee -a /etc/hosts;
fi
fi
I0610 18:52:52.885382 6599 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0610 18:52:52.885404 6599 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/19046-5942/.minikube CaCertPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/19046-5942/.minikube}
I0610 18:52:52.885424 6599 buildroot.go:174] setting up certificates
I0610 18:52:52.885432 6599 provision.go:84] configureAuth start
I0610 18:52:52.885439 6599 main.go:141] libmachine: (addons-992000) Calling .GetMachineName
I0610 18:52:52.885576 6599 main.go:141] libmachine: (addons-992000) Calling .GetIP
I0610 18:52:52.885670 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:52.885760 6599 provision.go:143] copyHostCerts
I0610 18:52:52.885853 6599 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/19046-5942/.minikube/ca.pem (1082 bytes)
I0610 18:52:52.886118 6599 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19046-5942/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/19046-5942/.minikube/cert.pem (1123 bytes)
I0610 18:52:52.886309 6599 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19046-5942/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/19046-5942/.minikube/key.pem (1679 bytes)
I0610 18:52:52.886466 6599 provision.go:117] generating server cert: /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca-key.pem org=jenkins.addons-992000 san=[127.0.0.1 192.169.0.6 addons-992000 localhost minikube]
I0610 18:52:52.979109 6599 provision.go:177] copyRemoteCerts
I0610 18:52:52.979166 6599 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0610 18:52:52.979183 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:52.979362 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:52.979538 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:52.979633 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:52.979807 6599 sshutil.go:53] new ssh client: &{IP:192.169.0.6 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/id_rsa Username:docker}
I0610 18:52:53.016558 6599 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19046-5942/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0610 18:52:53.036613 6599 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/server.pem --> /etc/docker/server.pem (1208 bytes)
I0610 18:52:53.056107 6599 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19046-5942/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0610 18:52:53.075539 6599 provision.go:87] duration metric: took 190.090771ms to configureAuth
I0610 18:52:53.075551 6599 buildroot.go:189] setting minikube options for container-runtime
I0610 18:52:53.075683 6599 config.go:182] Loaded profile config "addons-992000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.1
I0610 18:52:53.075696 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:53.075842 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:53.075923 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:53.076012 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:53.076098 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:53.076190 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:53.076305 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:53.076431 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:53.076439 6599 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0610 18:52:53.133661 6599 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0610 18:52:53.133675 6599 buildroot.go:70] root file system type: tmpfs
I0610 18:52:53.133754 6599 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0610 18:52:53.133771 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:53.133908 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:53.134021 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:53.134127 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:53.134219 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:53.134360 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:53.134502 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:53.134556 6599 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %s "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I0610 18:52:53.203985 6599 main.go:141] libmachine: SSH cmd err, output: <nil>: [Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP $MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
I0610 18:52:53.204008 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:53.204144 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:53.204247 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:53.204347 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:53.204438 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:53.204562 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:53.204706 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:53.204722 6599 main.go:141] libmachine: About to run SSH command:
sudo diff -u /lib/systemd/system/docker.service /lib/systemd/system/docker.service.new || { sudo mv /lib/systemd/system/docker.service.new /lib/systemd/system/docker.service; sudo systemctl -f daemon-reload && sudo systemctl -f enable docker && sudo systemctl -f restart docker; }
I0610 18:52:54.718513 6599 main.go:141] libmachine: SSH cmd err, output: <nil>: diff: can't stat '/lib/systemd/system/docker.service': No such file or directory
Created symlink /etc/systemd/system/multi-user.target.wants/docker.service → /usr/lib/systemd/system/docker.service.
I0610 18:52:54.718543 6599 main.go:141] libmachine: Checking connection to Docker...
I0610 18:52:54.718555 6599 main.go:141] libmachine: (addons-992000) Calling .GetURL
I0610 18:52:54.718805 6599 main.go:141] libmachine: Docker is up and running!
I0610 18:52:54.718829 6599 main.go:141] libmachine: Reticulating splines...
I0610 18:52:54.718834 6599 client.go:171] duration metric: took 14.113404685s to LocalClient.Create
I0610 18:52:54.718845 6599 start.go:167] duration metric: took 14.11345827s to libmachine.API.Create "addons-992000"
I0610 18:52:54.718883 6599 start.go:293] postStartSetup for "addons-992000" (driver="hyperkit")
I0610 18:52:54.718901 6599 start.go:322] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I0610 18:52:54.718911 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:54.719172 6599 ssh_runner.go:195] Run: sudo mkdir -p /etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs
I0610 18:52:54.719190 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:54.719402 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:54.719560 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:54.719640 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:54.719816 6599 sshutil.go:53] new ssh client: &{IP:192.169.0.6 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/id_rsa Username:docker}
I0610 18:52:54.760540 6599 ssh_runner.go:195] Run: cat /etc/os-release
I0610 18:52:54.764321 6599 info.go:137] Remote host: Buildroot 2023.02.9
I0610 18:52:54.764332 6599 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19046-5942/.minikube/addons for local assets ...
I0610 18:52:54.764431 6599 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19046-5942/.minikube/files for local assets ...
I0610 18:52:54.764479 6599 start.go:296] duration metric: took 45.588863ms for postStartSetup
I0610 18:52:54.764500 6599 main.go:141] libmachine: (addons-992000) Calling .GetConfigRaw
I0610 18:52:54.765174 6599 main.go:141] libmachine: (addons-992000) Calling .GetIP
I0610 18:52:54.765322 6599 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19046-5942/.minikube/profiles/addons-992000/config.json ...
I0610 18:52:54.765695 6599 start.go:128] duration metric: took 14.193163931s to createHost
I0610 18:52:54.765712 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:54.765828 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:54.765910 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:54.766010 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:54.766083 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:54.766190 6599 main.go:141] libmachine: Using SSH client type: native
I0610 18:52:54.766322 6599 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x7cc2f00] 0x7cc5c60 <nil> [] 0s} 192.169.0.6 22 <nil> <nil>}
I0610 18:52:54.766329 6599 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0610 18:52:54.827047 6599 main.go:141] libmachine: SSH cmd err, output: <nil>: 1718070774.595339328
I0610 18:52:54.827058 6599 fix.go:216] guest clock: 1718070774.595339328
I0610 18:52:54.827063 6599 fix.go:229] Guest: 2024-06-10 18:52:54.595339328 -0700 PDT Remote: 2024-06-10 18:52:54.765703 -0700 PDT m=+14.599132954 (delta=-170.363672ms)
I0610 18:52:54.827079 6599 fix.go:200] guest clock delta is within tolerance: -170.363672ms
I0610 18:52:54.827082 6599 start.go:83] releasing machines lock for "addons-992000", held for 14.254691141s
I0610 18:52:54.827109 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:54.827233 6599 main.go:141] libmachine: (addons-992000) Calling .GetIP
I0610 18:52:54.827341 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:54.827671 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:54.827783 6599 main.go:141] libmachine: (addons-992000) Calling .DriverName
I0610 18:52:54.827890 6599 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0610 18:52:54.827922 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:54.827927 6599 ssh_runner.go:195] Run: cat /version.json
I0610 18:52:54.827936 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHHostname
I0610 18:52:54.828048 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:54.828067 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHPort
I0610 18:52:54.828156 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:54.828175 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHKeyPath
I0610 18:52:54.828236 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:54.828280 6599 main.go:141] libmachine: (addons-992000) Calling .GetSSHUsername
I0610 18:52:54.828358 6599 sshutil.go:53] new ssh client: &{IP:192.169.0.6 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/id_rsa Username:docker}
I0610 18:52:54.828365 6599 sshutil.go:53] new ssh client: &{IP:192.169.0.6 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19046-5942/.minikube/machines/addons-992000/id_rsa Username:docker}
I0610 18:52:54.914694 6599 ssh_runner.go:195] Run: systemctl --version
I0610 18:52:54.919992 6599 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0610 18:52:54.924237 6599 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0610 18:52:54.924284 6599 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f ( ( -name *bridge* -or -name *podman* ) -and -not -name *.mk_disabled ) -printf "%p, " -exec sh -c "sudo mv {} {}.mk_disabled" ;
I0610 18:52:54.937347 6599 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0610 18:52:54.937363 6599 start.go:494] detecting cgroup driver to use...
I0610 18:52:54.937462 6599 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///run/containerd/containerd.sock
" | sudo tee /etc/crictl.yaml"
I0610 18:52:54.952139 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.9"|' /etc/containerd/config.toml"
I0610 18:52:54.960890 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)restrict_oom_score_adj = .*$|\1restrict_oom_score_adj = false|' /etc/containerd/config.toml"
I0610 18:52:54.969553 6599 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0610 18:52:54.969590 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0610 18:52:54.978313 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runtime.v1.linux"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0610 18:52:54.987065 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0610 18:52:54.995826 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0610 18:52:55.004623 6599 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0610 18:52:55.013539 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)conf_dir = .*$|\1conf_dir = "/etc/cni/net.d"|g' /etc/containerd/config.toml"
I0610 18:52:55.022350 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0610 18:52:55.031309 6599 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)\[plugins."io.containerd.grpc.v1.cri"\]|&\n\1 enable_unprivileged_ports = true|' /etc/containerd/config.toml"
I0610 18:52:55.040270 6599 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0610 18:52:55.048241 6599 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0610 18:52:55.056300 6599 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0610 18:52:55.164049 6599 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0610 18:52:55.183586 6599 start.go:494] detecting cgroup driver to use...
I0610 18:52:55.183749 6599 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0610 18:52:55.198768 6599 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0610 18:52:55.210072 6599 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0610 18:52:55.242123 6599 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0610 18:52:55.252823 6599 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0610 18:52:55.263296 6599 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0610 18:52:55.284426 6599 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0610 18:52:55.294874 6599 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I0610 18:52:55.310146 6599 ssh_runner.go:195] Run: which cri-dockerd
I0610 18:52:55.312924 6599 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0610 18:52:55.320089 6599 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0610 18:52:55.333491 6599 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0610 18:52:55.429516 6599 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0610 18:52:55.524668 6599 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0610 18:52:55.524753 6599 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0610 18:52:55.538490 6599 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0610 18:52:55.639322 6599 ssh_runner.go:195] Run: sudo systemctl restart docker
I0610 18:53:56.450879 6599 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m0.810577714s)
I0610 18:53:56.450941 6599 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0610 18:53:56.485974 6599 out.go:177]
W0610 18:53:56.507860 6599 out.go:239] 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 --
Jun 11 01:52:53 addons-992000 systemd[1]: Starting Docker Application Container Engine...
Jun 11 01:52:53 addons-992000 dockerd[523]: time="2024-06-11T01:52:53.290178013Z" level=info msg="Starting up"
Jun 11 01:52:53 addons-992000 dockerd[523]: time="2024-06-11T01:52:53.290694596Z" level=info msg="containerd not running, starting managed containerd"
Jun 11 01:52:53 addons-992000 dockerd[523]: time="2024-06-11T01:52:53.293560650Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=531
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.310485968Z" level=info msg="starting containerd" revision=3a4de459a68952ffb703bbe7f2290861a75b6b67 version=v1.7.17
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330468233Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330529371Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330591855Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330626117Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330700865Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330743819Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330888495Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330929290Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330962441Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330996869Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.331079059Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.331252459Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.332824907Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.332877443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333011655Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333054598Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333141633Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333205733Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333240262Z" level=info msg="metadata content store policy set" policy=shared
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.335906036Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.335977708Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336048089Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336093839Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336130262Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336220151Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336396255Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336496422Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336534526Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336620323Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336658506Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336689808Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336729204Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336769074Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336801781Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336838995Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336872239Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336918495Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336958586Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336991920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337024949Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337055511Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337084639Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337113828Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337151989Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337185175Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337219384Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337256552Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337289202Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337318946Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337348224Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337414618Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337463257Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337495308Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337524248Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337578068Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337640082Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337680855Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337711812Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337741954Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337771568Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337800282Z" level=info msg="NRI interface is disabled by configuration."
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337965599Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.338056998Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.338123980Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.338209604Z" level=info msg="containerd successfully booted in 0.028420s"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.320838224Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.327912320Z" level=info msg="Loading containers: start."
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.442369125Z" level=info msg="Loading containers: done."
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.450757855Z" level=info msg="Docker daemon" commit=de5c9cf containerd-snapshotter=false storage-driver=overlay2 version=26.1.4
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.450899239Z" level=info msg="Daemon has completed initialization"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.483379531Z" level=info msg="API listen on /var/run/docker.sock"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.483494845Z" level=info msg="API listen on [::]:2376"
Jun 11 01:52:54 addons-992000 systemd[1]: Started Docker Application Container Engine.
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.420886737Z" level=info msg="Processing signal 'terminated'"
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.421751261Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.421940209Z" level=info msg="Daemon shutdown complete"
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.421989265Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.422002534Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jun 11 01:52:55 addons-992000 systemd[1]: Stopping Docker Application Container Engine...
Jun 11 01:52:56 addons-992000 systemd[1]: docker.service: Deactivated successfully.
Jun 11 01:52:56 addons-992000 systemd[1]: Stopped Docker Application Container Engine.
Jun 11 01:52:56 addons-992000 systemd[1]: Starting Docker Application Container Engine...
Jun 11 01:52:56 addons-992000 dockerd[861]: time="2024-06-11T01:52:56.478266805Z" level=info msg="Starting up"
Jun 11 01:53:56 addons-992000 dockerd[861]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jun 11 01:53:56 addons-992000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jun 11 01:53:56 addons-992000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jun 11 01:53:56 addons-992000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
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 --
Jun 11 01:52:53 addons-992000 systemd[1]: Starting Docker Application Container Engine...
Jun 11 01:52:53 addons-992000 dockerd[523]: time="2024-06-11T01:52:53.290178013Z" level=info msg="Starting up"
Jun 11 01:52:53 addons-992000 dockerd[523]: time="2024-06-11T01:52:53.290694596Z" level=info msg="containerd not running, starting managed containerd"
Jun 11 01:52:53 addons-992000 dockerd[523]: time="2024-06-11T01:52:53.293560650Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=531
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.310485968Z" level=info msg="starting containerd" revision=3a4de459a68952ffb703bbe7f2290861a75b6b67 version=v1.7.17
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330468233Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330529371Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330591855Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330626117Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330700865Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330743819Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330888495Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330929290Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330962441Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.330996869Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.331079059Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.331252459Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.332824907Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.332877443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333011655Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333054598Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333141633Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333205733Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.333240262Z" level=info msg="metadata content store policy set" policy=shared
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.335906036Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.335977708Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336048089Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336093839Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336130262Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336220151Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336396255Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336496422Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336534526Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336620323Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336658506Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336689808Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336729204Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336769074Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336801781Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336838995Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336872239Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336918495Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336958586Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.336991920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337024949Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337055511Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337084639Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337113828Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337151989Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337185175Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337219384Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337256552Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337289202Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337318946Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337348224Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337414618Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337463257Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337495308Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337524248Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337578068Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337640082Z" 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
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337680855Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337711812Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337741954Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337771568Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337800282Z" level=info msg="NRI interface is disabled by configuration."
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.337965599Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.338056998Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.338123980Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jun 11 01:52:53 addons-992000 dockerd[531]: time="2024-06-11T01:52:53.338209604Z" level=info msg="containerd successfully booted in 0.028420s"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.320838224Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.327912320Z" level=info msg="Loading containers: start."
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.442369125Z" level=info msg="Loading containers: done."
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.450757855Z" level=info msg="Docker daemon" commit=de5c9cf containerd-snapshotter=false storage-driver=overlay2 version=26.1.4
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.450899239Z" level=info msg="Daemon has completed initialization"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.483379531Z" level=info msg="API listen on /var/run/docker.sock"
Jun 11 01:52:54 addons-992000 dockerd[523]: time="2024-06-11T01:52:54.483494845Z" level=info msg="API listen on [::]:2376"
Jun 11 01:52:54 addons-992000 systemd[1]: Started Docker Application Container Engine.
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.420886737Z" level=info msg="Processing signal 'terminated'"
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.421751261Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.421940209Z" level=info msg="Daemon shutdown complete"
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.421989265Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jun 11 01:52:55 addons-992000 dockerd[523]: time="2024-06-11T01:52:55.422002534Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jun 11 01:52:55 addons-992000 systemd[1]: Stopping Docker Application Container Engine...
Jun 11 01:52:56 addons-992000 systemd[1]: docker.service: Deactivated successfully.
Jun 11 01:52:56 addons-992000 systemd[1]: Stopped Docker Application Container Engine.
Jun 11 01:52:56 addons-992000 systemd[1]: Starting Docker Application Container Engine...
Jun 11 01:52:56 addons-992000 dockerd[861]: time="2024-06-11T01:52:56.478266805Z" level=info msg="Starting up"
Jun 11 01:53:56 addons-992000 dockerd[861]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jun 11 01:53:56 addons-992000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jun 11 01:53:56 addons-992000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jun 11 01:53:56 addons-992000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0610 18:53:56.507971 6599 out.go:239] *
*
W0610 18:53:56.509180 6599 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0610 18:53:56.593735 6599 out.go:177]
** /stderr **
addons_test.go:112: out/minikube-darwin-amd64 start -p addons-992000 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --addons=nvidia-device-plugin --addons=yakd --addons=volcano --driver=hyperkit --addons=ingress --addons=ingress-dns --addons=helm-tiller failed: exit status 90
--- FAIL: TestAddons/Setup (76.48s)