=== RUN TestNetworkPlugins/group/false/Start
net_test.go:112: (dbg) Run: out/minikube-darwin-amd64 start -p false-579000 --memory=3072 --alsologtostderr --wait=true --wait-timeout=15m --cni=false --driver=hyperkit
net_test.go:112: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p false-579000 --memory=3072 --alsologtostderr --wait=true --wait-timeout=15m --cni=false --driver=hyperkit : exit status 90 (1m18.787711914s)
-- stdout --
* [false-579000] minikube v1.33.1 on Darwin 14.5
- MINIKUBE_LOCATION=19312
- KUBECONFIG=/Users/jenkins/minikube-integration/19312-1133/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/19312-1133/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting "false-579000" primary control-plane node in "false-579000" cluster
* Creating hyperkit VM (CPUs=2, Memory=3072MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I0721 17:36:30.795959 7400 out.go:291] Setting OutFile to fd 1 ...
I0721 17:36:30.796798 7400 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0721 17:36:30.796816 7400 out.go:304] Setting ErrFile to fd 2...
I0721 17:36:30.796827 7400 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0721 17:36:30.797289 7400 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/19312-1133/.minikube/bin
I0721 17:36:30.799128 7400 out.go:298] Setting JSON to false
I0721 17:36:30.823094 7400 start.go:129] hostinfo: {"hostname":"MacOS-Agent-4.local","uptime":5761,"bootTime":1721602829,"procs":550,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.5","kernelVersion":"23.5.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"f2f27e25-cfda-5ffd-9706-e98286194e62"}
W0721 17:36:30.823192 7400 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0721 17:36:30.879603 7400 out.go:177] * [false-579000] minikube v1.33.1 on Darwin 14.5
I0721 17:36:30.937587 7400 notify.go:220] Checking for updates...
I0721 17:36:30.962417 7400 out.go:177] - MINIKUBE_LOCATION=19312
I0721 17:36:30.983674 7400 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/19312-1133/kubeconfig
I0721 17:36:31.006548 7400 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0721 17:36:31.056705 7400 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0721 17:36:31.078490 7400 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/19312-1133/.minikube
I0721 17:36:31.126390 7400 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0721 17:36:31.165034 7400 config.go:182] Loaded profile config "custom-flannel-579000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.3
I0721 17:36:31.165238 7400 driver.go:392] Setting default libvirt URI to qemu:///system
I0721 17:36:31.197647 7400 out.go:177] * Using the hyperkit driver based on user configuration
I0721 17:36:31.272729 7400 start.go:297] selected driver: hyperkit
I0721 17:36:31.272792 7400 start.go:901] validating driver "hyperkit" against <nil>
I0721 17:36:31.272819 7400 start.go:912] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0721 17:36:31.277288 7400 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0721 17:36:31.277406 7400 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/19312-1133/.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
I0721 17:36:31.285885 7400 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.33.1
I0721 17:36:31.289907 7400 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0721 17:36:31.289931 7400 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0721 17:36:31.289974 7400 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0721 17:36:31.290198 7400 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]
I0721 17:36:31.290248 7400 cni.go:84] Creating CNI manager for "false"
I0721 17:36:31.290323 7400 start.go:340] cluster config:
{Name:false-579000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1721324606-19298@sha256:1c495b056df42bd3fd9a5c30d049e1802f9ed73a342611781f1ccc3c3853953f Memory:3072 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.3 ClusterName:false-579000 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime
:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:false} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.30.3 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:15m0s 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: SSH
AgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0721 17:36:31.290412 7400 iso.go:125] acquiring lock: {Name:mkaaddfae7820d472d8601a0d0245a68cd63d48a Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0721 17:36:31.343388 7400 out.go:177] * Starting "false-579000" primary control-plane node in "false-579000" cluster
I0721 17:36:31.380656 7400 preload.go:131] Checking if preload exists for k8s version v1.30.3 and runtime docker
I0721 17:36:31.380734 7400 preload.go:146] Found local preload: /Users/jenkins/minikube-integration/19312-1133/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.3-docker-overlay2-amd64.tar.lz4
I0721 17:36:31.380766 7400 cache.go:56] Caching tarball of preloaded images
I0721 17:36:31.381041 7400 preload.go:172] Found /Users/jenkins/minikube-integration/19312-1133/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0721 17:36:31.381069 7400 cache.go:59] Finished verifying existence of preloaded tar for v1.30.3 on docker
I0721 17:36:31.381224 7400 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19312-1133/.minikube/profiles/false-579000/config.json ...
I0721 17:36:31.381267 7400 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/19312-1133/.minikube/profiles/false-579000/config.json: {Name:mkd98faca25ae1ba61cb991599ca1326d0affc61 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0721 17:36:31.381934 7400 start.go:360] acquireMachinesLock for false-579000: {Name:mkdb041d73dfb446961cf68e5515b5a40105f893 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0721 17:36:31.382064 7400 start.go:364] duration metric: took 96.853µs to acquireMachinesLock for "false-579000"
I0721 17:36:31.382104 7400 start.go:93] Provisioning new machine with config: &{Name:false-579000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19298/minikube-v1.33.1-1721324531-19298-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1721324606-19298@sha256:1c495b056df42bd3fd9a5c30d049e1802f9ed73a342611781f1ccc3c3853953f Memory:3072 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:{Ku
bernetesVersion:v1.30.3 ClusterName:false-579000 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:false} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.30.3 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:15m0s 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.3 ContainerRuntime:docker ControlPlane:true Worker:true}
I0721 17:36:31.382194 7400 start.go:125] createHost starting for "" (driver="hyperkit")
I0721 17:36:31.403446 7400 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=3072MB, Disk=20000MB) ...
I0721 17:36:31.403604 7400 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0721 17:36:31.403639 7400 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0721 17:36:31.412328 7400 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:55824
I0721 17:36:31.412685 7400 main.go:141] libmachine: () Calling .GetVersion
I0721 17:36:31.413096 7400 main.go:141] libmachine: Using API Version 1
I0721 17:36:31.413106 7400 main.go:141] libmachine: () Calling .SetConfigRaw
I0721 17:36:31.413338 7400 main.go:141] libmachine: () Calling .GetMachineName
I0721 17:36:31.413456 7400 main.go:141] libmachine: (false-579000) Calling .GetMachineName
I0721 17:36:31.413548 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:31.413650 7400 start.go:159] libmachine.API.Create for "false-579000" (driver="hyperkit")
I0721 17:36:31.413676 7400 client.go:168] LocalClient.Create starting
I0721 17:36:31.413712 7400 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca.pem
I0721 17:36:31.413766 7400 main.go:141] libmachine: Decoding PEM data...
I0721 17:36:31.413783 7400 main.go:141] libmachine: Parsing certificate...
I0721 17:36:31.413858 7400 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/19312-1133/.minikube/certs/cert.pem
I0721 17:36:31.413898 7400 main.go:141] libmachine: Decoding PEM data...
I0721 17:36:31.413911 7400 main.go:141] libmachine: Parsing certificate...
I0721 17:36:31.413922 7400 main.go:141] libmachine: Running pre-create checks...
I0721 17:36:31.413935 7400 main.go:141] libmachine: (false-579000) Calling .PreCreateCheck
I0721 17:36:31.414022 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:31.414210 7400 main.go:141] libmachine: (false-579000) Calling .GetConfigRaw
I0721 17:36:31.440642 7400 main.go:141] libmachine: Creating machine...
I0721 17:36:31.440664 7400 main.go:141] libmachine: (false-579000) Calling .Create
I0721 17:36:31.440752 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:31.440880 7400 main.go:141] libmachine: (false-579000) DBG | I0721 17:36:31.440750 7408 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/19312-1133/.minikube
I0721 17:36:31.440975 7400 main.go:141] libmachine: (false-579000) Downloading /Users/jenkins/minikube-integration/19312-1133/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/19312-1133/.minikube/cache/iso/amd64/minikube-v1.33.1-1721324531-19298-amd64.iso...
I0721 17:36:31.616087 7400 main.go:141] libmachine: (false-579000) DBG | I0721 17:36:31.615991 7408 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/id_rsa...
I0721 17:36:31.655388 7400 main.go:141] libmachine: (false-579000) DBG | I0721 17:36:31.655316 7408 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/false-579000.rawdisk...
I0721 17:36:31.655405 7400 main.go:141] libmachine: (false-579000) DBG | Writing magic tar header
I0721 17:36:31.655412 7400 main.go:141] libmachine: (false-579000) DBG | Writing SSH key tar header
I0721 17:36:31.655793 7400 main.go:141] libmachine: (false-579000) DBG | I0721 17:36:31.655730 7408 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000 ...
I0721 17:36:32.015136 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:32.015162 7400 main.go:141] libmachine: (false-579000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/hyperkit.pid
I0721 17:36:32.015177 7400 main.go:141] libmachine: (false-579000) DBG | Using UUID c1a9720f-571d-4c8a-8c0a-1354b84608b8
I0721 17:36:32.040192 7400 main.go:141] libmachine: (false-579000) DBG | Generated MAC 12:21:9a:ad:33:58
I0721 17:36:32.040210 7400 main.go:141] libmachine: (false-579000) 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=false-579000
I0721 17:36:32.040239 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"c1a9720f-571d-4c8a-8c0a-1354b84608b8", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0001ae630)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/bzimage", Initrd:"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/initrd", Bootrom:"", CPUs:2, Memory:3072, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0721 17:36:32.040266 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"c1a9720f-571d-4c8a-8c0a-1354b84608b8", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0001ae630)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/bzimage", Initrd:"/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/initrd", Bootrom:"", CPUs:2, Memory:3072, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0721 17:36:32.040314 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/hyperkit.pid", "-c", "2", "-m", "3072M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "c1a9720f-571d-4c8a-8c0a-1354b84608b8", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/false-579000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/tty,log=/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/bzimage,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-
579000/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=false-579000"}
I0721 17:36:32.040358 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/hyperkit.pid -c 2 -m 3072M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U c1a9720f-571d-4c8a-8c0a-1354b84608b8 -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/false-579000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/tty,log=/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/console-ring -f kexec,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/bzimage,/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/initrd,earlyprintk=serial loglevel=3 console=ttyS0 cons
ole=tty0 noembed nomodeset norestore waitusb=10 systemd.legacy_systemd_cgroup_controller=yes random.trust_cpu=on hw_rng_model=virtio base host=false-579000"
I0721 17:36:32.040377 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0721 17:36:32.043254 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 DEBUG: hyperkit: Pid is 7409
I0721 17:36:32.043699 7400 main.go:141] libmachine: (false-579000) DBG | Attempt 0
I0721 17:36:32.043714 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:32.043771 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:32.044922 7400 main.go:141] libmachine: (false-579000) DBG | Searching for 12:21:9a:ad:33:58 in /var/db/dhcpd_leases ...
I0721 17:36:32.045023 7400 main.go:141] libmachine: (false-579000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0721 17:36:32.045037 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:22:a2:e7:d:b5:27 ID:1,22:a2:e7:d:b5:27 Lease:0x669efacf}
I0721 17:36:32.045068 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:3e:88:c:25:c2:8e ID:1,3e:88:c:25:c2:8e Lease:0x669efa56}
I0721 17:36:32.045083 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:f6:b0:ba:2b:d9:60 ID:1,f6:b0:ba:2b:d9:60 Lease:0x669efa3d}
I0721 17:36:32.045109 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:2a:7c:16:d9:f2:a4 ID:1,2a:7c:16:d9:f2:a4 Lease:0x669da8b2}
I0721 17:36:32.045145 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:76:3d:e1:23:b9:41 ID:1,76:3d:e1:23:b9:41 Lease:0x669ef9ff}
I0721 17:36:32.045184 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:c2:22:be:13:77:6d ID:1,c2:22:be:13:77:6d Lease:0x669da875}
I0721 17:36:32.045208 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:46:b0:41:2e:5c:fd ID:1,46:b0:41:2e:5c:fd Lease:0x669ef974}
I0721 17:36:32.045241 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:4e:89:bf:9a:9a:b1 ID:1,4e:89:bf:9a:9a:b1 Lease:0x669ef996}
I0721 17:36:32.045255 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:ba:9c:e:ad:75:25 ID:1,ba:9c:e:ad:75:25 Lease:0x669ef932}
I0721 17:36:32.045263 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:ea:8c:b1:c7:35:10 ID:1,ea:8c:b1:c7:35:10 Lease:0x669ef8b3}
I0721 17:36:32.045271 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:74:a4:6b:a6:8d ID:1,b2:74:a4:6b:a6:8d Lease:0x669ef87a}
I0721 17:36:32.045297 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:d2:57:d1:2:81:d5 ID:1,d2:57:d1:2:81:d5 Lease:0x669ef86a}
I0721 17:36:32.045312 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:9a:22:5e:a7:17:a9 ID:1,9a:22:5e:a7:17:a9 Lease:0x669da6f0}
I0721 17:36:32.045329 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:56:23:47:9b:eb:9b ID:1,56:23:47:9b:eb:9b Lease:0x669da6df}
I0721 17:36:32.045346 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:e:22:5d:e1:e7:1f ID:1,e:22:5d:e1:e7:1f Lease:0x669da6c0}
I0721 17:36:32.045358 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:52:8e:db:45:cb:ef ID:1,52:8e:db:45:cb:ef Lease:0x669ef809}
I0721 17:36:32.045371 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:ee:3:1d:14:7b:78 ID:1,ee:3:1d:14:7b:78 Lease:0x669ef797}
I0721 17:36:32.045382 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:1a:5d:bf:32:8f:a4 ID:1,1a:5d:bf:32:8f:a4 Lease:0x669ef729}
I0721 17:36:32.045404 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:7e:ae:41:39:79:19 ID:1,7e:ae:41:39:79:19 Lease:0x669ef6e2}
I0721 17:36:32.045418 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:d2:15:f:15:e4:c9 ID:1,d2:15:f:15:e4:c9 Lease:0x669ef60c}
I0721 17:36:32.045427 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:5e:2:13:a2:11:ad ID:1,5e:2:13:a2:11:ad Lease:0x669da37f}
I0721 17:36:32.045434 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:5a:3c:34:f8:5b:60 ID:1,5a:3c:34:f8:5b:60 Lease:0x669ef56f}
I0721 17:36:32.045441 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:fe:53:7:33:c:85 ID:1,fe:53:7:33:c:85 Lease:0x669ef51b}
I0721 17:36:32.045448 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:42:19:c4:6e:ab:2b ID:1,42:19:c4:6e:ab:2b Lease:0x669da1bf}
I0721 17:36:32.045459 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:aa:65:e5:11:58:85 ID:1,aa:65:e5:11:58:85 Lease:0x669da18f}
I0721 17:36:32.045471 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:72:49:13:5a:ba:1f ID:1,72:49:13:5a:ba:1f Lease:0x669ef2c0}
I0721 17:36:32.045482 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:2:66:9:8e:e1:2b ID:1,2:66:9:8e:e1:2b Lease:0x669ef298}
I0721 17:36:32.045497 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a:52:da:48:2:2b ID:1,a:52:da:48:2:2b Lease:0x669ef1e6}
I0721 17:36:32.045509 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:be:93:9:2a:bf:cf ID:1,be:93:9:2a:bf:cf Lease:0x669ef1b5}
I0721 17:36:32.045516 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ea:4a:cb:cd:5b:35 ID:1,ea:4a:cb:cd:5b:35 Lease:0x669ef132}
I0721 17:36:32.045524 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:b6:95:39:84:4f:df ID:1,b6:95:39:84:4f:df Lease:0x669ef119}
I0721 17:36:32.045534 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:fa:f4:8a:1d:7c:9 ID:1,fa:f4:8a:1d:7c:9 Lease:0x669d9f28}
I0721 17:36:32.045544 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:86:46:bc:37:74:22 ID:1,86:46:bc:37:74:22 Lease:0x669ef0df}
I0721 17:36:32.045554 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6:cd:8e:d7:24:37 ID:1,6:cd:8e:d7:24:37 Lease:0x669ef0cc}
I0721 17:36:32.045562 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:d6:6f:d:80:8e:f4 ID:1,d6:6f:d:80:8e:f4 Lease:0x669eeca5}
I0721 17:36:32.045569 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ba:9d:5f:d7:4d:44 ID:1,ba:9d:5f:d7:4d:44 Lease:0x669d9a84}
I0721 17:36:32.045576 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:5e:54:4b:e2:e9:77 ID:1,5e:54:4b:e2:e9:77 Lease:0x669eea38}
I0721 17:36:32.050695 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0721 17:36:32.059286 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0721 17:36:32.060086 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0721 17:36:32.060108 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0721 17:36:32.060128 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0721 17:36:32.060144 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0721 17:36:32.459945 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0721 17:36:32.459960 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0721 17:36:32.574852 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0721 17:36:32.574873 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0721 17:36:32.574881 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0721 17:36:32.574895 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0721 17:36:32.575713 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0721 17:36:32.575723 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:32 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0721 17:36:34.045744 7400 main.go:141] libmachine: (false-579000) DBG | Attempt 1
I0721 17:36:34.045758 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:34.045802 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:34.046701 7400 main.go:141] libmachine: (false-579000) DBG | Searching for 12:21:9a:ad:33:58 in /var/db/dhcpd_leases ...
I0721 17:36:34.046773 7400 main.go:141] libmachine: (false-579000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0721 17:36:34.046784 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:22:a2:e7:d:b5:27 ID:1,22:a2:e7:d:b5:27 Lease:0x669efacf}
I0721 17:36:34.046801 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:3e:88:c:25:c2:8e ID:1,3e:88:c:25:c2:8e Lease:0x669efa56}
I0721 17:36:34.046809 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:f6:b0:ba:2b:d9:60 ID:1,f6:b0:ba:2b:d9:60 Lease:0x669efa3d}
I0721 17:36:34.046816 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:2a:7c:16:d9:f2:a4 ID:1,2a:7c:16:d9:f2:a4 Lease:0x669da8b2}
I0721 17:36:34.046828 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:76:3d:e1:23:b9:41 ID:1,76:3d:e1:23:b9:41 Lease:0x669ef9ff}
I0721 17:36:34.046836 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:c2:22:be:13:77:6d ID:1,c2:22:be:13:77:6d Lease:0x669da875}
I0721 17:36:34.046846 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:46:b0:41:2e:5c:fd ID:1,46:b0:41:2e:5c:fd Lease:0x669ef974}
I0721 17:36:34.046854 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:4e:89:bf:9a:9a:b1 ID:1,4e:89:bf:9a:9a:b1 Lease:0x669ef996}
I0721 17:36:34.046863 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:ba:9c:e:ad:75:25 ID:1,ba:9c:e:ad:75:25 Lease:0x669ef932}
I0721 17:36:34.046870 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:ea:8c:b1:c7:35:10 ID:1,ea:8c:b1:c7:35:10 Lease:0x669ef8b3}
I0721 17:36:34.046876 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:74:a4:6b:a6:8d ID:1,b2:74:a4:6b:a6:8d Lease:0x669ef87a}
I0721 17:36:34.046883 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:d2:57:d1:2:81:d5 ID:1,d2:57:d1:2:81:d5 Lease:0x669ef86a}
I0721 17:36:34.046890 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:9a:22:5e:a7:17:a9 ID:1,9a:22:5e:a7:17:a9 Lease:0x669da6f0}
I0721 17:36:34.046896 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:56:23:47:9b:eb:9b ID:1,56:23:47:9b:eb:9b Lease:0x669da6df}
I0721 17:36:34.046903 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:e:22:5d:e1:e7:1f ID:1,e:22:5d:e1:e7:1f Lease:0x669da6c0}
I0721 17:36:34.046912 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:52:8e:db:45:cb:ef ID:1,52:8e:db:45:cb:ef Lease:0x669ef809}
I0721 17:36:34.046918 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:ee:3:1d:14:7b:78 ID:1,ee:3:1d:14:7b:78 Lease:0x669ef797}
I0721 17:36:34.046925 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:1a:5d:bf:32:8f:a4 ID:1,1a:5d:bf:32:8f:a4 Lease:0x669ef729}
I0721 17:36:34.046933 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:7e:ae:41:39:79:19 ID:1,7e:ae:41:39:79:19 Lease:0x669ef6e2}
I0721 17:36:34.046939 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:d2:15:f:15:e4:c9 ID:1,d2:15:f:15:e4:c9 Lease:0x669ef60c}
I0721 17:36:34.046945 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:5e:2:13:a2:11:ad ID:1,5e:2:13:a2:11:ad Lease:0x669da37f}
I0721 17:36:34.046962 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:5a:3c:34:f8:5b:60 ID:1,5a:3c:34:f8:5b:60 Lease:0x669ef56f}
I0721 17:36:34.046978 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:fe:53:7:33:c:85 ID:1,fe:53:7:33:c:85 Lease:0x669ef51b}
I0721 17:36:34.046992 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:42:19:c4:6e:ab:2b ID:1,42:19:c4:6e:ab:2b Lease:0x669da1bf}
I0721 17:36:34.047000 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:aa:65:e5:11:58:85 ID:1,aa:65:e5:11:58:85 Lease:0x669da18f}
I0721 17:36:34.047008 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:72:49:13:5a:ba:1f ID:1,72:49:13:5a:ba:1f Lease:0x669ef2c0}
I0721 17:36:34.047015 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:2:66:9:8e:e1:2b ID:1,2:66:9:8e:e1:2b Lease:0x669ef298}
I0721 17:36:34.047021 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a:52:da:48:2:2b ID:1,a:52:da:48:2:2b Lease:0x669ef1e6}
I0721 17:36:34.047028 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:be:93:9:2a:bf:cf ID:1,be:93:9:2a:bf:cf Lease:0x669ef1b5}
I0721 17:36:34.047035 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ea:4a:cb:cd:5b:35 ID:1,ea:4a:cb:cd:5b:35 Lease:0x669ef132}
I0721 17:36:34.047042 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:b6:95:39:84:4f:df ID:1,b6:95:39:84:4f:df Lease:0x669ef119}
I0721 17:36:34.047051 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:fa:f4:8a:1d:7c:9 ID:1,fa:f4:8a:1d:7c:9 Lease:0x669d9f28}
I0721 17:36:34.047058 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:86:46:bc:37:74:22 ID:1,86:46:bc:37:74:22 Lease:0x669ef0df}
I0721 17:36:34.047065 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6:cd:8e:d7:24:37 ID:1,6:cd:8e:d7:24:37 Lease:0x669ef0cc}
I0721 17:36:34.047073 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:d6:6f:d:80:8e:f4 ID:1,d6:6f:d:80:8e:f4 Lease:0x669eeca5}
I0721 17:36:34.047080 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ba:9d:5f:d7:4d:44 ID:1,ba:9d:5f:d7:4d:44 Lease:0x669d9a84}
I0721 17:36:34.047088 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:5e:54:4b:e2:e9:77 ID:1,5e:54:4b:e2:e9:77 Lease:0x669eea38}
I0721 17:36:36.047948 7400 main.go:141] libmachine: (false-579000) DBG | Attempt 2
I0721 17:36:36.047962 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:36.048111 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:36.048932 7400 main.go:141] libmachine: (false-579000) DBG | Searching for 12:21:9a:ad:33:58 in /var/db/dhcpd_leases ...
I0721 17:36:36.049001 7400 main.go:141] libmachine: (false-579000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0721 17:36:36.049009 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:22:a2:e7:d:b5:27 ID:1,22:a2:e7:d:b5:27 Lease:0x669efacf}
I0721 17:36:36.049034 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:3e:88:c:25:c2:8e ID:1,3e:88:c:25:c2:8e Lease:0x669efa56}
I0721 17:36:36.049045 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:f6:b0:ba:2b:d9:60 ID:1,f6:b0:ba:2b:d9:60 Lease:0x669efa3d}
I0721 17:36:36.049064 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:2a:7c:16:d9:f2:a4 ID:1,2a:7c:16:d9:f2:a4 Lease:0x669da8b2}
I0721 17:36:36.049079 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:76:3d:e1:23:b9:41 ID:1,76:3d:e1:23:b9:41 Lease:0x669ef9ff}
I0721 17:36:36.049088 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:c2:22:be:13:77:6d ID:1,c2:22:be:13:77:6d Lease:0x669da875}
I0721 17:36:36.049097 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:46:b0:41:2e:5c:fd ID:1,46:b0:41:2e:5c:fd Lease:0x669ef974}
I0721 17:36:36.049110 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:4e:89:bf:9a:9a:b1 ID:1,4e:89:bf:9a:9a:b1 Lease:0x669ef996}
I0721 17:36:36.049130 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:ba:9c:e:ad:75:25 ID:1,ba:9c:e:ad:75:25 Lease:0x669ef932}
I0721 17:36:36.049145 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:ea:8c:b1:c7:35:10 ID:1,ea:8c:b1:c7:35:10 Lease:0x669ef8b3}
I0721 17:36:36.049155 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:74:a4:6b:a6:8d ID:1,b2:74:a4:6b:a6:8d Lease:0x669ef87a}
I0721 17:36:36.049162 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:d2:57:d1:2:81:d5 ID:1,d2:57:d1:2:81:d5 Lease:0x669ef86a}
I0721 17:36:36.049169 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:9a:22:5e:a7:17:a9 ID:1,9a:22:5e:a7:17:a9 Lease:0x669da6f0}
I0721 17:36:36.049180 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:56:23:47:9b:eb:9b ID:1,56:23:47:9b:eb:9b Lease:0x669da6df}
I0721 17:36:36.049192 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:e:22:5d:e1:e7:1f ID:1,e:22:5d:e1:e7:1f Lease:0x669da6c0}
I0721 17:36:36.049199 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:52:8e:db:45:cb:ef ID:1,52:8e:db:45:cb:ef Lease:0x669ef809}
I0721 17:36:36.049208 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:ee:3:1d:14:7b:78 ID:1,ee:3:1d:14:7b:78 Lease:0x669ef797}
I0721 17:36:36.049215 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:1a:5d:bf:32:8f:a4 ID:1,1a:5d:bf:32:8f:a4 Lease:0x669ef729}
I0721 17:36:36.049223 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:7e:ae:41:39:79:19 ID:1,7e:ae:41:39:79:19 Lease:0x669ef6e2}
I0721 17:36:36.049230 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:d2:15:f:15:e4:c9 ID:1,d2:15:f:15:e4:c9 Lease:0x669ef60c}
I0721 17:36:36.049238 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:5e:2:13:a2:11:ad ID:1,5e:2:13:a2:11:ad Lease:0x669da37f}
I0721 17:36:36.049244 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:5a:3c:34:f8:5b:60 ID:1,5a:3c:34:f8:5b:60 Lease:0x669ef56f}
I0721 17:36:36.049260 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:fe:53:7:33:c:85 ID:1,fe:53:7:33:c:85 Lease:0x669ef51b}
I0721 17:36:36.049270 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:42:19:c4:6e:ab:2b ID:1,42:19:c4:6e:ab:2b Lease:0x669da1bf}
I0721 17:36:36.049279 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:aa:65:e5:11:58:85 ID:1,aa:65:e5:11:58:85 Lease:0x669da18f}
I0721 17:36:36.049291 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:72:49:13:5a:ba:1f ID:1,72:49:13:5a:ba:1f Lease:0x669ef2c0}
I0721 17:36:36.049304 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:2:66:9:8e:e1:2b ID:1,2:66:9:8e:e1:2b Lease:0x669ef298}
I0721 17:36:36.049327 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a:52:da:48:2:2b ID:1,a:52:da:48:2:2b Lease:0x669ef1e6}
I0721 17:36:36.049337 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:be:93:9:2a:bf:cf ID:1,be:93:9:2a:bf:cf Lease:0x669ef1b5}
I0721 17:36:36.049344 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ea:4a:cb:cd:5b:35 ID:1,ea:4a:cb:cd:5b:35 Lease:0x669ef132}
I0721 17:36:36.049351 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:b6:95:39:84:4f:df ID:1,b6:95:39:84:4f:df Lease:0x669ef119}
I0721 17:36:36.049357 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:fa:f4:8a:1d:7c:9 ID:1,fa:f4:8a:1d:7c:9 Lease:0x669d9f28}
I0721 17:36:36.049365 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:86:46:bc:37:74:22 ID:1,86:46:bc:37:74:22 Lease:0x669ef0df}
I0721 17:36:36.049372 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6:cd:8e:d7:24:37 ID:1,6:cd:8e:d7:24:37 Lease:0x669ef0cc}
I0721 17:36:36.049380 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:d6:6f:d:80:8e:f4 ID:1,d6:6f:d:80:8e:f4 Lease:0x669eeca5}
I0721 17:36:36.049387 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ba:9d:5f:d7:4d:44 ID:1,ba:9d:5f:d7:4d:44 Lease:0x669d9a84}
I0721 17:36:36.049393 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:5e:54:4b:e2:e9:77 ID:1,5e:54:4b:e2:e9:77 Lease:0x669eea38}
I0721 17:36:37.864301 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:37 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I0721 17:36:37.864355 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:37 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I0721 17:36:37.864365 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:37 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I0721 17:36:37.887420 7400 main.go:141] libmachine: (false-579000) DBG | 2024/07/21 17:36:37 INFO : hyperkit: stderr: rdmsr to register 0xc0011029 on vcpu 1
I0721 17:36:38.051224 7400 main.go:141] libmachine: (false-579000) DBG | Attempt 3
I0721 17:36:38.051248 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:38.051394 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:38.052940 7400 main.go:141] libmachine: (false-579000) DBG | Searching for 12:21:9a:ad:33:58 in /var/db/dhcpd_leases ...
I0721 17:36:38.053053 7400 main.go:141] libmachine: (false-579000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0721 17:36:38.053068 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:22:a2:e7:d:b5:27 ID:1,22:a2:e7:d:b5:27 Lease:0x669efacf}
I0721 17:36:38.053119 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:3e:88:c:25:c2:8e ID:1,3e:88:c:25:c2:8e Lease:0x669efa56}
I0721 17:36:38.053133 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:f6:b0:ba:2b:d9:60 ID:1,f6:b0:ba:2b:d9:60 Lease:0x669efa3d}
I0721 17:36:38.053143 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:2a:7c:16:d9:f2:a4 ID:1,2a:7c:16:d9:f2:a4 Lease:0x669da8b2}
I0721 17:36:38.053153 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:76:3d:e1:23:b9:41 ID:1,76:3d:e1:23:b9:41 Lease:0x669ef9ff}
I0721 17:36:38.053161 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:c2:22:be:13:77:6d ID:1,c2:22:be:13:77:6d Lease:0x669da875}
I0721 17:36:38.053171 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:46:b0:41:2e:5c:fd ID:1,46:b0:41:2e:5c:fd Lease:0x669ef974}
I0721 17:36:38.053183 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:4e:89:bf:9a:9a:b1 ID:1,4e:89:bf:9a:9a:b1 Lease:0x669ef996}
I0721 17:36:38.053192 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:ba:9c:e:ad:75:25 ID:1,ba:9c:e:ad:75:25 Lease:0x669ef932}
I0721 17:36:38.053201 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:ea:8c:b1:c7:35:10 ID:1,ea:8c:b1:c7:35:10 Lease:0x669ef8b3}
I0721 17:36:38.053209 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:74:a4:6b:a6:8d ID:1,b2:74:a4:6b:a6:8d Lease:0x669ef87a}
I0721 17:36:38.053238 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:d2:57:d1:2:81:d5 ID:1,d2:57:d1:2:81:d5 Lease:0x669ef86a}
I0721 17:36:38.053247 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:9a:22:5e:a7:17:a9 ID:1,9a:22:5e:a7:17:a9 Lease:0x669da6f0}
I0721 17:36:38.053256 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:56:23:47:9b:eb:9b ID:1,56:23:47:9b:eb:9b Lease:0x669da6df}
I0721 17:36:38.053267 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:e:22:5d:e1:e7:1f ID:1,e:22:5d:e1:e7:1f Lease:0x669da6c0}
I0721 17:36:38.053299 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:52:8e:db:45:cb:ef ID:1,52:8e:db:45:cb:ef Lease:0x669ef809}
I0721 17:36:38.053325 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:ee:3:1d:14:7b:78 ID:1,ee:3:1d:14:7b:78 Lease:0x669ef797}
I0721 17:36:38.053343 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:1a:5d:bf:32:8f:a4 ID:1,1a:5d:bf:32:8f:a4 Lease:0x669ef729}
I0721 17:36:38.053353 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:7e:ae:41:39:79:19 ID:1,7e:ae:41:39:79:19 Lease:0x669ef6e2}
I0721 17:36:38.053363 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:d2:15:f:15:e4:c9 ID:1,d2:15:f:15:e4:c9 Lease:0x669ef60c}
I0721 17:36:38.053382 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:5e:2:13:a2:11:ad ID:1,5e:2:13:a2:11:ad Lease:0x669da37f}
I0721 17:36:38.053401 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:5a:3c:34:f8:5b:60 ID:1,5a:3c:34:f8:5b:60 Lease:0x669ef56f}
I0721 17:36:38.053412 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:fe:53:7:33:c:85 ID:1,fe:53:7:33:c:85 Lease:0x669ef51b}
I0721 17:36:38.053423 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:42:19:c4:6e:ab:2b ID:1,42:19:c4:6e:ab:2b Lease:0x669da1bf}
I0721 17:36:38.053434 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:aa:65:e5:11:58:85 ID:1,aa:65:e5:11:58:85 Lease:0x669da18f}
I0721 17:36:38.053443 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:72:49:13:5a:ba:1f ID:1,72:49:13:5a:ba:1f Lease:0x669ef2c0}
I0721 17:36:38.053455 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:2:66:9:8e:e1:2b ID:1,2:66:9:8e:e1:2b Lease:0x669ef298}
I0721 17:36:38.053463 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a:52:da:48:2:2b ID:1,a:52:da:48:2:2b Lease:0x669ef1e6}
I0721 17:36:38.053472 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:be:93:9:2a:bf:cf ID:1,be:93:9:2a:bf:cf Lease:0x669ef1b5}
I0721 17:36:38.053495 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ea:4a:cb:cd:5b:35 ID:1,ea:4a:cb:cd:5b:35 Lease:0x669ef132}
I0721 17:36:38.053503 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:b6:95:39:84:4f:df ID:1,b6:95:39:84:4f:df Lease:0x669ef119}
I0721 17:36:38.053519 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:fa:f4:8a:1d:7c:9 ID:1,fa:f4:8a:1d:7c:9 Lease:0x669d9f28}
I0721 17:36:38.053539 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:86:46:bc:37:74:22 ID:1,86:46:bc:37:74:22 Lease:0x669ef0df}
I0721 17:36:38.053560 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6:cd:8e:d7:24:37 ID:1,6:cd:8e:d7:24:37 Lease:0x669ef0cc}
I0721 17:36:38.053578 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:d6:6f:d:80:8e:f4 ID:1,d6:6f:d:80:8e:f4 Lease:0x669eeca5}
I0721 17:36:38.053590 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ba:9d:5f:d7:4d:44 ID:1,ba:9d:5f:d7:4d:44 Lease:0x669d9a84}
I0721 17:36:38.053602 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:5e:54:4b:e2:e9:77 ID:1,5e:54:4b:e2:e9:77 Lease:0x669eea38}
I0721 17:36:40.054244 7400 main.go:141] libmachine: (false-579000) DBG | Attempt 4
I0721 17:36:40.054258 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:40.054347 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:40.055214 7400 main.go:141] libmachine: (false-579000) DBG | Searching for 12:21:9a:ad:33:58 in /var/db/dhcpd_leases ...
I0721 17:36:40.055305 7400 main.go:141] libmachine: (false-579000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0721 17:36:40.055318 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:22:a2:e7:d:b5:27 ID:1,22:a2:e7:d:b5:27 Lease:0x669efacf}
I0721 17:36:40.055326 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:3e:88:c:25:c2:8e ID:1,3e:88:c:25:c2:8e Lease:0x669efa56}
I0721 17:36:40.055334 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:f6:b0:ba:2b:d9:60 ID:1,f6:b0:ba:2b:d9:60 Lease:0x669efa3d}
I0721 17:36:40.055343 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:2a:7c:16:d9:f2:a4 ID:1,2a:7c:16:d9:f2:a4 Lease:0x669da8b2}
I0721 17:36:40.055362 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:76:3d:e1:23:b9:41 ID:1,76:3d:e1:23:b9:41 Lease:0x669ef9ff}
I0721 17:36:40.055382 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:c2:22:be:13:77:6d ID:1,c2:22:be:13:77:6d Lease:0x669da875}
I0721 17:36:40.055395 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:46:b0:41:2e:5c:fd ID:1,46:b0:41:2e:5c:fd Lease:0x669ef974}
I0721 17:36:40.055441 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:4e:89:bf:9a:9a:b1 ID:1,4e:89:bf:9a:9a:b1 Lease:0x669ef996}
I0721 17:36:40.055455 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:ba:9c:e:ad:75:25 ID:1,ba:9c:e:ad:75:25 Lease:0x669ef932}
I0721 17:36:40.055482 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:ea:8c:b1:c7:35:10 ID:1,ea:8c:b1:c7:35:10 Lease:0x669ef8b3}
I0721 17:36:40.055494 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:74:a4:6b:a6:8d ID:1,b2:74:a4:6b:a6:8d Lease:0x669ef87a}
I0721 17:36:40.055503 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:d2:57:d1:2:81:d5 ID:1,d2:57:d1:2:81:d5 Lease:0x669ef86a}
I0721 17:36:40.055510 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:9a:22:5e:a7:17:a9 ID:1,9a:22:5e:a7:17:a9 Lease:0x669da6f0}
I0721 17:36:40.055516 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:56:23:47:9b:eb:9b ID:1,56:23:47:9b:eb:9b Lease:0x669da6df}
I0721 17:36:40.055523 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:e:22:5d:e1:e7:1f ID:1,e:22:5d:e1:e7:1f Lease:0x669da6c0}
I0721 17:36:40.055540 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:52:8e:db:45:cb:ef ID:1,52:8e:db:45:cb:ef Lease:0x669ef809}
I0721 17:36:40.055556 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:ee:3:1d:14:7b:78 ID:1,ee:3:1d:14:7b:78 Lease:0x669ef797}
I0721 17:36:40.055568 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:1a:5d:bf:32:8f:a4 ID:1,1a:5d:bf:32:8f:a4 Lease:0x669ef729}
I0721 17:36:40.055594 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:7e:ae:41:39:79:19 ID:1,7e:ae:41:39:79:19 Lease:0x669ef6e2}
I0721 17:36:40.055602 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:d2:15:f:15:e4:c9 ID:1,d2:15:f:15:e4:c9 Lease:0x669ef60c}
I0721 17:36:40.055608 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:5e:2:13:a2:11:ad ID:1,5e:2:13:a2:11:ad Lease:0x669da37f}
I0721 17:36:40.055619 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:5a:3c:34:f8:5b:60 ID:1,5a:3c:34:f8:5b:60 Lease:0x669ef56f}
I0721 17:36:40.055627 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:fe:53:7:33:c:85 ID:1,fe:53:7:33:c:85 Lease:0x669ef51b}
I0721 17:36:40.055646 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:42:19:c4:6e:ab:2b ID:1,42:19:c4:6e:ab:2b Lease:0x669da1bf}
I0721 17:36:40.055657 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:aa:65:e5:11:58:85 ID:1,aa:65:e5:11:58:85 Lease:0x669da18f}
I0721 17:36:40.055668 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:72:49:13:5a:ba:1f ID:1,72:49:13:5a:ba:1f Lease:0x669ef2c0}
I0721 17:36:40.055676 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:2:66:9:8e:e1:2b ID:1,2:66:9:8e:e1:2b Lease:0x669ef298}
I0721 17:36:40.055683 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a:52:da:48:2:2b ID:1,a:52:da:48:2:2b Lease:0x669ef1e6}
I0721 17:36:40.055688 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:be:93:9:2a:bf:cf ID:1,be:93:9:2a:bf:cf Lease:0x669ef1b5}
I0721 17:36:40.055699 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ea:4a:cb:cd:5b:35 ID:1,ea:4a:cb:cd:5b:35 Lease:0x669ef132}
I0721 17:36:40.055706 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:b6:95:39:84:4f:df ID:1,b6:95:39:84:4f:df Lease:0x669ef119}
I0721 17:36:40.055718 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:fa:f4:8a:1d:7c:9 ID:1,fa:f4:8a:1d:7c:9 Lease:0x669d9f28}
I0721 17:36:40.055726 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:86:46:bc:37:74:22 ID:1,86:46:bc:37:74:22 Lease:0x669ef0df}
I0721 17:36:40.055732 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6:cd:8e:d7:24:37 ID:1,6:cd:8e:d7:24:37 Lease:0x669ef0cc}
I0721 17:36:40.055738 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:d6:6f:d:80:8e:f4 ID:1,d6:6f:d:80:8e:f4 Lease:0x669eeca5}
I0721 17:36:40.055745 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ba:9d:5f:d7:4d:44 ID:1,ba:9d:5f:d7:4d:44 Lease:0x669d9a84}
I0721 17:36:40.055756 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:5e:54:4b:e2:e9:77 ID:1,5e:54:4b:e2:e9:77 Lease:0x669eea38}
I0721 17:36:42.055911 7400 main.go:141] libmachine: (false-579000) DBG | Attempt 5
I0721 17:36:42.055928 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:42.056030 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:42.056885 7400 main.go:141] libmachine: (false-579000) DBG | Searching for 12:21:9a:ad:33:58 in /var/db/dhcpd_leases ...
I0721 17:36:42.056971 7400 main.go:141] libmachine: (false-579000) DBG | Found 38 entries in /var/db/dhcpd_leases!
I0721 17:36:42.056983 7400 main.go:141] libmachine: (false-579000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:12:21:9a:ad:33:58 ID:1,12:21:9a:ad:33:58 Lease:0x669efb18}
I0721 17:36:42.056991 7400 main.go:141] libmachine: (false-579000) DBG | Found match: 12:21:9a:ad:33:58
I0721 17:36:42.056997 7400 main.go:141] libmachine: (false-579000) DBG | IP: 192.169.0.39
I0721 17:36:42.057039 7400 main.go:141] libmachine: (false-579000) Calling .GetConfigRaw
I0721 17:36:42.057646 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:42.057747 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:42.057827 7400 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0721 17:36:42.057834 7400 main.go:141] libmachine: (false-579000) Calling .GetState
I0721 17:36:42.057911 7400 main.go:141] libmachine: (false-579000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0721 17:36:42.057972 7400 main.go:141] libmachine: (false-579000) DBG | hyperkit pid from json: 7409
I0721 17:36:42.058821 7400 main.go:141] libmachine: Detecting operating system of created instance...
I0721 17:36:42.058833 7400 main.go:141] libmachine: Waiting for SSH to be available...
I0721 17:36:42.058841 7400 main.go:141] libmachine: Getting to WaitForSSH function...
I0721 17:36:42.058846 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:42.058929 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:42.059006 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:42.059088 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:42.059184 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:42.059300 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:42.059485 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:42.059493 7400 main.go:141] libmachine: About to run SSH command:
exit 0
I0721 17:36:42.079484 7400 main.go:141] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I0721 17:36:45.142255 7400 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0721 17:36:45.142277 7400 main.go:141] libmachine: Detecting the provisioner...
I0721 17:36:45.142284 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.142420 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.142523 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.142603 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.142701 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.142830 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:45.142993 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:45.143001 7400 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0721 17:36:45.205220 7400 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"
I0721 17:36:45.205274 7400 main.go:141] libmachine: found compatible host: buildroot
I0721 17:36:45.205281 7400 main.go:141] libmachine: Provisioning with buildroot...
I0721 17:36:45.205286 7400 main.go:141] libmachine: (false-579000) Calling .GetMachineName
I0721 17:36:45.205423 7400 buildroot.go:166] provisioning hostname "false-579000"
I0721 17:36:45.205434 7400 main.go:141] libmachine: (false-579000) Calling .GetMachineName
I0721 17:36:45.205518 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.205613 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.205695 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.205781 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.205863 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.206009 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:45.206159 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:45.206167 7400 main.go:141] libmachine: About to run SSH command:
sudo hostname false-579000 && echo "false-579000" | sudo tee /etc/hostname
I0721 17:36:45.279603 7400 main.go:141] libmachine: SSH cmd err, output: <nil>: false-579000
I0721 17:36:45.279621 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.279758 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.279842 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.279920 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.280007 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.280127 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:45.280261 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:45.280272 7400 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfalse-579000' /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 false-579000/g' /etc/hosts;
else
echo '127.0.1.1 false-579000' | sudo tee -a /etc/hosts;
fi
fi
I0721 17:36:45.349031 7400 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0721 17:36:45.349050 7400 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/19312-1133/.minikube CaCertPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/19312-1133/.minikube}
I0721 17:36:45.349064 7400 buildroot.go:174] setting up certificates
I0721 17:36:45.349083 7400 provision.go:84] configureAuth start
I0721 17:36:45.349091 7400 main.go:141] libmachine: (false-579000) Calling .GetMachineName
I0721 17:36:45.349228 7400 main.go:141] libmachine: (false-579000) Calling .GetIP
I0721 17:36:45.349325 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.349433 7400 provision.go:143] copyHostCerts
I0721 17:36:45.349547 7400 exec_runner.go:144] found /Users/jenkins/minikube-integration/19312-1133/.minikube/key.pem, removing ...
I0721 17:36:45.349560 7400 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19312-1133/.minikube/key.pem
I0721 17:36:45.349731 7400 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19312-1133/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/19312-1133/.minikube/key.pem (1675 bytes)
I0721 17:36:45.349972 7400 exec_runner.go:144] found /Users/jenkins/minikube-integration/19312-1133/.minikube/ca.pem, removing ...
I0721 17:36:45.349979 7400 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19312-1133/.minikube/ca.pem
I0721 17:36:45.350064 7400 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/19312-1133/.minikube/ca.pem (1078 bytes)
I0721 17:36:45.350227 7400 exec_runner.go:144] found /Users/jenkins/minikube-integration/19312-1133/.minikube/cert.pem, removing ...
I0721 17:36:45.350233 7400 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19312-1133/.minikube/cert.pem
I0721 17:36:45.350315 7400 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19312-1133/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/19312-1133/.minikube/cert.pem (1123 bytes)
I0721 17:36:45.350456 7400 provision.go:117] generating server cert: /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca-key.pem org=jenkins.false-579000 san=[127.0.0.1 192.169.0.39 false-579000 localhost minikube]
I0721 17:36:45.464521 7400 provision.go:177] copyRemoteCerts
I0721 17:36:45.464583 7400 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0721 17:36:45.464612 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.464761 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.464857 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.464946 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.465034 7400 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/id_rsa Username:docker}
I0721 17:36:45.502903 7400 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19312-1133/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0721 17:36:45.523552 7400 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/server.pem --> /etc/docker/server.pem (1204 bytes)
I0721 17:36:45.543879 7400 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19312-1133/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0721 17:36:45.564605 7400 provision.go:87] duration metric: took 215.500903ms to configureAuth
I0721 17:36:45.564620 7400 buildroot.go:189] setting minikube options for container-runtime
I0721 17:36:45.564745 7400 config.go:182] Loaded profile config "false-579000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.3
I0721 17:36:45.564759 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:45.564898 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.564987 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.565070 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.565173 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.565262 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.565380 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:45.565510 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:45.565518 7400 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0721 17:36:45.627958 7400 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0721 17:36:45.627970 7400 buildroot.go:70] root file system type: tmpfs
I0721 17:36:45.628033 7400 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0721 17:36:45.628046 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.628191 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.628299 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.628408 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.628522 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.628662 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:45.628822 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:45.628869 7400 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
I0721 17:36:45.702176 7400 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
I0721 17:36:45.702197 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:45.702331 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:45.702416 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.702517 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:45.702623 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:45.702783 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:45.702931 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:45.702944 7400 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; }
I0721 17:36:47.355605 7400 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.
I0721 17:36:47.355621 7400 main.go:141] libmachine: Checking connection to Docker...
I0721 17:36:47.355627 7400 main.go:141] libmachine: (false-579000) Calling .GetURL
I0721 17:36:47.355790 7400 main.go:141] libmachine: Docker is up and running!
I0721 17:36:47.355802 7400 main.go:141] libmachine: Reticulating splines...
I0721 17:36:47.355807 7400 client.go:171] duration metric: took 15.941737442s to LocalClient.Create
I0721 17:36:47.355821 7400 start.go:167] duration metric: took 15.941782301s to libmachine.API.Create "false-579000"
I0721 17:36:47.355831 7400 start.go:293] postStartSetup for "false-579000" (driver="hyperkit")
I0721 17:36:47.355838 7400 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]
I0721 17:36:47.355848 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:47.355996 7400 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
I0721 17:36:47.356009 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:47.356096 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:47.356188 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:47.356270 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:47.356366 7400 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/id_rsa Username:docker}
I0721 17:36:47.395386 7400 ssh_runner.go:195] Run: cat /etc/os-release
I0721 17:36:47.398747 7400 info.go:137] Remote host: Buildroot 2023.02.9
I0721 17:36:47.398767 7400 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19312-1133/.minikube/addons for local assets ...
I0721 17:36:47.398856 7400 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19312-1133/.minikube/files for local assets ...
I0721 17:36:47.399046 7400 filesync.go:149] local asset: /Users/jenkins/minikube-integration/19312-1133/.minikube/files/etc/ssl/certs/16742.pem -> 16742.pem in /etc/ssl/certs
I0721 17:36:47.399263 7400 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0721 17:36:47.407327 7400 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19312-1133/.minikube/files/etc/ssl/certs/16742.pem --> /etc/ssl/certs/16742.pem (1708 bytes)
I0721 17:36:47.429257 7400 start.go:296] duration metric: took 73.416104ms for postStartSetup
I0721 17:36:47.429292 7400 main.go:141] libmachine: (false-579000) Calling .GetConfigRaw
I0721 17:36:47.429966 7400 main.go:141] libmachine: (false-579000) Calling .GetIP
I0721 17:36:47.430124 7400 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19312-1133/.minikube/profiles/false-579000/config.json ...
I0721 17:36:47.430472 7400 start.go:128] duration metric: took 16.047871779s to createHost
I0721 17:36:47.430486 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:47.430580 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:47.430661 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:47.430771 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:47.430864 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:47.430971 7400 main.go:141] libmachine: Using SSH client type: native
I0721 17:36:47.431147 7400 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb0970c0] 0xb099e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0721 17:36:47.431155 7400 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0721 17:36:47.493592 7400 main.go:141] libmachine: SSH cmd err, output: <nil>: 1721608607.551054816
I0721 17:36:47.493612 7400 fix.go:216] guest clock: 1721608607.551054816
I0721 17:36:47.493618 7400 fix.go:229] Guest: 2024-07-21 17:36:47.551054816 -0700 PDT Remote: 2024-07-21 17:36:47.430481 -0700 PDT m=+16.671087319 (delta=120.573816ms)
I0721 17:36:47.493637 7400 fix.go:200] guest clock delta is within tolerance: 120.573816ms
I0721 17:36:47.493641 7400 start.go:83] releasing machines lock for "false-579000", held for 16.1111744s
I0721 17:36:47.493662 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:47.493804 7400 main.go:141] libmachine: (false-579000) Calling .GetIP
I0721 17:36:47.493900 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:47.494200 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:47.494295 7400 main.go:141] libmachine: (false-579000) Calling .DriverName
I0721 17:36:47.494370 7400 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0721 17:36:47.494399 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:47.494429 7400 ssh_runner.go:195] Run: cat /version.json
I0721 17:36:47.494441 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHHostname
I0721 17:36:47.494486 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:47.494545 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHPort
I0721 17:36:47.494584 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:47.494640 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHKeyPath
I0721 17:36:47.494685 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:47.494734 7400 main.go:141] libmachine: (false-579000) Calling .GetSSHUsername
I0721 17:36:47.494763 7400 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/id_rsa Username:docker}
I0721 17:36:47.494810 7400 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19312-1133/.minikube/machines/false-579000/id_rsa Username:docker}
I0721 17:36:47.533511 7400 ssh_runner.go:195] Run: systemctl --version
I0721 17:36:47.537851 7400 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0721 17:36:47.542921 7400 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0721 17:36:47.542974 7400 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f -name *bridge* -not -name *podman* -not -name *.mk_disabled -printf "%p, " -exec sh -c "sudo sed -i -r -e '/"dst": ".*:.*"/d' -e 's|^(.*)"dst": (.*)[,*]$|\1"dst": \2|g' -e '/"subnet": ".*:.*"/d' -e 's|^(.*)"subnet": ".*"(.*)[,*]$|\1"subnet": "10.244.0.0/16"\2|g' {}" ;
I0721 17:36:47.591349 7400 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f -name *podman* -not -name *.mk_disabled -printf "%p, " -exec sh -c "sudo sed -i -r -e 's|^(.*)"subnet": ".*"(.*)$|\1"subnet": "10.244.0.0/16"\2|g' -e 's|^(.*)"gateway": ".*"(.*)$|\1"gateway": "10.244.0.1"\2|g' {}" ;
I0721 17:36:47.604501 7400 cni.go:308] configured [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0721 17:36:47.604517 7400 start.go:495] detecting cgroup driver to use...
I0721 17:36:47.604628 7400 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"
I0721 17:36:47.623613 7400 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"
I0721 17:36:47.634846 7400 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"
I0721 17:36:47.644046 7400 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0721 17:36:47.644104 7400 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0721 17:36:47.652954 7400 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"
I0721 17:36:47.661996 7400 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0721 17:36:47.671005 7400 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0721 17:36:47.681405 7400 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0721 17:36:47.691426 7400 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"
I0721 17:36:47.700536 7400 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0721 17:36:47.709864 7400 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"
I0721 17:36:47.718950 7400 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0721 17:36:47.727015 7400 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0721 17:36:47.735029 7400 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0721 17:36:47.832981 7400 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0721 17:36:47.850813 7400 start.go:495] detecting cgroup driver to use...
I0721 17:36:47.850890 7400 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0721 17:36:47.871163 7400 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0721 17:36:47.887946 7400 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0721 17:36:47.908421 7400 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0721 17:36:47.921651 7400 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0721 17:36:47.933538 7400 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0721 17:36:47.960069 7400 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0721 17:36:47.972335 7400 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"
I0721 17:36:47.987662 7400 ssh_runner.go:195] Run: which cri-dockerd
I0721 17:36:47.990622 7400 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0721 17:36:47.998858 7400 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0721 17:36:48.012505 7400 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0721 17:36:48.108891 7400 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0721 17:36:48.211236 7400 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0721 17:36:48.211308 7400 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0721 17:36:48.226080 7400 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0721 17:36:48.338337 7400 ssh_runner.go:195] Run: sudo systemctl restart docker
I0721 17:37:49.362177 7400 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.022778944s)
I0721 17:37:49.362244 7400 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0721 17:37:49.399488 7400 out.go:177]
W0721 17:37:49.420881 7400 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 --
Jul 22 00:36:46 false-579000 systemd[1]: Starting Docker Application Container Engine...
Jul 22 00:36:46 false-579000 dockerd[537]: time="2024-07-22T00:36:46.100479542Z" level=info msg="Starting up"
Jul 22 00:36:46 false-579000 dockerd[537]: time="2024-07-22T00:36:46.100896890Z" level=info msg="containerd not running, starting managed containerd"
Jul 22 00:36:46 false-579000 dockerd[537]: time="2024-07-22T00:36:46.101440902Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=546
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.118778975Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134206502Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134256249Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134305577Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134316164Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134379816Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134412573Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134548637Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134585147Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134600272Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134608077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134669307Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134842765Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136331319Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136369531Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136461554Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136519619Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136590756Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136658589Z" level=info msg="metadata content store policy set" policy=shared
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.151918748Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152043378Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152058612Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152069245Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152079124Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152170021Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152358786Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152439089Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152473547Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152484478Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152495846Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152504781Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152517111Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152531653Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152541158Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152551759Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152561769Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152569031Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152582975Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152592263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152600543Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152609565Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152617071Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152626522Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152634044Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152645523Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152659036Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152669989Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152677488Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152686377Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152706422Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152719018Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152742329Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152753828Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152766460Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152819349Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152834678Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152842290Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152855261Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152864686Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152873120Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152879852Z" level=info msg="NRI interface is disabled by configuration."
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153045737Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153078891Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153105414Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153119346Z" level=info msg="containerd successfully booted in 0.035155s"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.190843508Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.200208770Z" level=info msg="Loading containers: start."
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.284358849Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.374162691Z" level=info msg="Loading containers: done."
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.381382314Z" level=info msg="Docker daemon" commit=662f78c containerd-snapshotter=false storage-driver=overlay2 version=27.0.3
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.381470336Z" level=info msg="Daemon has completed initialization"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.411786515Z" level=info msg="API listen on /var/run/docker.sock"
Jul 22 00:36:47 false-579000 systemd[1]: Started Docker Application Container Engine.
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.412362302Z" level=info msg="API listen on [::]:2376"
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.408973107Z" level=info msg="Processing signal 'terminated'"
Jul 22 00:36:48 false-579000 systemd[1]: Stopping Docker Application Container Engine...
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410106491Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410567823Z" level=info msg="Daemon shutdown complete"
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410686939Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410704205Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jul 22 00:36:49 false-579000 systemd[1]: docker.service: Deactivated successfully.
Jul 22 00:36:49 false-579000 systemd[1]: Stopped Docker Application Container Engine.
Jul 22 00:36:49 false-579000 systemd[1]: Starting Docker Application Container Engine...
Jul 22 00:36:49 false-579000 dockerd[942]: time="2024-07-22T00:36:49.446797848Z" level=info msg="Starting up"
Jul 22 00:37:49 false-579000 dockerd[942]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jul 22 00:37:49 false-579000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jul 22 00:37:49 false-579000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jul 22 00:37:49 false-579000 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 --
Jul 22 00:36:46 false-579000 systemd[1]: Starting Docker Application Container Engine...
Jul 22 00:36:46 false-579000 dockerd[537]: time="2024-07-22T00:36:46.100479542Z" level=info msg="Starting up"
Jul 22 00:36:46 false-579000 dockerd[537]: time="2024-07-22T00:36:46.100896890Z" level=info msg="containerd not running, starting managed containerd"
Jul 22 00:36:46 false-579000 dockerd[537]: time="2024-07-22T00:36:46.101440902Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=546
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.118778975Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134206502Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134256249Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134305577Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134316164Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134379816Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134412573Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134548637Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134585147Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134600272Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134608077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134669307Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.134842765Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136331319Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136369531Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136461554Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136519619Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136590756Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.136658589Z" level=info msg="metadata content store policy set" policy=shared
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.151918748Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152043378Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152058612Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152069245Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152079124Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152170021Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152358786Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152439089Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152473547Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152484478Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152495846Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152504781Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152517111Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152531653Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152541158Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152551759Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152561769Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152569031Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152582975Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152592263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152600543Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152609565Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152617071Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152626522Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152634044Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152645523Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152659036Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152669989Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152677488Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152686377Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152706422Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152719018Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152742329Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152753828Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152766460Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152819349Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152834678Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152842290Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152855261Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152864686Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152873120Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.152879852Z" level=info msg="NRI interface is disabled by configuration."
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153045737Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153078891Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153105414Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jul 22 00:36:46 false-579000 dockerd[546]: time="2024-07-22T00:36:46.153119346Z" level=info msg="containerd successfully booted in 0.035155s"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.190843508Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.200208770Z" level=info msg="Loading containers: start."
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.284358849Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.374162691Z" level=info msg="Loading containers: done."
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.381382314Z" level=info msg="Docker daemon" commit=662f78c containerd-snapshotter=false storage-driver=overlay2 version=27.0.3
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.381470336Z" level=info msg="Daemon has completed initialization"
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.411786515Z" level=info msg="API listen on /var/run/docker.sock"
Jul 22 00:36:47 false-579000 systemd[1]: Started Docker Application Container Engine.
Jul 22 00:36:47 false-579000 dockerd[537]: time="2024-07-22T00:36:47.412362302Z" level=info msg="API listen on [::]:2376"
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.408973107Z" level=info msg="Processing signal 'terminated'"
Jul 22 00:36:48 false-579000 systemd[1]: Stopping Docker Application Container Engine...
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410106491Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410567823Z" level=info msg="Daemon shutdown complete"
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410686939Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jul 22 00:36:48 false-579000 dockerd[537]: time="2024-07-22T00:36:48.410704205Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jul 22 00:36:49 false-579000 systemd[1]: docker.service: Deactivated successfully.
Jul 22 00:36:49 false-579000 systemd[1]: Stopped Docker Application Container Engine.
Jul 22 00:36:49 false-579000 systemd[1]: Starting Docker Application Container Engine...
Jul 22 00:36:49 false-579000 dockerd[942]: time="2024-07-22T00:36:49.446797848Z" level=info msg="Starting up"
Jul 22 00:37:49 false-579000 dockerd[942]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jul 22 00:37:49 false-579000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jul 22 00:37:49 false-579000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jul 22 00:37:49 false-579000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0721 17:37:49.421005 7400 out.go:239] *
*
W0721 17:37:49.422384 7400 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0721 17:37:49.485601 7400 out.go:177]
** /stderr **
net_test.go:114: failed start: exit status 90
--- FAIL: TestNetworkPlugins/group/false/Start (78.81s)