=== RUN TestPreload
preload_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p test-preload-613000 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=hyperkit --kubernetes-version=v1.24.4
preload_test.go:44: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p test-preload-613000 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=hyperkit --kubernetes-version=v1.24.4: exit status 42 (1m1.191192413s)
-- stdout --
* [test-preload-613000] minikube v1.30.1 on Darwin 13.4
- MINIKUBE_LOCATION=16761
- KUBECONFIG=/Users/jenkins/minikube-integration/16761-9282/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/16761-9282/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting control plane node test-preload-613000 in cluster test-preload-613000
* Creating hyperkit VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
* Preparing Kubernetes v1.24.4 on Docker 24.0.2 ...
-- /stdout --
** stderr **
I0626 12:11:53.722666 12087 out.go:296] Setting OutFile to fd 1 ...
I0626 12:11:53.722939 12087 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0626 12:11:53.722947 12087 out.go:309] Setting ErrFile to fd 2...
I0626 12:11:53.722953 12087 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0626 12:11:53.723092 12087 root.go:336] Updating PATH: /Users/jenkins/minikube-integration/16761-9282/.minikube/bin
I0626 12:11:53.750292 12087 out.go:303] Setting JSON to false
I0626 12:11:53.770235 12087 start.go:127] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":6084,"bootTime":1687800629,"procs":397,"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"}
W0626 12:11:53.770319 12087 start.go:135] gopshost.Virtualization returned error: not implemented yet
I0626 12:11:53.791814 12087 out.go:177] * [test-preload-613000] minikube v1.30.1 on Darwin 13.4
I0626 12:11:53.832990 12087 out.go:177] - MINIKUBE_LOCATION=16761
I0626 12:11:53.832996 12087 notify.go:220] Checking for updates...
I0626 12:11:53.857103 12087 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/16761-9282/kubeconfig
I0626 12:11:53.877971 12087 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0626 12:11:53.898903 12087 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0626 12:11:53.919797 12087 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/16761-9282/.minikube
I0626 12:11:53.941054 12087 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0626 12:11:53.962441 12087 driver.go:373] Setting default libvirt URI to qemu:///system
I0626 12:11:53.990966 12087 out.go:177] * Using the hyperkit driver based on user configuration
I0626 12:11:54.032795 12087 start.go:297] selected driver: hyperkit
I0626 12:11:54.032821 12087 start.go:954] validating driver "hyperkit" against <nil>
I0626 12:11:54.032836 12087 start.go:965] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0626 12:11:54.036946 12087 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.037053 12087 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/16761-9282/.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
I0626 12:11:54.043738 12087 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.30.1
I0626 12:11:54.047205 12087 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0626 12:11:54.047223 12087 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0626 12:11:54.047254 12087 start_flags.go:305] no existing cluster config was found, will generate one from the flags
I0626 12:11:54.047496 12087 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]
I0626 12:11:54.047520 12087 cni.go:84] Creating CNI manager for ""
I0626 12:11:54.047533 12087 cni.go:152] "hyperkit" driver + "docker" runtime found, recommending bridge
I0626 12:11:54.047540 12087 start_flags.go:314] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0626 12:11:54.047549 12087 start_flags.go:319] config:
{Name:test-preload-613000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1687538068-16731@sha256:d08658afefe15fb29b5fcdace4d88182b61941d4fc6089c962f9de20073de953 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.24.4 ClusterName:test-preload-613000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRu
ntime: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: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}
I0626 12:11:54.047691 12087 iso.go:125] acquiring lock: {Name:mk0cd2ef55d098046b8862f564576e428d400aa9 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.069094 12087 out.go:177] * Starting control plane node test-preload-613000 in cluster test-preload-613000
I0626 12:11:54.110892 12087 preload.go:132] Checking if preload exists for k8s version v1.24.4 and runtime docker
I0626 12:11:54.111176 12087 cache.go:107] acquiring lock: {Name:mk7c88e3b9088030dc4d985c2fe961b98ebfa73e Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111181 12087 cache.go:107] acquiring lock: {Name:mkdc84f7b8b5d03fe5ffccbd745c973db2af3be1 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111246 12087 cache.go:107] acquiring lock: {Name:mkd94f821b8de3246ae94255664b8ddd41f09ba9 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111291 12087 cache.go:107] acquiring lock: {Name:mka9afbbfc5771e401744d539a38cc8df72d70e0 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111411 12087 cache.go:107] acquiring lock: {Name:mk7303d90da45ca5309d560f53a6c42db9ae26c8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111418 12087 cache.go:107] acquiring lock: {Name:mk7b3819d8357e22800a88b5b0926845c1a34d61 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111446 12087 cache.go:107] acquiring lock: {Name:mk471a72ae4cf2b8b02001d7319db227df5790ab Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111465 12087 cache.go:107] acquiring lock: {Name:mk3fb5b813dc3d2f9024582dd5dc01abceaa8fcc Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0626 12:11:54.111649 12087 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16761-9282/.minikube/profiles/test-preload-613000/config.json ...
I0626 12:11:54.111736 12087 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/16761-9282/.minikube/profiles/test-preload-613000/config.json: {Name:mk2f53bf8289040d62553041f8e8b5bbd8b00b18 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0626 12:11:54.111916 12087 image.go:134] retrieving image: registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:11:54.111929 12087 image.go:134] retrieving image: registry.k8s.io/kube-proxy:v1.24.4
I0626 12:11:54.111969 12087 image.go:134] retrieving image: registry.k8s.io/etcd:3.5.3-0
I0626 12:11:54.111978 12087 image.go:134] retrieving image: registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:11:54.111923 12087 image.go:134] retrieving image: gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:11:54.112061 12087 image.go:134] retrieving image: registry.k8s.io/pause:3.7
I0626 12:11:54.112082 12087 image.go:134] retrieving image: registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:11:54.112385 12087 image.go:134] retrieving image: registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:11:54.112802 12087 start.go:365] acquiring machines lock for test-preload-613000: {Name:mk4f87e333725438ca9a769a7fb318a480b00e5a Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0626 12:11:54.112965 12087 start.go:369] acquired machines lock for "test-preload-613000" in 122.451µs
I0626 12:11:54.113011 12087 start.go:93] Provisioning new machine with config: &{Name:test-preload-613000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/16703/minikube-v1.30.1-1687455737-16703-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1687538068-16731@sha256:d08658afefe15fb29b5fcdace4d88182b61941d4fc6089c962f9de20073de953 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 Kubern
etesConfig:{KubernetesVersion:v1.24.4 ClusterName:test-preload-613000 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.24.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:
262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0} &{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:docker ControlPlane:true Worker:true}
I0626 12:11:54.113156 12087 start.go:125] createHost starting for "" (driver="hyperkit")
I0626 12:11:54.135151 12087 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
I0626 12:11:54.135907 12087 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0626 12:11:54.136069 12087 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0626 12:11:54.140427 12087 image.go:177] daemon lookup for registry.k8s.io/pause:3.7: Error response from daemon: No such image: registry.k8s.io/pause:3.7
I0626 12:11:54.140459 12087 image.go:177] daemon lookup for gcr.io/k8s-minikube/storage-provisioner:v5: Error response from daemon: No such image: gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:11:54.140538 12087 image.go:177] daemon lookup for registry.k8s.io/kube-controller-manager:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:11:54.140609 12087 image.go:177] daemon lookup for registry.k8s.io/kube-proxy:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-proxy:v1.24.4
I0626 12:11:54.140427 12087 image.go:177] daemon lookup for registry.k8s.io/kube-apiserver:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:11:54.141640 12087 image.go:177] daemon lookup for registry.k8s.io/etcd:3.5.3-0: Error response from daemon: No such image: registry.k8s.io/etcd:3.5.3-0
I0626 12:11:54.141692 12087 image.go:177] daemon lookup for registry.k8s.io/kube-scheduler:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:11:54.141779 12087 image.go:177] daemon lookup for registry.k8s.io/coredns/coredns:v1.8.6: Error response from daemon: No such image: registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:11:54.145610 12087 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:59564
I0626 12:11:54.145951 12087 main.go:141] libmachine: () Calling .GetVersion
I0626 12:11:54.146403 12087 main.go:141] libmachine: Using API Version 1
I0626 12:11:54.146415 12087 main.go:141] libmachine: () Calling .SetConfigRaw
I0626 12:11:54.146647 12087 main.go:141] libmachine: () Calling .GetMachineName
I0626 12:11:54.146751 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetMachineName
I0626 12:11:54.146844 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:11:54.146949 12087 start.go:159] libmachine.API.Create for "test-preload-613000" (driver="hyperkit")
I0626 12:11:54.146975 12087 client.go:168] LocalClient.Create starting
I0626 12:11:54.147023 12087 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca.pem
I0626 12:11:54.147061 12087 main.go:141] libmachine: Decoding PEM data...
I0626 12:11:54.147078 12087 main.go:141] libmachine: Parsing certificate...
I0626 12:11:54.147152 12087 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16761-9282/.minikube/certs/cert.pem
I0626 12:11:54.147177 12087 main.go:141] libmachine: Decoding PEM data...
I0626 12:11:54.147190 12087 main.go:141] libmachine: Parsing certificate...
I0626 12:11:54.147204 12087 main.go:141] libmachine: Running pre-create checks...
I0626 12:11:54.147230 12087 main.go:141] libmachine: (test-preload-613000) Calling .PreCreateCheck
I0626 12:11:54.147336 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:11:54.147485 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetConfigRaw
I0626 12:11:54.147904 12087 main.go:141] libmachine: Creating machine...
I0626 12:11:54.147913 12087 main.go:141] libmachine: (test-preload-613000) Calling .Create
I0626 12:11:54.147998 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:11:54.148110 12087 main.go:141] libmachine: (test-preload-613000) DBG | I0626 12:11:54.147987 12095 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/16761-9282/.minikube
I0626 12:11:54.148205 12087 main.go:141] libmachine: (test-preload-613000) Downloading /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/16761-9282/.minikube/cache/iso/amd64/minikube-v1.30.1-1687455737-16703-amd64.iso...
I0626 12:11:54.348390 12087 main.go:141] libmachine: (test-preload-613000) DBG | I0626 12:11:54.348312 12095 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/id_rsa...
I0626 12:11:54.555267 12087 main.go:141] libmachine: (test-preload-613000) DBG | I0626 12:11:54.555164 12095 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/test-preload-613000.rawdisk...
I0626 12:11:54.555294 12087 main.go:141] libmachine: (test-preload-613000) DBG | Writing magic tar header
I0626 12:11:54.555304 12087 main.go:141] libmachine: (test-preload-613000) DBG | Writing SSH key tar header
I0626 12:11:54.555761 12087 main.go:141] libmachine: (test-preload-613000) DBG | I0626 12:11:54.555719 12095 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000 ...
I0626 12:11:54.892475 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:11:54.892505 12087 main.go:141] libmachine: (test-preload-613000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/hyperkit.pid
I0626 12:11:54.892520 12087 main.go:141] libmachine: (test-preload-613000) DBG | Using UUID 4ff5daa4-1455-11ee-bbab-f01898ef957c
I0626 12:11:54.996667 12087 main.go:141] libmachine: (test-preload-613000) DBG | Generated MAC b6:19:74:5b:4:87
I0626 12:11:54.996710 12087 main.go:141] libmachine: (test-preload-613000) 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=test-preload-613000
I0626 12:11:54.996797 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:54 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"4ff5daa4-1455-11ee-bbab-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000110480)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/initrd", Bootrom:"", CPUs:2, Memory:2200, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", pro
cess:(*os.Process)(nil)}
I0626 12:11:54.996841 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:54 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"4ff5daa4-1455-11ee-bbab-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000110480)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/initrd", Bootrom:"", CPUs:2, Memory:2200, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", pro
cess:(*os.Process)(nil)}
I0626 12:11:54.996882 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:54 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/hyperkit.pid", "-c", "2", "-m", "2200M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "4ff5daa4-1455-11ee-bbab-f01898ef957c", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/test-preload-613000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/tty,log=/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/bzimage,/Users/jenkins/m
inikube-integration/16761-9282/.minikube/machines/test-preload-613000/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=test-preload-613000"}
I0626 12:11:54.996915 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:54 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/hyperkit.pid -c 2 -m 2200M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 4ff5daa4-1455-11ee-bbab-f01898ef957c -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/test-preload-613000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/tty,log=/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/console-ring -f kexec,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/bzimage,/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload
-613000/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=test-preload-613000"
I0626 12:11:54.996951 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:54 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0626 12:11:54.999483 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:54 DEBUG: hyperkit: Pid is 12138
I0626 12:11:54.999827 12087 main.go:141] libmachine: (test-preload-613000) DBG | Attempt 0
I0626 12:11:54.999841 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:11:54.999893 12087 main.go:141] libmachine: (test-preload-613000) DBG | hyperkit pid from json: 12138
I0626 12:11:55.000705 12087 main.go:141] libmachine: (test-preload-613000) DBG | Searching for b6:19:74:5b:4:87 in /var/db/dhcpd_leases ...
I0626 12:11:55.000813 12087 main.go:141] libmachine: (test-preload-613000) DBG | Found 57 entries in /var/db/dhcpd_leases!
I0626 12:11:55.000828 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:22:b8:6e:bb:8:a6 ID:1,22:b8:6e:bb:8:a6 Lease:0x6499e2ee}
I0626 12:11:55.000845 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:66:c:3f:a1:31:b8 ID:1,66:c:3f:a1:31:b8 Lease:0x6499e263}
I0626 12:11:55.000865 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:2:c8:5:48:a2:83 ID:1,2:c8:5:48:a2:83 Lease:0x649b3431}
I0626 12:11:55.000879 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:de:49:82:1b:8b:59 ID:1,de:49:82:1b:8b:59 Lease:0x649b33fd}
I0626 12:11:55.000895 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:9a:14:9:51:6e:b3 ID:1,9a:14:9:51:6e:b3 Lease:0x6499e110}
I0626 12:11:55.000923 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:aa:be:dc:a:cb:88 ID:1,aa:be:dc:a:cb:88 Lease:0x6499e0fa}
I0626 12:11:55.000950 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:de:76:34:d7:88:30 ID:1,de:76:34:d7:88:30 Lease:0x649b3229}
I0626 12:11:55.000963 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:86:9e:90:94:e5:a4 ID:1,86:9e:90:94:e5:a4 Lease:0x649b3203}
I0626 12:11:55.000978 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ea:b2:6e:b9:ec ID:1,e:ea:b2:6e:b9:ec Lease:0x649b31c2}
I0626 12:11:55.000989 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:92:56:ef:da:18:85 ID:1,92:56:ef:da:18:85 Lease:0x649b3128}
I0626 12:11:55.001004 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:3a:37:dc:c7:7c:b7 ID:1,3a:37:dc:c7:7c:b7 Lease:0x649b30ec}
I0626 12:11:55.001022 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:e9:2b:1b:34:49 ID:1,d2:e9:2b:1b:34:49 Lease:0x649b3002}
I0626 12:11:55.001044 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:46:44:2d:22:87:40 ID:1,46:44:2d:22:87:40 Lease:0x6499de77}
I0626 12:11:55.001062 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:f2:28:ec:9d:a9:1d ID:1,f2:28:ec:9d:a9:1d Lease:0x649b2e89}
I0626 12:11:55.001075 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:3a:79:67:c5:5a:5f ID:1,3a:79:67:c5:5a:5f Lease:0x649b2d91}
I0626 12:11:55.001084 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2a:82:8b:6d:ce:64 ID:1,2a:82:8b:6d:ce:64 Lease:0x649b2ce0}
I0626 12:11:55.001093 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:da:e:bf:34:ae:e0 ID:1,da:e:bf:34:ae:e0 Lease:0x649b2c1a}
I0626 12:11:55.001120 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:8e:8c:d7:11:ef:f8 ID:1,8e:8c:d7:11:ef:f8 Lease:0x649b2a7d}
I0626 12:11:55.001129 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:1e:13:6f:e2:9:a6 ID:1,1e:13:6f:e2:9:a6 Lease:0x649b2a9d}
I0626 12:11:55.001138 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:f6:35:73:80:7a:f3 ID:1,f6:35:73:80:7a:f3 Lease:0x649b29b7}
I0626 12:11:55.001146 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:46:82:c:ca:f0:5e ID:1,46:82:c:ca:f0:5e Lease:0x649b29aa}
I0626 12:11:55.001154 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:92:16:74:50:f5:99 ID:1,92:16:74:50:f5:99 Lease:0x649b2958}
I0626 12:11:55.001162 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:9a:51:89:f8:b2:a5 ID:1,9a:51:89:f8:b2:a5 Lease:0x649b293d}
I0626 12:11:55.001172 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:ca:52:6a:de:11:1d ID:1,ca:52:6a:de:11:1d Lease:0x649b28fc}
I0626 12:11:55.001181 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:de:df:d4:bd:10:9 ID:1,de:df:d4:bd:10:9 Lease:0x649b28e2}
I0626 12:11:55.001188 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:36:1d:20:d3:ed:ab ID:1,36:1d:20:d3:ed:ab Lease:0x649b2892}
I0626 12:11:55.001197 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:4e:71:5e:42:50:48 ID:1,4e:71:5e:42:50:48 Lease:0x649b2882}
I0626 12:11:55.001205 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:26:d3:b4:3:ff:33 ID:1,26:d3:b4:3:ff:33 Lease:0x6499d6f8}
I0626 12:11:55.001220 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:b2:62:75:43:f2:33 ID:1,b2:62:75:43:f2:33 Lease:0x649b2839}
I0626 12:11:55.001228 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:1a:14:d4:46:4e:75 ID:1,1a:14:d4:46:4e:75 Lease:0x6499d6cf}
I0626 12:11:55.001241 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:fa:e5:59:e9:7e:2e ID:1,fa:e5:59:e9:7e:2e Lease:0x649b281c}
I0626 12:11:55.001253 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:3a:1a:d9:95:36:24 ID:1,3a:1a:d9:95:36:24 Lease:0x649b27b6}
I0626 12:11:55.001261 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:9e:7e:71:f7:7c:94 ID:1,9e:7e:71:f7:7c:94 Lease:0x6499d625}
I0626 12:11:55.001269 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:ca:2:85:77:1:ba ID:1,ca:2:85:77:1:ba Lease:0x649b267d}
I0626 12:11:55.001281 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:4a:37:dc:95:11:a3 ID:1,4a:37:dc:95:11:a3 Lease:0x6499d4e2}
I0626 12:11:55.001289 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:be:a5:f8:58:75:92 ID:1,be:a5:f8:58:75:92 Lease:0x649b2637}
I0626 12:11:55.001298 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:de:6e:1f:14:16:82 ID:1,de:6e:1f:14:16:82 Lease:0x6499d4b9}
I0626 12:11:55.001306 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:ca:e7:4d:16:e3:4c ID:1,ca:e7:4d:16:e3:4c Lease:0x6499d4ad}
I0626 12:11:55.001315 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:4a:ac:27:45:10:bf ID:1,4a:ac:27:45:10:bf Lease:0x649b25ec}
I0626 12:11:55.001323 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:d6:f9:7d:58:54:f8 ID:1,d6:f9:7d:58:54:f8 Lease:0x649b25d7}
I0626 12:11:55.001332 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:7a:3f:e3:82:66:27 ID:1,7a:3f:e3:82:66:27 Lease:0x649b2568}
I0626 12:11:55.001340 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:7e:ed:e0:ed:b1:32 ID:1,7e:ed:e0:ed:b1:32 Lease:0x649b24f6}
I0626 12:11:55.001349 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:be:ae:58:5f:c2:f4 ID:1,be:ae:58:5f:c2:f4 Lease:0x649b24a6}
I0626 12:11:55.001359 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:fe:f7:89:a0:eb:a2 ID:1,fe:f7:89:a0:eb:a2 Lease:0x6499d2ac}
I0626 12:11:55.001367 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:d6:1f:64:e7:b:52 ID:1,d6:1f:64:e7:b:52 Lease:0x6499d205}
I0626 12:11:55.001375 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:3a:76:86:fc:4b:f2 ID:1,3a:76:86:fc:4b:f2 Lease:0x649b23d4}
I0626 12:11:55.001383 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:7a:64:57:4b:ca:d1 ID:1,7a:64:57:4b:ca:d1 Lease:0x649b239f}
I0626 12:11:55.001390 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:12:bb:40:84:c3:e3 ID:1,12:bb:40:84:c3:e3 Lease:0x6499d0c1}
I0626 12:11:55.001400 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:8e:a1:bd:ad:b4:7e ID:1,8e:a1:bd:ad:b4:7e Lease:0x6499d0ab}
I0626 12:11:55.001407 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:12:53:43:56:9e:4d ID:1,12:53:43:56:9e:4d Lease:0x6499d07f}
I0626 12:11:55.001416 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:ee:37:d3:9e:86:bf ID:1,ee:37:d3:9e:86:bf Lease:0x649b21bb}
I0626 12:11:55.001431 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:2a:a1:24:cd:3e:92 ID:1,2a:a1:24:cd:3e:92 Lease:0x649b217a}
I0626 12:11:55.001444 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:ee:d7:55:bc:55:db ID:1,ee:d7:55:bc:55:db Lease:0x649b20f6}
I0626 12:11:55.001456 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:76:fa:37:51:d9:3b ID:1,76:fa:37:51:d9:3b Lease:0x6499cf5b}
I0626 12:11:55.001465 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:ca:42:95:d8:ab:42 ID:1,ca:42:95:d8:ab:42 Lease:0x649b1fce}
I0626 12:11:55.001477 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:b6:40:c:c8:ae:41 ID:1,b6:40:c:c8:ae:41 Lease:0x649b1fa0}
I0626 12:11:55.001486 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6a:41:f:9:c6:83 ID:1,6a:41:f:9:c6:83 Lease:0x649b1e6a}
I0626 12:11:55.006293 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0626 12:11:55.060867 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0626 12:11:55.061753 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0626 12:11:55.061794 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0626 12:11:55.061829 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0626 12:11:55.061853 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0626 12:11:55.423596 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7
I0626 12:11:55.427148 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0626 12:11:55.427163 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0626 12:11:55.531282 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0626 12:11:55.531318 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0626 12:11:55.531329 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0626 12:11:55.531360 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0626 12:11:55.531858 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 exists
I0626 12:11:55.531874 12087 cache.go:96] cache image "registry.k8s.io/pause:3.7" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7" took 1.420578774s
I0626 12:11:55.531887 12087 cache.go:80] save to tar file registry.k8s.io/pause:3.7 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 succeeded
I0626 12:11:55.532124 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0626 12:11:55.532137 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:11:55 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0626 12:11:55.864317 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5
I0626 12:11:55.869605 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4
I0626 12:11:55.919551 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4
I0626 12:11:56.083981 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4
I0626 12:11:56.377771 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0
I0626 12:11:56.583454 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4
I0626 12:11:56.708502 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 exists
I0626 12:11:56.708521 12087 cache.go:96] cache image "gcr.io/k8s-minikube/storage-provisioner:v5" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5" took 2.597426165s
I0626 12:11:56.708532 12087 cache.go:80] save to tar file gcr.io/k8s-minikube/storage-provisioner:v5 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 succeeded
I0626 12:11:56.850306 12087 cache.go:162] opening: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6
I0626 12:11:57.002423 12087 main.go:141] libmachine: (test-preload-613000) DBG | Attempt 1
I0626 12:11:57.002441 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:11:57.002577 12087 main.go:141] libmachine: (test-preload-613000) DBG | hyperkit pid from json: 12138
I0626 12:11:57.003315 12087 main.go:141] libmachine: (test-preload-613000) DBG | Searching for b6:19:74:5b:4:87 in /var/db/dhcpd_leases ...
I0626 12:11:57.003413 12087 main.go:141] libmachine: (test-preload-613000) DBG | Found 57 entries in /var/db/dhcpd_leases!
I0626 12:11:57.003422 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:22:b8:6e:bb:8:a6 ID:1,22:b8:6e:bb:8:a6 Lease:0x6499e2ee}
I0626 12:11:57.003436 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:66:c:3f:a1:31:b8 ID:1,66:c:3f:a1:31:b8 Lease:0x6499e263}
I0626 12:11:57.003445 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:2:c8:5:48:a2:83 ID:1,2:c8:5:48:a2:83 Lease:0x649b3431}
I0626 12:11:57.003460 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:de:49:82:1b:8b:59 ID:1,de:49:82:1b:8b:59 Lease:0x649b33fd}
I0626 12:11:57.003469 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:9a:14:9:51:6e:b3 ID:1,9a:14:9:51:6e:b3 Lease:0x6499e110}
I0626 12:11:57.003490 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:aa:be:dc:a:cb:88 ID:1,aa:be:dc:a:cb:88 Lease:0x6499e0fa}
I0626 12:11:57.003504 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:de:76:34:d7:88:30 ID:1,de:76:34:d7:88:30 Lease:0x649b3229}
I0626 12:11:57.003514 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:86:9e:90:94:e5:a4 ID:1,86:9e:90:94:e5:a4 Lease:0x649b3203}
I0626 12:11:57.003527 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ea:b2:6e:b9:ec ID:1,e:ea:b2:6e:b9:ec Lease:0x649b31c2}
I0626 12:11:57.003538 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:92:56:ef:da:18:85 ID:1,92:56:ef:da:18:85 Lease:0x649b3128}
I0626 12:11:57.003549 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:3a:37:dc:c7:7c:b7 ID:1,3a:37:dc:c7:7c:b7 Lease:0x649b30ec}
I0626 12:11:57.003561 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:e9:2b:1b:34:49 ID:1,d2:e9:2b:1b:34:49 Lease:0x649b3002}
I0626 12:11:57.003577 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:46:44:2d:22:87:40 ID:1,46:44:2d:22:87:40 Lease:0x6499de77}
I0626 12:11:57.003597 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:f2:28:ec:9d:a9:1d ID:1,f2:28:ec:9d:a9:1d Lease:0x649b2e89}
I0626 12:11:57.003612 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:3a:79:67:c5:5a:5f ID:1,3a:79:67:c5:5a:5f Lease:0x649b2d91}
I0626 12:11:57.003630 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2a:82:8b:6d:ce:64 ID:1,2a:82:8b:6d:ce:64 Lease:0x649b2ce0}
I0626 12:11:57.003640 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:da:e:bf:34:ae:e0 ID:1,da:e:bf:34:ae:e0 Lease:0x649b2c1a}
I0626 12:11:57.003650 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:8e:8c:d7:11:ef:f8 ID:1,8e:8c:d7:11:ef:f8 Lease:0x649b2a7d}
I0626 12:11:57.003659 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:1e:13:6f:e2:9:a6 ID:1,1e:13:6f:e2:9:a6 Lease:0x649b2a9d}
I0626 12:11:57.003667 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:f6:35:73:80:7a:f3 ID:1,f6:35:73:80:7a:f3 Lease:0x649b29b7}
I0626 12:11:57.003676 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:46:82:c:ca:f0:5e ID:1,46:82:c:ca:f0:5e Lease:0x649b29aa}
I0626 12:11:57.003687 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:92:16:74:50:f5:99 ID:1,92:16:74:50:f5:99 Lease:0x649b2958}
I0626 12:11:57.003697 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:9a:51:89:f8:b2:a5 ID:1,9a:51:89:f8:b2:a5 Lease:0x649b293d}
I0626 12:11:57.003716 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:ca:52:6a:de:11:1d ID:1,ca:52:6a:de:11:1d Lease:0x649b28fc}
I0626 12:11:57.003725 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:de:df:d4:bd:10:9 ID:1,de:df:d4:bd:10:9 Lease:0x649b28e2}
I0626 12:11:57.003733 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:36:1d:20:d3:ed:ab ID:1,36:1d:20:d3:ed:ab Lease:0x649b2892}
I0626 12:11:57.003741 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:4e:71:5e:42:50:48 ID:1,4e:71:5e:42:50:48 Lease:0x649b2882}
I0626 12:11:57.003749 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:26:d3:b4:3:ff:33 ID:1,26:d3:b4:3:ff:33 Lease:0x6499d6f8}
I0626 12:11:57.003757 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:b2:62:75:43:f2:33 ID:1,b2:62:75:43:f2:33 Lease:0x649b2839}
I0626 12:11:57.003766 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:1a:14:d4:46:4e:75 ID:1,1a:14:d4:46:4e:75 Lease:0x6499d6cf}
I0626 12:11:57.003775 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:fa:e5:59:e9:7e:2e ID:1,fa:e5:59:e9:7e:2e Lease:0x649b281c}
I0626 12:11:57.003784 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:3a:1a:d9:95:36:24 ID:1,3a:1a:d9:95:36:24 Lease:0x649b27b6}
I0626 12:11:57.003797 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:9e:7e:71:f7:7c:94 ID:1,9e:7e:71:f7:7c:94 Lease:0x6499d625}
I0626 12:11:57.003806 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:ca:2:85:77:1:ba ID:1,ca:2:85:77:1:ba Lease:0x649b267d}
I0626 12:11:57.003814 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:4a:37:dc:95:11:a3 ID:1,4a:37:dc:95:11:a3 Lease:0x6499d4e2}
I0626 12:11:57.003822 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:be:a5:f8:58:75:92 ID:1,be:a5:f8:58:75:92 Lease:0x649b2637}
I0626 12:11:57.003830 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:de:6e:1f:14:16:82 ID:1,de:6e:1f:14:16:82 Lease:0x6499d4b9}
I0626 12:11:57.003841 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:ca:e7:4d:16:e3:4c ID:1,ca:e7:4d:16:e3:4c Lease:0x6499d4ad}
I0626 12:11:57.003854 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:4a:ac:27:45:10:bf ID:1,4a:ac:27:45:10:bf Lease:0x649b25ec}
I0626 12:11:57.003862 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:d6:f9:7d:58:54:f8 ID:1,d6:f9:7d:58:54:f8 Lease:0x649b25d7}
I0626 12:11:57.003877 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:7a:3f:e3:82:66:27 ID:1,7a:3f:e3:82:66:27 Lease:0x649b2568}
I0626 12:11:57.003891 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:7e:ed:e0:ed:b1:32 ID:1,7e:ed:e0:ed:b1:32 Lease:0x649b24f6}
I0626 12:11:57.003903 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:be:ae:58:5f:c2:f4 ID:1,be:ae:58:5f:c2:f4 Lease:0x649b24a6}
I0626 12:11:57.003912 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:fe:f7:89:a0:eb:a2 ID:1,fe:f7:89:a0:eb:a2 Lease:0x6499d2ac}
I0626 12:11:57.003920 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:d6:1f:64:e7:b:52 ID:1,d6:1f:64:e7:b:52 Lease:0x6499d205}
I0626 12:11:57.003928 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:3a:76:86:fc:4b:f2 ID:1,3a:76:86:fc:4b:f2 Lease:0x649b23d4}
I0626 12:11:57.003937 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:7a:64:57:4b:ca:d1 ID:1,7a:64:57:4b:ca:d1 Lease:0x649b239f}
I0626 12:11:57.003948 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:12:bb:40:84:c3:e3 ID:1,12:bb:40:84:c3:e3 Lease:0x6499d0c1}
I0626 12:11:57.003957 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:8e:a1:bd:ad:b4:7e ID:1,8e:a1:bd:ad:b4:7e Lease:0x6499d0ab}
I0626 12:11:57.003965 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:12:53:43:56:9e:4d ID:1,12:53:43:56:9e:4d Lease:0x6499d07f}
I0626 12:11:57.003974 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:ee:37:d3:9e:86:bf ID:1,ee:37:d3:9e:86:bf Lease:0x649b21bb}
I0626 12:11:57.003982 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:2a:a1:24:cd:3e:92 ID:1,2a:a1:24:cd:3e:92 Lease:0x649b217a}
I0626 12:11:57.003992 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:ee:d7:55:bc:55:db ID:1,ee:d7:55:bc:55:db Lease:0x649b20f6}
I0626 12:11:57.004002 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:76:fa:37:51:d9:3b ID:1,76:fa:37:51:d9:3b Lease:0x6499cf5b}
I0626 12:11:57.004010 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:ca:42:95:d8:ab:42 ID:1,ca:42:95:d8:ab:42 Lease:0x649b1fce}
I0626 12:11:57.004025 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:b6:40:c:c8:ae:41 ID:1,b6:40:c:c8:ae:41 Lease:0x649b1fa0}
I0626 12:11:57.004034 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6a:41:f:9:c6:83 ID:1,6a:41:f:9:c6:83 Lease:0x649b1e6a}
I0626 12:11:58.079601 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 exists
I0626 12:11:58.079620 12087 cache.go:96] cache image "registry.k8s.io/kube-scheduler:v1.24.4" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4" took 3.968310723s
I0626 12:11:58.079629 12087 cache.go:80] save to tar file registry.k8s.io/kube-scheduler:v1.24.4 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 succeeded
I0626 12:11:58.156114 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 exists
I0626 12:11:58.156128 12087 cache.go:96] cache image "registry.k8s.io/coredns/coredns:v1.8.6" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6" took 4.044897958s
I0626 12:11:58.156136 12087 cache.go:80] save to tar file registry.k8s.io/coredns/coredns:v1.8.6 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 succeeded
I0626 12:11:59.005611 12087 main.go:141] libmachine: (test-preload-613000) DBG | Attempt 2
I0626 12:11:59.005632 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:11:59.005692 12087 main.go:141] libmachine: (test-preload-613000) DBG | hyperkit pid from json: 12138
I0626 12:11:59.006440 12087 main.go:141] libmachine: (test-preload-613000) DBG | Searching for b6:19:74:5b:4:87 in /var/db/dhcpd_leases ...
I0626 12:11:59.006531 12087 main.go:141] libmachine: (test-preload-613000) DBG | Found 57 entries in /var/db/dhcpd_leases!
I0626 12:11:59.006540 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:22:b8:6e:bb:8:a6 ID:1,22:b8:6e:bb:8:a6 Lease:0x6499e2ee}
I0626 12:11:59.006556 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:66:c:3f:a1:31:b8 ID:1,66:c:3f:a1:31:b8 Lease:0x6499e263}
I0626 12:11:59.006565 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:2:c8:5:48:a2:83 ID:1,2:c8:5:48:a2:83 Lease:0x649b3431}
I0626 12:11:59.006587 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:de:49:82:1b:8b:59 ID:1,de:49:82:1b:8b:59 Lease:0x649b33fd}
I0626 12:11:59.006603 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:9a:14:9:51:6e:b3 ID:1,9a:14:9:51:6e:b3 Lease:0x6499e110}
I0626 12:11:59.006612 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:aa:be:dc:a:cb:88 ID:1,aa:be:dc:a:cb:88 Lease:0x6499e0fa}
I0626 12:11:59.006619 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:de:76:34:d7:88:30 ID:1,de:76:34:d7:88:30 Lease:0x649b3229}
I0626 12:11:59.006627 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:86:9e:90:94:e5:a4 ID:1,86:9e:90:94:e5:a4 Lease:0x649b3203}
I0626 12:11:59.006636 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ea:b2:6e:b9:ec ID:1,e:ea:b2:6e:b9:ec Lease:0x649b31c2}
I0626 12:11:59.006646 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:92:56:ef:da:18:85 ID:1,92:56:ef:da:18:85 Lease:0x649b3128}
I0626 12:11:59.006655 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:3a:37:dc:c7:7c:b7 ID:1,3a:37:dc:c7:7c:b7 Lease:0x649b30ec}
I0626 12:11:59.006667 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:e9:2b:1b:34:49 ID:1,d2:e9:2b:1b:34:49 Lease:0x649b3002}
I0626 12:11:59.006675 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:46:44:2d:22:87:40 ID:1,46:44:2d:22:87:40 Lease:0x6499de77}
I0626 12:11:59.006682 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:f2:28:ec:9d:a9:1d ID:1,f2:28:ec:9d:a9:1d Lease:0x649b2e89}
I0626 12:11:59.006689 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:3a:79:67:c5:5a:5f ID:1,3a:79:67:c5:5a:5f Lease:0x649b2d91}
I0626 12:11:59.006700 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2a:82:8b:6d:ce:64 ID:1,2a:82:8b:6d:ce:64 Lease:0x649b2ce0}
I0626 12:11:59.006709 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:da:e:bf:34:ae:e0 ID:1,da:e:bf:34:ae:e0 Lease:0x649b2c1a}
I0626 12:11:59.006718 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:8e:8c:d7:11:ef:f8 ID:1,8e:8c:d7:11:ef:f8 Lease:0x649b2a7d}
I0626 12:11:59.006728 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:1e:13:6f:e2:9:a6 ID:1,1e:13:6f:e2:9:a6 Lease:0x649b2a9d}
I0626 12:11:59.006736 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:f6:35:73:80:7a:f3 ID:1,f6:35:73:80:7a:f3 Lease:0x649b29b7}
I0626 12:11:59.006745 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:46:82:c:ca:f0:5e ID:1,46:82:c:ca:f0:5e Lease:0x649b29aa}
I0626 12:11:59.006753 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:92:16:74:50:f5:99 ID:1,92:16:74:50:f5:99 Lease:0x649b2958}
I0626 12:11:59.006760 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:9a:51:89:f8:b2:a5 ID:1,9a:51:89:f8:b2:a5 Lease:0x649b293d}
I0626 12:11:59.006769 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:ca:52:6a:de:11:1d ID:1,ca:52:6a:de:11:1d Lease:0x649b28fc}
I0626 12:11:59.006781 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:de:df:d4:bd:10:9 ID:1,de:df:d4:bd:10:9 Lease:0x649b28e2}
I0626 12:11:59.006789 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:36:1d:20:d3:ed:ab ID:1,36:1d:20:d3:ed:ab Lease:0x649b2892}
I0626 12:11:59.006797 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:4e:71:5e:42:50:48 ID:1,4e:71:5e:42:50:48 Lease:0x649b2882}
I0626 12:11:59.006806 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:26:d3:b4:3:ff:33 ID:1,26:d3:b4:3:ff:33 Lease:0x6499d6f8}
I0626 12:11:59.006819 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:b2:62:75:43:f2:33 ID:1,b2:62:75:43:f2:33 Lease:0x649b2839}
I0626 12:11:59.006828 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:1a:14:d4:46:4e:75 ID:1,1a:14:d4:46:4e:75 Lease:0x6499d6cf}
I0626 12:11:59.006835 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:fa:e5:59:e9:7e:2e ID:1,fa:e5:59:e9:7e:2e Lease:0x649b281c}
I0626 12:11:59.006844 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:3a:1a:d9:95:36:24 ID:1,3a:1a:d9:95:36:24 Lease:0x649b27b6}
I0626 12:11:59.006852 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:9e:7e:71:f7:7c:94 ID:1,9e:7e:71:f7:7c:94 Lease:0x6499d625}
I0626 12:11:59.006862 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:ca:2:85:77:1:ba ID:1,ca:2:85:77:1:ba Lease:0x649b267d}
I0626 12:11:59.006870 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:4a:37:dc:95:11:a3 ID:1,4a:37:dc:95:11:a3 Lease:0x6499d4e2}
I0626 12:11:59.006879 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:be:a5:f8:58:75:92 ID:1,be:a5:f8:58:75:92 Lease:0x649b2637}
I0626 12:11:59.006887 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:de:6e:1f:14:16:82 ID:1,de:6e:1f:14:16:82 Lease:0x6499d4b9}
I0626 12:11:59.006894 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:ca:e7:4d:16:e3:4c ID:1,ca:e7:4d:16:e3:4c Lease:0x6499d4ad}
I0626 12:11:59.006902 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:4a:ac:27:45:10:bf ID:1,4a:ac:27:45:10:bf Lease:0x649b25ec}
I0626 12:11:59.006910 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:d6:f9:7d:58:54:f8 ID:1,d6:f9:7d:58:54:f8 Lease:0x649b25d7}
I0626 12:11:59.006919 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:7a:3f:e3:82:66:27 ID:1,7a:3f:e3:82:66:27 Lease:0x649b2568}
I0626 12:11:59.006926 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:7e:ed:e0:ed:b1:32 ID:1,7e:ed:e0:ed:b1:32 Lease:0x649b24f6}
I0626 12:11:59.006934 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:be:ae:58:5f:c2:f4 ID:1,be:ae:58:5f:c2:f4 Lease:0x649b24a6}
I0626 12:11:59.006942 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:fe:f7:89:a0:eb:a2 ID:1,fe:f7:89:a0:eb:a2 Lease:0x6499d2ac}
I0626 12:11:59.006950 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:d6:1f:64:e7:b:52 ID:1,d6:1f:64:e7:b:52 Lease:0x6499d205}
I0626 12:11:59.006959 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:3a:76:86:fc:4b:f2 ID:1,3a:76:86:fc:4b:f2 Lease:0x649b23d4}
I0626 12:11:59.006968 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:7a:64:57:4b:ca:d1 ID:1,7a:64:57:4b:ca:d1 Lease:0x649b239f}
I0626 12:11:59.006976 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:12:bb:40:84:c3:e3 ID:1,12:bb:40:84:c3:e3 Lease:0x6499d0c1}
I0626 12:11:59.006985 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:8e:a1:bd:ad:b4:7e ID:1,8e:a1:bd:ad:b4:7e Lease:0x6499d0ab}
I0626 12:11:59.006994 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:12:53:43:56:9e:4d ID:1,12:53:43:56:9e:4d Lease:0x6499d07f}
I0626 12:11:59.007001 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:ee:37:d3:9e:86:bf ID:1,ee:37:d3:9e:86:bf Lease:0x649b21bb}
I0626 12:11:59.007011 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:2a:a1:24:cd:3e:92 ID:1,2a:a1:24:cd:3e:92 Lease:0x649b217a}
I0626 12:11:59.007020 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:ee:d7:55:bc:55:db ID:1,ee:d7:55:bc:55:db Lease:0x649b20f6}
I0626 12:11:59.007028 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:76:fa:37:51:d9:3b ID:1,76:fa:37:51:d9:3b Lease:0x6499cf5b}
I0626 12:11:59.007037 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:ca:42:95:d8:ab:42 ID:1,ca:42:95:d8:ab:42 Lease:0x649b1fce}
I0626 12:11:59.007045 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:b6:40:c:c8:ae:41 ID:1,b6:40:c:c8:ae:41 Lease:0x649b1fa0}
I0626 12:11:59.007054 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6a:41:f:9:c6:83 ID:1,6a:41:f:9:c6:83 Lease:0x649b1e6a}
I0626 12:12:00.251242 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:12:00 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I0626 12:12:00.251357 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:12:00 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I0626 12:12:00.251367 12087 main.go:141] libmachine: (test-preload-613000) DBG | 2023/06/26 12:12:00 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I0626 12:12:00.536789 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 exists
I0626 12:12:00.536810 12087 cache.go:96] cache image "registry.k8s.io/kube-controller-manager:v1.24.4" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4" took 6.425575081s
I0626 12:12:00.536821 12087 cache.go:80] save to tar file registry.k8s.io/kube-controller-manager:v1.24.4 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 succeeded
I0626 12:12:01.008256 12087 main.go:141] libmachine: (test-preload-613000) DBG | Attempt 3
I0626 12:12:01.008273 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:12:01.008348 12087 main.go:141] libmachine: (test-preload-613000) DBG | hyperkit pid from json: 12138
I0626 12:12:01.009140 12087 main.go:141] libmachine: (test-preload-613000) DBG | Searching for b6:19:74:5b:4:87 in /var/db/dhcpd_leases ...
I0626 12:12:01.009237 12087 main.go:141] libmachine: (test-preload-613000) DBG | Found 57 entries in /var/db/dhcpd_leases!
I0626 12:12:01.009248 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:22:b8:6e:bb:8:a6 ID:1,22:b8:6e:bb:8:a6 Lease:0x6499e2ee}
I0626 12:12:01.009271 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:66:c:3f:a1:31:b8 ID:1,66:c:3f:a1:31:b8 Lease:0x6499e263}
I0626 12:12:01.009298 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:2:c8:5:48:a2:83 ID:1,2:c8:5:48:a2:83 Lease:0x649b3431}
I0626 12:12:01.009307 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:de:49:82:1b:8b:59 ID:1,de:49:82:1b:8b:59 Lease:0x649b33fd}
I0626 12:12:01.009318 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:9a:14:9:51:6e:b3 ID:1,9a:14:9:51:6e:b3 Lease:0x6499e110}
I0626 12:12:01.009325 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:aa:be:dc:a:cb:88 ID:1,aa:be:dc:a:cb:88 Lease:0x6499e0fa}
I0626 12:12:01.009339 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:de:76:34:d7:88:30 ID:1,de:76:34:d7:88:30 Lease:0x649b3229}
I0626 12:12:01.009347 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:86:9e:90:94:e5:a4 ID:1,86:9e:90:94:e5:a4 Lease:0x649b3203}
I0626 12:12:01.009354 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ea:b2:6e:b9:ec ID:1,e:ea:b2:6e:b9:ec Lease:0x649b31c2}
I0626 12:12:01.009362 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:92:56:ef:da:18:85 ID:1,92:56:ef:da:18:85 Lease:0x649b3128}
I0626 12:12:01.009387 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:3a:37:dc:c7:7c:b7 ID:1,3a:37:dc:c7:7c:b7 Lease:0x649b30ec}
I0626 12:12:01.009395 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:e9:2b:1b:34:49 ID:1,d2:e9:2b:1b:34:49 Lease:0x649b3002}
I0626 12:12:01.009409 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:46:44:2d:22:87:40 ID:1,46:44:2d:22:87:40 Lease:0x6499de77}
I0626 12:12:01.009419 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:f2:28:ec:9d:a9:1d ID:1,f2:28:ec:9d:a9:1d Lease:0x649b2e89}
I0626 12:12:01.009428 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:3a:79:67:c5:5a:5f ID:1,3a:79:67:c5:5a:5f Lease:0x649b2d91}
I0626 12:12:01.009436 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2a:82:8b:6d:ce:64 ID:1,2a:82:8b:6d:ce:64 Lease:0x649b2ce0}
I0626 12:12:01.009445 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:da:e:bf:34:ae:e0 ID:1,da:e:bf:34:ae:e0 Lease:0x649b2c1a}
I0626 12:12:01.009452 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:8e:8c:d7:11:ef:f8 ID:1,8e:8c:d7:11:ef:f8 Lease:0x649b2a7d}
I0626 12:12:01.009460 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:1e:13:6f:e2:9:a6 ID:1,1e:13:6f:e2:9:a6 Lease:0x649b2a9d}
I0626 12:12:01.009468 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:f6:35:73:80:7a:f3 ID:1,f6:35:73:80:7a:f3 Lease:0x649b29b7}
I0626 12:12:01.009475 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:46:82:c:ca:f0:5e ID:1,46:82:c:ca:f0:5e Lease:0x649b29aa}
I0626 12:12:01.009492 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:92:16:74:50:f5:99 ID:1,92:16:74:50:f5:99 Lease:0x649b2958}
I0626 12:12:01.009507 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:9a:51:89:f8:b2:a5 ID:1,9a:51:89:f8:b2:a5 Lease:0x649b293d}
I0626 12:12:01.009516 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:ca:52:6a:de:11:1d ID:1,ca:52:6a:de:11:1d Lease:0x649b28fc}
I0626 12:12:01.009524 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:de:df:d4:bd:10:9 ID:1,de:df:d4:bd:10:9 Lease:0x649b28e2}
I0626 12:12:01.009542 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:36:1d:20:d3:ed:ab ID:1,36:1d:20:d3:ed:ab Lease:0x649b2892}
I0626 12:12:01.009557 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:4e:71:5e:42:50:48 ID:1,4e:71:5e:42:50:48 Lease:0x649b2882}
I0626 12:12:01.009567 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:26:d3:b4:3:ff:33 ID:1,26:d3:b4:3:ff:33 Lease:0x6499d6f8}
I0626 12:12:01.009577 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:b2:62:75:43:f2:33 ID:1,b2:62:75:43:f2:33 Lease:0x649b2839}
I0626 12:12:01.009585 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:1a:14:d4:46:4e:75 ID:1,1a:14:d4:46:4e:75 Lease:0x6499d6cf}
I0626 12:12:01.009594 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:fa:e5:59:e9:7e:2e ID:1,fa:e5:59:e9:7e:2e Lease:0x649b281c}
I0626 12:12:01.009601 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:3a:1a:d9:95:36:24 ID:1,3a:1a:d9:95:36:24 Lease:0x649b27b6}
I0626 12:12:01.009610 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:9e:7e:71:f7:7c:94 ID:1,9e:7e:71:f7:7c:94 Lease:0x6499d625}
I0626 12:12:01.009648 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:ca:2:85:77:1:ba ID:1,ca:2:85:77:1:ba Lease:0x649b267d}
I0626 12:12:01.009662 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:4a:37:dc:95:11:a3 ID:1,4a:37:dc:95:11:a3 Lease:0x6499d4e2}
I0626 12:12:01.009670 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:be:a5:f8:58:75:92 ID:1,be:a5:f8:58:75:92 Lease:0x649b2637}
I0626 12:12:01.009679 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:de:6e:1f:14:16:82 ID:1,de:6e:1f:14:16:82 Lease:0x6499d4b9}
I0626 12:12:01.009687 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:ca:e7:4d:16:e3:4c ID:1,ca:e7:4d:16:e3:4c Lease:0x6499d4ad}
I0626 12:12:01.009696 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:4a:ac:27:45:10:bf ID:1,4a:ac:27:45:10:bf Lease:0x649b25ec}
I0626 12:12:01.009704 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:d6:f9:7d:58:54:f8 ID:1,d6:f9:7d:58:54:f8 Lease:0x649b25d7}
I0626 12:12:01.009712 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:7a:3f:e3:82:66:27 ID:1,7a:3f:e3:82:66:27 Lease:0x649b2568}
I0626 12:12:01.009722 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:7e:ed:e0:ed:b1:32 ID:1,7e:ed:e0:ed:b1:32 Lease:0x649b24f6}
I0626 12:12:01.009730 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:be:ae:58:5f:c2:f4 ID:1,be:ae:58:5f:c2:f4 Lease:0x649b24a6}
I0626 12:12:01.009742 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:fe:f7:89:a0:eb:a2 ID:1,fe:f7:89:a0:eb:a2 Lease:0x6499d2ac}
I0626 12:12:01.009751 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:d6:1f:64:e7:b:52 ID:1,d6:1f:64:e7:b:52 Lease:0x6499d205}
I0626 12:12:01.009774 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:3a:76:86:fc:4b:f2 ID:1,3a:76:86:fc:4b:f2 Lease:0x649b23d4}
I0626 12:12:01.009785 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:7a:64:57:4b:ca:d1 ID:1,7a:64:57:4b:ca:d1 Lease:0x649b239f}
I0626 12:12:01.009797 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:12:bb:40:84:c3:e3 ID:1,12:bb:40:84:c3:e3 Lease:0x6499d0c1}
I0626 12:12:01.009808 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:8e:a1:bd:ad:b4:7e ID:1,8e:a1:bd:ad:b4:7e Lease:0x6499d0ab}
I0626 12:12:01.009816 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:12:53:43:56:9e:4d ID:1,12:53:43:56:9e:4d Lease:0x6499d07f}
I0626 12:12:01.009825 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:ee:37:d3:9e:86:bf ID:1,ee:37:d3:9e:86:bf Lease:0x649b21bb}
I0626 12:12:01.009833 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:2a:a1:24:cd:3e:92 ID:1,2a:a1:24:cd:3e:92 Lease:0x649b217a}
I0626 12:12:01.009842 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:ee:d7:55:bc:55:db ID:1,ee:d7:55:bc:55:db Lease:0x649b20f6}
I0626 12:12:01.009851 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:76:fa:37:51:d9:3b ID:1,76:fa:37:51:d9:3b Lease:0x6499cf5b}
I0626 12:12:01.009860 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:ca:42:95:d8:ab:42 ID:1,ca:42:95:d8:ab:42 Lease:0x649b1fce}
I0626 12:12:01.009868 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:b6:40:c:c8:ae:41 ID:1,b6:40:c:c8:ae:41 Lease:0x649b1fa0}
I0626 12:12:01.009877 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6a:41:f:9:c6:83 ID:1,6a:41:f:9:c6:83 Lease:0x649b1e6a}
I0626 12:12:01.478689 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 exists
I0626 12:12:01.478708 12087 cache.go:96] cache image "registry.k8s.io/kube-apiserver:v1.24.4" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4" took 7.367718428s
I0626 12:12:01.478716 12087 cache.go:80] save to tar file registry.k8s.io/kube-apiserver:v1.24.4 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 succeeded
I0626 12:12:02.216303 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 exists
I0626 12:12:02.216320 12087 cache.go:96] cache image "registry.k8s.io/kube-proxy:v1.24.4" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4" took 8.105322628s
I0626 12:12:02.216328 12087 cache.go:80] save to tar file registry.k8s.io/kube-proxy:v1.24.4 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 succeeded
I0626 12:12:03.009803 12087 main.go:141] libmachine: (test-preload-613000) DBG | Attempt 4
I0626 12:12:03.009819 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:12:03.009899 12087 main.go:141] libmachine: (test-preload-613000) DBG | hyperkit pid from json: 12138
I0626 12:12:03.010624 12087 main.go:141] libmachine: (test-preload-613000) DBG | Searching for b6:19:74:5b:4:87 in /var/db/dhcpd_leases ...
I0626 12:12:03.010729 12087 main.go:141] libmachine: (test-preload-613000) DBG | Found 58 entries in /var/db/dhcpd_leases!
I0626 12:12:03.010744 12087 main.go:141] libmachine: (test-preload-613000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.59 HWAddress:b6:19:74:5b:4:87 ID:1,b6:19:74:5b:4:87 Lease:0x649b3482}
I0626 12:12:03.010754 12087 main.go:141] libmachine: (test-preload-613000) DBG | Found match: b6:19:74:5b:4:87
I0626 12:12:03.010761 12087 main.go:141] libmachine: (test-preload-613000) DBG | IP: 192.168.64.59
I0626 12:12:03.010790 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetConfigRaw
I0626 12:12:03.011320 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:03.011420 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:03.011523 12087 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0626 12:12:03.011533 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetState
I0626 12:12:03.011618 12087 main.go:141] libmachine: (test-preload-613000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0626 12:12:03.011695 12087 main.go:141] libmachine: (test-preload-613000) DBG | hyperkit pid from json: 12138
I0626 12:12:03.012430 12087 main.go:141] libmachine: Detecting operating system of created instance...
I0626 12:12:03.012439 12087 main.go:141] libmachine: Waiting for SSH to be available...
I0626 12:12:03.012446 12087 main.go:141] libmachine: Getting to WaitForSSH function...
I0626 12:12:03.012455 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:03.012544 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:03.012634 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:03.012727 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:03.012812 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:03.012956 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:03.013346 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:03.013354 12087 main.go:141] libmachine: About to run SSH command:
exit 0
I0626 12:12:04.040523 12087 main.go:141] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I0626 12:12:07.110642 12087 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0626 12:12:07.110654 12087 main.go:141] libmachine: Detecting the provisioner...
I0626 12:12:07.110661 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.110793 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.110880 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.110964 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.111051 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.111180 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:07.111490 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:07.111498 12087 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0626 12:12:07.162679 12087 cache.go:157] /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 exists
I0626 12:12:07.162697 12087 cache.go:96] cache image "registry.k8s.io/etcd:3.5.3-0" -> "/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0" took 13.05177325s
I0626 12:12:07.162706 12087 cache.go:80] save to tar file registry.k8s.io/etcd:3.5.3-0 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 succeeded
I0626 12:12:07.162718 12087 cache.go:87] Successfully saved all images to host disk.
I0626 12:12:07.180262 12087 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-ge2e95ab-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I0626 12:12:07.180315 12087 main.go:141] libmachine: found compatible host: buildroot
I0626 12:12:07.180322 12087 main.go:141] libmachine: Provisioning with buildroot...
I0626 12:12:07.180328 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetMachineName
I0626 12:12:07.180460 12087 buildroot.go:166] provisioning hostname "test-preload-613000"
I0626 12:12:07.180475 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetMachineName
I0626 12:12:07.180567 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.180646 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.180727 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.180816 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.180893 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.181012 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:07.181317 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:07.181327 12087 main.go:141] libmachine: About to run SSH command:
sudo hostname test-preload-613000 && echo "test-preload-613000" | sudo tee /etc/hostname
I0626 12:12:07.262089 12087 main.go:141] libmachine: SSH cmd err, output: <nil>: test-preload-613000
I0626 12:12:07.262107 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.262248 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.262343 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.262424 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.262546 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.262691 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:07.263003 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:07.263015 12087 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\stest-preload-613000' /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 test-preload-613000/g' /etc/hosts;
else
echo '127.0.1.1 test-preload-613000' | sudo tee -a /etc/hosts;
fi
fi
I0626 12:12:07.341652 12087 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0626 12:12:07.341672 12087 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/16761-9282/.minikube CaCertPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/16761-9282/.minikube}
I0626 12:12:07.341688 12087 buildroot.go:174] setting up certificates
I0626 12:12:07.341698 12087 provision.go:83] configureAuth start
I0626 12:12:07.341706 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetMachineName
I0626 12:12:07.341840 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetIP
I0626 12:12:07.341933 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.342010 12087 provision.go:138] copyHostCerts
I0626 12:12:07.342096 12087 exec_runner.go:144] found /Users/jenkins/minikube-integration/16761-9282/.minikube/ca.pem, removing ...
I0626 12:12:07.342104 12087 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16761-9282/.minikube/ca.pem
I0626 12:12:07.342225 12087 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/16761-9282/.minikube/ca.pem (1082 bytes)
I0626 12:12:07.342451 12087 exec_runner.go:144] found /Users/jenkins/minikube-integration/16761-9282/.minikube/cert.pem, removing ...
I0626 12:12:07.342460 12087 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16761-9282/.minikube/cert.pem
I0626 12:12:07.342529 12087 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16761-9282/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/16761-9282/.minikube/cert.pem (1123 bytes)
I0626 12:12:07.342710 12087 exec_runner.go:144] found /Users/jenkins/minikube-integration/16761-9282/.minikube/key.pem, removing ...
I0626 12:12:07.342716 12087 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16761-9282/.minikube/key.pem
I0626 12:12:07.342781 12087 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16761-9282/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/16761-9282/.minikube/key.pem (1679 bytes)
I0626 12:12:07.342909 12087 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca-key.pem org=jenkins.test-preload-613000 san=[192.168.64.59 192.168.64.59 localhost 127.0.0.1 minikube test-preload-613000]
I0626 12:12:07.477418 12087 provision.go:172] copyRemoteCerts
I0626 12:12:07.477491 12087 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0626 12:12:07.477509 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.477651 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.477739 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.477823 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.477906 12087 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/id_rsa Username:docker}
I0626 12:12:07.519424 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0626 12:12:07.536492 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/server.pem --> /etc/docker/server.pem (1233 bytes)
I0626 12:12:07.552359 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0626 12:12:07.568265 12087 provision.go:86] duration metric: configureAuth took 226.56008ms
I0626 12:12:07.568275 12087 buildroot.go:189] setting minikube options for container-runtime
I0626 12:12:07.568398 12087 config.go:182] Loaded profile config "test-preload-613000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.24.4
I0626 12:12:07.568411 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:07.568540 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.568620 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.568710 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.568792 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.568867 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.568984 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:07.569278 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:07.569286 12087 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0626 12:12:07.642462 12087 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0626 12:12:07.642473 12087 buildroot.go:70] root file system type: tmpfs
I0626 12:12:07.642546 12087 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0626 12:12:07.642559 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.642701 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.642799 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.642892 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.642972 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.643102 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:07.643422 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:07.643467 12087 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
I0626 12:12:07.722961 12087 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
I0626 12:12:07.722981 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:07.723129 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:07.723221 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.723313 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:07.723397 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:07.723534 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:07.723838 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:07.723851 12087 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; }
I0626 12:12:08.317976 12087 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.
I0626 12:12:08.317993 12087 main.go:141] libmachine: Checking connection to Docker...
I0626 12:12:08.318003 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetURL
I0626 12:12:08.318157 12087 main.go:141] libmachine: Docker is up and running!
I0626 12:12:08.318165 12087 main.go:141] libmachine: Reticulating splines...
I0626 12:12:08.318169 12087 client.go:171] LocalClient.Create took 14.171517862s
I0626 12:12:08.318184 12087 start.go:167] duration metric: libmachine.API.Create for "test-preload-613000" took 14.171564349s
I0626 12:12:08.318195 12087 start.go:300] post-start starting for "test-preload-613000" (driver="hyperkit")
I0626 12:12:08.318206 12087 start.go:329] 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]
I0626 12:12:08.318221 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:08.318365 12087 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
I0626 12:12:08.318377 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:08.318458 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:08.318534 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:08.318618 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:08.318719 12087 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/id_rsa Username:docker}
I0626 12:12:08.357735 12087 ssh_runner.go:195] Run: cat /etc/os-release
I0626 12:12:08.360306 12087 info.go:137] Remote host: Buildroot 2021.02.12
I0626 12:12:08.360319 12087 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16761-9282/.minikube/addons for local assets ...
I0626 12:12:08.360397 12087 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16761-9282/.minikube/files for local assets ...
I0626 12:12:08.360588 12087 filesync.go:149] local asset: /Users/jenkins/minikube-integration/16761-9282/.minikube/files/etc/ssl/certs/97292.pem -> 97292.pem in /etc/ssl/certs
I0626 12:12:08.360768 12087 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0626 12:12:08.370411 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/files/etc/ssl/certs/97292.pem --> /etc/ssl/certs/97292.pem (1708 bytes)
I0626 12:12:08.386107 12087 start.go:303] post-start completed in 67.905757ms
I0626 12:12:08.386133 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetConfigRaw
I0626 12:12:08.386673 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetIP
I0626 12:12:08.386834 12087 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16761-9282/.minikube/profiles/test-preload-613000/config.json ...
I0626 12:12:08.387135 12087 start.go:128] duration metric: createHost completed in 14.274294973s
I0626 12:12:08.387152 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:08.387236 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:08.387326 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:08.387401 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:08.387483 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:08.387593 12087 main.go:141] libmachine: Using SSH client type: native
I0626 12:12:08.387890 12087 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d0a0] 0x1410140 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I0626 12:12:08.387897 12087 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0626 12:12:08.455618 12087 main.go:141] libmachine: SSH cmd err, output: <nil>: 1687806728.603087574
I0626 12:12:08.455631 12087 fix.go:206] guest clock: 1687806728.603087574
I0626 12:12:08.455637 12087 fix.go:219] Guest: 2023-06-26 12:12:08.603087574 -0700 PDT Remote: 2023-06-26 12:12:08.387143 -0700 PDT m=+14.716578559 (delta=215.944574ms)
I0626 12:12:08.455653 12087 fix.go:190] guest clock delta is within tolerance: 215.944574ms
I0626 12:12:08.455657 12087 start.go:83] releasing machines lock for "test-preload-613000", held for 14.343011504s
I0626 12:12:08.455674 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:08.455808 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetIP
I0626 12:12:08.455909 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:08.456187 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:08.456302 12087 main.go:141] libmachine: (test-preload-613000) Calling .DriverName
I0626 12:12:08.456385 12087 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0626 12:12:08.456411 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:08.456414 12087 ssh_runner.go:195] Run: cat /version.json
I0626 12:12:08.456423 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHHostname
I0626 12:12:08.456522 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:08.456537 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHPort
I0626 12:12:08.456622 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:08.456636 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHKeyPath
I0626 12:12:08.456722 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:08.456739 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetSSHUsername
I0626 12:12:08.456815 12087 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/id_rsa Username:docker}
I0626 12:12:08.456825 12087 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16761-9282/.minikube/machines/test-preload-613000/id_rsa Username:docker}
I0626 12:12:08.536353 12087 ssh_runner.go:195] Run: systemctl --version
I0626 12:12:08.540129 12087 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0626 12:12:08.543703 12087 cni.go:215] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0626 12:12:08.543752 12087 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" ;
I0626 12:12:08.553360 12087 cni.go:268] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0626 12:12:08.553376 12087 start.go:466] detecting cgroup driver to use...
I0626 12:12:08.553491 12087 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"
I0626 12:12:08.566189 12087 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.7"|' /etc/containerd/config.toml"
I0626 12:12:08.573154 12087 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"
I0626 12:12:08.580856 12087 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I0626 12:12:08.580915 12087 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0626 12:12:08.588062 12087 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"
I0626 12:12:08.595200 12087 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0626 12:12:08.602265 12087 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0626 12:12:08.609210 12087 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0626 12:12:08.616436 12087 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"
I0626 12:12:08.623353 12087 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0626 12:12:08.629703 12087 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0626 12:12:08.635911 12087 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0626 12:12:08.721307 12087 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0626 12:12:08.733520 12087 start.go:466] detecting cgroup driver to use...
I0626 12:12:08.744560 12087 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0626 12:12:08.757481 12087 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0626 12:12:08.767866 12087 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0626 12:12:08.779650 12087 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0626 12:12:08.789182 12087 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0626 12:12:08.797850 12087 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0626 12:12:08.849151 12087 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0626 12:12:08.858218 12087 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"
I0626 12:12:08.870143 12087 ssh_runner.go:195] Run: which cri-dockerd
I0626 12:12:08.872543 12087 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0626 12:12:08.878045 12087 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0626 12:12:08.888865 12087 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0626 12:12:08.978350 12087 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0626 12:12:09.077771 12087 docker.go:535] configuring docker to use "cgroupfs" as cgroup driver...
I0626 12:12:09.077785 12087 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (144 bytes)
I0626 12:12:09.090292 12087 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0626 12:12:09.189637 12087 ssh_runner.go:195] Run: sudo systemctl restart docker
I0626 12:12:10.558123 12087 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.368500263s)
I0626 12:12:10.558187 12087 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0626 12:12:10.654984 12087 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I0626 12:12:10.740709 12087 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0626 12:12:10.822853 12087 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0626 12:12:10.917514 12087 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I0626 12:12:10.932765 12087 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0626 12:12:11.028162 12087 ssh_runner.go:195] Run: sudo systemctl restart cri-docker
I0626 12:12:11.083247 12087 start.go:513] Will wait 60s for socket path /var/run/cri-dockerd.sock
I0626 12:12:11.083347 12087 ssh_runner.go:195] Run: stat /var/run/cri-dockerd.sock
I0626 12:12:11.087016 12087 start.go:534] Will wait 60s for crictl version
I0626 12:12:11.087065 12087 ssh_runner.go:195] Run: which crictl
I0626 12:12:11.091791 12087 ssh_runner.go:195] Run: sudo /usr/bin/crictl version
I0626 12:12:11.117940 12087 start.go:550] Version: 0.1.0
RuntimeName: docker
RuntimeVersion: 24.0.2
RuntimeApiVersion: v1alpha2
I0626 12:12:11.118026 12087 ssh_runner.go:195] Run: docker version --format {{.Server.Version}}
I0626 12:12:11.135116 12087 ssh_runner.go:195] Run: docker version --format {{.Server.Version}}
I0626 12:12:11.181705 12087 out.go:204] * Preparing Kubernetes v1.24.4 on Docker 24.0.2 ...
I0626 12:12:11.181770 12087 main.go:141] libmachine: (test-preload-613000) Calling .GetIP
I0626 12:12:11.182171 12087 ssh_runner.go:195] Run: grep 192.168.64.1 host.minikube.internal$ /etc/hosts
I0626 12:12:11.186471 12087 ssh_runner.go:195] Run: /bin/bash -c "{ grep -v $'\thost.minikube.internal$' "/etc/hosts"; echo "192.168.64.1 host.minikube.internal"; } > /tmp/h.$$; sudo cp /tmp/h.$$ "/etc/hosts""
I0626 12:12:11.195362 12087 preload.go:132] Checking if preload exists for k8s version v1.24.4 and runtime docker
I0626 12:12:11.195422 12087 ssh_runner.go:195] Run: docker images --format {{.Repository}}:{{.Tag}}
I0626 12:12:11.207880 12087 docker.go:636] Got preloaded images:
I0626 12:12:11.207893 12087 docker.go:642] registry.k8s.io/kube-apiserver:v1.24.4 wasn't preloaded
I0626 12:12:11.207900 12087 cache_images.go:88] LoadImages start: [registry.k8s.io/kube-apiserver:v1.24.4 registry.k8s.io/kube-controller-manager:v1.24.4 registry.k8s.io/kube-scheduler:v1.24.4 registry.k8s.io/kube-proxy:v1.24.4 registry.k8s.io/pause:3.7 registry.k8s.io/etcd:3.5.3-0 registry.k8s.io/coredns/coredns:v1.8.6 gcr.io/k8s-minikube/storage-provisioner:v5]
I0626 12:12:11.214649 12087 image.go:134] retrieving image: registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:12:11.216299 12087 image.go:134] retrieving image: registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:12:11.216363 12087 image.go:134] retrieving image: registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:12:11.216394 12087 image.go:134] retrieving image: gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:12:11.216530 12087 image.go:134] retrieving image: registry.k8s.io/kube-proxy:v1.24.4
I0626 12:12:11.216555 12087 image.go:134] retrieving image: registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:12:11.217140 12087 image.go:134] retrieving image: registry.k8s.io/pause:3.7
I0626 12:12:11.217135 12087 image.go:134] retrieving image: registry.k8s.io/etcd:3.5.3-0
I0626 12:12:11.221833 12087 image.go:177] daemon lookup for registry.k8s.io/coredns/coredns:v1.8.6: Error response from daemon: No such image: registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:12:11.224643 12087 image.go:177] daemon lookup for registry.k8s.io/pause:3.7: Error response from daemon: No such image: registry.k8s.io/pause:3.7
I0626 12:12:11.224667 12087 image.go:177] daemon lookup for registry.k8s.io/etcd:3.5.3-0: Error response from daemon: No such image: registry.k8s.io/etcd:3.5.3-0
I0626 12:12:11.224681 12087 image.go:177] daemon lookup for registry.k8s.io/kube-scheduler:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:12:11.224702 12087 image.go:177] daemon lookup for registry.k8s.io/kube-apiserver:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:12:11.224710 12087 image.go:177] daemon lookup for gcr.io/k8s-minikube/storage-provisioner:v5: Error response from daemon: No such image: gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:12:11.224643 12087 image.go:177] daemon lookup for registry.k8s.io/kube-proxy:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-proxy:v1.24.4
I0626 12:12:11.224825 12087 image.go:177] daemon lookup for registry.k8s.io/kube-controller-manager:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:12:11.840034 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:12:11.852619 12087 cache_images.go:116] "registry.k8s.io/coredns/coredns:v1.8.6" needs transfer: "registry.k8s.io/coredns/coredns:v1.8.6" does not exist at hash "a4ca41631cc7ac19ce1be3ebf0314ac5f47af7c711f17066006db82ee3b75b03" in container runtime
I0626 12:12:11.852663 12087 docker.go:316] Removing image: registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:12:11.852720 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/coredns/coredns:v1.8.6
I0626 12:12:11.865923 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6
I0626 12:12:11.866087 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/coredns_v1.8.6
I0626 12:12:11.868870 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/coredns_v1.8.6: stat -c "%s %y" /var/lib/minikube/images/coredns_v1.8.6: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/coredns_v1.8.6': No such file or directory
I0626 12:12:11.868897 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 --> /var/lib/minikube/images/coredns_v1.8.6 (13586432 bytes)
I0626 12:12:11.925721 12087 docker.go:283] Loading image: /var/lib/minikube/images/coredns_v1.8.6
I0626 12:12:11.925736 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/coredns_v1.8.6 | docker load"
I0626 12:12:12.368870 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/pause:3.7
I0626 12:12:12.528298 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 from cache
I0626 12:12:12.528383 12087 cache_images.go:116] "registry.k8s.io/pause:3.7" needs transfer: "registry.k8s.io/pause:3.7" does not exist at hash "221177c6082a88ea4f6240ab2450d540955ac6f4d5454f0e15751b653ebda165" in container runtime
I0626 12:12:12.528413 12087 docker.go:316] Removing image: registry.k8s.io/pause:3.7
I0626 12:12:12.528463 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/pause:3.7
I0626 12:12:12.528479 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/etcd:3.5.3-0
I0626 12:12:12.540558 12087 cache_images.go:116] "registry.k8s.io/etcd:3.5.3-0" needs transfer: "registry.k8s.io/etcd:3.5.3-0" does not exist at hash "aebe758cef4cd05b9f8cee39758227714d02f42ef3088023c1e3cd454f927a2b" in container runtime
I0626 12:12:12.540585 12087 docker.go:316] Removing image: registry.k8s.io/etcd:3.5.3-0
I0626 12:12:12.540649 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/etcd:3.5.3-0
I0626 12:12:12.548180 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7
I0626 12:12:12.548312 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/pause_3.7
I0626 12:12:12.557187 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/pause_3.7: stat -c "%s %y" /var/lib/minikube/images/pause_3.7: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/pause_3.7': No such file or directory
I0626 12:12:12.557217 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0
I0626 12:12:12.557217 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 --> /var/lib/minikube/images/pause_3.7 (311296 bytes)
I0626 12:12:12.557343 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/etcd_3.5.3-0
I0626 12:12:12.561623 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/etcd_3.5.3-0: stat -c "%s %y" /var/lib/minikube/images/etcd_3.5.3-0: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/etcd_3.5.3-0': No such file or directory
I0626 12:12:12.561660 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 --> /var/lib/minikube/images/etcd_3.5.3-0 (102146048 bytes)
I0626 12:12:12.581197 12087 docker.go:283] Loading image: /var/lib/minikube/images/pause_3.7
I0626 12:12:12.581224 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/pause_3.7 | docker load"
I0626 12:12:12.643415 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 from cache
I0626 12:12:12.920503 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:12:12.951787 12087 docker.go:283] Loading image: /var/lib/minikube/images/etcd_3.5.3-0
I0626 12:12:12.951805 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/etcd_3.5.3-0 | docker load"
I0626 12:12:12.959023 12087 cache_images.go:116] "registry.k8s.io/kube-apiserver:v1.24.4" needs transfer: "registry.k8s.io/kube-apiserver:v1.24.4" does not exist at hash "6cab9d1bed1be49c215505c1a438ce0af66eb54b4e95f06e52037fcd36631f3d" in container runtime
I0626 12:12:12.959083 12087 docker.go:316] Removing image: registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:12:12.959154 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/kube-apiserver:v1.24.4
I0626 12:12:13.231846 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:12:13.399632 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:12:13.640755 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/kube-proxy:v1.24.4
I0626 12:12:13.928561 12087 ssh_runner.go:195] Run: docker image inspect --format {{.Id}} registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:12:17.069153 12087 ssh_runner.go:235] Completed: /bin/bash -c "sudo cat /var/lib/minikube/images/etcd_3.5.3-0 | docker load": (4.117425267s)
I0626 12:12:17.069172 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 from cache
I0626 12:12:17.069204 12087 ssh_runner.go:235] Completed: docker rmi registry.k8s.io/kube-apiserver:v1.24.4: (4.110132586s)
I0626 12:12:17.069226 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4
I0626 12:12:17.069232 12087 ssh_runner.go:235] Completed: docker image inspect --format {{.Id}} gcr.io/k8s-minikube/storage-provisioner:v5: (3.837455898s)
I0626 12:12:17.069263 12087 cache_images.go:116] "gcr.io/k8s-minikube/storage-provisioner:v5" needs transfer: "gcr.io/k8s-minikube/storage-provisioner:v5" does not exist at hash "6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562" in container runtime
I0626 12:12:17.069273 12087 ssh_runner.go:235] Completed: docker image inspect --format {{.Id}} registry.k8s.io/kube-scheduler:v1.24.4: (3.669705237s)
I0626 12:12:17.069291 12087 docker.go:316] Removing image: gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:12:17.069296 12087 cache_images.go:116] "registry.k8s.io/kube-scheduler:v1.24.4" needs transfer: "registry.k8s.io/kube-scheduler:v1.24.4" does not exist at hash "03fa22539fc1ccdb96fb15098e7a02fff03d0e366ce5d80891eb0a3a8594a0c9" in container runtime
I0626 12:12:17.069321 12087 docker.go:316] Removing image: registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:12:17.069331 12087 ssh_runner.go:235] Completed: docker image inspect --format {{.Id}} registry.k8s.io/kube-proxy:v1.24.4: (3.428625154s)
I0626 12:12:17.069349 12087 ssh_runner.go:195] Run: docker rmi gcr.io/k8s-minikube/storage-provisioner:v5
I0626 12:12:17.069366 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/kube-scheduler:v1.24.4
I0626 12:12:17.069367 12087 cache_images.go:116] "registry.k8s.io/kube-proxy:v1.24.4" needs transfer: "registry.k8s.io/kube-proxy:v1.24.4" does not exist at hash "7a53d1e08ef58144850b48d05908b4ef5b611bff99a5a66dbcba7ab9f79433f7" in container runtime
I0626 12:12:17.069367 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-apiserver_v1.24.4
I0626 12:12:17.069376 12087 ssh_runner.go:235] Completed: docker image inspect --format {{.Id}} registry.k8s.io/kube-controller-manager:v1.24.4: (3.140863962s)
I0626 12:12:17.069398 12087 docker.go:316] Removing image: registry.k8s.io/kube-proxy:v1.24.4
I0626 12:12:17.069407 12087 cache_images.go:116] "registry.k8s.io/kube-controller-manager:v1.24.4" needs transfer: "registry.k8s.io/kube-controller-manager:v1.24.4" does not exist at hash "1f99cb6da9a82e81081f65acdad10cdca2e5ec4084f91009bdcff31dd6151d48" in container runtime
I0626 12:12:17.069426 12087 docker.go:316] Removing image: registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:12:17.069451 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/kube-proxy:v1.24.4
I0626 12:12:17.069472 12087 ssh_runner.go:195] Run: docker rmi registry.k8s.io/kube-controller-manager:v1.24.4
I0626 12:12:17.090478 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4
I0626 12:12:17.090627 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-proxy_v1.24.4
I0626 12:12:17.102169 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-apiserver_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-apiserver_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-apiserver_v1.24.4': No such file or directory
I0626 12:12:17.102186 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4
I0626 12:12:17.102192 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5
I0626 12:12:17.102219 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 --> /var/lib/minikube/images/kube-apiserver_v1.24.4 (33814016 bytes)
I0626 12:12:17.102227 12087 cache_images.go:286] Loading image from: /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4
I0626 12:12:17.102277 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-proxy_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-proxy_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-proxy_v1.24.4': No such file or directory
I0626 12:12:17.102314 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 --> /var/lib/minikube/images/kube-proxy_v1.24.4 (39519744 bytes)
I0626 12:12:17.102363 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/storage-provisioner_v5
I0626 12:12:17.102378 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-scheduler_v1.24.4
I0626 12:12:17.102448 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-controller-manager_v1.24.4
I0626 12:12:17.122776 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-scheduler_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-scheduler_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-scheduler_v1.24.4': No such file or directory
I0626 12:12:17.122792 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/storage-provisioner_v5: stat -c "%s %y" /var/lib/minikube/images/storage-provisioner_v5: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/storage-provisioner_v5': No such file or directory
I0626 12:12:17.122816 12087 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-controller-manager_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-controller-manager_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-controller-manager_v1.24.4': No such file or directory
I0626 12:12:17.122823 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 --> /var/lib/minikube/images/kube-scheduler_v1.24.4 (15491584 bytes)
I0626 12:12:17.122823 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 --> /var/lib/minikube/images/storage-provisioner_v5 (9060352 bytes)
I0626 12:12:17.122838 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 --> /var/lib/minikube/images/kube-controller-manager_v1.24.4 (31047168 bytes)
I0626 12:12:17.293684 12087 docker.go:283] Loading image: /var/lib/minikube/images/storage-provisioner_v5
I0626 12:12:17.293713 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/storage-provisioner_v5 | docker load"
I0626 12:12:18.038395 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 from cache
I0626 12:12:18.038419 12087 docker.go:283] Loading image: /var/lib/minikube/images/kube-scheduler_v1.24.4
I0626 12:12:18.038430 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-scheduler_v1.24.4 | docker load"
I0626 12:12:19.288742 12087 ssh_runner.go:235] Completed: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-scheduler_v1.24.4 | docker load": (1.250328377s)
I0626 12:12:19.288758 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 from cache
I0626 12:12:19.288772 12087 docker.go:283] Loading image: /var/lib/minikube/images/kube-controller-manager_v1.24.4
I0626 12:12:19.288779 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-controller-manager_v1.24.4 | docker load"
I0626 12:12:20.732740 12087 ssh_runner.go:235] Completed: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-controller-manager_v1.24.4 | docker load": (1.443980549s)
I0626 12:12:20.732754 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 from cache
I0626 12:12:20.732770 12087 docker.go:283] Loading image: /var/lib/minikube/images/kube-apiserver_v1.24.4
I0626 12:12:20.732787 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-apiserver_v1.24.4 | docker load"
I0626 12:12:22.221825 12087 ssh_runner.go:235] Completed: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-apiserver_v1.24.4 | docker load": (1.489060561s)
I0626 12:12:22.221840 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 from cache
I0626 12:12:22.221856 12087 docker.go:283] Loading image: /var/lib/minikube/images/kube-proxy_v1.24.4
I0626 12:12:22.221871 12087 ssh_runner.go:195] Run: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-proxy_v1.24.4 | docker load"
I0626 12:12:24.376631 12087 ssh_runner.go:235] Completed: /bin/bash -c "sudo cat /var/lib/minikube/images/kube-proxy_v1.24.4 | docker load": (2.154795999s)
I0626 12:12:24.376646 12087 cache_images.go:315] Transferred and loaded /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 from cache
I0626 12:12:24.376665 12087 cache_images.go:123] Successfully loaded all cached images
I0626 12:12:24.376670 12087 cache_images.go:92] LoadImages completed in 13.169059264s
I0626 12:12:24.376754 12087 ssh_runner.go:195] Run: docker info --format {{.CgroupDriver}}
I0626 12:12:24.396967 12087 cni.go:84] Creating CNI manager for ""
I0626 12:12:24.396983 12087 cni.go:152] "hyperkit" driver + "docker" runtime found, recommending bridge
I0626 12:12:24.396997 12087 kubeadm.go:87] Using pod CIDR: 10.244.0.0/16
I0626 12:12:24.397012 12087 kubeadm.go:176] kubeadm options: {CertDir:/var/lib/minikube/certs ServiceCIDR:10.96.0.0/12 PodSubnet:10.244.0.0/16 AdvertiseAddress:192.168.64.59 APIServerPort:8443 KubernetesVersion:v1.24.4 EtcdDataDir:/var/lib/minikube/etcd EtcdExtraArgs:map[] ClusterName:test-preload-613000 NodeName:test-preload-613000 DNSDomain:cluster.local CRISocket:/var/run/cri-dockerd.sock ImageRepository: ComponentOptions:[{Component:apiServer ExtraArgs:map[enable-admission-plugins:NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota] Pairs:map[certSANs:["127.0.0.1", "localhost", "192.168.64.59"]]} {Component:controllerManager ExtraArgs:map[allocate-node-cidrs:true leader-elect:false] Pairs:map[]} {Component:scheduler ExtraArgs:map[leader-elect:false] Pairs:map[]}] FeatureArgs:map[] NodeIP:192.168.64.59 CgroupDriver:cgroupfs ClientCAFile:/var/lib/minikube/certs/ca.crt StaticPod
Path:/etc/kubernetes/manifests ControlPlaneAddress:control-plane.minikube.internal KubeProxyOptions:map[] ResolvConfSearchRegression:false KubeletConfigOpts:map[hairpinMode:hairpin-veth runtimeRequestTimeout:15m] PrependCriSocketUnix:true}
I0626 12:12:24.397115 12087 kubeadm.go:181] kubeadm config:
apiVersion: kubeadm.k8s.io/v1beta3
kind: InitConfiguration
localAPIEndpoint:
advertiseAddress: 192.168.64.59
bindPort: 8443
bootstrapTokens:
- groups:
- system:bootstrappers:kubeadm:default-node-token
ttl: 24h0m0s
usages:
- signing
- authentication
nodeRegistration:
criSocket: unix:///var/run/cri-dockerd.sock
name: "test-preload-613000"
kubeletExtraArgs:
node-ip: 192.168.64.59
taints: []
---
apiVersion: kubeadm.k8s.io/v1beta3
kind: ClusterConfiguration
apiServer:
certSANs: ["127.0.0.1", "localhost", "192.168.64.59"]
extraArgs:
enable-admission-plugins: "NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota"
controllerManager:
extraArgs:
allocate-node-cidrs: "true"
leader-elect: "false"
scheduler:
extraArgs:
leader-elect: "false"
certificatesDir: /var/lib/minikube/certs
clusterName: mk
controlPlaneEndpoint: control-plane.minikube.internal:8443
etcd:
local:
dataDir: /var/lib/minikube/etcd
extraArgs:
proxy-refresh-interval: "70000"
kubernetesVersion: v1.24.4
networking:
dnsDomain: cluster.local
podSubnet: "10.244.0.0/16"
serviceSubnet: 10.96.0.0/12
---
apiVersion: kubelet.config.k8s.io/v1beta1
kind: KubeletConfiguration
authentication:
x509:
clientCAFile: /var/lib/minikube/certs/ca.crt
cgroupDriver: cgroupfs
hairpinMode: hairpin-veth
runtimeRequestTimeout: 15m
clusterDomain: "cluster.local"
# disable disk resource management by default
imageGCHighThresholdPercent: 100
evictionHard:
nodefs.available: "0%"
nodefs.inodesFree: "0%"
imagefs.available: "0%"
failSwapOn: false
staticPodPath: /etc/kubernetes/manifests
---
apiVersion: kubeproxy.config.k8s.io/v1alpha1
kind: KubeProxyConfiguration
clusterCIDR: "10.244.0.0/16"
metricsBindAddress: 0.0.0.0:10249
conntrack:
maxPerCore: 0
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_established"
tcpEstablishedTimeout: 0s
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_close"
tcpCloseWaitTimeout: 0s
I0626 12:12:24.397183 12087 kubeadm.go:976] kubelet [Unit]
Wants=docker.socket
[Service]
ExecStart=
ExecStart=/var/lib/minikube/binaries/v1.24.4/kubelet --bootstrap-kubeconfig=/etc/kubernetes/bootstrap-kubelet.conf --config=/var/lib/kubelet/config.yaml --container-runtime-endpoint=unix:///var/run/cri-dockerd.sock --hostname-override=test-preload-613000 --kubeconfig=/etc/kubernetes/kubelet.conf --node-ip=192.168.64.59
[Install]
config:
{KubernetesVersion:v1.24.4 ClusterName:test-preload-613000 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:}
I0626 12:12:24.397242 12087 ssh_runner.go:195] Run: sudo ls /var/lib/minikube/binaries/v1.24.4
I0626 12:12:24.403229 12087 binaries.go:47] Didn't find k8s binaries: sudo ls /var/lib/minikube/binaries/v1.24.4: Process exited with status 2
stdout:
stderr:
ls: cannot access '/var/lib/minikube/binaries/v1.24.4': No such file or directory
Initiating transfer...
I0626 12:12:24.403275 12087 ssh_runner.go:195] Run: sudo mkdir -p /var/lib/minikube/binaries/v1.24.4
I0626 12:12:24.409265 12087 download.go:107] Downloading: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubelet?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubelet.sha256 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubelet
I0626 12:12:24.409266 12087 download.go:107] Downloading: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl.sha256 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubectl
I0626 12:12:24.409265 12087 download.go:107] Downloading: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256 -> /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubeadm
I0626 12:12:28.851278 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubectl
I0626 12:12:28.854394 12087 ssh_runner.go:352] existence check for /var/lib/minikube/binaries/v1.24.4/kubectl: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubectl: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/binaries/v1.24.4/kubectl': No such file or directory
I0626 12:12:28.854418 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubectl --> /var/lib/minikube/binaries/v1.24.4/kubectl (45715456 bytes)
I0626 12:12:30.127975 12087 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service kubelet
I0626 12:12:30.137446 12087 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubelet
I0626 12:12:30.140364 12087 ssh_runner.go:352] existence check for /var/lib/minikube/binaries/v1.24.4/kubelet: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubelet: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/binaries/v1.24.4/kubelet': No such file or directory
I0626 12:12:30.140386 12087 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubelet --> /var/lib/minikube/binaries/v1.24.4/kubelet (116062680 bytes)
I0626 12:12:54.724286 12087 out.go:177]
W0626 12:12:54.746084 12087 out.go:239] X Exiting due to INET_DOWNLOAD_TIMEOUT: updating control plane: downloading binaries: downloading kubeadm: download failed: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256: getter: &{Ctx:context.Background Src:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256 Dst:/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubeadm.download Pwd: Mode:2 Umask:---------- Detectors:[0x4b27768 0x4b27768 0x4b27768 0x4b27768 0x4b27768 0x4b27768 0x4b27768] Decompressors:map[bz2:0xc000177000 gz:0xc000177008 tar:0xc000176fb0 tar.bz2:0xc000176fc0 tar.gz:0xc000176fd0 tar.xz:0xc000176fe0 tar.zst:0xc000176ff0 tbz2:0xc000176fc0 tgz:0xc000176fd0 txz:0xc000176fe0 tzst:0xc000176ff0 xz:0xc000177010 zip:0xc000177020 zst:0xc000177018] Getters:map[file:0xc001e219b0 http:0xc000b06460 htt
ps:0xc000b064b0] Dir:false ProgressListener:<nil> Insecure:false DisableSymlinks:false Options:[]}: invalid checksum: Error downloading checksum file: Get "https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256": dial tcp 34.107.204.206:443: i/o timeout
X Exiting due to INET_DOWNLOAD_TIMEOUT: updating control plane: downloading binaries: downloading kubeadm: download failed: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256: getter: &{Ctx:context.Background Src:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256 Dst:/Users/jenkins/minikube-integration/16761-9282/.minikube/cache/linux/amd64/v1.24.4/kubeadm.download Pwd: Mode:2 Umask:---------- Detectors:[0x4b27768 0x4b27768 0x4b27768 0x4b27768 0x4b27768 0x4b27768 0x4b27768] Decompressors:map[bz2:0xc000177000 gz:0xc000177008 tar:0xc000176fb0 tar.bz2:0xc000176fc0 tar.gz:0xc000176fd0 tar.xz:0xc000176fe0 tar.zst:0xc000176ff0 tbz2:0xc000176fc0 tgz:0xc000176fd0 txz:0xc000176fe0 tzst:0xc000176ff0 xz:0xc000177010 zip:0xc000177020 zst:0xc000177018] Getters:map[file:0xc001e219b0 http:0xc000b06460 https:0xc000b064b0] Dir:false ProgressListene
r:<nil> Insecure:false DisableSymlinks:false Options:[]}: invalid checksum: Error downloading checksum file: Get "https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256": dial tcp 34.107.204.206:443: i/o timeout
W0626 12:12:54.746139 12087 out.go:239] * Suggestion: A firewall is likely blocking minikube from reaching the internet. You may need to configure minikube to use a proxy.
* Suggestion: A firewall is likely blocking minikube from reaching the internet. You may need to configure minikube to use a proxy.
W0626 12:12:54.746162 12087 out.go:239] * Documentation: https://minikube.sigs.k8s.io/docs/handbook/vpn_and_proxy/
* Documentation: https://minikube.sigs.k8s.io/docs/handbook/vpn_and_proxy/
W0626 12:12:54.746193 12087 out.go:239] * Related issue: https://github.com/kubernetes/minikube/issues/3846
* Related issue: https://github.com/kubernetes/minikube/issues/3846
I0626 12:12:54.789132 12087 out.go:177]
** /stderr **
preload_test.go:46: out/minikube-darwin-amd64 start -p test-preload-613000 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=hyperkit --kubernetes-version=v1.24.4 failed: exit status 42
panic.go:522: *** TestPreload FAILED at 2023-06-26 12:12:54.878524 -0700 PDT m=+1634.975201191
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p test-preload-613000 -n test-preload-613000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p test-preload-613000 -n test-preload-613000: exit status 6 (128.343369ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0626 12:12:54.997412 12336 status.go:415] kubeconfig endpoint: extract IP: "test-preload-613000" does not appear in /Users/jenkins/minikube-integration/16761-9282/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "test-preload-613000" 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`")
helpers_test.go:175: Cleaning up "test-preload-613000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p test-preload-613000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p test-preload-613000: (2.482129226s)
--- FAIL: TestPreload (63.83s)