=== RUN TestMultiNode/serial/FreshStart2Nodes
multinode_test.go:85: (dbg) Run: out/minikube-darwin-amd64 start -p multinode-826000 --wait=true --memory=2200 --nodes=2 -v=8 --alsologtostderr --driver=hyperkit
multinode_test.go:85: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p multinode-826000 --wait=true --memory=2200 --nodes=2 -v=8 --alsologtostderr --driver=hyperkit : exit status 90 (20.633241432s)
-- stdout --
* [multinode-826000] minikube v1.30.1 on Darwin 13.4
- MINIKUBE_LOCATION=16578
- KUBECONFIG=/Users/jenkins/minikube-integration/16578-1235/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/16578-1235/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting control plane node multinode-826000 in cluster multinode-826000
* Creating hyperkit VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I0610 09:38:27.333749 3473 out.go:296] Setting OutFile to fd 1 ...
I0610 09:38:27.333925 3473 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0610 09:38:27.333932 3473 out.go:309] Setting ErrFile to fd 2...
I0610 09:38:27.333938 3473 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0610 09:38:27.334053 3473 root.go:336] Updating PATH: /Users/jenkins/minikube-integration/16578-1235/.minikube/bin
I0610 09:38:27.335469 3473 out.go:303] Setting JSON to false
I0610 09:38:27.354476 3473 start.go:127] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":2277,"bootTime":1686412830,"procs":396,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"13.4","kernelVersion":"22.5.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"2965c349-98a5-5970-aaa9-9eedd3ae5959"}
W0610 09:38:27.354567 3473 start.go:135] gopshost.Virtualization returned error: not implemented yet
I0610 09:38:27.375962 3473 out.go:177] * [multinode-826000] minikube v1.30.1 on Darwin 13.4
I0610 09:38:27.434159 3473 out.go:177] - MINIKUBE_LOCATION=16578
I0610 09:38:27.434171 3473 notify.go:220] Checking for updates...
I0610 09:38:27.457028 3473 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/16578-1235/kubeconfig
I0610 09:38:27.480128 3473 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0610 09:38:27.501126 3473 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0610 09:38:27.522053 3473 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/16578-1235/.minikube
I0610 09:38:27.543289 3473 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0610 09:38:27.564451 3473 driver.go:375] Setting default libvirt URI to qemu:///system
I0610 09:38:27.592961 3473 out.go:177] * Using the hyperkit driver based on user configuration
I0610 09:38:27.635123 3473 start.go:297] selected driver: hyperkit
I0610 09:38:27.635156 3473 start.go:875] validating driver "hyperkit" against <nil>
I0610 09:38:27.635178 3473 start.go:886] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0610 09:38:27.638596 3473 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0610 09:38:27.638708 3473 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/16578-1235/.minikube/bin:/Users/jenkins/workspace/out/:/usr/bin:/bin:/usr/sbin:/sbin:/Users/jenkins/google-cloud-sdk/bin:/usr/local/bin/:/usr/local/go/bin/:/Users/jenkins/go/bin
I0610 09:38:27.645437 3473 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.30.1
I0610 09:38:27.648787 3473 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0610 09:38:27.648805 3473 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0610 09:38:27.648893 3473 start_flags.go:305] no existing cluster config was found, will generate one from the flags
I0610 09:38:27.649081 3473 start_flags.go:919] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0610 09:38:27.649110 3473 cni.go:84] Creating CNI manager for ""
I0610 09:38:27.649119 3473 cni.go:136] 0 nodes found, recommending kindnet
I0610 09:38:27.649125 3473 start_flags.go:314] Found "CNI" CNI - setting NetworkPlugin=cni
I0610 09:38:27.649135 3473 start_flags.go:319] config:
{Name:multinode-826000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1686006988-16632@sha256:412dc5cf58908f3565f59ed5f2b8341f53e998f8d8b54f59253c8f8f335f5a7b Memory:2200 CPUs:2 DiskSize:20000 VMDriver: 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:0 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.27.2 ClusterName:multinode-826000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:
docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:true 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:}
I0610 09:38:27.649267 3473 iso.go:125] acquiring lock: {Name:mkc028968ad126cece35ec994c5f11699b30bc34 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0610 09:38:27.690866 3473 out.go:177] * Starting control plane node multinode-826000 in cluster multinode-826000
I0610 09:38:27.712068 3473 preload.go:132] Checking if preload exists for k8s version v1.27.2 and runtime docker
I0610 09:38:27.712173 3473 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/16578-1235/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.27.2-docker-overlay2-amd64.tar.lz4
I0610 09:38:27.712205 3473 cache.go:57] Caching tarball of preloaded images
I0610 09:38:27.712372 3473 preload.go:174] Found /Users/jenkins/minikube-integration/16578-1235/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.27.2-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0610 09:38:27.712389 3473 cache.go:60] Finished verifying existence of preloaded tar for v1.27.2 on docker
I0610 09:38:27.712852 3473 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16578-1235/.minikube/profiles/multinode-826000/config.json ...
I0610 09:38:27.712895 3473 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/16578-1235/.minikube/profiles/multinode-826000/config.json: {Name:mk96a955df354a5a4a4dd6f4c58a67dc01bf2b2f Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0610 09:38:27.713503 3473 cache.go:195] Successfully downloaded all kic artifacts
I0610 09:38:27.713554 3473 start.go:364] acquiring machines lock for multinode-826000: {Name:mk73e5861e2a32aaad6eda5ce405a92c74d96949 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0610 09:38:27.713650 3473 start.go:368] acquired machines lock for "multinode-826000" in 81.582µs
I0610 09:38:27.713693 3473 start.go:93] Provisioning new machine with config: &{Name:multinode-826000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/16019/minikube-v1.30.1-1686096373-16019-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1686006988-16632@sha256:412dc5cf58908f3565f59ed5f2b8341f53e998f8d8b54f59253c8f8f335f5a7b Memory:2200 CPUs:2 DiskSize:20000 VMDriver: 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:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 Kubernete
sConfig:{KubernetesVersion:v1.27.2 ClusterName:multinode-826000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.27.2 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:true 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:} &{Name: IP: Port:8443 KubernetesVersion:v1.27.2 ContainerRuntime:docker ControlPlane:true Worker:true}
I0610 09:38:27.713776 3473 start.go:125] createHost starting for "" (driver="hyperkit")
I0610 09:38:27.756009 3473 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
I0610 09:38:27.756463 3473 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0610 09:38:27.756515 3473 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0610 09:38:27.764652 3473 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:50999
I0610 09:38:27.765038 3473 main.go:141] libmachine: () Calling .GetVersion
I0610 09:38:27.765488 3473 main.go:141] libmachine: Using API Version 1
I0610 09:38:27.765500 3473 main.go:141] libmachine: () Calling .SetConfigRaw
I0610 09:38:27.765728 3473 main.go:141] libmachine: () Calling .GetMachineName
I0610 09:38:27.765830 3473 main.go:141] libmachine: (multinode-826000) Calling .GetMachineName
I0610 09:38:27.765916 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:27.766019 3473 start.go:159] libmachine.API.Create for "multinode-826000" (driver="hyperkit")
I0610 09:38:27.766040 3473 client.go:168] LocalClient.Create starting
I0610 09:38:27.766090 3473 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem
I0610 09:38:27.766132 3473 main.go:141] libmachine: Decoding PEM data...
I0610 09:38:27.766146 3473 main.go:141] libmachine: Parsing certificate...
I0610 09:38:27.766190 3473 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/cert.pem
I0610 09:38:27.766219 3473 main.go:141] libmachine: Decoding PEM data...
I0610 09:38:27.766229 3473 main.go:141] libmachine: Parsing certificate...
I0610 09:38:27.766244 3473 main.go:141] libmachine: Running pre-create checks...
I0610 09:38:27.766253 3473 main.go:141] libmachine: (multinode-826000) Calling .PreCreateCheck
I0610 09:38:27.766322 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:27.766502 3473 main.go:141] libmachine: (multinode-826000) Calling .GetConfigRaw
I0610 09:38:27.766943 3473 main.go:141] libmachine: Creating machine...
I0610 09:38:27.766951 3473 main.go:141] libmachine: (multinode-826000) Calling .Create
I0610 09:38:27.767017 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:27.767133 3473 main.go:141] libmachine: (multinode-826000) DBG | I0610 09:38:27.767015 3481 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/16578-1235/.minikube
I0610 09:38:27.767191 3473 main.go:141] libmachine: (multinode-826000) Downloading /Users/jenkins/minikube-integration/16578-1235/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/16578-1235/.minikube/cache/iso/amd64/minikube-v1.30.1-1686096373-16019-amd64.iso...
I0610 09:38:27.936787 3473 main.go:141] libmachine: (multinode-826000) DBG | I0610 09:38:27.936695 3481 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/id_rsa...
I0610 09:38:28.007219 3473 main.go:141] libmachine: (multinode-826000) DBG | I0610 09:38:28.007149 3481 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/multinode-826000.rawdisk...
I0610 09:38:28.007259 3473 main.go:141] libmachine: (multinode-826000) DBG | Writing magic tar header
I0610 09:38:28.007270 3473 main.go:141] libmachine: (multinode-826000) DBG | Writing SSH key tar header
I0610 09:38:28.008073 3473 main.go:141] libmachine: (multinode-826000) DBG | I0610 09:38:28.007982 3481 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000 ...
I0610 09:38:28.321332 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:28.321354 3473 main.go:141] libmachine: (multinode-826000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/hyperkit.pid
I0610 09:38:28.321369 3473 main.go:141] libmachine: (multinode-826000) DBG | Using UUID 39ebe0dc-07ad-11ee-b579-f01898ef957c
I0610 09:38:28.441914 3473 main.go:141] libmachine: (multinode-826000) DBG | Generated MAC fa:20:3f:84:ae:92
I0610 09:38:28.441938 3473 main.go:141] libmachine: (multinode-826000) 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=multinode-826000
I0610 09:38:28.441979 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"39ebe0dc-07ad-11ee-b579-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc00009f1d0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/initrd", Bootrom:"", CPUs:2, Memory:2200, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Proce
ss)(nil)}
I0610 09:38:28.442013 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"39ebe0dc-07ad-11ee-b579-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc00009f1d0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/initrd", Bootrom:"", CPUs:2, Memory:2200, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Proce
ss)(nil)}
I0610 09:38:28.442087 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/hyperkit.pid", "-c", "2", "-m", "2200M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "39ebe0dc-07ad-11ee-b579-f01898ef957c", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/multinode-826000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/tty,log=/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/bzimage,/Users/jenkins/minikube-integration/1657
8-1235/.minikube/machines/multinode-826000/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=multinode-826000"}
I0610 09:38:28.442128 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/hyperkit.pid -c 2 -m 2200M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 39ebe0dc-07ad-11ee-b579-f01898ef957c -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/multinode-826000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/tty,log=/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/console-ring -f kexec,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/bzimage,/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/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=multinode-826000"
I0610 09:38:28.442145 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0610 09:38:28.444699 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 DEBUG: hyperkit: Pid is 3484
I0610 09:38:28.445051 3473 main.go:141] libmachine: (multinode-826000) DBG | Attempt 0
I0610 09:38:28.445061 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:28.445124 3473 main.go:141] libmachine: (multinode-826000) DBG | hyperkit pid from json: 3484
I0610 09:38:28.445914 3473 main.go:141] libmachine: (multinode-826000) DBG | Searching for fa:20:3f:84:ae:92 in /var/db/dhcpd_leases ...
I0610 09:38:28.445971 3473 main.go:141] libmachine: (multinode-826000) DBG | Found 10 entries in /var/db/dhcpd_leases!
I0610 09:38:28.445995 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:32:30:6d:e9:c8:b4 ID:1,32:30:6d:e9:c8:b4 Lease:0x6484a701}
I0610 09:38:28.446004 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:a6:94:da:ab:ab:e2 ID:1,a6:94:da:ab:ab:e2 Lease:0x6484a6eb}
I0610 09:38:28.446011 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:3a:96:c4:94:8e:b0 ID:1,3a:96:c4:94:8e:b0 Lease:0x6485f81d}
I0610 09:38:28.446018 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:e6:27:b7:b3:13:83 ID:1,e6:27:b7:b3:13:83 Lease:0x6485f7f9}
I0610 09:38:28.446027 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ea:f7:ed:fb:5e:ee ID:1,ea:f7:ed:fb:5e:ee Lease:0x6485f7ba}
I0610 09:38:28.446034 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:c2:ab:cc:f4:2:8a ID:1,c2:ab:cc:f4:2:8a Lease:0x6485f73e}
I0610 09:38:28.446040 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:7e:c9:b9:4e:e6:61 ID:1,7e:c9:b9:4e:e6:61 Lease:0x6485f6f5}
I0610 09:38:28.446051 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:2a:80:59:1b:ab:5a ID:1,2a:80:59:1b:ab:5a Lease:0x6485f613}
I0610 09:38:28.446065 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:ca:4:36:62:66:5d ID:1,ca:4:36:62:66:5d Lease:0x6485f5e7}
I0610 09:38:28.446074 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ca:e3:b4:f8:a0:57 ID:1,ca:e3:b4:f8:a0:57 Lease:0x6485f4b1}
I0610 09:38:28.451134 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0610 09:38:28.505717 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0610 09:38:28.506467 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0610 09:38:28.506503 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0610 09:38:28.506530 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0610 09:38:28.506549 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0610 09:38:28.862344 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0610 09:38:28.862366 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0610 09:38:28.966386 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0610 09:38:28.966406 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0610 09:38:28.966446 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0610 09:38:28.966466 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0610 09:38:28.967290 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0610 09:38:28.967304 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:28 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0610 09:38:30.447641 3473 main.go:141] libmachine: (multinode-826000) DBG | Attempt 1
I0610 09:38:30.447658 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:30.447712 3473 main.go:141] libmachine: (multinode-826000) DBG | hyperkit pid from json: 3484
I0610 09:38:30.448466 3473 main.go:141] libmachine: (multinode-826000) DBG | Searching for fa:20:3f:84:ae:92 in /var/db/dhcpd_leases ...
I0610 09:38:30.448517 3473 main.go:141] libmachine: (multinode-826000) DBG | Found 10 entries in /var/db/dhcpd_leases!
I0610 09:38:30.448528 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:32:30:6d:e9:c8:b4 ID:1,32:30:6d:e9:c8:b4 Lease:0x6484a701}
I0610 09:38:30.448548 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:a6:94:da:ab:ab:e2 ID:1,a6:94:da:ab:ab:e2 Lease:0x6484a6eb}
I0610 09:38:30.448557 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:3a:96:c4:94:8e:b0 ID:1,3a:96:c4:94:8e:b0 Lease:0x6485f81d}
I0610 09:38:30.448578 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:e6:27:b7:b3:13:83 ID:1,e6:27:b7:b3:13:83 Lease:0x6485f7f9}
I0610 09:38:30.448586 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ea:f7:ed:fb:5e:ee ID:1,ea:f7:ed:fb:5e:ee Lease:0x6485f7ba}
I0610 09:38:30.448597 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:c2:ab:cc:f4:2:8a ID:1,c2:ab:cc:f4:2:8a Lease:0x6485f73e}
I0610 09:38:30.448606 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:7e:c9:b9:4e:e6:61 ID:1,7e:c9:b9:4e:e6:61 Lease:0x6485f6f5}
I0610 09:38:30.448626 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:2a:80:59:1b:ab:5a ID:1,2a:80:59:1b:ab:5a Lease:0x6485f613}
I0610 09:38:30.448640 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:ca:4:36:62:66:5d ID:1,ca:4:36:62:66:5d Lease:0x6485f5e7}
I0610 09:38:30.448649 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ca:e3:b4:f8:a0:57 ID:1,ca:e3:b4:f8:a0:57 Lease:0x6485f4b1}
I0610 09:38:32.449606 3473 main.go:141] libmachine: (multinode-826000) DBG | Attempt 2
I0610 09:38:32.449620 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:32.449659 3473 main.go:141] libmachine: (multinode-826000) DBG | hyperkit pid from json: 3484
I0610 09:38:32.450436 3473 main.go:141] libmachine: (multinode-826000) DBG | Searching for fa:20:3f:84:ae:92 in /var/db/dhcpd_leases ...
I0610 09:38:32.450472 3473 main.go:141] libmachine: (multinode-826000) DBG | Found 10 entries in /var/db/dhcpd_leases!
I0610 09:38:32.450485 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:32:30:6d:e9:c8:b4 ID:1,32:30:6d:e9:c8:b4 Lease:0x6484a701}
I0610 09:38:32.450495 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:a6:94:da:ab:ab:e2 ID:1,a6:94:da:ab:ab:e2 Lease:0x6484a6eb}
I0610 09:38:32.450504 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:3a:96:c4:94:8e:b0 ID:1,3a:96:c4:94:8e:b0 Lease:0x6485f81d}
I0610 09:38:32.450511 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:e6:27:b7:b3:13:83 ID:1,e6:27:b7:b3:13:83 Lease:0x6485f7f9}
I0610 09:38:32.450519 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ea:f7:ed:fb:5e:ee ID:1,ea:f7:ed:fb:5e:ee Lease:0x6485f7ba}
I0610 09:38:32.450536 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:c2:ab:cc:f4:2:8a ID:1,c2:ab:cc:f4:2:8a Lease:0x6485f73e}
I0610 09:38:32.450545 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:7e:c9:b9:4e:e6:61 ID:1,7e:c9:b9:4e:e6:61 Lease:0x6485f6f5}
I0610 09:38:32.450553 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:2a:80:59:1b:ab:5a ID:1,2a:80:59:1b:ab:5a Lease:0x6485f613}
I0610 09:38:32.450571 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:ca:4:36:62:66:5d ID:1,ca:4:36:62:66:5d Lease:0x6485f5e7}
I0610 09:38:32.450585 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ca:e3:b4:f8:a0:57 ID:1,ca:e3:b4:f8:a0:57 Lease:0x6485f4b1}
I0610 09:38:33.481660 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:33 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I0610 09:38:33.481689 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:33 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I0610 09:38:33.481698 3473 main.go:141] libmachine: (multinode-826000) DBG | 2023/06/10 09:38:33 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I0610 09:38:34.452291 3473 main.go:141] libmachine: (multinode-826000) DBG | Attempt 3
I0610 09:38:34.452316 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:34.452398 3473 main.go:141] libmachine: (multinode-826000) DBG | hyperkit pid from json: 3484
I0610 09:38:34.453119 3473 main.go:141] libmachine: (multinode-826000) DBG | Searching for fa:20:3f:84:ae:92 in /var/db/dhcpd_leases ...
I0610 09:38:34.453178 3473 main.go:141] libmachine: (multinode-826000) DBG | Found 10 entries in /var/db/dhcpd_leases!
I0610 09:38:34.453186 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:32:30:6d:e9:c8:b4 ID:1,32:30:6d:e9:c8:b4 Lease:0x6484a701}
I0610 09:38:34.453197 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:a6:94:da:ab:ab:e2 ID:1,a6:94:da:ab:ab:e2 Lease:0x6484a6eb}
I0610 09:38:34.453211 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:3a:96:c4:94:8e:b0 ID:1,3a:96:c4:94:8e:b0 Lease:0x6485f81d}
I0610 09:38:34.453219 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:e6:27:b7:b3:13:83 ID:1,e6:27:b7:b3:13:83 Lease:0x6485f7f9}
I0610 09:38:34.453228 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ea:f7:ed:fb:5e:ee ID:1,ea:f7:ed:fb:5e:ee Lease:0x6485f7ba}
I0610 09:38:34.453237 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:c2:ab:cc:f4:2:8a ID:1,c2:ab:cc:f4:2:8a Lease:0x6485f73e}
I0610 09:38:34.453246 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:7e:c9:b9:4e:e6:61 ID:1,7e:c9:b9:4e:e6:61 Lease:0x6485f6f5}
I0610 09:38:34.453254 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:2a:80:59:1b:ab:5a ID:1,2a:80:59:1b:ab:5a Lease:0x6485f613}
I0610 09:38:34.453261 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:ca:4:36:62:66:5d ID:1,ca:4:36:62:66:5d Lease:0x6485f5e7}
I0610 09:38:34.453278 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ca:e3:b4:f8:a0:57 ID:1,ca:e3:b4:f8:a0:57 Lease:0x6485f4b1}
I0610 09:38:36.455145 3473 main.go:141] libmachine: (multinode-826000) DBG | Attempt 4
I0610 09:38:36.455164 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:36.455224 3473 main.go:141] libmachine: (multinode-826000) DBG | hyperkit pid from json: 3484
I0610 09:38:36.455962 3473 main.go:141] libmachine: (multinode-826000) DBG | Searching for fa:20:3f:84:ae:92 in /var/db/dhcpd_leases ...
I0610 09:38:36.455978 3473 main.go:141] libmachine: (multinode-826000) DBG | Found 11 entries in /var/db/dhcpd_leases!
I0610 09:38:36.455995 3473 main.go:141] libmachine: (multinode-826000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:fa:20:3f:84:ae:92 ID:1,fa:20:3f:84:ae:92 Lease:0x6485f88c}
I0610 09:38:36.456002 3473 main.go:141] libmachine: (multinode-826000) DBG | Found match: fa:20:3f:84:ae:92
I0610 09:38:36.456008 3473 main.go:141] libmachine: (multinode-826000) DBG | IP: 192.168.64.12
I0610 09:38:36.456038 3473 main.go:141] libmachine: (multinode-826000) Calling .GetConfigRaw
I0610 09:38:36.456591 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:36.456700 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:36.456804 3473 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0610 09:38:36.456814 3473 main.go:141] libmachine: (multinode-826000) Calling .GetState
I0610 09:38:36.456899 3473 main.go:141] libmachine: (multinode-826000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0610 09:38:36.456955 3473 main.go:141] libmachine: (multinode-826000) DBG | hyperkit pid from json: 3484
I0610 09:38:36.457659 3473 main.go:141] libmachine: Detecting operating system of created instance...
I0610 09:38:36.457674 3473 main.go:141] libmachine: Waiting for SSH to be available...
I0610 09:38:36.457680 3473 main.go:141] libmachine: Getting to WaitForSSH function...
I0610 09:38:36.457685 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:36.457785 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:36.457893 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:36.458001 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:36.458097 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:36.458229 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:36.458577 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:36.458585 3473 main.go:141] libmachine: About to run SSH command:
exit 0
I0610 09:38:37.535299 3473 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0610 09:38:37.535316 3473 main.go:141] libmachine: Detecting the provisioner...
I0610 09:38:37.535335 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:37.535518 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:37.535608 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:37.535698 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:37.535804 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:37.535952 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:37.536275 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:37.536303 3473 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0610 09:38:37.612594 3473 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-ge0c6143-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I0610 09:38:37.612670 3473 main.go:141] libmachine: found compatible host: buildroot
I0610 09:38:37.612677 3473 main.go:141] libmachine: Provisioning with buildroot...
I0610 09:38:37.612683 3473 main.go:141] libmachine: (multinode-826000) Calling .GetMachineName
I0610 09:38:37.612827 3473 buildroot.go:166] provisioning hostname "multinode-826000"
I0610 09:38:37.612836 3473 main.go:141] libmachine: (multinode-826000) Calling .GetMachineName
I0610 09:38:37.612940 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:37.613047 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:37.613159 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:37.613266 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:37.613388 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:37.613531 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:37.613842 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:37.613851 3473 main.go:141] libmachine: About to run SSH command:
sudo hostname multinode-826000 && echo "multinode-826000" | sudo tee /etc/hostname
I0610 09:38:37.693963 3473 main.go:141] libmachine: SSH cmd err, output: <nil>: multinode-826000
I0610 09:38:37.693981 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:37.694118 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:37.694210 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:37.694296 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:37.694392 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:37.694529 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:37.694832 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:37.694845 3473 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\smultinode-826000' /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 multinode-826000/g' /etc/hosts;
else
echo '127.0.1.1 multinode-826000' | sudo tee -a /etc/hosts;
fi
fi
I0610 09:38:37.769734 3473 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0610 09:38:37.769753 3473 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/16578-1235/.minikube CaCertPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/16578-1235/.minikube}
I0610 09:38:37.769764 3473 buildroot.go:174] setting up certificates
I0610 09:38:37.769775 3473 provision.go:83] configureAuth start
I0610 09:38:37.769783 3473 main.go:141] libmachine: (multinode-826000) Calling .GetMachineName
I0610 09:38:37.769882 3473 main.go:141] libmachine: (multinode-826000) Calling .GetIP
I0610 09:38:37.769979 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:37.770051 3473 provision.go:138] copyHostCerts
I0610 09:38:37.770088 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem -> /Users/jenkins/minikube-integration/16578-1235/.minikube/ca.pem
I0610 09:38:37.770148 3473 exec_runner.go:144] found /Users/jenkins/minikube-integration/16578-1235/.minikube/ca.pem, removing ...
I0610 09:38:37.770156 3473 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16578-1235/.minikube/ca.pem
I0610 09:38:37.770318 3473 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/16578-1235/.minikube/ca.pem (1078 bytes)
I0610 09:38:37.770562 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/cert.pem -> /Users/jenkins/minikube-integration/16578-1235/.minikube/cert.pem
I0610 09:38:37.770600 3473 exec_runner.go:144] found /Users/jenkins/minikube-integration/16578-1235/.minikube/cert.pem, removing ...
I0610 09:38:37.770605 3473 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16578-1235/.minikube/cert.pem
I0610 09:38:37.770674 3473 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/16578-1235/.minikube/cert.pem (1123 bytes)
I0610 09:38:37.771042 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/key.pem -> /Users/jenkins/minikube-integration/16578-1235/.minikube/key.pem
I0610 09:38:37.771087 3473 exec_runner.go:144] found /Users/jenkins/minikube-integration/16578-1235/.minikube/key.pem, removing ...
I0610 09:38:37.771092 3473 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16578-1235/.minikube/key.pem
I0610 09:38:37.771161 3473 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/16578-1235/.minikube/key.pem (1679 bytes)
I0610 09:38:37.771299 3473 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca-key.pem org=jenkins.multinode-826000 san=[192.168.64.12 192.168.64.12 localhost 127.0.0.1 minikube multinode-826000]
I0610 09:38:38.062354 3473 provision.go:172] copyRemoteCerts
I0610 09:38:38.062425 3473 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0610 09:38:38.062442 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.062578 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.062684 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.062784 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.062890 3473 sshutil.go:53] new ssh client: &{IP:192.168.64.12 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/id_rsa Username:docker}
I0610 09:38:38.105770 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem -> /etc/docker/ca.pem
I0610 09:38:38.105878 3473 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16578-1235/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0610 09:38:38.122161 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server.pem -> /etc/docker/server.pem
I0610 09:38:38.122216 3473 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server.pem --> /etc/docker/server.pem (1224 bytes)
I0610 09:38:38.138387 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server-key.pem -> /etc/docker/server-key.pem
I0610 09:38:38.138449 3473 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16578-1235/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0610 09:38:38.154015 3473 provision.go:86] duration metric: configureAuth took 384.229655ms
I0610 09:38:38.154028 3473 buildroot.go:189] setting minikube options for container-runtime
I0610 09:38:38.154164 3473 config.go:182] Loaded profile config "multinode-826000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.27.2
I0610 09:38:38.154176 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:38.154327 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.154410 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.154500 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.154585 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.154676 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.154797 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:38.155091 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:38.155099 3473 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0610 09:38:38.228253 3473 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0610 09:38:38.228267 3473 buildroot.go:70] root file system type: tmpfs
I0610 09:38:38.228335 3473 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0610 09:38:38.228349 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.228489 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.228572 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.228677 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.228760 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.228912 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:38.229212 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:38.229259 3473 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %s "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I0610 09:38:38.310505 3473 main.go:141] libmachine: SSH cmd err, output: <nil>: [Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP $MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
I0610 09:38:38.310529 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.310663 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.310759 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.310857 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.310957 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.311094 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:38.311403 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:38.311415 3473 main.go:141] libmachine: About to run SSH command:
sudo diff -u /lib/systemd/system/docker.service /lib/systemd/system/docker.service.new || { sudo mv /lib/systemd/system/docker.service.new /lib/systemd/system/docker.service; sudo systemctl -f daemon-reload && sudo systemctl -f enable docker && sudo systemctl -f restart docker; }
I0610 09:38:38.799528 3473 main.go:141] libmachine: SSH cmd err, output: <nil>: diff: can't stat '/lib/systemd/system/docker.service': No such file or directory
Created symlink /etc/systemd/system/multi-user.target.wants/docker.service → /usr/lib/systemd/system/docker.service.
I0610 09:38:38.799549 3473 main.go:141] libmachine: Checking connection to Docker...
I0610 09:38:38.799562 3473 main.go:141] libmachine: (multinode-826000) Calling .GetURL
I0610 09:38:38.799698 3473 main.go:141] libmachine: Docker is up and running!
I0610 09:38:38.799706 3473 main.go:141] libmachine: Reticulating splines...
I0610 09:38:38.799710 3473 client.go:171] LocalClient.Create took 11.033704528s
I0610 09:38:38.799720 3473 start.go:167] duration metric: libmachine.API.Create for "multinode-826000" took 11.033740567s
I0610 09:38:38.799729 3473 start.go:300] post-start starting for "multinode-826000" (driver="hyperkit")
I0610 09:38:38.799735 3473 start.go:328] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I0610 09:38:38.799748 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:38.799908 3473 ssh_runner.go:195] Run: sudo mkdir -p /etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs
I0610 09:38:38.799923 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.800011 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.800101 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.800210 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.800296 3473 sshutil.go:53] new ssh client: &{IP:192.168.64.12 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/id_rsa Username:docker}
I0610 09:38:38.844251 3473 ssh_runner.go:195] Run: cat /etc/os-release
I0610 09:38:38.846656 3473 command_runner.go:130] > NAME=Buildroot
I0610 09:38:38.846664 3473 command_runner.go:130] > VERSION=2021.02.12-1-ge0c6143-dirty
I0610 09:38:38.846668 3473 command_runner.go:130] > ID=buildroot
I0610 09:38:38.846672 3473 command_runner.go:130] > VERSION_ID=2021.02.12
I0610 09:38:38.846676 3473 command_runner.go:130] > PRETTY_NAME="Buildroot 2021.02.12"
I0610 09:38:38.846854 3473 info.go:137] Remote host: Buildroot 2021.02.12
I0610 09:38:38.846862 3473 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16578-1235/.minikube/addons for local assets ...
I0610 09:38:38.846939 3473 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16578-1235/.minikube/files for local assets ...
I0610 09:38:38.847103 3473 filesync.go:149] local asset: /Users/jenkins/minikube-integration/16578-1235/.minikube/files/etc/ssl/certs/16822.pem -> 16822.pem in /etc/ssl/certs
I0610 09:38:38.847109 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/files/etc/ssl/certs/16822.pem -> /etc/ssl/certs/16822.pem
I0610 09:38:38.847280 3473 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0610 09:38:38.853501 3473 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16578-1235/.minikube/files/etc/ssl/certs/16822.pem --> /etc/ssl/certs/16822.pem (1708 bytes)
I0610 09:38:38.868717 3473 start.go:303] post-start completed in 68.979715ms
I0610 09:38:38.868750 3473 main.go:141] libmachine: (multinode-826000) Calling .GetConfigRaw
I0610 09:38:38.869309 3473 main.go:141] libmachine: (multinode-826000) Calling .GetIP
I0610 09:38:38.869465 3473 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16578-1235/.minikube/profiles/multinode-826000/config.json ...
I0610 09:38:38.869752 3473 start.go:128] duration metric: createHost completed in 11.15600736s
I0610 09:38:38.869768 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.869859 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.869941 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.870016 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.870109 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.870220 3473 main.go:141] libmachine: Using SSH client type: native
I0610 09:38:38.870513 3473 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140c4a0] 0x140f540 <nil> [] 0s} 192.168.64.12 22 <nil> <nil>}
I0610 09:38:38.870521 3473 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0610 09:38:38.942587 3473 main.go:141] libmachine: SSH cmd err, output: <nil>: 1686415118.972883939
I0610 09:38:38.942598 3473 fix.go:207] guest clock: 1686415118.972883939
I0610 09:38:38.942604 3473 fix.go:220] Guest: 2023-06-10 09:38:38.972883939 -0700 PDT Remote: 2023-06-10 09:38:38.869761 -0700 PDT m=+11.567522724 (delta=103.122939ms)
I0610 09:38:38.942623 3473 fix.go:191] guest clock delta is within tolerance: 103.122939ms
I0610 09:38:38.942629 3473 start.go:83] releasing machines lock for "multinode-826000", held for 11.229009233s
I0610 09:38:38.942646 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:38.942778 3473 main.go:141] libmachine: (multinode-826000) Calling .GetIP
I0610 09:38:38.942874 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:38.943201 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:38.943305 3473 main.go:141] libmachine: (multinode-826000) Calling .DriverName
I0610 09:38:38.943388 3473 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0610 09:38:38.943415 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.943425 3473 ssh_runner.go:195] Run: cat /version.json
I0610 09:38:38.943435 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHHostname
I0610 09:38:38.943526 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.943539 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHPort
I0610 09:38:38.943608 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.943634 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHKeyPath
I0610 09:38:38.943705 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.943727 3473 main.go:141] libmachine: (multinode-826000) Calling .GetSSHUsername
I0610 09:38:38.943788 3473 sshutil.go:53] new ssh client: &{IP:192.168.64.12 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/id_rsa Username:docker}
I0610 09:38:38.943829 3473 sshutil.go:53] new ssh client: &{IP:192.168.64.12 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16578-1235/.minikube/machines/multinode-826000/id_rsa Username:docker}
I0610 09:38:39.025573 3473 command_runner.go:130] > <a href="https://github.com/kubernetes/registry.k8s.io">Temporary Redirect</a>.
I0610 09:38:39.026604 3473 command_runner.go:130] > {"iso_version": "v1.30.1-1686096373-16019", "kicbase_version": "v0.0.39-1686006988-16632", "minikube_version": "v1.30.1", "commit": "25a6e24452a99fbf54228d85990beeaaccbd5c35"}
I0610 09:38:39.026751 3473 ssh_runner.go:195] Run: systemctl --version
I0610 09:38:39.031124 3473 command_runner.go:130] > systemd 247 (247)
I0610 09:38:39.031141 3473 command_runner.go:130] > -PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK -SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ +LZ4 -ZSTD +SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid
I0610 09:38:39.031542 3473 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I0610 09:38:39.034965 3473 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W0610 09:38:39.035049 3473 cni.go:208] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0610 09:38:39.035091 3473 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f ( ( -name *bridge* -or -name *podman* ) -and -not -name *.mk_disabled ) -printf "%p, " -exec sh -c "sudo mv {} {}.mk_disabled" ;
I0610 09:38:39.044543 3473 command_runner.go:139] > /etc/cni/net.d/87-podman-bridge.conflist,
I0610 09:38:39.044566 3473 cni.go:261] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0610 09:38:39.044574 3473 preload.go:132] Checking if preload exists for k8s version v1.27.2 and runtime docker
I0610 09:38:39.044657 3473 ssh_runner.go:195] Run: docker images --format {{.Repository}}:{{.Tag}}
I0610 09:38:39.058584 3473 docker.go:633] Got preloaded images:
I0610 09:38:39.058596 3473 docker.go:639] registry.k8s.io/kube-apiserver:v1.27.2 wasn't preloaded
I0610 09:38:39.058659 3473 ssh_runner.go:195] Run: sudo cat /var/lib/docker/image/overlay2/repositories.json
I0610 09:38:39.065006 3473 command_runner.go:139] > {"Repositories":{}}
I0610 09:38:39.065324 3473 ssh_runner.go:195] Run: which lz4
I0610 09:38:39.067435 3473 command_runner.go:130] > /usr/bin/lz4
I0610 09:38:39.067601 3473 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/16578-1235/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.27.2-docker-overlay2-amd64.tar.lz4 -> /preloaded.tar.lz4
I0610 09:38:39.067722 3473 ssh_runner.go:195] Run: stat -c "%s %y" /preloaded.tar.lz4
I0610 09:38:39.070046 3473 command_runner.go:130] ! stat: cannot statx '/preloaded.tar.lz4': No such file or directory
I0610 09:38:39.070202 3473 ssh_runner.go:352] existence check for /preloaded.tar.lz4: stat -c "%s %y" /preloaded.tar.lz4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/preloaded.tar.lz4': No such file or directory
I0610 09:38:39.070223 3473 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16578-1235/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.27.2-docker-overlay2-amd64.tar.lz4 --> /preloaded.tar.lz4 (412256110 bytes)
I0610 09:38:40.519523 3473 docker.go:597] Took 1.451869 seconds to copy over tarball
I0610 09:38:40.519589 3473 ssh_runner.go:195] Run: sudo tar -I lz4 -C /var -xf /preloaded.tar.lz4
I0610 09:38:44.117701 3473 ssh_runner.go:235] Completed: sudo tar -I lz4 -C /var -xf /preloaded.tar.lz4: (3.598110114s)
I0610 09:38:44.117716 3473 ssh_runner.go:146] rm: /preloaded.tar.lz4
I0610 09:38:44.144081 3473 ssh_runner.go:195] Run: sudo cat /var/lib/docker/image/overlay2/repositories.json
I0610 09:38:44.150207 3473 command_runner.go:139] > {"Repositories":{"gcr.io/k8s-minikube/storage-provisioner":{"gcr.io/k8s-minikube/storage-provisioner:v5":"sha256:6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562","gcr.io/k8s-minikube/storage-provisioner@sha256:18eb69d1418e854ad5a19e399310e52808a8321e4c441c1dddad8977a0d7a944":"sha256:6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562"},"registry.k8s.io/coredns/coredns":{"registry.k8s.io/coredns/coredns:v1.10.1":"sha256:ead0a4a53df89fd173874b46093b6e62d8c72967bbf606d672c9e8c9b601a4fc","registry.k8s.io/coredns/coredns@sha256:a0ead06651cf580044aeb0a0feba63591858fb2e43ade8c9dea45a6a89ae7e5e":"sha256:ead0a4a53df89fd173874b46093b6e62d8c72967bbf606d672c9e8c9b601a4fc"},"registry.k8s.io/etcd":{"registry.k8s.io/etcd:3.5.7-0":"sha256:86b6af7dd652c1b38118be1c338e9354b33469e69a218f7e290a0ca5304ad681","registry.k8s.io/etcd@sha256:51eae8381dcb1078289fa7b4f3df2630cdc18d09fb56f8e56b41c40e191d6c83":"sha256:86b6af7dd652c1b38118be1c338e
9354b33469e69a218f7e290a0ca5304ad681"},"registry.k8s.io/kube-apiserver":{"registry.k8s.io/kube-apiserver:v1.27.2":"sha256:c5b13e4f7806de1dcc1c1146c7ec7c89d77ac340c3695118cf84bb0b5f989370","registry.k8s.io/kube-apiserver@sha256:94e48585629fde3c1d06c6ae8f62885d3052f12a1072ffd97611296525eff5b9":"sha256:c5b13e4f7806de1dcc1c1146c7ec7c89d77ac340c3695118cf84bb0b5f989370"},"registry.k8s.io/kube-controller-manager":{"registry.k8s.io/kube-controller-manager:v1.27.2":"sha256:ac2b7465ebba99362b6ea11fca1357b90ae6854b4464a25c55e6eef622103e12","registry.k8s.io/kube-controller-manager@sha256:b0990ef7c9ce9edd0f57355a7e4cb43a71e864bfd2cd55bc68e4998e00213b56":"sha256:ac2b7465ebba99362b6ea11fca1357b90ae6854b4464a25c55e6eef622103e12"},"registry.k8s.io/kube-proxy":{"registry.k8s.io/kube-proxy:v1.27.2":"sha256:b8aa50768fd675409bd7edcc4f6a18290dad5d9c2515aad12d32174dc13e7dee","registry.k8s.io/kube-proxy@sha256:1e4f13f5f5c215813fb9c9c6f56da1c0354363f2a69bd12732658f79d585864f":"sha256:b8aa50768fd675409bd7edcc4f6a18290dad5d9c2515aad12d
32174dc13e7dee"},"registry.k8s.io/kube-scheduler":{"registry.k8s.io/kube-scheduler:v1.27.2":"sha256:89e70da428d29a45b89f5daa196229ceddea947f4708b3a61669e0069cb6b8b0","registry.k8s.io/kube-scheduler@sha256:89e8c591cc58bd0e8651dddee3de290399b1ae5ad14779afe84779083fe05177":"sha256:89e70da428d29a45b89f5daa196229ceddea947f4708b3a61669e0069cb6b8b0"},"registry.k8s.io/pause":{"registry.k8s.io/pause:3.9":"sha256:e6f1816883972d4be47bd48879a08919b96afcd344132622e4d444987919323c","registry.k8s.io/pause@sha256:7031c1b283388d2c2e09b57badb803c05ebed362dc88d84b480cc47f72a21097":"sha256:e6f1816883972d4be47bd48879a08919b96afcd344132622e4d444987919323c"}}}
I0610 09:38:44.150291 3473 ssh_runner.go:362] scp memory --> /var/lib/docker/image/overlay2/repositories.json (2629 bytes)
I0610 09:38:44.161379 3473 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0610 09:38:44.242567 3473 ssh_runner.go:195] Run: sudo systemctl restart docker
I0610 09:38:45.602471 3473 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.359885473s)
I0610 09:38:45.602504 3473 start.go:481] detecting cgroup driver to use...
I0610 09:38:45.602603 3473 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///run/containerd/containerd.sock
" | sudo tee /etc/crictl.yaml"
I0610 09:38:45.614419 3473 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I0610 09:38:45.614814 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.9"|' /etc/containerd/config.toml"
I0610 09:38:45.621254 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)restrict_oom_score_adj = .*$|\1restrict_oom_score_adj = false|' /etc/containerd/config.toml"
I0610 09:38:45.627670 3473 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I0610 09:38:45.627707 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0610 09:38:45.634121 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runtime.v1.linux"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0610 09:38:45.640518 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0610 09:38:45.646880 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0610 09:38:45.653267 3473 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0610 09:38:45.659811 3473 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)conf_dir = .*$|\1conf_dir = "/etc/cni/net.d"|g' /etc/containerd/config.toml"
I0610 09:38:45.666340 3473 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0610 09:38:45.672075 3473 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I0610 09:38:45.672124 3473 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0610 09:38:45.678054 3473 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0610 09:38:45.762857 3473 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0610 09:38:45.773803 3473 start.go:481] detecting cgroup driver to use...
I0610 09:38:45.773876 3473 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0610 09:38:45.783029 3473 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I0610 09:38:45.783531 3473 command_runner.go:130] > [Unit]
I0610 09:38:45.783541 3473 command_runner.go:130] > Description=Docker Application Container Engine
I0610 09:38:45.783550 3473 command_runner.go:130] > Documentation=https://docs.docker.com
I0610 09:38:45.783555 3473 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I0610 09:38:45.783559 3473 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I0610 09:38:45.783563 3473 command_runner.go:130] > StartLimitBurst=3
I0610 09:38:45.783567 3473 command_runner.go:130] > StartLimitIntervalSec=60
I0610 09:38:45.783571 3473 command_runner.go:130] > [Service]
I0610 09:38:45.783574 3473 command_runner.go:130] > Type=notify
I0610 09:38:45.783577 3473 command_runner.go:130] > Restart=on-failure
I0610 09:38:45.783584 3473 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I0610 09:38:45.783590 3473 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I0610 09:38:45.783596 3473 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I0610 09:38:45.783602 3473 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I0610 09:38:45.783609 3473 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I0610 09:38:45.783615 3473 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I0610 09:38:45.783621 3473 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I0610 09:38:45.783628 3473 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I0610 09:38:45.783633 3473 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I0610 09:38:45.783637 3473 command_runner.go:130] > ExecStart=
I0610 09:38:45.783648 3473 command_runner.go:130] > 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
I0610 09:38:45.783653 3473 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I0610 09:38:45.783659 3473 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I0610 09:38:45.783665 3473 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I0610 09:38:45.783668 3473 command_runner.go:130] > LimitNOFILE=infinity
I0610 09:38:45.783672 3473 command_runner.go:130] > LimitNPROC=infinity
I0610 09:38:45.783676 3473 command_runner.go:130] > LimitCORE=infinity
I0610 09:38:45.783680 3473 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I0610 09:38:45.783685 3473 command_runner.go:130] > # Only systemd 226 and above support this version.
I0610 09:38:45.783689 3473 command_runner.go:130] > TasksMax=infinity
I0610 09:38:45.783693 3473 command_runner.go:130] > TimeoutStartSec=0
I0610 09:38:45.783698 3473 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I0610 09:38:45.783701 3473 command_runner.go:130] > Delegate=yes
I0610 09:38:45.783711 3473 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I0610 09:38:45.783717 3473 command_runner.go:130] > KillMode=process
I0610 09:38:45.783722 3473 command_runner.go:130] > [Install]
I0610 09:38:45.783730 3473 command_runner.go:130] > WantedBy=multi-user.target
I0610 09:38:45.783880 3473 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0610 09:38:45.792806 3473 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0610 09:38:45.804389 3473 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0610 09:38:45.813241 3473 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0610 09:38:45.821639 3473 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0610 09:38:45.841034 3473 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0610 09:38:45.850000 3473 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I0610 09:38:45.862186 3473 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I0610 09:38:45.862531 3473 ssh_runner.go:195] Run: which cri-dockerd
I0610 09:38:45.864664 3473 command_runner.go:130] > /usr/bin/cri-dockerd
I0610 09:38:45.864790 3473 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0610 09:38:45.870398 3473 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0610 09:38:45.881504 3473 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0610 09:38:45.964319 3473 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0610 09:38:46.055927 3473 docker.go:532] configuring docker to use "cgroupfs" as cgroup driver...
I0610 09:38:46.055943 3473 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (144 bytes)
I0610 09:38:46.067030 3473 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0610 09:38:46.149878 3473 ssh_runner.go:195] Run: sudo systemctl restart docker
I0610 09:38:47.440336 3473 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.290440937s)
I0610 09:38:47.440412 3473 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0610 09:38:47.525491 3473 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I0610 09:38:47.607918 3473 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0610 09:38:47.696783 3473 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0610 09:38:47.785687 3473 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I0610 09:38:47.796389 3473 command_runner.go:130] ! Job failed. See "journalctl -xe" for details.
I0610 09:38:47.819432 3473 out.go:177]
W0610 09:38:47.841144 3473 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
W0610 09:38:47.841169 3473 out.go:239] *
*
W0610 09:38:47.842315 3473 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0610 09:38:47.904804 3473 out.go:177]
** /stderr **
multinode_test.go:87: failed to start cluster. args "out/minikube-darwin-amd64 start -p multinode-826000 --wait=true --memory=2200 --nodes=2 -v=8 --alsologtostderr --driver=hyperkit " : exit status 90
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p multinode-826000 -n multinode-826000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p multinode-826000 -n multinode-826000: exit status 6 (128.266404ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0610 09:38:48.058359 3488 status.go:415] kubeconfig endpoint: extract IP: "multinode-826000" does not appear in /Users/jenkins/minikube-integration/16578-1235/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "multinode-826000" host is not running, skipping log retrieval (state="Running\nWARNING: Your kubectl is pointing to stale minikube-vm.\nTo fix the kubectl context, run `minikube update-context`")
--- FAIL: TestMultiNode/serial/FreshStart2Nodes (20.77s)