=== RUN TestNetworkPlugins/group/false/Start
net_test.go:112: (dbg) Run: out/minikube-darwin-amd64 start -p false-709000 --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-709000 --memory=3072 --alsologtostderr --wait=true --wait-timeout=15m --cni=false --driver=hyperkit : exit status 90 (1m15.758978886s)
-- stdout --
* [false-709000] minikube v1.33.1 on Darwin 14.5
- MINIKUBE_LOCATION=19302
- KUBECONFIG=/Users/jenkins/minikube-integration/19302-1411/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/19302-1411/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting "false-709000" primary control-plane node in "false-709000" cluster
* Creating hyperkit VM (CPUs=2, Memory=3072MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I0718 21:31:35.245415 7353 out.go:291] Setting OutFile to fd 1 ...
I0718 21:31:35.245737 7353 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0718 21:31:35.245743 7353 out.go:304] Setting ErrFile to fd 2...
I0718 21:31:35.245747 7353 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0718 21:31:35.245947 7353 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/19302-1411/.minikube/bin
I0718 21:31:35.247787 7353 out.go:298] Setting JSON to false
I0718 21:31:35.272337 7353 start.go:129] hostinfo: {"hostname":"MacOS-Agent-4.local","uptime":5469,"bootTime":1721358026,"procs":521,"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"}
W0718 21:31:35.272446 7353 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0718 21:31:35.348193 7353 out.go:177] * [false-709000] minikube v1.33.1 on Darwin 14.5
I0718 21:31:35.390771 7353 notify.go:220] Checking for updates...
I0718 21:31:35.415349 7353 out.go:177] - MINIKUBE_LOCATION=19302
I0718 21:31:35.477796 7353 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/19302-1411/kubeconfig
I0718 21:31:35.499578 7353 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0718 21:31:35.522585 7353 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0718 21:31:35.543606 7353 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/19302-1411/.minikube
I0718 21:31:35.564523 7353 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0718 21:31:35.586470 7353 config.go:182] Loaded profile config "calico-709000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.3
I0718 21:31:35.586664 7353 driver.go:392] Setting default libvirt URI to qemu:///system
I0718 21:31:35.616560 7353 out.go:177] * Using the hyperkit driver based on user configuration
I0718 21:31:35.658517 7353 start.go:297] selected driver: hyperkit
I0718 21:31:35.658542 7353 start.go:901] validating driver "hyperkit" against <nil>
I0718 21:31:35.658583 7353 start.go:912] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0718 21:31:35.662954 7353 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0718 21:31:35.663112 7353 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/19302-1411/.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
I0718 21:31:35.671697 7353 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.33.1
I0718 21:31:35.675953 7353 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0718 21:31:35.676006 7353 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0718 21:31:35.676037 7353 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0718 21:31:35.676245 7353 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]
I0718 21:31:35.676289 7353 cni.go:84] Creating CNI manager for "false"
I0718 21:31:35.676347 7353 start.go:340] cluster config:
{Name:false-709000 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-709000 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}
I0718 21:31:35.676456 7353 iso.go:125] acquiring lock: {Name:mka3a56e9fb30ac1fad44235cb5c998fd919cd8d Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0718 21:31:35.718441 7353 out.go:177] * Starting "false-709000" primary control-plane node in "false-709000" cluster
I0718 21:31:35.739514 7353 preload.go:131] Checking if preload exists for k8s version v1.30.3 and runtime docker
I0718 21:31:35.739583 7353 preload.go:146] Found local preload: /Users/jenkins/minikube-integration/19302-1411/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.3-docker-overlay2-amd64.tar.lz4
I0718 21:31:35.739615 7353 cache.go:56] Caching tarball of preloaded images
I0718 21:31:35.739828 7353 preload.go:172] Found /Users/jenkins/minikube-integration/19302-1411/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0718 21:31:35.739850 7353 cache.go:59] Finished verifying existence of preloaded tar for v1.30.3 on docker
I0718 21:31:35.740038 7353 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19302-1411/.minikube/profiles/false-709000/config.json ...
I0718 21:31:35.740076 7353 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/19302-1411/.minikube/profiles/false-709000/config.json: {Name:mkb3f13d486cf51287e11cdd1d869b2a87b1dc9c Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0718 21:31:35.740721 7353 start.go:360] acquireMachinesLock for false-709000: {Name:mk8a0ac4b11cd5d9eba5ac8b9ae33317742f9112 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0718 21:31:35.740835 7353 start.go:364] duration metric: took 93.477µs to acquireMachinesLock for "false-709000"
I0718 21:31:35.740874 7353 start.go:93] Provisioning new machine with config: &{Name:false-709000 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-709000 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}
I0718 21:31:35.740965 7353 start.go:125] createHost starting for "" (driver="hyperkit")
I0718 21:31:35.783479 7353 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=3072MB, Disk=20000MB) ...
I0718 21:31:35.783748 7353 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0718 21:31:35.783822 7353 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0718 21:31:35.793909 7353 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:55576
I0718 21:31:35.794280 7353 main.go:141] libmachine: () Calling .GetVersion
I0718 21:31:35.794703 7353 main.go:141] libmachine: Using API Version 1
I0718 21:31:35.794719 7353 main.go:141] libmachine: () Calling .SetConfigRaw
I0718 21:31:35.794933 7353 main.go:141] libmachine: () Calling .GetMachineName
I0718 21:31:35.795056 7353 main.go:141] libmachine: (false-709000) Calling .GetMachineName
I0718 21:31:35.795155 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:35.795260 7353 start.go:159] libmachine.API.Create for "false-709000" (driver="hyperkit")
I0718 21:31:35.795284 7353 client.go:168] LocalClient.Create starting
I0718 21:31:35.795316 7353 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca.pem
I0718 21:31:35.795367 7353 main.go:141] libmachine: Decoding PEM data...
I0718 21:31:35.795381 7353 main.go:141] libmachine: Parsing certificate...
I0718 21:31:35.795430 7353 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/19302-1411/.minikube/certs/cert.pem
I0718 21:31:35.795470 7353 main.go:141] libmachine: Decoding PEM data...
I0718 21:31:35.795483 7353 main.go:141] libmachine: Parsing certificate...
I0718 21:31:35.795495 7353 main.go:141] libmachine: Running pre-create checks...
I0718 21:31:35.795500 7353 main.go:141] libmachine: (false-709000) Calling .PreCreateCheck
I0718 21:31:35.795586 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:35.795809 7353 main.go:141] libmachine: (false-709000) Calling .GetConfigRaw
I0718 21:31:35.796277 7353 main.go:141] libmachine: Creating machine...
I0718 21:31:35.796286 7353 main.go:141] libmachine: (false-709000) Calling .Create
I0718 21:31:35.796352 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:35.796468 7353 main.go:141] libmachine: (false-709000) DBG | I0718 21:31:35.796347 7363 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/19302-1411/.minikube
I0718 21:31:35.796526 7353 main.go:141] libmachine: (false-709000) Downloading /Users/jenkins/minikube-integration/19302-1411/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/19302-1411/.minikube/cache/iso/amd64/minikube-v1.33.1-1721324531-19298-amd64.iso...
I0718 21:31:35.975549 7353 main.go:141] libmachine: (false-709000) DBG | I0718 21:31:35.975485 7363 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/id_rsa...
I0718 21:31:36.145580 7353 main.go:141] libmachine: (false-709000) DBG | I0718 21:31:36.145492 7363 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/false-709000.rawdisk...
I0718 21:31:36.145595 7353 main.go:141] libmachine: (false-709000) DBG | Writing magic tar header
I0718 21:31:36.145603 7353 main.go:141] libmachine: (false-709000) DBG | Writing SSH key tar header
I0718 21:31:36.146351 7353 main.go:141] libmachine: (false-709000) DBG | I0718 21:31:36.146265 7363 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000 ...
I0718 21:31:36.505108 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:36.505128 7353 main.go:141] libmachine: (false-709000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/hyperkit.pid
I0718 21:31:36.505154 7353 main.go:141] libmachine: (false-709000) DBG | Using UUID 9f9675a6-2456-4af3-8b01-3e7edb4067c9
I0718 21:31:36.535303 7353 main.go:141] libmachine: (false-709000) DBG | Generated MAC be:8:7d:e5:59:9a
I0718 21:31:36.535320 7353 main.go:141] libmachine: (false-709000) 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-709000
I0718 21:31:36.535363 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"9f9675a6-2456-4af3-8b01-3e7edb4067c9", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0001d0240)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/bzimage", Initrd:"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/initrd", Bootrom:"", CPUs:2, Memory:3072, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0718 21:31:36.535398 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"9f9675a6-2456-4af3-8b01-3e7edb4067c9", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0001d0240)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/bzimage", Initrd:"/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/initrd", Bootrom:"", CPUs:2, Memory:3072, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0718 21:31:36.535437 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/hyperkit.pid", "-c", "2", "-m", "3072M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "9f9675a6-2456-4af3-8b01-3e7edb4067c9", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/false-709000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/tty,log=/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/bzimage,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-
709000/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-709000"}
I0718 21:31:36.535467 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/hyperkit.pid -c 2 -m 3072M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 9f9675a6-2456-4af3-8b01-3e7edb4067c9 -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/false-709000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/tty,log=/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/console-ring -f kexec,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/bzimage,/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/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-709000"
I0718 21:31:36.535481 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0718 21:31:36.538286 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 DEBUG: hyperkit: Pid is 7364
I0718 21:31:36.538740 7353 main.go:141] libmachine: (false-709000) DBG | Attempt 0
I0718 21:31:36.538753 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:36.538908 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:36.539855 7353 main.go:141] libmachine: (false-709000) DBG | Searching for be:8:7d:e5:59:9a in /var/db/dhcpd_leases ...
I0718 21:31:36.539972 7353 main.go:141] libmachine: (false-709000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0718 21:31:36.539993 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:76:69:1e:0:fd:8c ID:1,76:69:1e:0:fd:8c Lease:0x669b3d56}
I0718 21:31:36.540010 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:8a:78:d2:74:27:42 ID:1,8a:78:d2:74:27:42 Lease:0x669b3d2e}
I0718 21:31:36.540021 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:da:ad:aa:85:e4:ef ID:1,da:ad:aa:85:e4:ef Lease:0x669b3d03}
I0718 21:31:36.540032 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:8a:4c:82:b5:b9:fe ID:1,8a:4c:82:b5:b9:fe Lease:0x669b3cd2}
I0718 21:31:36.540043 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:72:fb:d0:92:80:5e ID:1,72:fb:d0:92:80:5e Lease:0x6699eb5f}
I0718 21:31:36.540054 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:42:4f:ae:89:6:58 ID:1,42:4f:ae:89:6:58 Lease:0x669b3c49}
I0718 21:31:36.540067 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:2e:a0:3:ba:bd:c9 ID:1,2e:a0:3:ba:bd:c9 Lease:0x669b3c6a}
I0718 21:31:36.540078 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:86:4b:29:be:5b:d8 ID:1,86:4b:29:be:5b:d8 Lease:0x669b3c08}
I0718 21:31:36.540089 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:56:b7:46:b5:45:39 ID:1,56:b7:46:b5:45:39 Lease:0x669b3b83}
I0718 21:31:36.540099 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:52:2f:eb:ef:10:d ID:1,52:2f:eb:ef:10:d Lease:0x669b3b4c}
I0718 21:31:36.540120 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:3e:79:98:ae:b1:97 ID:1,3e:79:98:ae:b1:97 Lease:0x669b3b3e}
I0718 21:31:36.540144 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:16:a3:68:1b:ee:2f ID:1,16:a3:68:1b:ee:2f Lease:0x6699e9bf}
I0718 21:31:36.540166 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:2:aa:9e:1e:9c:88 ID:1,2:aa:9e:1e:9c:88 Lease:0x669b3b12}
I0718 21:31:36.540182 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:fa:57:50:16:6f:5d ID:1,fa:57:50:16:6f:5d Lease:0x6699e98c}
I0718 21:31:36.540200 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:1e:af:f7:18:b5:e8 ID:1,1e:af:f7:18:b5:e8 Lease:0x669b3ad5}
I0718 21:31:36.540215 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:6e:17:17:e8:8d:a7 ID:1,6e:17:17:e8:8d:a7 Lease:0x669b3a67}
I0718 21:31:36.540231 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:36:c7:27:fa:6a:4e ID:1,36:c7:27:fa:6a:4e Lease:0x669b3a12}
I0718 21:31:36.540243 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:46:fe:ea:f2:ee:32 ID:1,46:fe:ea:f2:ee:32 Lease:0x669b39e5}
I0718 21:31:36.540256 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:2:b9:df:ce:6b:51 ID:1,2:b9:df:ce:6b:51 Lease:0x6699e7c9}
I0718 21:31:36.540265 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:6:4c:de:4f:d8:27 ID:1,6:4c:de:4f:d8:27 Lease:0x6699e6d1}
I0718 21:31:36.540276 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:3a:d2:59:42:45:2c ID:1,3a:d2:59:42:45:2c Lease:0x6699e7d7}
I0718 21:31:36.540296 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:d2:e2:11:67:74:1c ID:1,d2:e2:11:67:74:1c Lease:0x669b386d}
I0718 21:31:36.540313 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:a:aa:dd:3b:99:bf ID:1,a:aa:dd:3b:99:bf Lease:0x6699e547}
I0718 21:31:36.540328 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:72:c0:82:86:ae:7b ID:1,72:c0:82:86:ae:7b Lease:0x6699e52d}
I0718 21:31:36.540349 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:66:6c:75:31:3:1a ID:1,66:6c:75:31:3:1a Lease:0x6699e4fd}
I0718 21:31:36.540362 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:4a:cc:c:f8:b6:77 ID:1,4a:cc:c:f8:b6:77 Lease:0x669b35c6}
I0718 21:31:36.540375 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:7e:eb:ab:51:e7:9a ID:1,7e:eb:ab:51:e7:9a Lease:0x669b35b5}
I0718 21:31:36.540383 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a2:11:11:3f:ac:6d ID:1,a2:11:11:3f:ac:6d Lease:0x669b3576}
I0718 21:31:36.540395 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:ce:10:ef:df:f5:dd ID:1,ce:10:ef:df:f5:dd Lease:0x669b3547}
I0718 21:31:36.540407 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ce:83:63:32:49:35 ID:1,ce:83:63:32:49:35 Lease:0x669b34e5}
I0718 21:31:36.540418 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:da:90:24:7c:c3:59 ID:1,da:90:24:7c:c3:59 Lease:0x669b34ca}
I0718 21:31:36.540486 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:9e:c5:b:2c:73:ff ID:1,9e:c5:b:2c:73:ff Lease:0x6699e275}
I0718 21:31:36.540516 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:7a:cb:90:8a:a3:a2 ID:1,7a:cb:90:8a:a3:a2 Lease:0x669b34a2}
I0718 21:31:36.540530 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:32:a2:3f:92:70:d7 ID:1,32:a2:3f:92:70:d7 Lease:0x669b341a}
I0718 21:31:36.540544 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:e:d3:ca:79:1e:6d ID:1,e:d3:ca:79:1e:6d Lease:0x669b30df}
I0718 21:31:36.540562 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ca:fb:e4:e6:3d:70 ID:1,ca:fb:e4:e6:3d:70 Lease:0x6699debd}
I0718 21:31:36.540577 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:de:8a:ce:de:23:52 ID:1,de:8a:ce:de:23:52 Lease:0x669b2e44}
I0718 21:31:36.545824 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0718 21:31:36.554123 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0718 21:31:36.554970 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0718 21:31:36.555003 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0718 21:31:36.555021 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0718 21:31:36.555034 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0718 21:31:36.954573 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0718 21:31:36.954588 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:36 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0718 21:31:37.069011 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:37 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0718 21:31:37.069031 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:37 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0718 21:31:37.069057 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:37 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0718 21:31:37.069067 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:37 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0718 21:31:37.069892 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:37 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0718 21:31:37.069901 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:37 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0718 21:31:38.540946 7353 main.go:141] libmachine: (false-709000) DBG | Attempt 1
I0718 21:31:38.540962 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:38.541033 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:38.541893 7353 main.go:141] libmachine: (false-709000) DBG | Searching for be:8:7d:e5:59:9a in /var/db/dhcpd_leases ...
I0718 21:31:38.541950 7353 main.go:141] libmachine: (false-709000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0718 21:31:38.541965 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:76:69:1e:0:fd:8c ID:1,76:69:1e:0:fd:8c Lease:0x669b3d56}
I0718 21:31:38.541989 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:8a:78:d2:74:27:42 ID:1,8a:78:d2:74:27:42 Lease:0x669b3d2e}
I0718 21:31:38.541998 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:da:ad:aa:85:e4:ef ID:1,da:ad:aa:85:e4:ef Lease:0x669b3d03}
I0718 21:31:38.542009 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:8a:4c:82:b5:b9:fe ID:1,8a:4c:82:b5:b9:fe Lease:0x669b3cd2}
I0718 21:31:38.542019 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:72:fb:d0:92:80:5e ID:1,72:fb:d0:92:80:5e Lease:0x6699eb5f}
I0718 21:31:38.542028 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:42:4f:ae:89:6:58 ID:1,42:4f:ae:89:6:58 Lease:0x669b3c49}
I0718 21:31:38.542036 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:2e:a0:3:ba:bd:c9 ID:1,2e:a0:3:ba:bd:c9 Lease:0x669b3c6a}
I0718 21:31:38.542044 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:86:4b:29:be:5b:d8 ID:1,86:4b:29:be:5b:d8 Lease:0x669b3c08}
I0718 21:31:38.542054 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:56:b7:46:b5:45:39 ID:1,56:b7:46:b5:45:39 Lease:0x669b3b83}
I0718 21:31:38.542062 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:52:2f:eb:ef:10:d ID:1,52:2f:eb:ef:10:d Lease:0x669b3b4c}
I0718 21:31:38.542068 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:3e:79:98:ae:b1:97 ID:1,3e:79:98:ae:b1:97 Lease:0x669b3b3e}
I0718 21:31:38.542077 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:16:a3:68:1b:ee:2f ID:1,16:a3:68:1b:ee:2f Lease:0x6699e9bf}
I0718 21:31:38.542082 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:2:aa:9e:1e:9c:88 ID:1,2:aa:9e:1e:9c:88 Lease:0x669b3b12}
I0718 21:31:38.542096 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:fa:57:50:16:6f:5d ID:1,fa:57:50:16:6f:5d Lease:0x6699e98c}
I0718 21:31:38.542104 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:1e:af:f7:18:b5:e8 ID:1,1e:af:f7:18:b5:e8 Lease:0x669b3ad5}
I0718 21:31:38.542111 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:6e:17:17:e8:8d:a7 ID:1,6e:17:17:e8:8d:a7 Lease:0x669b3a67}
I0718 21:31:38.542116 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:36:c7:27:fa:6a:4e ID:1,36:c7:27:fa:6a:4e Lease:0x669b3a12}
I0718 21:31:38.542130 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:46:fe:ea:f2:ee:32 ID:1,46:fe:ea:f2:ee:32 Lease:0x669b39e5}
I0718 21:31:38.542138 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:2:b9:df:ce:6b:51 ID:1,2:b9:df:ce:6b:51 Lease:0x6699e7c9}
I0718 21:31:38.542149 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:6:4c:de:4f:d8:27 ID:1,6:4c:de:4f:d8:27 Lease:0x6699e6d1}
I0718 21:31:38.542157 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:3a:d2:59:42:45:2c ID:1,3a:d2:59:42:45:2c Lease:0x6699e7d7}
I0718 21:31:38.542164 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:d2:e2:11:67:74:1c ID:1,d2:e2:11:67:74:1c Lease:0x669b386d}
I0718 21:31:38.542173 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:a:aa:dd:3b:99:bf ID:1,a:aa:dd:3b:99:bf Lease:0x6699e547}
I0718 21:31:38.542189 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:72:c0:82:86:ae:7b ID:1,72:c0:82:86:ae:7b Lease:0x6699e52d}
I0718 21:31:38.542201 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:66:6c:75:31:3:1a ID:1,66:6c:75:31:3:1a Lease:0x6699e4fd}
I0718 21:31:38.542209 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:4a:cc:c:f8:b6:77 ID:1,4a:cc:c:f8:b6:77 Lease:0x669b35c6}
I0718 21:31:38.542216 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:7e:eb:ab:51:e7:9a ID:1,7e:eb:ab:51:e7:9a Lease:0x669b35b5}
I0718 21:31:38.542222 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a2:11:11:3f:ac:6d ID:1,a2:11:11:3f:ac:6d Lease:0x669b3576}
I0718 21:31:38.542234 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:ce:10:ef:df:f5:dd ID:1,ce:10:ef:df:f5:dd Lease:0x669b3547}
I0718 21:31:38.542246 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ce:83:63:32:49:35 ID:1,ce:83:63:32:49:35 Lease:0x669b34e5}
I0718 21:31:38.542261 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:da:90:24:7c:c3:59 ID:1,da:90:24:7c:c3:59 Lease:0x669b34ca}
I0718 21:31:38.542268 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:9e:c5:b:2c:73:ff ID:1,9e:c5:b:2c:73:ff Lease:0x6699e275}
I0718 21:31:38.542274 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:7a:cb:90:8a:a3:a2 ID:1,7a:cb:90:8a:a3:a2 Lease:0x669b34a2}
I0718 21:31:38.542288 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:32:a2:3f:92:70:d7 ID:1,32:a2:3f:92:70:d7 Lease:0x669b341a}
I0718 21:31:38.542296 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:e:d3:ca:79:1e:6d ID:1,e:d3:ca:79:1e:6d Lease:0x669b30df}
I0718 21:31:38.542303 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ca:fb:e4:e6:3d:70 ID:1,ca:fb:e4:e6:3d:70 Lease:0x6699debd}
I0718 21:31:38.542310 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:de:8a:ce:de:23:52 ID:1,de:8a:ce:de:23:52 Lease:0x669b2e44}
I0718 21:31:40.542313 7353 main.go:141] libmachine: (false-709000) DBG | Attempt 2
I0718 21:31:40.542330 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:40.542445 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:40.543366 7353 main.go:141] libmachine: (false-709000) DBG | Searching for be:8:7d:e5:59:9a in /var/db/dhcpd_leases ...
I0718 21:31:40.543439 7353 main.go:141] libmachine: (false-709000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0718 21:31:40.543449 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:76:69:1e:0:fd:8c ID:1,76:69:1e:0:fd:8c Lease:0x669b3d56}
I0718 21:31:40.543460 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:8a:78:d2:74:27:42 ID:1,8a:78:d2:74:27:42 Lease:0x669b3d2e}
I0718 21:31:40.543472 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:da:ad:aa:85:e4:ef ID:1,da:ad:aa:85:e4:ef Lease:0x669b3d03}
I0718 21:31:40.543479 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:8a:4c:82:b5:b9:fe ID:1,8a:4c:82:b5:b9:fe Lease:0x669b3cd2}
I0718 21:31:40.543485 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:72:fb:d0:92:80:5e ID:1,72:fb:d0:92:80:5e Lease:0x6699eb5f}
I0718 21:31:40.543492 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:42:4f:ae:89:6:58 ID:1,42:4f:ae:89:6:58 Lease:0x669b3c49}
I0718 21:31:40.543498 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:2e:a0:3:ba:bd:c9 ID:1,2e:a0:3:ba:bd:c9 Lease:0x669b3c6a}
I0718 21:31:40.543503 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:86:4b:29:be:5b:d8 ID:1,86:4b:29:be:5b:d8 Lease:0x669b3c08}
I0718 21:31:40.543514 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:56:b7:46:b5:45:39 ID:1,56:b7:46:b5:45:39 Lease:0x669b3b83}
I0718 21:31:40.543522 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:52:2f:eb:ef:10:d ID:1,52:2f:eb:ef:10:d Lease:0x669b3b4c}
I0718 21:31:40.543546 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:3e:79:98:ae:b1:97 ID:1,3e:79:98:ae:b1:97 Lease:0x669b3b3e}
I0718 21:31:40.543556 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:16:a3:68:1b:ee:2f ID:1,16:a3:68:1b:ee:2f Lease:0x6699e9bf}
I0718 21:31:40.543563 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:2:aa:9e:1e:9c:88 ID:1,2:aa:9e:1e:9c:88 Lease:0x669b3b12}
I0718 21:31:40.543569 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:fa:57:50:16:6f:5d ID:1,fa:57:50:16:6f:5d Lease:0x6699e98c}
I0718 21:31:40.543578 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:1e:af:f7:18:b5:e8 ID:1,1e:af:f7:18:b5:e8 Lease:0x669b3ad5}
I0718 21:31:40.543586 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:6e:17:17:e8:8d:a7 ID:1,6e:17:17:e8:8d:a7 Lease:0x669b3a67}
I0718 21:31:40.543592 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:36:c7:27:fa:6a:4e ID:1,36:c7:27:fa:6a:4e Lease:0x669b3a12}
I0718 21:31:40.543598 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:46:fe:ea:f2:ee:32 ID:1,46:fe:ea:f2:ee:32 Lease:0x669b39e5}
I0718 21:31:40.543611 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:2:b9:df:ce:6b:51 ID:1,2:b9:df:ce:6b:51 Lease:0x6699e7c9}
I0718 21:31:40.543624 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:6:4c:de:4f:d8:27 ID:1,6:4c:de:4f:d8:27 Lease:0x6699e6d1}
I0718 21:31:40.543633 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:3a:d2:59:42:45:2c ID:1,3a:d2:59:42:45:2c Lease:0x6699e7d7}
I0718 21:31:40.543641 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:d2:e2:11:67:74:1c ID:1,d2:e2:11:67:74:1c Lease:0x669b386d}
I0718 21:31:40.543647 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:a:aa:dd:3b:99:bf ID:1,a:aa:dd:3b:99:bf Lease:0x6699e547}
I0718 21:31:40.543654 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:72:c0:82:86:ae:7b ID:1,72:c0:82:86:ae:7b Lease:0x6699e52d}
I0718 21:31:40.543661 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:66:6c:75:31:3:1a ID:1,66:6c:75:31:3:1a Lease:0x6699e4fd}
I0718 21:31:40.543669 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:4a:cc:c:f8:b6:77 ID:1,4a:cc:c:f8:b6:77 Lease:0x669b35c6}
I0718 21:31:40.543682 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:7e:eb:ab:51:e7:9a ID:1,7e:eb:ab:51:e7:9a Lease:0x669b35b5}
I0718 21:31:40.543690 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a2:11:11:3f:ac:6d ID:1,a2:11:11:3f:ac:6d Lease:0x669b3576}
I0718 21:31:40.543697 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:ce:10:ef:df:f5:dd ID:1,ce:10:ef:df:f5:dd Lease:0x669b3547}
I0718 21:31:40.543705 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ce:83:63:32:49:35 ID:1,ce:83:63:32:49:35 Lease:0x669b34e5}
I0718 21:31:40.543712 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:da:90:24:7c:c3:59 ID:1,da:90:24:7c:c3:59 Lease:0x669b34ca}
I0718 21:31:40.543719 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:9e:c5:b:2c:73:ff ID:1,9e:c5:b:2c:73:ff Lease:0x6699e275}
I0718 21:31:40.543737 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:7a:cb:90:8a:a3:a2 ID:1,7a:cb:90:8a:a3:a2 Lease:0x669b34a2}
I0718 21:31:40.543752 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:32:a2:3f:92:70:d7 ID:1,32:a2:3f:92:70:d7 Lease:0x669b341a}
I0718 21:31:40.543764 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:e:d3:ca:79:1e:6d ID:1,e:d3:ca:79:1e:6d Lease:0x669b30df}
I0718 21:31:40.543773 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ca:fb:e4:e6:3d:70 ID:1,ca:fb:e4:e6:3d:70 Lease:0x6699debd}
I0718 21:31:40.543783 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:de:8a:ce:de:23:52 ID:1,de:8a:ce:de:23:52 Lease:0x669b2e44}
I0718 21:31:42.384696 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:42 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I0718 21:31:42.384737 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:42 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I0718 21:31:42.384750 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:42 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I0718 21:31:42.407768 7353 main.go:141] libmachine: (false-709000) DBG | 2024/07/18 21:31:42 INFO : hyperkit: stderr: rdmsr to register 0xc0011029 on vcpu 1
I0718 21:31:42.545413 7353 main.go:141] libmachine: (false-709000) DBG | Attempt 3
I0718 21:31:42.545428 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:42.545540 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:42.546395 7353 main.go:141] libmachine: (false-709000) DBG | Searching for be:8:7d:e5:59:9a in /var/db/dhcpd_leases ...
I0718 21:31:42.546465 7353 main.go:141] libmachine: (false-709000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0718 21:31:42.546473 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:76:69:1e:0:fd:8c ID:1,76:69:1e:0:fd:8c Lease:0x669b3d56}
I0718 21:31:42.546483 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:8a:78:d2:74:27:42 ID:1,8a:78:d2:74:27:42 Lease:0x669b3d2e}
I0718 21:31:42.546498 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:da:ad:aa:85:e4:ef ID:1,da:ad:aa:85:e4:ef Lease:0x669b3d03}
I0718 21:31:42.546505 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:8a:4c:82:b5:b9:fe ID:1,8a:4c:82:b5:b9:fe Lease:0x669b3cd2}
I0718 21:31:42.546512 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:72:fb:d0:92:80:5e ID:1,72:fb:d0:92:80:5e Lease:0x6699eb5f}
I0718 21:31:42.546518 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:42:4f:ae:89:6:58 ID:1,42:4f:ae:89:6:58 Lease:0x669b3c49}
I0718 21:31:42.546530 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:2e:a0:3:ba:bd:c9 ID:1,2e:a0:3:ba:bd:c9 Lease:0x669b3c6a}
I0718 21:31:42.546537 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:86:4b:29:be:5b:d8 ID:1,86:4b:29:be:5b:d8 Lease:0x669b3c08}
I0718 21:31:42.546544 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:56:b7:46:b5:45:39 ID:1,56:b7:46:b5:45:39 Lease:0x669b3b83}
I0718 21:31:42.546552 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:52:2f:eb:ef:10:d ID:1,52:2f:eb:ef:10:d Lease:0x669b3b4c}
I0718 21:31:42.546559 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:3e:79:98:ae:b1:97 ID:1,3e:79:98:ae:b1:97 Lease:0x669b3b3e}
I0718 21:31:42.546564 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:16:a3:68:1b:ee:2f ID:1,16:a3:68:1b:ee:2f Lease:0x6699e9bf}
I0718 21:31:42.546580 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:2:aa:9e:1e:9c:88 ID:1,2:aa:9e:1e:9c:88 Lease:0x669b3b12}
I0718 21:31:42.546592 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:fa:57:50:16:6f:5d ID:1,fa:57:50:16:6f:5d Lease:0x6699e98c}
I0718 21:31:42.546602 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:1e:af:f7:18:b5:e8 ID:1,1e:af:f7:18:b5:e8 Lease:0x669b3ad5}
I0718 21:31:42.546612 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:6e:17:17:e8:8d:a7 ID:1,6e:17:17:e8:8d:a7 Lease:0x669b3a67}
I0718 21:31:42.546625 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:36:c7:27:fa:6a:4e ID:1,36:c7:27:fa:6a:4e Lease:0x669b3a12}
I0718 21:31:42.546635 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:46:fe:ea:f2:ee:32 ID:1,46:fe:ea:f2:ee:32 Lease:0x669b39e5}
I0718 21:31:42.546651 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:2:b9:df:ce:6b:51 ID:1,2:b9:df:ce:6b:51 Lease:0x6699e7c9}
I0718 21:31:42.546664 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:6:4c:de:4f:d8:27 ID:1,6:4c:de:4f:d8:27 Lease:0x6699e6d1}
I0718 21:31:42.546673 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:3a:d2:59:42:45:2c ID:1,3a:d2:59:42:45:2c Lease:0x6699e7d7}
I0718 21:31:42.546681 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:d2:e2:11:67:74:1c ID:1,d2:e2:11:67:74:1c Lease:0x669b386d}
I0718 21:31:42.546690 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:a:aa:dd:3b:99:bf ID:1,a:aa:dd:3b:99:bf Lease:0x6699e547}
I0718 21:31:42.546696 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:72:c0:82:86:ae:7b ID:1,72:c0:82:86:ae:7b Lease:0x6699e52d}
I0718 21:31:42.546708 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:66:6c:75:31:3:1a ID:1,66:6c:75:31:3:1a Lease:0x6699e4fd}
I0718 21:31:42.546721 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:4a:cc:c:f8:b6:77 ID:1,4a:cc:c:f8:b6:77 Lease:0x669b35c6}
I0718 21:31:42.546729 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:7e:eb:ab:51:e7:9a ID:1,7e:eb:ab:51:e7:9a Lease:0x669b35b5}
I0718 21:31:42.546738 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a2:11:11:3f:ac:6d ID:1,a2:11:11:3f:ac:6d Lease:0x669b3576}
I0718 21:31:42.546745 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:ce:10:ef:df:f5:dd ID:1,ce:10:ef:df:f5:dd Lease:0x669b3547}
I0718 21:31:42.546752 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ce:83:63:32:49:35 ID:1,ce:83:63:32:49:35 Lease:0x669b34e5}
I0718 21:31:42.546759 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:da:90:24:7c:c3:59 ID:1,da:90:24:7c:c3:59 Lease:0x669b34ca}
I0718 21:31:42.546766 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:9e:c5:b:2c:73:ff ID:1,9e:c5:b:2c:73:ff Lease:0x6699e275}
I0718 21:31:42.546773 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:7a:cb:90:8a:a3:a2 ID:1,7a:cb:90:8a:a3:a2 Lease:0x669b34a2}
I0718 21:31:42.546781 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:32:a2:3f:92:70:d7 ID:1,32:a2:3f:92:70:d7 Lease:0x669b341a}
I0718 21:31:42.546788 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:e:d3:ca:79:1e:6d ID:1,e:d3:ca:79:1e:6d Lease:0x669b30df}
I0718 21:31:42.546795 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ca:fb:e4:e6:3d:70 ID:1,ca:fb:e4:e6:3d:70 Lease:0x6699debd}
I0718 21:31:42.546803 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:de:8a:ce:de:23:52 ID:1,de:8a:ce:de:23:52 Lease:0x669b2e44}
I0718 21:31:44.546809 7353 main.go:141] libmachine: (false-709000) DBG | Attempt 4
I0718 21:31:44.546824 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:44.546933 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:44.547756 7353 main.go:141] libmachine: (false-709000) DBG | Searching for be:8:7d:e5:59:9a in /var/db/dhcpd_leases ...
I0718 21:31:44.547824 7353 main.go:141] libmachine: (false-709000) DBG | Found 37 entries in /var/db/dhcpd_leases!
I0718 21:31:44.547833 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:76:69:1e:0:fd:8c ID:1,76:69:1e:0:fd:8c Lease:0x669b3d56}
I0718 21:31:44.547841 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:8a:78:d2:74:27:42 ID:1,8a:78:d2:74:27:42 Lease:0x669b3d2e}
I0718 21:31:44.547846 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:da:ad:aa:85:e4:ef ID:1,da:ad:aa:85:e4:ef Lease:0x669b3d03}
I0718 21:31:44.547864 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:8a:4c:82:b5:b9:fe ID:1,8a:4c:82:b5:b9:fe Lease:0x669b3cd2}
I0718 21:31:44.547874 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:72:fb:d0:92:80:5e ID:1,72:fb:d0:92:80:5e Lease:0x6699eb5f}
I0718 21:31:44.547896 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:42:4f:ae:89:6:58 ID:1,42:4f:ae:89:6:58 Lease:0x669b3c49}
I0718 21:31:44.547904 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:2e:a0:3:ba:bd:c9 ID:1,2e:a0:3:ba:bd:c9 Lease:0x669b3c6a}
I0718 21:31:44.547911 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:86:4b:29:be:5b:d8 ID:1,86:4b:29:be:5b:d8 Lease:0x669b3c08}
I0718 21:31:44.547918 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:56:b7:46:b5:45:39 ID:1,56:b7:46:b5:45:39 Lease:0x669b3b83}
I0718 21:31:44.547931 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:52:2f:eb:ef:10:d ID:1,52:2f:eb:ef:10:d Lease:0x669b3b4c}
I0718 21:31:44.547941 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:3e:79:98:ae:b1:97 ID:1,3e:79:98:ae:b1:97 Lease:0x669b3b3e}
I0718 21:31:44.547957 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:16:a3:68:1b:ee:2f ID:1,16:a3:68:1b:ee:2f Lease:0x6699e9bf}
I0718 21:31:44.547970 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:2:aa:9e:1e:9c:88 ID:1,2:aa:9e:1e:9c:88 Lease:0x669b3b12}
I0718 21:31:44.547994 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:fa:57:50:16:6f:5d ID:1,fa:57:50:16:6f:5d Lease:0x6699e98c}
I0718 21:31:44.548004 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:1e:af:f7:18:b5:e8 ID:1,1e:af:f7:18:b5:e8 Lease:0x669b3ad5}
I0718 21:31:44.548015 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:6e:17:17:e8:8d:a7 ID:1,6e:17:17:e8:8d:a7 Lease:0x669b3a67}
I0718 21:31:44.548027 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:36:c7:27:fa:6a:4e ID:1,36:c7:27:fa:6a:4e Lease:0x669b3a12}
I0718 21:31:44.548035 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:46:fe:ea:f2:ee:32 ID:1,46:fe:ea:f2:ee:32 Lease:0x669b39e5}
I0718 21:31:44.548043 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:2:b9:df:ce:6b:51 ID:1,2:b9:df:ce:6b:51 Lease:0x6699e7c9}
I0718 21:31:44.548050 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:6:4c:de:4f:d8:27 ID:1,6:4c:de:4f:d8:27 Lease:0x6699e6d1}
I0718 21:31:44.548059 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:3a:d2:59:42:45:2c ID:1,3a:d2:59:42:45:2c Lease:0x6699e7d7}
I0718 21:31:44.548073 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:d2:e2:11:67:74:1c ID:1,d2:e2:11:67:74:1c Lease:0x669b386d}
I0718 21:31:44.548086 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:a:aa:dd:3b:99:bf ID:1,a:aa:dd:3b:99:bf Lease:0x6699e547}
I0718 21:31:44.548094 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:72:c0:82:86:ae:7b ID:1,72:c0:82:86:ae:7b Lease:0x6699e52d}
I0718 21:31:44.548100 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:66:6c:75:31:3:1a ID:1,66:6c:75:31:3:1a Lease:0x6699e4fd}
I0718 21:31:44.548107 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:4a:cc:c:f8:b6:77 ID:1,4a:cc:c:f8:b6:77 Lease:0x669b35c6}
I0718 21:31:44.548115 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:7e:eb:ab:51:e7:9a ID:1,7e:eb:ab:51:e7:9a Lease:0x669b35b5}
I0718 21:31:44.548122 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:a2:11:11:3f:ac:6d ID:1,a2:11:11:3f:ac:6d Lease:0x669b3576}
I0718 21:31:44.548131 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:ce:10:ef:df:f5:dd ID:1,ce:10:ef:df:f5:dd Lease:0x669b3547}
I0718 21:31:44.548153 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:ce:83:63:32:49:35 ID:1,ce:83:63:32:49:35 Lease:0x669b34e5}
I0718 21:31:44.548182 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:da:90:24:7c:c3:59 ID:1,da:90:24:7c:c3:59 Lease:0x669b34ca}
I0718 21:31:44.548193 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:9e:c5:b:2c:73:ff ID:1,9e:c5:b:2c:73:ff Lease:0x6699e275}
I0718 21:31:44.548202 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:7a:cb:90:8a:a3:a2 ID:1,7a:cb:90:8a:a3:a2 Lease:0x669b34a2}
I0718 21:31:44.548209 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:32:a2:3f:92:70:d7 ID:1,32:a2:3f:92:70:d7 Lease:0x669b341a}
I0718 21:31:44.548217 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:e:d3:ca:79:1e:6d ID:1,e:d3:ca:79:1e:6d Lease:0x669b30df}
I0718 21:31:44.548227 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:ca:fb:e4:e6:3d:70 ID:1,ca:fb:e4:e6:3d:70 Lease:0x6699debd}
I0718 21:31:44.548239 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.2 HWAddress:de:8a:ce:de:23:52 ID:1,de:8a:ce:de:23:52 Lease:0x669b2e44}
I0718 21:31:46.549873 7353 main.go:141] libmachine: (false-709000) DBG | Attempt 5
I0718 21:31:46.549884 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:46.549963 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:46.550797 7353 main.go:141] libmachine: (false-709000) DBG | Searching for be:8:7d:e5:59:9a in /var/db/dhcpd_leases ...
I0718 21:31:46.550879 7353 main.go:141] libmachine: (false-709000) DBG | Found 38 entries in /var/db/dhcpd_leases!
I0718 21:31:46.550910 7353 main.go:141] libmachine: (false-709000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:be:8:7d:e5:59:9a ID:1,be:8:7d:e5:59:9a Lease:0x669b3db1}
I0718 21:31:46.550924 7353 main.go:141] libmachine: (false-709000) DBG | Found match: be:8:7d:e5:59:9a
I0718 21:31:46.550946 7353 main.go:141] libmachine: (false-709000) DBG | IP: 192.169.0.39
I0718 21:31:46.550961 7353 main.go:141] libmachine: (false-709000) Calling .GetConfigRaw
I0718 21:31:46.551599 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:46.551694 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:46.551786 7353 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0718 21:31:46.551794 7353 main.go:141] libmachine: (false-709000) Calling .GetState
I0718 21:31:46.551868 7353 main.go:141] libmachine: (false-709000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0718 21:31:46.551942 7353 main.go:141] libmachine: (false-709000) DBG | hyperkit pid from json: 7364
I0718 21:31:46.552762 7353 main.go:141] libmachine: Detecting operating system of created instance...
I0718 21:31:46.552774 7353 main.go:141] libmachine: Waiting for SSH to be available...
I0718 21:31:46.552781 7353 main.go:141] libmachine: Getting to WaitForSSH function...
I0718 21:31:46.552787 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:46.552878 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:46.552980 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.553067 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.553164 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:46.553831 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:46.554039 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:46.554047 7353 main.go:141] libmachine: About to run SSH command:
exit 0
I0718 21:31:46.616480 7353 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0718 21:31:46.616493 7353 main.go:141] libmachine: Detecting the provisioner...
I0718 21:31:46.616498 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:46.616636 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:46.616741 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.616841 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.616943 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:46.617098 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:46.617245 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:46.617253 7353 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0718 21:31:46.686093 7353 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"
I0718 21:31:46.686178 7353 main.go:141] libmachine: found compatible host: buildroot
I0718 21:31:46.686188 7353 main.go:141] libmachine: Provisioning with buildroot...
I0718 21:31:46.686193 7353 main.go:141] libmachine: (false-709000) Calling .GetMachineName
I0718 21:31:46.686341 7353 buildroot.go:166] provisioning hostname "false-709000"
I0718 21:31:46.686351 7353 main.go:141] libmachine: (false-709000) Calling .GetMachineName
I0718 21:31:46.686449 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:46.686552 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:46.686645 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.686722 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.686840 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:46.686978 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:46.687136 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:46.687144 7353 main.go:141] libmachine: About to run SSH command:
sudo hostname false-709000 && echo "false-709000" | sudo tee /etc/hostname
I0718 21:31:46.758342 7353 main.go:141] libmachine: SSH cmd err, output: <nil>: false-709000
I0718 21:31:46.758361 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:46.758514 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:46.758616 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.758709 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.758795 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:46.758926 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:46.759077 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:46.759089 7353 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfalse-709000' /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-709000/g' /etc/hosts;
else
echo '127.0.1.1 false-709000' | sudo tee -a /etc/hosts;
fi
fi
I0718 21:31:46.825162 7353 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0718 21:31:46.825182 7353 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/19302-1411/.minikube CaCertPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/19302-1411/.minikube}
I0718 21:31:46.825193 7353 buildroot.go:174] setting up certificates
I0718 21:31:46.825204 7353 provision.go:84] configureAuth start
I0718 21:31:46.825211 7353 main.go:141] libmachine: (false-709000) Calling .GetMachineName
I0718 21:31:46.825347 7353 main.go:141] libmachine: (false-709000) Calling .GetIP
I0718 21:31:46.825437 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:46.825527 7353 provision.go:143] copyHostCerts
I0718 21:31:46.825619 7353 exec_runner.go:144] found /Users/jenkins/minikube-integration/19302-1411/.minikube/cert.pem, removing ...
I0718 21:31:46.825632 7353 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19302-1411/.minikube/cert.pem
I0718 21:31:46.825774 7353 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19302-1411/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/19302-1411/.minikube/cert.pem (1123 bytes)
I0718 21:31:46.826001 7353 exec_runner.go:144] found /Users/jenkins/minikube-integration/19302-1411/.minikube/key.pem, removing ...
I0718 21:31:46.826009 7353 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19302-1411/.minikube/key.pem
I0718 21:31:46.826127 7353 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19302-1411/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/19302-1411/.minikube/key.pem (1675 bytes)
I0718 21:31:46.826305 7353 exec_runner.go:144] found /Users/jenkins/minikube-integration/19302-1411/.minikube/ca.pem, removing ...
I0718 21:31:46.826312 7353 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19302-1411/.minikube/ca.pem
I0718 21:31:46.826398 7353 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/19302-1411/.minikube/ca.pem (1082 bytes)
I0718 21:31:46.826549 7353 provision.go:117] generating server cert: /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca-key.pem org=jenkins.false-709000 san=[127.0.0.1 192.169.0.39 false-709000 localhost minikube]
I0718 21:31:46.919033 7353 provision.go:177] copyRemoteCerts
I0718 21:31:46.919093 7353 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0718 21:31:46.919111 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:46.919265 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:46.919379 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:46.919482 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:46.919581 7353 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/id_rsa Username:docker}
I0718 21:31:46.964067 7353 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19302-1411/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0718 21:31:46.984459 7353 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/server.pem --> /etc/docker/server.pem (1204 bytes)
I0718 21:31:47.004861 7353 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19302-1411/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0718 21:31:47.025615 7353 provision.go:87] duration metric: took 200.388888ms to configureAuth
I0718 21:31:47.025631 7353 buildroot.go:189] setting minikube options for container-runtime
I0718 21:31:47.025768 7353 config.go:182] Loaded profile config "false-709000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.3
I0718 21:31:47.025783 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:47.025925 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:47.026026 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:47.026120 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:47.026212 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:47.026318 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:47.026433 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:47.026570 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:47.026578 7353 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0718 21:31:47.086863 7353 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0718 21:31:47.086875 7353 buildroot.go:70] root file system type: tmpfs
I0718 21:31:47.086952 7353 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0718 21:31:47.086967 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:47.087095 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:47.087195 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:47.087298 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:47.087400 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:47.087556 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:47.087691 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:47.087734 7353 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
I0718 21:31:47.160270 7353 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
I0718 21:31:47.160289 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:47.160439 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:47.160537 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:47.160649 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:47.160742 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:47.160862 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:47.161013 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:47.161026 7353 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; }
I0718 21:31:48.742452 7353 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.
I0718 21:31:48.742468 7353 main.go:141] libmachine: Checking connection to Docker...
I0718 21:31:48.742475 7353 main.go:141] libmachine: (false-709000) Calling .GetURL
I0718 21:31:48.742629 7353 main.go:141] libmachine: Docker is up and running!
I0718 21:31:48.742636 7353 main.go:141] libmachine: Reticulating splines...
I0718 21:31:48.742640 7353 client.go:171] duration metric: took 12.946963113s to LocalClient.Create
I0718 21:31:48.742652 7353 start.go:167] duration metric: took 12.947004807s to libmachine.API.Create "false-709000"
I0718 21:31:48.742662 7353 start.go:293] postStartSetup for "false-709000" (driver="hyperkit")
I0718 21:31:48.742669 7353 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]
I0718 21:31:48.742679 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:48.742844 7353 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
I0718 21:31:48.742857 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:48.742942 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:48.743024 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:48.743107 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:48.743203 7353 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/id_rsa Username:docker}
I0718 21:31:48.785863 7353 ssh_runner.go:195] Run: cat /etc/os-release
I0718 21:31:48.790348 7353 info.go:137] Remote host: Buildroot 2023.02.9
I0718 21:31:48.790367 7353 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19302-1411/.minikube/addons for local assets ...
I0718 21:31:48.790501 7353 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19302-1411/.minikube/files for local assets ...
I0718 21:31:48.790912 7353 filesync.go:149] local asset: /Users/jenkins/minikube-integration/19302-1411/.minikube/files/etc/ssl/certs/19482.pem -> 19482.pem in /etc/ssl/certs
I0718 21:31:48.791138 7353 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0718 21:31:48.800179 7353 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19302-1411/.minikube/files/etc/ssl/certs/19482.pem --> /etc/ssl/certs/19482.pem (1708 bytes)
I0718 21:31:48.830977 7353 start.go:296] duration metric: took 88.294727ms for postStartSetup
I0718 21:31:48.831006 7353 main.go:141] libmachine: (false-709000) Calling .GetConfigRaw
I0718 21:31:48.831610 7353 main.go:141] libmachine: (false-709000) Calling .GetIP
I0718 21:31:48.831754 7353 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19302-1411/.minikube/profiles/false-709000/config.json ...
I0718 21:31:48.832107 7353 start.go:128] duration metric: took 13.090733857s to createHost
I0718 21:31:48.832124 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:48.832216 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:48.832304 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:48.832375 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:48.832454 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:48.832549 7353 main.go:141] libmachine: Using SSH client type: native
I0718 21:31:48.832665 7353 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xb3020c0] 0xb304e20 <nil> [] 0s} 192.169.0.39 22 <nil> <nil>}
I0718 21:31:48.832672 7353 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0718 21:31:48.894282 7353 main.go:141] libmachine: SSH cmd err, output: <nil>: 1721363508.952861209
I0718 21:31:48.894295 7353 fix.go:216] guest clock: 1721363508.952861209
I0718 21:31:48.894300 7353 fix.go:229] Guest: 2024-07-18 21:31:48.952861209 -0700 PDT Remote: 2024-07-18 21:31:48.832116 -0700 PDT m=+13.655434543 (delta=120.745209ms)
I0718 21:31:48.894321 7353 fix.go:200] guest clock delta is within tolerance: 120.745209ms
I0718 21:31:48.894325 7353 start.go:83] releasing machines lock for "false-709000", held for 13.153084208s
I0718 21:31:48.894344 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:48.894479 7353 main.go:141] libmachine: (false-709000) Calling .GetIP
I0718 21:31:48.894580 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:48.894893 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:48.895003 7353 main.go:141] libmachine: (false-709000) Calling .DriverName
I0718 21:31:48.895144 7353 ssh_runner.go:195] Run: cat /version.json
I0718 21:31:48.895157 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:48.895259 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:48.895342 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:48.895422 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:48.895502 7353 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/id_rsa Username:docker}
I0718 21:31:48.895731 7353 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0718 21:31:48.895760 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHHostname
I0718 21:31:48.895876 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHPort
I0718 21:31:48.895991 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHKeyPath
I0718 21:31:48.896157 7353 main.go:141] libmachine: (false-709000) Calling .GetSSHUsername
I0718 21:31:48.896268 7353 sshutil.go:53] new ssh client: &{IP:192.169.0.39 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19302-1411/.minikube/machines/false-709000/id_rsa Username:docker}
I0718 21:31:48.928508 7353 ssh_runner.go:195] Run: systemctl --version
I0718 21:31:48.933657 7353 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0718 21:31:48.978164 7353 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0718 21:31:48.978231 7353 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' {}" ;
I0718 21:31:48.985509 7353 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' {}" ;
I0718 21:31:48.997967 7353 cni.go:308] configured [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0718 21:31:48.997984 7353 start.go:495] detecting cgroup driver to use...
I0718 21:31:48.998097 7353 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"
I0718 21:31:49.015833 7353 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"
I0718 21:31:49.028723 7353 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"
I0718 21:31:49.038325 7353 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0718 21:31:49.038381 7353 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0718 21:31:49.047902 7353 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"
I0718 21:31:49.057692 7353 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0718 21:31:49.067624 7353 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0718 21:31:49.077392 7353 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0718 21:31:49.087178 7353 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"
I0718 21:31:49.096757 7353 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0718 21:31:49.106597 7353 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"
I0718 21:31:49.116319 7353 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0718 21:31:49.124465 7353 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0718 21:31:49.132430 7353 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0718 21:31:49.231501 7353 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0718 21:31:49.250528 7353 start.go:495] detecting cgroup driver to use...
I0718 21:31:49.250600 7353 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0718 21:31:49.270135 7353 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0718 21:31:49.286196 7353 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0718 21:31:49.306146 7353 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0718 21:31:49.317400 7353 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0718 21:31:49.328120 7353 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0718 21:31:49.349704 7353 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0718 21:31:49.360468 7353 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"
I0718 21:31:49.376016 7353 ssh_runner.go:195] Run: which cri-dockerd
I0718 21:31:49.379170 7353 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0718 21:31:49.386832 7353 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0718 21:31:49.401405 7353 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0718 21:31:49.503777 7353 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0718 21:31:49.613578 7353 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0718 21:31:49.613660 7353 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0718 21:31:49.627808 7353 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0718 21:31:49.727056 7353 ssh_runner.go:195] Run: sudo systemctl restart docker
I0718 21:32:50.755027 7353 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.0261228s)
I0718 21:32:50.755104 7353 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0718 21:32:50.791097 7353 out.go:177]
W0718 21:32:50.811829 7353 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 19 04:31:47 false-709000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 04:31:47 false-709000 dockerd[517]: time="2024-07-19T04:31:47.543166338Z" level=info msg="Starting up"
Jul 19 04:31:47 false-709000 dockerd[517]: time="2024-07-19T04:31:47.543591946Z" level=info msg="containerd not running, starting managed containerd"
Jul 19 04:31:47 false-709000 dockerd[517]: time="2024-07-19T04:31:47.544216378Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=523
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.560679207Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576521501Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576597141Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576665465Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576703457Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576790313Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576830457Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577002810Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577049814Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577082814Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577152011Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577238009Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577425406Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579048117Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579103132Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579243948Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579288483Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579447087Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579522224Z" level=info msg="metadata content store policy set" policy=shared
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581518866Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581606688Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581655174Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581704530Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581750111Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581843489Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582495177Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582611706Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582651025Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582666724Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582680621Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582691570Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582703568Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582716770Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582729611Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582742626Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582757107Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582770326Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582787700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582798243Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582810031Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582822027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582833618Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582845456Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582866020Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582880575Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582892984Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582906838Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582941059Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582955709Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582967467Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582987307Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583009621Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583022234Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583033273Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583065480Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583103197Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583118606Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583130654Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583138623Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583149869Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583193320Z" level=info msg="NRI interface is disabled by configuration."
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583935098Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.584063779Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.584151116Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.584215671Z" level=info msg="containerd successfully booted in 0.024126s"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.574362447Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.586481564Z" level=info msg="Loading containers: start."
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.678577893Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.764009569Z" level=info msg="Loading containers: done."
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.771813852Z" level=info msg="Docker daemon" commit=662f78c containerd-snapshotter=false storage-driver=overlay2 version=27.0.3
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.771935175Z" level=info msg="Daemon has completed initialization"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.798118345Z" level=info msg="API listen on /var/run/docker.sock"
Jul 19 04:31:48 false-709000 systemd[1]: Started Docker Application Container Engine.
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.798445615Z" level=info msg="API listen on [::]:2376"
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.799636925Z" level=info msg="Processing signal 'terminated'"
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800455538Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800817331Z" level=info msg="Daemon shutdown complete"
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800887289Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800925276Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jul 19 04:31:49 false-709000 systemd[1]: Stopping Docker Application Container Engine...
Jul 19 04:31:50 false-709000 systemd[1]: docker.service: Deactivated successfully.
Jul 19 04:31:50 false-709000 systemd[1]: Stopped Docker Application Container Engine.
Jul 19 04:31:50 false-709000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 04:31:50 false-709000 dockerd[1006]: time="2024-07-19T04:31:50.840810457Z" level=info msg="Starting up"
Jul 19 04:32:50 false-709000 dockerd[1006]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jul 19 04:32:50 false-709000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 04:32:50 false-709000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jul 19 04:32:50 false-709000 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 19 04:31:47 false-709000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 04:31:47 false-709000 dockerd[517]: time="2024-07-19T04:31:47.543166338Z" level=info msg="Starting up"
Jul 19 04:31:47 false-709000 dockerd[517]: time="2024-07-19T04:31:47.543591946Z" level=info msg="containerd not running, starting managed containerd"
Jul 19 04:31:47 false-709000 dockerd[517]: time="2024-07-19T04:31:47.544216378Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=523
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.560679207Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576521501Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576597141Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576665465Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576703457Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576790313Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.576830457Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577002810Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577049814Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577082814Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577152011Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577238009Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.577425406Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579048117Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579103132Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579243948Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579288483Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579447087Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.579522224Z" level=info msg="metadata content store policy set" policy=shared
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581518866Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581606688Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581655174Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581704530Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581750111Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.581843489Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582495177Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582611706Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582651025Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582666724Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582680621Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582691570Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582703568Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582716770Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582729611Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582742626Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582757107Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582770326Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582787700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582798243Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582810031Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582822027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582833618Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582845456Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582866020Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582880575Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582892984Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582906838Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582941059Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582955709Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582967467Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.582987307Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583009621Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583022234Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583033273Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583065480Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583103197Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583118606Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583130654Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583138623Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583149869Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583193320Z" level=info msg="NRI interface is disabled by configuration."
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.583935098Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.584063779Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.584151116Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Jul 19 04:31:47 false-709000 dockerd[523]: time="2024-07-19T04:31:47.584215671Z" level=info msg="containerd successfully booted in 0.024126s"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.574362447Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.586481564Z" level=info msg="Loading containers: start."
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.678577893Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.764009569Z" level=info msg="Loading containers: done."
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.771813852Z" level=info msg="Docker daemon" commit=662f78c containerd-snapshotter=false storage-driver=overlay2 version=27.0.3
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.771935175Z" level=info msg="Daemon has completed initialization"
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.798118345Z" level=info msg="API listen on /var/run/docker.sock"
Jul 19 04:31:48 false-709000 systemd[1]: Started Docker Application Container Engine.
Jul 19 04:31:48 false-709000 dockerd[517]: time="2024-07-19T04:31:48.798445615Z" level=info msg="API listen on [::]:2376"
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.799636925Z" level=info msg="Processing signal 'terminated'"
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800455538Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800817331Z" level=info msg="Daemon shutdown complete"
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800887289Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Jul 19 04:31:49 false-709000 dockerd[517]: time="2024-07-19T04:31:49.800925276Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Jul 19 04:31:49 false-709000 systemd[1]: Stopping Docker Application Container Engine...
Jul 19 04:31:50 false-709000 systemd[1]: docker.service: Deactivated successfully.
Jul 19 04:31:50 false-709000 systemd[1]: Stopped Docker Application Container Engine.
Jul 19 04:31:50 false-709000 systemd[1]: Starting Docker Application Container Engine...
Jul 19 04:31:50 false-709000 dockerd[1006]: time="2024-07-19T04:31:50.840810457Z" level=info msg="Starting up"
Jul 19 04:32:50 false-709000 dockerd[1006]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Jul 19 04:32:50 false-709000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 04:32:50 false-709000 systemd[1]: docker.service: Failed with result 'exit-code'.
Jul 19 04:32:50 false-709000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0718 21:32:50.811906 7353 out.go:239] *
*
W0718 21:32:50.812547 7353 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0718 21:32:50.874728 7353 out.go:177]
** /stderr **
net_test.go:114: failed start: exit status 90
--- FAIL: TestNetworkPlugins/group/false/Start (75.78s)