=== RUN TestPreload
preload_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p test-preload-013639 --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-013639 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=hyperkit --kubernetes-version=v1.24.4: exit status 90 (54.644051336s)
-- stdout --
* [test-preload-013639] minikube v1.27.1 on Darwin 12.6
- MINIKUBE_LOCATION=14956
- KUBECONFIG=/Users/jenkins/minikube-integration/14956-13627/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/14956-13627/.minikube
* Using the hyperkit driver based on user configuration
* Starting control plane node test-preload-013639 in cluster test-preload-013639
* Creating hyperkit VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I1025 01:36:39.366330 19054 out.go:296] Setting OutFile to fd 1 ...
I1025 01:36:39.366524 19054 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1025 01:36:39.366529 19054 out.go:309] Setting ErrFile to fd 2...
I1025 01:36:39.366536 19054 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1025 01:36:39.366638 19054 root.go:334] Updating PATH: /Users/jenkins/minikube-integration/14956-13627/.minikube/bin
I1025 01:36:39.367098 19054 out.go:303] Setting JSON to false
I1025 01:36:39.383169 19054 start.go:116] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":9372,"bootTime":1666677627,"procs":382,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"12.6","kernelVersion":"21.6.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"2965c349-98a5-5970-aaa9-9eedd3ae5959"}
W1025 01:36:39.383256 19054 start.go:124] gopshost.Virtualization returned error: not implemented yet
I1025 01:36:39.405687 19054 out.go:177] * [test-preload-013639] minikube v1.27.1 on Darwin 12.6
I1025 01:36:39.427333 19054 notify.go:220] Checking for updates...
I1025 01:36:39.449100 19054 out.go:177] - MINIKUBE_LOCATION=14956
I1025 01:36:39.470441 19054 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/14956-13627/kubeconfig
I1025 01:36:39.492623 19054 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1025 01:36:39.514496 19054 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I1025 01:36:39.536610 19054 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/14956-13627/.minikube
I1025 01:36:39.558881 19054 driver.go:362] Setting default libvirt URI to qemu:///system
I1025 01:36:39.587250 19054 out.go:177] * Using the hyperkit driver based on user configuration
I1025 01:36:39.629429 19054 start.go:282] selected driver: hyperkit
I1025 01:36:39.629457 19054 start.go:808] validating driver "hyperkit" against <nil>
I1025 01:36:39.629485 19054 start.go:819] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I1025 01:36:39.632749 19054 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.632877 19054 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/14956-13627/.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
I1025 01:36:39.639176 19054 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.27.1
I1025 01:36:39.642029 19054 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1025 01:36:39.642045 19054 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I1025 01:36:39.642079 19054 start_flags.go:303] no existing cluster config was found, will generate one from the flags
I1025 01:36:39.642272 19054 start_flags.go:885] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I1025 01:36:39.642299 19054 cni.go:95] Creating CNI manager for ""
I1025 01:36:39.642308 19054 cni.go:169] CNI unnecessary in this configuration, recommending no CNI
I1025 01:36:39.642317 19054 start_flags.go:317] config:
{Name:test-preload-013639 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.35-1665430468-15094@sha256:2c137487f3327e6653ff519ec7fd599d25c0275ae67f44e4a71485aabe1e7191 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-013639 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRu
ntime:docker CRISocket: NetworkPlugin: 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:/opt/socket_vmnet/bin/socket_vmnet_client SocketVMnetPath:/var/run/socket_vmnet}
I1025 01:36:39.642421 19054 iso.go:124] acquiring lock: {Name:mkbdf283d65d9f3738189f535547b67aa25437d5 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.685508 19054 out.go:177] * Starting control plane node test-preload-013639 in cluster test-preload-013639
I1025 01:36:39.707521 19054 preload.go:132] Checking if preload exists for k8s version v1.24.4 and runtime docker
I1025 01:36:39.707804 19054 cache.go:107] acquiring lock: {Name:mk2b9e163a1036548939a28f47a3737298831c0b Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.707833 19054 cache.go:107] acquiring lock: {Name:mk92161e599ea80da21a35a3b913fc900ca5aeea Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.707921 19054 cache.go:107] acquiring lock: {Name:mkb1d55cf1978fec84e19a097c4c9480908af9ee Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.707919 19054 cache.go:107] acquiring lock: {Name:mkf5d2df470735a4a4b1e2cf7d3aba8b1cc867fc Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.708050 19054 cache.go:107] acquiring lock: {Name:mk49fbbbcee57e4997be10cbcd265a03cfc980a4 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.708088 19054 cache.go:107] acquiring lock: {Name:mkefd488ac63f838ea0bdfc6dc9f74dd27473e57 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.708157 19054 cache.go:107] acquiring lock: {Name:mka0c1bcb5c417cbfa61e016e642aab3dd8027e5 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.708200 19054 cache.go:107] acquiring lock: {Name:mk7e7a60f3b26830abaad9f3b77dd8b76cdebd8a Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1025 01:36:39.708331 19054 profile.go:148] Saving config to /Users/jenkins/minikube-integration/14956-13627/.minikube/profiles/test-preload-013639/config.json ...
I1025 01:36:39.708412 19054 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/14956-13627/.minikube/profiles/test-preload-013639/config.json: {Name:mkd29a8954c55022299154ccfd71dcd7b175e00e Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1025 01:36:39.708669 19054 image.go:134] retrieving image: k8s.gcr.io/kube-apiserver:v1.24.4
I1025 01:36:39.708680 19054 image.go:134] retrieving image: gcr.io/k8s-minikube/storage-provisioner:v5
I1025 01:36:39.708704 19054 image.go:134] retrieving image: k8s.gcr.io/etcd:3.5.3-0
I1025 01:36:39.708687 19054 image.go:134] retrieving image: k8s.gcr.io/kube-controller-manager:v1.24.4
I1025 01:36:39.708679 19054 image.go:134] retrieving image: k8s.gcr.io/pause:3.7
I1025 01:36:39.708821 19054 image.go:134] retrieving image: k8s.gcr.io/kube-scheduler:v1.24.4
I1025 01:36:39.709000 19054 image.go:134] retrieving image: k8s.gcr.io/coredns/coredns:v1.8.6
I1025 01:36:39.709038 19054 image.go:134] retrieving image: k8s.gcr.io/kube-proxy:v1.24.4
I1025 01:36:39.709326 19054 cache.go:208] Successfully downloaded all kic artifacts
I1025 01:36:39.709383 19054 start.go:364] acquiring machines lock for test-preload-013639: {Name:mkf71c9e8c631ac7e6bbb3b4f2aefe704231ff72 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1025 01:36:39.709486 19054 start.go:368] acquired machines lock for "test-preload-013639" in 70.385µs
I1025 01:36:39.709536 19054 start.go:93] Provisioning new machine with config: &{Name:test-preload-013639 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/15159/minikube-v1.27.0-1666206003-15159-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.35-1665430468-15094@sha256:2c137487f3327e6653ff519ec7fd599d25c0275ae67f44e4a71485aabe1e7191 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-013639 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: 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:262
144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath:/opt/socket_vmnet/bin/socket_vmnet_client SocketVMnetPath:/var/run/socket_vmnet} &{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:docker ControlPlane:true Worker:true}
I1025 01:36:39.709608 19054 start.go:125] createHost starting for "" (driver="hyperkit")
I1025 01:36:39.752345 19054 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
I1025 01:36:39.752798 19054 main.go:134] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1025 01:36:39.752866 19054 main.go:134] libmachine: Launching plugin server for driver hyperkit
I1025 01:36:39.760264 19054 image.go:177] daemon lookup for gcr.io/k8s-minikube/storage-provisioner:v5: Error: No such image: gcr.io/k8s-minikube/storage-provisioner:v5
I1025 01:36:39.761474 19054 image.go:177] daemon lookup for k8s.gcr.io/coredns/coredns:v1.8.6: Error: No such image: k8s.gcr.io/coredns/coredns:v1.8.6
I1025 01:36:39.761661 19054 image.go:177] daemon lookup for k8s.gcr.io/kube-controller-manager:v1.24.4: Error: No such image: k8s.gcr.io/kube-controller-manager:v1.24.4
I1025 01:36:39.762460 19054 image.go:177] daemon lookup for k8s.gcr.io/pause:3.7: Error: No such image: k8s.gcr.io/pause:3.7
I1025 01:36:39.762905 19054 image.go:177] daemon lookup for k8s.gcr.io/kube-proxy:v1.24.4: Error: No such image: k8s.gcr.io/kube-proxy:v1.24.4
I1025 01:36:39.762976 19054 image.go:177] daemon lookup for k8s.gcr.io/kube-apiserver:v1.24.4: Error: No such image: k8s.gcr.io/kube-apiserver:v1.24.4
I1025 01:36:39.763196 19054 image.go:177] daemon lookup for k8s.gcr.io/etcd:3.5.3-0: Error: No such image: k8s.gcr.io/etcd:3.5.3-0
I1025 01:36:39.763360 19054 image.go:177] daemon lookup for k8s.gcr.io/kube-scheduler:v1.24.4: Error: No such image: k8s.gcr.io/kube-scheduler:v1.24.4
I1025 01:36:39.763574 19054 main.go:134] libmachine: Plugin server listening at address 127.0.0.1:57287
I1025 01:36:39.763932 19054 main.go:134] libmachine: () Calling .GetVersion
I1025 01:36:39.764358 19054 main.go:134] libmachine: Using API Version 1
I1025 01:36:39.764369 19054 main.go:134] libmachine: () Calling .SetConfigRaw
I1025 01:36:39.764630 19054 main.go:134] libmachine: () Calling .GetMachineName
I1025 01:36:39.764754 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetMachineName
I1025 01:36:39.764843 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:39.764946 19054 start.go:159] libmachine.API.Create for "test-preload-013639" (driver="hyperkit")
I1025 01:36:39.764985 19054 client.go:168] LocalClient.Create starting
I1025 01:36:39.765011 19054 main.go:134] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca.pem
I1025 01:36:39.765055 19054 main.go:134] libmachine: Decoding PEM data...
I1025 01:36:39.765069 19054 main.go:134] libmachine: Parsing certificate...
I1025 01:36:39.765125 19054 main.go:134] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/14956-13627/.minikube/certs/cert.pem
I1025 01:36:39.765153 19054 main.go:134] libmachine: Decoding PEM data...
I1025 01:36:39.765164 19054 main.go:134] libmachine: Parsing certificate...
I1025 01:36:39.765179 19054 main.go:134] libmachine: Running pre-create checks...
I1025 01:36:39.765187 19054 main.go:134] libmachine: (test-preload-013639) Calling .PreCreateCheck
I1025 01:36:39.765264 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:39.765393 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetConfigRaw
I1025 01:36:39.765841 19054 main.go:134] libmachine: Creating machine...
I1025 01:36:39.765850 19054 main.go:134] libmachine: (test-preload-013639) Calling .Create
I1025 01:36:39.765939 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:39.766027 19054 main.go:134] libmachine: (test-preload-013639) DBG | I1025 01:36:39.765922 19062 common.go:116] Making disk image using store path: /Users/jenkins/minikube-integration/14956-13627/.minikube
I1025 01:36:39.766078 19054 main.go:134] libmachine: (test-preload-013639) Downloading /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/14956-13627/.minikube/cache/iso/amd64/minikube-v1.27.0-1666206003-15159-amd64.iso...
I1025 01:36:39.949266 19054 main.go:134] libmachine: (test-preload-013639) DBG | I1025 01:36:39.949185 19062 common.go:123] Creating ssh key: /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/id_rsa...
I1025 01:36:40.126298 19054 main.go:134] libmachine: (test-preload-013639) DBG | I1025 01:36:40.126213 19062 common.go:129] Creating raw disk image: /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/test-preload-013639.rawdisk...
I1025 01:36:40.126321 19054 main.go:134] libmachine: (test-preload-013639) DBG | Writing magic tar header
I1025 01:36:40.126329 19054 main.go:134] libmachine: (test-preload-013639) DBG | Writing SSH key tar header
I1025 01:36:40.126924 19054 main.go:134] libmachine: (test-preload-013639) DBG | I1025 01:36:40.126863 19062 common.go:143] Fixing permissions on /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639 ...
I1025 01:36:40.320705 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:40.320735 19054 main.go:134] libmachine: (test-preload-013639) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/hyperkit.pid
I1025 01:36:40.320828 19054 main.go:134] libmachine: (test-preload-013639) DBG | Using UUID 253995b0-5440-11ed-b3aa-f01898ef957c
I1025 01:36:40.475168 19054 main.go:134] libmachine: (test-preload-013639) DBG | Generated MAC 1e:40:ad:f:1b:2a
I1025 01:36:40.475183 19054 main.go:134] libmachine: (test-preload-013639) 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-013639
I1025 01:36:40.475215 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"253995b0-5440-11ed-b3aa-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000220420)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/bzimage", Initrd:"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/initrd", Bootrom:"", CPUs:2, Memory:2200, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"",
process:(*os.Process)(nil)}
I1025 01:36:40.475240 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"253995b0-5440-11ed-b3aa-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000220420)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/bzimage", Initrd:"/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/initrd", Bootrom:"", CPUs:2, Memory:2200, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"",
process:(*os.Process)(nil)}
I1025 01:36:40.475301 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/hyperkit.pid", "-c", "2", "-m", "2200M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "253995b0-5440-11ed-b3aa-f01898ef957c", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/test-preload-013639.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/tty,log=/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/bzimage,/Users/jen
kins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/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-013639"}
I1025 01:36:40.475331 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/hyperkit.pid -c 2 -m 2200M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 253995b0-5440-11ed-b3aa-f01898ef957c -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/test-preload-013639.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/tty,log=/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/console-ring -f kexec,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/bzimage,/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-
preload-013639/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-013639"
I1025 01:36:40.475341 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I1025 01:36:40.476625 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 DEBUG: hyperkit: Pid is 19097
I1025 01:36:40.476978 19054 main.go:134] libmachine: (test-preload-013639) DBG | Attempt 0
I1025 01:36:40.476998 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:40.477064 19054 main.go:134] libmachine: (test-preload-013639) DBG | hyperkit pid from json: 19097
I1025 01:36:40.477984 19054 main.go:134] libmachine: (test-preload-013639) DBG | Searching for 1e:40:ad:f:1b:2a in /var/db/dhcpd_leases ...
I1025 01:36:40.478060 19054 main.go:134] libmachine: (test-preload-013639) DBG | Found 57 entries in /var/db/dhcpd_leases!
I1025 01:36:40.478077 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:1a:4f:e6:63:41:e6 ID:1,1a:4f:e6:63:41:e6 Lease:0x6357a016}
I1025 01:36:40.478102 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:26:ee:9c:81:92:52 ID:1,26:ee:9c:81:92:52 Lease:0x6357a014}
I1025 01:36:40.478123 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:6a:6d:c3:25:f5:dd ID:1,6a:6d:c3:25:f5:dd Lease:0x6358f16c}
I1025 01:36:40.478141 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:96:5c:ce:89:25:26 ID:1,96:5c:ce:89:25:26 Lease:0x63579db4}
I1025 01:36:40.478155 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:8e:3b:da:c5:a9:91 ID:1,8e:3b:da:c5:a9:91 Lease:0x63579982}
I1025 01:36:40.478182 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:62:4:f0:e1:51:f9 ID:1,62:4:f0:e1:51:f9 Lease:0x6357996d}
I1025 01:36:40.478198 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:62:db:31:75:9c:69 ID:1,62:db:31:75:9c:69 Lease:0x6358eaa1}
I1025 01:36:40.478220 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:f6:ab:96:dd:83:f8 ID:1,f6:ab:96:dd:83:f8 Lease:0x6358ea7a}
I1025 01:36:40.478244 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:22:79:74:ef:f2:a2 ID:1,22:79:74:ef:f2:a2 Lease:0x6358ea11}
I1025 01:36:40.478265 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:67:5a:7d:b7:79 ID:1,56:67:5a:7d:b7:79 Lease:0x6358e9a0}
I1025 01:36:40.478283 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:d2:be:6f:c0:26:f9 ID:1,d2:be:6f:c0:26:f9 Lease:0x6358e892}
I1025 01:36:40.478303 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:32:32:b0:b:45:78 ID:1,32:32:b0:b:45:78 Lease:0x6358e85f}
I1025 01:36:40.478321 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:7c:5e:2d:2:e2 ID:1,8a:7c:5e:2d:2:e2 Lease:0x635796d5}
I1025 01:36:40.478335 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:a2:b6:c6:4:94:a7 ID:1,a2:b6:c6:4:94:a7 Lease:0x6357910c}
I1025 01:36:40.478354 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:32:b6:48:f4:be:e7 ID:1,32:b6:48:f4:be:e7 Lease:0x6358e170}
I1025 01:36:40.478367 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:66:16:80:50:65:fc ID:1,66:16:80:50:65:fc Lease:0x6358e0e1}
I1025 01:36:40.478376 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:4d:28:3a:6b:62 ID:1,36:4d:28:3a:6b:62 Lease:0x6358df40}
I1025 01:36:40.478394 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:ee:2a:3:e1:7c:e ID:1,ee:2a:3:e1:7c:e Lease:0x63578fa1}
I1025 01:36:40.478431 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:6a:9a:c6:f7:f7:7b ID:1,6a:9a:c6:f7:f7:7b Lease:0x63578d70}
I1025 01:36:40.478452 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:e2:3f:6f:79:e2:cc ID:1,e2:3f:6f:79:e2:cc Lease:0x63578d26}
I1025 01:36:40.478473 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:3a:6d:5a:ce:31:54 ID:1,3a:6d:5a:ce:31:54 Lease:0x6358de25}
I1025 01:36:40.478488 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:76:dc:e4:cc:c3:5f ID:1,76:dc:e4:cc:c3:5f Lease:0x6358de1a}
I1025 01:36:40.478498 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:36:8a:11:e8:26:de ID:1,36:8a:11:e8:26:de Lease:0x63578c9b}
I1025 01:36:40.478511 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:8e:22:97:74:90:5d ID:1,8e:22:97:74:90:5d Lease:0x63578c4f}
I1025 01:36:40.478538 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:ee:9:18:f5:21:23 ID:1,ee:9:18:f5:21:23 Lease:0x6358dccf}
I1025 01:36:40.478552 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:4e:3:87:7a:85:99 ID:1,4e:3:87:7a:85:99 Lease:0x63578be4}
I1025 01:36:40.478562 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:ca:11:6:9e:c6:8b ID:1,ca:11:6:9e:c6:8b Lease:0x6358dcb3}
I1025 01:36:40.478583 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2e:21:94:a9:9e:79 ID:1,2e:21:94:a9:9e:79 Lease:0x63578b2a}
I1025 01:36:40.478601 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:46:a1:1c:fe:30:fc ID:1,46:a1:1c:fe:30:fc Lease:0x63578b45}
I1025 01:36:40.478620 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:a:97:18:1b:52:24 ID:1,a:97:18:1b:52:24 Lease:0x63578b07}
I1025 01:36:40.478635 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:42:64:b7:88:f7:40 ID:1,42:64:b7:88:f7:40 Lease:0x63578ae9}
I1025 01:36:40.478651 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:a2:fc:bc:60:d0:98 ID:1,a2:fc:bc:60:d0:98 Lease:0x6358dbfe}
I1025 01:36:40.478666 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:82:aa:8a:64:51:72 ID:1,82:aa:8a:64:51:72 Lease:0x6358dbf1}
I1025 01:36:40.478683 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:2a:3a:a1:c8:71:3 ID:1,2a:3a:a1:c8:71:3 Lease:0x6358dba5}
I1025 01:36:40.478708 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:1e:67:b2:8e:4f:8 ID:1,1e:67:b2:8e:4f:8 Lease:0x6358dac8}
I1025 01:36:40.478721 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:1a:6e:3e:b7:da:33 ID:1,1a:6e:3e:b7:da:33 Lease:0x63578933}
I1025 01:36:40.478730 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:e2:dd:4a:8e:96:7f ID:1,e2:dd:4a:8e:96:7f Lease:0x6358da88}
I1025 01:36:40.478737 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:4a:98:cb:b4:b3:37 ID:1,4a:98:cb:b4:b3:37 Lease:0x6358da6b}
I1025 01:36:40.478745 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:1a:b5:23:e5:52:b4 ID:1,1a:b5:23:e5:52:b4 Lease:0x635788ff}
I1025 01:36:40.478752 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:b2:c4:ad:c8:8c:ca ID:1,b2:c4:ad:c8:8c:ca Lease:0x635788d9}
I1025 01:36:40.478763 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:c6:1f:2b:84:df:e5 ID:1,c6:1f:2b:84:df:e5 Lease:0x6358da24}
I1025 01:36:40.478772 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:e:d7:1e:97:a4:c6 ID:1,e:d7:1e:97:a4:c6 Lease:0x6358d9da}
I1025 01:36:40.478780 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:b6:ce:7d:46:13:ac ID:1,b6:ce:7d:46:13:ac Lease:0x63578834}
I1025 01:36:40.478791 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:82:56:da:28:4b:d ID:1,82:56:da:28:4b:d Lease:0x6358d8e5}
I1025 01:36:40.478804 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:9a:6e:b9:d8:32:fb ID:1,9a:6e:b9:d8:32:fb Lease:0x63578757}
I1025 01:36:40.478812 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:50:cc:1d:7:40 ID:1,5e:50:cc:1d:7:40 Lease:0x635784e2}
I1025 01:36:40.478818 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:c2:8b:2f:75:29:1f ID:1,c2:8b:2f:75:29:1f Lease:0x6357875b}
I1025 01:36:40.478829 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:de:a6:c9:ff:49:9 ID:1,de:a6:c9:ff:49:9 Lease:0x6357875a}
I1025 01:36:40.478839 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:f6:bf:8e:3c:be:e8 ID:1,f6:bf:8e:3c:be:e8 Lease:0x635780aa}
I1025 01:36:40.478851 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:ea:26:a7:7f:1a:fd ID:1,ea:26:a7:7f:1a:fd Lease:0x63578095}
I1025 01:36:40.478862 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:92:f3:88:42:39:d5 ID:1,92:f3:88:42:39:d5 Lease:0x6357806f}
I1025 01:36:40.478873 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:9e:2f:9:ba:71:fb ID:1,9e:2f:9:ba:71:fb Lease:0x63578073}
I1025 01:36:40.478884 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:de:7e:ea:e8:16:c ID:1,de:7e:ea:e8:16:c Lease:0x6358d157}
I1025 01:36:40.478895 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:87:0:c6:b:cc ID:1,52:87:0:c6:b:cc Lease:0x63577fce}
I1025 01:36:40.478907 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b6:6b:82:4c:fa:5c ID:1,b6:6b:82:4c:fa:5c Lease:0x6358cfec}
I1025 01:36:40.478918 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:fa:67:d3:67:52:17 ID:1,fa:67:d3:67:52:17 Lease:0x6358cf99}
I1025 01:36:40.478927 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:62:9:e2:6e:cb:e2 ID:1,62:9:e2:6e:cb:e2 Lease:0x63577e0f}
I1025 01:36:40.482906 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I1025 01:36:40.538270 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I1025 01:36:40.538775 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I1025 01:36:40.538794 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1025 01:36:40.538803 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1025 01:36:40.538811 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1025 01:36:40.640927 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-controller-manager_v1.24.4
I1025 01:36:40.641631 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-apiserver_v1.24.4
I1025 01:36:40.642497 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-proxy_v1.24.4
I1025 01:36:40.643482 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/coredns/coredns_v1.8.6
I1025 01:36:40.643711 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/pause_3.7
I1025 01:36:40.644696 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/etcd_3.5.3-0
I1025 01:36:40.651258 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5
I1025 01:36:40.682674 19054 cache.go:161] opening: /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-scheduler_v1.24.4
I1025 01:36:40.870141 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/pause_3.7 exists
I1025 01:36:40.870160 19054 cache.go:96] cache image "k8s.gcr.io/pause:3.7" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/pause_3.7" took 1.162327687s
I1025 01:36:40.870169 19054 cache.go:80] save to tar file k8s.gcr.io/pause:3.7 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/pause_3.7 succeeded
I1025 01:36:40.888585 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I1025 01:36:40.888597 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I1025 01:36:40.992665 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I1025 01:36:40.992694 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1025 01:36:40.992707 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1025 01:36:40.992721 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1025 01:36:40.993572 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I1025 01:36:40.993584 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:40 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I1025 01:36:41.025157 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 exists
I1025 01:36:41.025169 19054 cache.go:96] cache image "gcr.io/k8s-minikube/storage-provisioner:v5" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5" took 1.317403885s
I1025 01:36:41.025179 19054 cache.go:80] save to tar file gcr.io/k8s-minikube/storage-provisioner:v5 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 succeeded
I1025 01:36:41.203927 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/coredns/coredns_v1.8.6 exists
I1025 01:36:41.203941 19054 cache.go:96] cache image "k8s.gcr.io/coredns/coredns:v1.8.6" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/coredns/coredns_v1.8.6" took 1.49586885s
I1025 01:36:41.203953 19054 cache.go:80] save to tar file k8s.gcr.io/coredns/coredns:v1.8.6 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/coredns/coredns_v1.8.6 succeeded
I1025 01:36:42.053025 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-scheduler_v1.24.4 exists
I1025 01:36:42.053042 19054 cache.go:96] cache image "k8s.gcr.io/kube-scheduler:v1.24.4" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-scheduler_v1.24.4" took 2.345106439s
I1025 01:36:42.053050 19054 cache.go:80] save to tar file k8s.gcr.io/kube-scheduler:v1.24.4 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-scheduler_v1.24.4 succeeded
I1025 01:36:42.478485 19054 main.go:134] libmachine: (test-preload-013639) DBG | Attempt 1
I1025 01:36:42.478505 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:42.478586 19054 main.go:134] libmachine: (test-preload-013639) DBG | hyperkit pid from json: 19097
I1025 01:36:42.479830 19054 main.go:134] libmachine: (test-preload-013639) DBG | Searching for 1e:40:ad:f:1b:2a in /var/db/dhcpd_leases ...
I1025 01:36:42.479941 19054 main.go:134] libmachine: (test-preload-013639) DBG | Found 57 entries in /var/db/dhcpd_leases!
I1025 01:36:42.479958 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:1a:4f:e6:63:41:e6 ID:1,1a:4f:e6:63:41:e6 Lease:0x6357a016}
I1025 01:36:42.479982 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:26:ee:9c:81:92:52 ID:1,26:ee:9c:81:92:52 Lease:0x6357a014}
I1025 01:36:42.479998 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:6a:6d:c3:25:f5:dd ID:1,6a:6d:c3:25:f5:dd Lease:0x6358f16c}
I1025 01:36:42.480007 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:96:5c:ce:89:25:26 ID:1,96:5c:ce:89:25:26 Lease:0x63579db4}
I1025 01:36:42.480013 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:8e:3b:da:c5:a9:91 ID:1,8e:3b:da:c5:a9:91 Lease:0x63579982}
I1025 01:36:42.480046 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:62:4:f0:e1:51:f9 ID:1,62:4:f0:e1:51:f9 Lease:0x6357996d}
I1025 01:36:42.480057 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:62:db:31:75:9c:69 ID:1,62:db:31:75:9c:69 Lease:0x6358eaa1}
I1025 01:36:42.480067 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:f6:ab:96:dd:83:f8 ID:1,f6:ab:96:dd:83:f8 Lease:0x6358ea7a}
I1025 01:36:42.480075 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:22:79:74:ef:f2:a2 ID:1,22:79:74:ef:f2:a2 Lease:0x6358ea11}
I1025 01:36:42.480087 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:67:5a:7d:b7:79 ID:1,56:67:5a:7d:b7:79 Lease:0x6358e9a0}
I1025 01:36:42.480098 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:d2:be:6f:c0:26:f9 ID:1,d2:be:6f:c0:26:f9 Lease:0x6358e892}
I1025 01:36:42.480108 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:32:32:b0:b:45:78 ID:1,32:32:b0:b:45:78 Lease:0x6358e85f}
I1025 01:36:42.480116 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:7c:5e:2d:2:e2 ID:1,8a:7c:5e:2d:2:e2 Lease:0x635796d5}
I1025 01:36:42.480126 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:a2:b6:c6:4:94:a7 ID:1,a2:b6:c6:4:94:a7 Lease:0x6357910c}
I1025 01:36:42.480134 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:32:b6:48:f4:be:e7 ID:1,32:b6:48:f4:be:e7 Lease:0x6358e170}
I1025 01:36:42.480142 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:66:16:80:50:65:fc ID:1,66:16:80:50:65:fc Lease:0x6358e0e1}
I1025 01:36:42.480149 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:4d:28:3a:6b:62 ID:1,36:4d:28:3a:6b:62 Lease:0x6358df40}
I1025 01:36:42.480159 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:ee:2a:3:e1:7c:e ID:1,ee:2a:3:e1:7c:e Lease:0x63578fa1}
I1025 01:36:42.480169 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:6a:9a:c6:f7:f7:7b ID:1,6a:9a:c6:f7:f7:7b Lease:0x63578d70}
I1025 01:36:42.480177 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:e2:3f:6f:79:e2:cc ID:1,e2:3f:6f:79:e2:cc Lease:0x63578d26}
I1025 01:36:42.480183 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:3a:6d:5a:ce:31:54 ID:1,3a:6d:5a:ce:31:54 Lease:0x6358de25}
I1025 01:36:42.480190 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:76:dc:e4:cc:c3:5f ID:1,76:dc:e4:cc:c3:5f Lease:0x6358de1a}
I1025 01:36:42.480199 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:36:8a:11:e8:26:de ID:1,36:8a:11:e8:26:de Lease:0x63578c9b}
I1025 01:36:42.480214 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:8e:22:97:74:90:5d ID:1,8e:22:97:74:90:5d Lease:0x63578c4f}
I1025 01:36:42.480226 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:ee:9:18:f5:21:23 ID:1,ee:9:18:f5:21:23 Lease:0x6358dccf}
I1025 01:36:42.480235 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:4e:3:87:7a:85:99 ID:1,4e:3:87:7a:85:99 Lease:0x63578be4}
I1025 01:36:42.480244 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:ca:11:6:9e:c6:8b ID:1,ca:11:6:9e:c6:8b Lease:0x6358dcb3}
I1025 01:36:42.480251 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2e:21:94:a9:9e:79 ID:1,2e:21:94:a9:9e:79 Lease:0x63578b2a}
I1025 01:36:42.480260 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:46:a1:1c:fe:30:fc ID:1,46:a1:1c:fe:30:fc Lease:0x63578b45}
I1025 01:36:42.480267 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:a:97:18:1b:52:24 ID:1,a:97:18:1b:52:24 Lease:0x63578b07}
I1025 01:36:42.480279 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:42:64:b7:88:f7:40 ID:1,42:64:b7:88:f7:40 Lease:0x63578ae9}
I1025 01:36:42.480287 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:a2:fc:bc:60:d0:98 ID:1,a2:fc:bc:60:d0:98 Lease:0x6358dbfe}
I1025 01:36:42.480296 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:82:aa:8a:64:51:72 ID:1,82:aa:8a:64:51:72 Lease:0x6358dbf1}
I1025 01:36:42.480307 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:2a:3a:a1:c8:71:3 ID:1,2a:3a:a1:c8:71:3 Lease:0x6358dba5}
I1025 01:36:42.480314 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:1e:67:b2:8e:4f:8 ID:1,1e:67:b2:8e:4f:8 Lease:0x6358dac8}
I1025 01:36:42.480323 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:1a:6e:3e:b7:da:33 ID:1,1a:6e:3e:b7:da:33 Lease:0x63578933}
I1025 01:36:42.480332 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:e2:dd:4a:8e:96:7f ID:1,e2:dd:4a:8e:96:7f Lease:0x6358da88}
I1025 01:36:42.480339 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:4a:98:cb:b4:b3:37 ID:1,4a:98:cb:b4:b3:37 Lease:0x6358da6b}
I1025 01:36:42.480349 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:1a:b5:23:e5:52:b4 ID:1,1a:b5:23:e5:52:b4 Lease:0x635788ff}
I1025 01:36:42.480357 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:b2:c4:ad:c8:8c:ca ID:1,b2:c4:ad:c8:8c:ca Lease:0x635788d9}
I1025 01:36:42.480365 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:c6:1f:2b:84:df:e5 ID:1,c6:1f:2b:84:df:e5 Lease:0x6358da24}
I1025 01:36:42.480373 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:e:d7:1e:97:a4:c6 ID:1,e:d7:1e:97:a4:c6 Lease:0x6358d9da}
I1025 01:36:42.480381 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:b6:ce:7d:46:13:ac ID:1,b6:ce:7d:46:13:ac Lease:0x63578834}
I1025 01:36:42.480389 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:82:56:da:28:4b:d ID:1,82:56:da:28:4b:d Lease:0x6358d8e5}
I1025 01:36:42.480397 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:9a:6e:b9:d8:32:fb ID:1,9a:6e:b9:d8:32:fb Lease:0x63578757}
I1025 01:36:42.480404 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:50:cc:1d:7:40 ID:1,5e:50:cc:1d:7:40 Lease:0x635784e2}
I1025 01:36:42.480411 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:c2:8b:2f:75:29:1f ID:1,c2:8b:2f:75:29:1f Lease:0x6357875b}
I1025 01:36:42.480419 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:de:a6:c9:ff:49:9 ID:1,de:a6:c9:ff:49:9 Lease:0x6357875a}
I1025 01:36:42.480427 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:f6:bf:8e:3c:be:e8 ID:1,f6:bf:8e:3c:be:e8 Lease:0x635780aa}
I1025 01:36:42.480434 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:ea:26:a7:7f:1a:fd ID:1,ea:26:a7:7f:1a:fd Lease:0x63578095}
I1025 01:36:42.480443 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:92:f3:88:42:39:d5 ID:1,92:f3:88:42:39:d5 Lease:0x6357806f}
I1025 01:36:42.480452 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:9e:2f:9:ba:71:fb ID:1,9e:2f:9:ba:71:fb Lease:0x63578073}
I1025 01:36:42.480460 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:de:7e:ea:e8:16:c ID:1,de:7e:ea:e8:16:c Lease:0x6358d157}
I1025 01:36:42.480472 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:87:0:c6:b:cc ID:1,52:87:0:c6:b:cc Lease:0x63577fce}
I1025 01:36:42.480480 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b6:6b:82:4c:fa:5c ID:1,b6:6b:82:4c:fa:5c Lease:0x6358cfec}
I1025 01:36:42.480488 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:fa:67:d3:67:52:17 ID:1,fa:67:d3:67:52:17 Lease:0x6358cf99}
I1025 01:36:42.480497 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:62:9:e2:6e:cb:e2 ID:1,62:9:e2:6e:cb:e2 Lease:0x63577e0f}
I1025 01:36:42.525401 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-controller-manager_v1.24.4 exists
I1025 01:36:42.525424 19054 cache.go:96] cache image "k8s.gcr.io/kube-controller-manager:v1.24.4" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-controller-manager_v1.24.4" took 2.817657491s
I1025 01:36:42.525438 19054 cache.go:80] save to tar file k8s.gcr.io/kube-controller-manager:v1.24.4 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-controller-manager_v1.24.4 succeeded
I1025 01:36:42.730865 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-apiserver_v1.24.4 exists
I1025 01:36:42.730879 19054 cache.go:96] cache image "k8s.gcr.io/kube-apiserver:v1.24.4" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-apiserver_v1.24.4" took 3.023033914s
I1025 01:36:42.730887 19054 cache.go:80] save to tar file k8s.gcr.io/kube-apiserver:v1.24.4 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-apiserver_v1.24.4 succeeded
I1025 01:36:43.345256 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-proxy_v1.24.4 exists
I1025 01:36:43.345275 19054 cache.go:96] cache image "k8s.gcr.io/kube-proxy:v1.24.4" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-proxy_v1.24.4" took 3.637238305s
I1025 01:36:43.345284 19054 cache.go:80] save to tar file k8s.gcr.io/kube-proxy:v1.24.4 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/kube-proxy_v1.24.4 succeeded
I1025 01:36:43.475704 19054 cache.go:156] /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/etcd_3.5.3-0 exists
I1025 01:36:43.475719 19054 cache.go:96] cache image "k8s.gcr.io/etcd:3.5.3-0" -> "/Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/etcd_3.5.3-0" took 3.767799244s
I1025 01:36:43.475727 19054 cache.go:80] save to tar file k8s.gcr.io/etcd:3.5.3-0 -> /Users/jenkins/minikube-integration/14956-13627/.minikube/cache/images/amd64/k8s.gcr.io/etcd_3.5.3-0 succeeded
I1025 01:36:43.475739 19054 cache.go:87] Successfully saved all images to host disk.
I1025 01:36:44.480652 19054 main.go:134] libmachine: (test-preload-013639) DBG | Attempt 2
I1025 01:36:44.480673 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:44.480721 19054 main.go:134] libmachine: (test-preload-013639) DBG | hyperkit pid from json: 19097
I1025 01:36:44.481352 19054 main.go:134] libmachine: (test-preload-013639) DBG | Searching for 1e:40:ad:f:1b:2a in /var/db/dhcpd_leases ...
I1025 01:36:44.481409 19054 main.go:134] libmachine: (test-preload-013639) DBG | Found 57 entries in /var/db/dhcpd_leases!
I1025 01:36:44.481420 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:1a:4f:e6:63:41:e6 ID:1,1a:4f:e6:63:41:e6 Lease:0x6357a016}
I1025 01:36:44.481437 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:26:ee:9c:81:92:52 ID:1,26:ee:9c:81:92:52 Lease:0x6357a014}
I1025 01:36:44.481445 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:6a:6d:c3:25:f5:dd ID:1,6a:6d:c3:25:f5:dd Lease:0x6358f16c}
I1025 01:36:44.481454 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:96:5c:ce:89:25:26 ID:1,96:5c:ce:89:25:26 Lease:0x63579db4}
I1025 01:36:44.481461 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:8e:3b:da:c5:a9:91 ID:1,8e:3b:da:c5:a9:91 Lease:0x63579982}
I1025 01:36:44.481479 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:62:4:f0:e1:51:f9 ID:1,62:4:f0:e1:51:f9 Lease:0x6357996d}
I1025 01:36:44.481495 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:62:db:31:75:9c:69 ID:1,62:db:31:75:9c:69 Lease:0x6358eaa1}
I1025 01:36:44.481513 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:f6:ab:96:dd:83:f8 ID:1,f6:ab:96:dd:83:f8 Lease:0x6358ea7a}
I1025 01:36:44.481528 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:22:79:74:ef:f2:a2 ID:1,22:79:74:ef:f2:a2 Lease:0x6358ea11}
I1025 01:36:44.481537 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:67:5a:7d:b7:79 ID:1,56:67:5a:7d:b7:79 Lease:0x6358e9a0}
I1025 01:36:44.481551 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:d2:be:6f:c0:26:f9 ID:1,d2:be:6f:c0:26:f9 Lease:0x6358e892}
I1025 01:36:44.481566 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:32:32:b0:b:45:78 ID:1,32:32:b0:b:45:78 Lease:0x6358e85f}
I1025 01:36:44.481576 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:7c:5e:2d:2:e2 ID:1,8a:7c:5e:2d:2:e2 Lease:0x635796d5}
I1025 01:36:44.481583 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:a2:b6:c6:4:94:a7 ID:1,a2:b6:c6:4:94:a7 Lease:0x6357910c}
I1025 01:36:44.481590 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:32:b6:48:f4:be:e7 ID:1,32:b6:48:f4:be:e7 Lease:0x6358e170}
I1025 01:36:44.481599 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:66:16:80:50:65:fc ID:1,66:16:80:50:65:fc Lease:0x6358e0e1}
I1025 01:36:44.481607 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:4d:28:3a:6b:62 ID:1,36:4d:28:3a:6b:62 Lease:0x6358df40}
I1025 01:36:44.481618 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:ee:2a:3:e1:7c:e ID:1,ee:2a:3:e1:7c:e Lease:0x63578fa1}
I1025 01:36:44.481632 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:6a:9a:c6:f7:f7:7b ID:1,6a:9a:c6:f7:f7:7b Lease:0x63578d70}
I1025 01:36:44.481648 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:e2:3f:6f:79:e2:cc ID:1,e2:3f:6f:79:e2:cc Lease:0x63578d26}
I1025 01:36:44.481659 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:3a:6d:5a:ce:31:54 ID:1,3a:6d:5a:ce:31:54 Lease:0x6358de25}
I1025 01:36:44.481668 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:76:dc:e4:cc:c3:5f ID:1,76:dc:e4:cc:c3:5f Lease:0x6358de1a}
I1025 01:36:44.481678 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:36:8a:11:e8:26:de ID:1,36:8a:11:e8:26:de Lease:0x63578c9b}
I1025 01:36:44.481685 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:8e:22:97:74:90:5d ID:1,8e:22:97:74:90:5d Lease:0x63578c4f}
I1025 01:36:44.481694 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:ee:9:18:f5:21:23 ID:1,ee:9:18:f5:21:23 Lease:0x6358dccf}
I1025 01:36:44.481701 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:4e:3:87:7a:85:99 ID:1,4e:3:87:7a:85:99 Lease:0x63578be4}
I1025 01:36:44.481714 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:ca:11:6:9e:c6:8b ID:1,ca:11:6:9e:c6:8b Lease:0x6358dcb3}
I1025 01:36:44.481722 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2e:21:94:a9:9e:79 ID:1,2e:21:94:a9:9e:79 Lease:0x63578b2a}
I1025 01:36:44.481729 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:46:a1:1c:fe:30:fc ID:1,46:a1:1c:fe:30:fc Lease:0x63578b45}
I1025 01:36:44.481743 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:a:97:18:1b:52:24 ID:1,a:97:18:1b:52:24 Lease:0x63578b07}
I1025 01:36:44.481751 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:42:64:b7:88:f7:40 ID:1,42:64:b7:88:f7:40 Lease:0x63578ae9}
I1025 01:36:44.481758 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:a2:fc:bc:60:d0:98 ID:1,a2:fc:bc:60:d0:98 Lease:0x6358dbfe}
I1025 01:36:44.481766 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:82:aa:8a:64:51:72 ID:1,82:aa:8a:64:51:72 Lease:0x6358dbf1}
I1025 01:36:44.481777 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:2a:3a:a1:c8:71:3 ID:1,2a:3a:a1:c8:71:3 Lease:0x6358dba5}
I1025 01:36:44.481789 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:1e:67:b2:8e:4f:8 ID:1,1e:67:b2:8e:4f:8 Lease:0x6358dac8}
I1025 01:36:44.481797 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:1a:6e:3e:b7:da:33 ID:1,1a:6e:3e:b7:da:33 Lease:0x63578933}
I1025 01:36:44.481806 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:e2:dd:4a:8e:96:7f ID:1,e2:dd:4a:8e:96:7f Lease:0x6358da88}
I1025 01:36:44.481815 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:4a:98:cb:b4:b3:37 ID:1,4a:98:cb:b4:b3:37 Lease:0x6358da6b}
I1025 01:36:44.481825 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:1a:b5:23:e5:52:b4 ID:1,1a:b5:23:e5:52:b4 Lease:0x635788ff}
I1025 01:36:44.481832 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:b2:c4:ad:c8:8c:ca ID:1,b2:c4:ad:c8:8c:ca Lease:0x635788d9}
I1025 01:36:44.481840 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:c6:1f:2b:84:df:e5 ID:1,c6:1f:2b:84:df:e5 Lease:0x6358da24}
I1025 01:36:44.481848 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:e:d7:1e:97:a4:c6 ID:1,e:d7:1e:97:a4:c6 Lease:0x6358d9da}
I1025 01:36:44.481857 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:b6:ce:7d:46:13:ac ID:1,b6:ce:7d:46:13:ac Lease:0x63578834}
I1025 01:36:44.481864 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:82:56:da:28:4b:d ID:1,82:56:da:28:4b:d Lease:0x6358d8e5}
I1025 01:36:44.481873 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:9a:6e:b9:d8:32:fb ID:1,9a:6e:b9:d8:32:fb Lease:0x63578757}
I1025 01:36:44.481880 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:50:cc:1d:7:40 ID:1,5e:50:cc:1d:7:40 Lease:0x635784e2}
I1025 01:36:44.481886 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:c2:8b:2f:75:29:1f ID:1,c2:8b:2f:75:29:1f Lease:0x6357875b}
I1025 01:36:44.481897 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:de:a6:c9:ff:49:9 ID:1,de:a6:c9:ff:49:9 Lease:0x6357875a}
I1025 01:36:44.481909 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:f6:bf:8e:3c:be:e8 ID:1,f6:bf:8e:3c:be:e8 Lease:0x635780aa}
I1025 01:36:44.481916 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:ea:26:a7:7f:1a:fd ID:1,ea:26:a7:7f:1a:fd Lease:0x63578095}
I1025 01:36:44.481924 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:92:f3:88:42:39:d5 ID:1,92:f3:88:42:39:d5 Lease:0x6357806f}
I1025 01:36:44.481932 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:9e:2f:9:ba:71:fb ID:1,9e:2f:9:ba:71:fb Lease:0x63578073}
I1025 01:36:44.481940 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:de:7e:ea:e8:16:c ID:1,de:7e:ea:e8:16:c Lease:0x6358d157}
I1025 01:36:44.481948 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:87:0:c6:b:cc ID:1,52:87:0:c6:b:cc Lease:0x63577fce}
I1025 01:36:44.481956 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b6:6b:82:4c:fa:5c ID:1,b6:6b:82:4c:fa:5c Lease:0x6358cfec}
I1025 01:36:44.481970 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:fa:67:d3:67:52:17 ID:1,fa:67:d3:67:52:17 Lease:0x6358cf99}
I1025 01:36:44.481984 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:62:9:e2:6e:cb:e2 ID:1,62:9:e2:6e:cb:e2 Lease:0x63577e0f}
I1025 01:36:45.337971 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:45 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I1025 01:36:45.337999 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:45 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I1025 01:36:45.338006 19054 main.go:134] libmachine: (test-preload-013639) DBG | 2022/10/25 01:36:45 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I1025 01:36:46.483394 19054 main.go:134] libmachine: (test-preload-013639) DBG | Attempt 3
I1025 01:36:46.483417 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:46.483472 19054 main.go:134] libmachine: (test-preload-013639) DBG | hyperkit pid from json: 19097
I1025 01:36:46.484101 19054 main.go:134] libmachine: (test-preload-013639) DBG | Searching for 1e:40:ad:f:1b:2a in /var/db/dhcpd_leases ...
I1025 01:36:46.484190 19054 main.go:134] libmachine: (test-preload-013639) DBG | Found 57 entries in /var/db/dhcpd_leases!
I1025 01:36:46.484199 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.56 HWAddress:1a:4f:e6:63:41:e6 ID:1,1a:4f:e6:63:41:e6 Lease:0x6357a016}
I1025 01:36:46.484207 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.55 HWAddress:26:ee:9c:81:92:52 ID:1,26:ee:9c:81:92:52 Lease:0x6357a014}
I1025 01:36:46.484215 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.58 HWAddress:6a:6d:c3:25:f5:dd ID:1,6a:6d:c3:25:f5:dd Lease:0x6358f16c}
I1025 01:36:46.484222 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.57 HWAddress:96:5c:ce:89:25:26 ID:1,96:5c:ce:89:25:26 Lease:0x63579db4}
I1025 01:36:46.484228 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.54 HWAddress:8e:3b:da:c5:a9:91 ID:1,8e:3b:da:c5:a9:91 Lease:0x63579982}
I1025 01:36:46.484236 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.53 HWAddress:62:4:f0:e1:51:f9 ID:1,62:4:f0:e1:51:f9 Lease:0x6357996d}
I1025 01:36:46.484256 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:62:db:31:75:9c:69 ID:1,62:db:31:75:9c:69 Lease:0x6358eaa1}
I1025 01:36:46.484262 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:f6:ab:96:dd:83:f8 ID:1,f6:ab:96:dd:83:f8 Lease:0x6358ea7a}
I1025 01:36:46.484271 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:22:79:74:ef:f2:a2 ID:1,22:79:74:ef:f2:a2 Lease:0x6358ea11}
I1025 01:36:46.484280 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:67:5a:7d:b7:79 ID:1,56:67:5a:7d:b7:79 Lease:0x6358e9a0}
I1025 01:36:46.484289 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:d2:be:6f:c0:26:f9 ID:1,d2:be:6f:c0:26:f9 Lease:0x6358e892}
I1025 01:36:46.484299 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:32:32:b0:b:45:78 ID:1,32:32:b0:b:45:78 Lease:0x6358e85f}
I1025 01:36:46.484308 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:7c:5e:2d:2:e2 ID:1,8a:7c:5e:2d:2:e2 Lease:0x635796d5}
I1025 01:36:46.484316 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:a2:b6:c6:4:94:a7 ID:1,a2:b6:c6:4:94:a7 Lease:0x6357910c}
I1025 01:36:46.484323 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:32:b6:48:f4:be:e7 ID:1,32:b6:48:f4:be:e7 Lease:0x6358e170}
I1025 01:36:46.484330 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:66:16:80:50:65:fc ID:1,66:16:80:50:65:fc Lease:0x6358e0e1}
I1025 01:36:46.484337 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:4d:28:3a:6b:62 ID:1,36:4d:28:3a:6b:62 Lease:0x6358df40}
I1025 01:36:46.484344 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:ee:2a:3:e1:7c:e ID:1,ee:2a:3:e1:7c:e Lease:0x63578fa1}
I1025 01:36:46.484350 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:6a:9a:c6:f7:f7:7b ID:1,6a:9a:c6:f7:f7:7b Lease:0x63578d70}
I1025 01:36:46.484356 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:e2:3f:6f:79:e2:cc ID:1,e2:3f:6f:79:e2:cc Lease:0x63578d26}
I1025 01:36:46.484363 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:3a:6d:5a:ce:31:54 ID:1,3a:6d:5a:ce:31:54 Lease:0x6358de25}
I1025 01:36:46.484371 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:76:dc:e4:cc:c3:5f ID:1,76:dc:e4:cc:c3:5f Lease:0x6358de1a}
I1025 01:36:46.484381 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:36:8a:11:e8:26:de ID:1,36:8a:11:e8:26:de Lease:0x63578c9b}
I1025 01:36:46.484391 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:8e:22:97:74:90:5d ID:1,8e:22:97:74:90:5d Lease:0x63578c4f}
I1025 01:36:46.484398 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:ee:9:18:f5:21:23 ID:1,ee:9:18:f5:21:23 Lease:0x6358dccf}
I1025 01:36:46.484406 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:4e:3:87:7a:85:99 ID:1,4e:3:87:7a:85:99 Lease:0x63578be4}
I1025 01:36:46.484413 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:ca:11:6:9e:c6:8b ID:1,ca:11:6:9e:c6:8b Lease:0x6358dcb3}
I1025 01:36:46.484426 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2e:21:94:a9:9e:79 ID:1,2e:21:94:a9:9e:79 Lease:0x63578b2a}
I1025 01:36:46.484441 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:46:a1:1c:fe:30:fc ID:1,46:a1:1c:fe:30:fc Lease:0x63578b45}
I1025 01:36:46.484450 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:a:97:18:1b:52:24 ID:1,a:97:18:1b:52:24 Lease:0x63578b07}
I1025 01:36:46.484459 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:42:64:b7:88:f7:40 ID:1,42:64:b7:88:f7:40 Lease:0x63578ae9}
I1025 01:36:46.484477 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:a2:fc:bc:60:d0:98 ID:1,a2:fc:bc:60:d0:98 Lease:0x6358dbfe}
I1025 01:36:46.484489 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:82:aa:8a:64:51:72 ID:1,82:aa:8a:64:51:72 Lease:0x6358dbf1}
I1025 01:36:46.484500 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:2a:3a:a1:c8:71:3 ID:1,2a:3a:a1:c8:71:3 Lease:0x6358dba5}
I1025 01:36:46.484508 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:1e:67:b2:8e:4f:8 ID:1,1e:67:b2:8e:4f:8 Lease:0x6358dac8}
I1025 01:36:46.484518 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:1a:6e:3e:b7:da:33 ID:1,1a:6e:3e:b7:da:33 Lease:0x63578933}
I1025 01:36:46.484526 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:e2:dd:4a:8e:96:7f ID:1,e2:dd:4a:8e:96:7f Lease:0x6358da88}
I1025 01:36:46.484538 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:4a:98:cb:b4:b3:37 ID:1,4a:98:cb:b4:b3:37 Lease:0x6358da6b}
I1025 01:36:46.484546 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:1a:b5:23:e5:52:b4 ID:1,1a:b5:23:e5:52:b4 Lease:0x635788ff}
I1025 01:36:46.484554 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:b2:c4:ad:c8:8c:ca ID:1,b2:c4:ad:c8:8c:ca Lease:0x635788d9}
I1025 01:36:46.484562 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:c6:1f:2b:84:df:e5 ID:1,c6:1f:2b:84:df:e5 Lease:0x6358da24}
I1025 01:36:46.484569 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:e:d7:1e:97:a4:c6 ID:1,e:d7:1e:97:a4:c6 Lease:0x6358d9da}
I1025 01:36:46.484577 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:b6:ce:7d:46:13:ac ID:1,b6:ce:7d:46:13:ac Lease:0x63578834}
I1025 01:36:46.484584 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:82:56:da:28:4b:d ID:1,82:56:da:28:4b:d Lease:0x6358d8e5}
I1025 01:36:46.484597 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:9a:6e:b9:d8:32:fb ID:1,9a:6e:b9:d8:32:fb Lease:0x63578757}
I1025 01:36:46.484605 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:50:cc:1d:7:40 ID:1,5e:50:cc:1d:7:40 Lease:0x635784e2}
I1025 01:36:46.484613 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:c2:8b:2f:75:29:1f ID:1,c2:8b:2f:75:29:1f Lease:0x6357875b}
I1025 01:36:46.484621 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:de:a6:c9:ff:49:9 ID:1,de:a6:c9:ff:49:9 Lease:0x6357875a}
I1025 01:36:46.484630 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:f6:bf:8e:3c:be:e8 ID:1,f6:bf:8e:3c:be:e8 Lease:0x635780aa}
I1025 01:36:46.484637 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:ea:26:a7:7f:1a:fd ID:1,ea:26:a7:7f:1a:fd Lease:0x63578095}
I1025 01:36:46.484645 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:92:f3:88:42:39:d5 ID:1,92:f3:88:42:39:d5 Lease:0x6357806f}
I1025 01:36:46.484652 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:9e:2f:9:ba:71:fb ID:1,9e:2f:9:ba:71:fb Lease:0x63578073}
I1025 01:36:46.484661 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:de:7e:ea:e8:16:c ID:1,de:7e:ea:e8:16:c Lease:0x6358d157}
I1025 01:36:46.484668 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:87:0:c6:b:cc ID:1,52:87:0:c6:b:cc Lease:0x63577fce}
I1025 01:36:46.484676 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b6:6b:82:4c:fa:5c ID:1,b6:6b:82:4c:fa:5c Lease:0x6358cfec}
I1025 01:36:46.484686 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:fa:67:d3:67:52:17 ID:1,fa:67:d3:67:52:17 Lease:0x6358cf99}
I1025 01:36:46.484695 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:62:9:e2:6e:cb:e2 ID:1,62:9:e2:6e:cb:e2 Lease:0x63577e0f}
I1025 01:36:48.486446 19054 main.go:134] libmachine: (test-preload-013639) DBG | Attempt 4
I1025 01:36:48.486460 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:48.486475 19054 main.go:134] libmachine: (test-preload-013639) DBG | hyperkit pid from json: 19097
I1025 01:36:48.487725 19054 main.go:134] libmachine: (test-preload-013639) DBG | Searching for 1e:40:ad:f:1b:2a in /var/db/dhcpd_leases ...
I1025 01:36:48.487815 19054 main.go:134] libmachine: (test-preload-013639) DBG | Found 58 entries in /var/db/dhcpd_leases!
I1025 01:36:48.487824 19054 main.go:134] libmachine: (test-preload-013639) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.59 HWAddress:1e:40:ad:f:1b:2a ID:1,1e:40:ad:f:1b:2a Lease:0x6358f19f}
I1025 01:36:48.487832 19054 main.go:134] libmachine: (test-preload-013639) DBG | Found match: 1e:40:ad:f:1b:2a
I1025 01:36:48.487836 19054 main.go:134] libmachine: (test-preload-013639) DBG | IP: 192.168.64.59
I1025 01:36:48.487884 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetConfigRaw
I1025 01:36:48.488394 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:48.488484 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:48.488584 19054 main.go:134] libmachine: Waiting for machine to be running, this may take a few minutes...
I1025 01:36:48.488597 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetState
I1025 01:36:48.488702 19054 main.go:134] libmachine: (test-preload-013639) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1025 01:36:48.488777 19054 main.go:134] libmachine: (test-preload-013639) DBG | hyperkit pid from json: 19097
I1025 01:36:48.489339 19054 main.go:134] libmachine: Detecting operating system of created instance...
I1025 01:36:48.489347 19054 main.go:134] libmachine: Waiting for SSH to be available...
I1025 01:36:48.489352 19054 main.go:134] libmachine: Getting to WaitForSSH function...
I1025 01:36:48.489360 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:48.489436 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:48.489527 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:48.489616 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:48.489720 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:48.489837 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:48.490008 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:48.490017 19054 main.go:134] libmachine: About to run SSH command:
exit 0
I1025 01:36:49.579574 19054 main.go:134] libmachine: SSH cmd err, output: <nil>:
I1025 01:36:49.579587 19054 main.go:134] libmachine: Detecting the provisioner...
I1025 01:36:49.579593 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:49.579761 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:49.579856 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:49.579947 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:49.580033 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:49.580165 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:49.580297 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:49.580307 19054 main.go:134] libmachine: About to run SSH command:
cat /etc/os-release
I1025 01:36:49.667416 19054 main.go:134] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-g08f7b49-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I1025 01:36:49.667460 19054 main.go:134] libmachine: found compatible host: buildroot
I1025 01:36:49.667466 19054 main.go:134] libmachine: Provisioning with buildroot...
I1025 01:36:49.667472 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetMachineName
I1025 01:36:49.667617 19054 buildroot.go:166] provisioning hostname "test-preload-013639"
I1025 01:36:49.667627 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetMachineName
I1025 01:36:49.667728 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:49.667812 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:49.667909 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:49.668010 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:49.668111 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:49.668264 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:49.668365 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:49.668374 19054 main.go:134] libmachine: About to run SSH command:
sudo hostname test-preload-013639 && echo "test-preload-013639" | sudo tee /etc/hostname
I1025 01:36:49.761369 19054 main.go:134] libmachine: SSH cmd err, output: <nil>: test-preload-013639
I1025 01:36:49.761390 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:49.761512 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:49.761610 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:49.761690 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:49.761777 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:49.761892 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:49.761995 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:49.762008 19054 main.go:134] libmachine: About to run SSH command:
if ! grep -xq '.*\stest-preload-013639' /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-013639/g' /etc/hosts;
else
echo '127.0.1.1 test-preload-013639' | sudo tee -a /etc/hosts;
fi
fi
I1025 01:36:49.851649 19054 main.go:134] libmachine: SSH cmd err, output: <nil>:
I1025 01:36:49.851665 19054 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/14956-13627/.minikube CaCertPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/14956-13627/.minikube}
I1025 01:36:49.851683 19054 buildroot.go:174] setting up certificates
I1025 01:36:49.851690 19054 provision.go:83] configureAuth start
I1025 01:36:49.851696 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetMachineName
I1025 01:36:49.851822 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetIP
I1025 01:36:49.851914 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:49.851998 19054 provision.go:138] copyHostCerts
I1025 01:36:49.852063 19054 exec_runner.go:144] found /Users/jenkins/minikube-integration/14956-13627/.minikube/key.pem, removing ...
I1025 01:36:49.852073 19054 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/14956-13627/.minikube/key.pem
I1025 01:36:49.852196 19054 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/14956-13627/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/14956-13627/.minikube/key.pem (1675 bytes)
I1025 01:36:49.852381 19054 exec_runner.go:144] found /Users/jenkins/minikube-integration/14956-13627/.minikube/ca.pem, removing ...
I1025 01:36:49.852387 19054 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/14956-13627/.minikube/ca.pem
I1025 01:36:49.852458 19054 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/14956-13627/.minikube/ca.pem (1078 bytes)
I1025 01:36:49.852659 19054 exec_runner.go:144] found /Users/jenkins/minikube-integration/14956-13627/.minikube/cert.pem, removing ...
I1025 01:36:49.852665 19054 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/14956-13627/.minikube/cert.pem
I1025 01:36:49.852737 19054 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/14956-13627/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/14956-13627/.minikube/cert.pem (1123 bytes)
I1025 01:36:49.852855 19054 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca-key.pem org=jenkins.test-preload-013639 san=[192.168.64.59 192.168.64.59 localhost 127.0.0.1 minikube test-preload-013639]
I1025 01:36:50.014589 19054 provision.go:172] copyRemoteCerts
I1025 01:36:50.014664 19054 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I1025 01:36:50.014692 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:50.014924 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:50.015069 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.015257 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:50.015486 19054 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/id_rsa Username:docker}
I1025 01:36:50.065460 19054 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/14956-13627/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I1025 01:36:50.081563 19054 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/server.pem --> /etc/docker/server.pem (1233 bytes)
I1025 01:36:50.097309 19054 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/14956-13627/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I1025 01:36:50.112937 19054 provision.go:86] duration metric: configureAuth took 261.240643ms
I1025 01:36:50.112948 19054 buildroot.go:189] setting minikube options for container-runtime
I1025 01:36:50.113059 19054 config.go:180] Loaded profile config "test-preload-013639": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.24.4
I1025 01:36:50.113071 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:50.113193 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:50.113276 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:50.113364 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.113438 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.113509 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:50.113608 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:50.113698 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:50.113705 19054 main.go:134] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I1025 01:36:50.198155 19054 main.go:134] libmachine: SSH cmd err, output: <nil>: tmpfs
I1025 01:36:50.198166 19054 buildroot.go:70] root file system type: tmpfs
I1025 01:36:50.198284 19054 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I1025 01:36:50.198301 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:50.198425 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:50.198525 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.198609 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.198699 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:50.198817 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:50.198917 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:50.198965 19054 main.go:134] 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
I1025 01:36:50.294154 19054 main.go:134] 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
I1025 01:36:50.294178 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:50.294302 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:50.294393 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.294475 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:50.294570 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:50.294691 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:50.294798 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:50.294810 19054 main.go:134] 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; }
I1025 01:36:52.841321 19054 main.go:134] 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.
I1025 01:36:52.841335 19054 main.go:134] libmachine: Checking connection to Docker...
I1025 01:36:52.841342 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetURL
I1025 01:36:52.841477 19054 main.go:134] libmachine: Docker is up and running!
I1025 01:36:52.841485 19054 main.go:134] libmachine: Reticulating splines...
I1025 01:36:52.841489 19054 client.go:171] LocalClient.Create took 13.07668932s
I1025 01:36:52.841501 19054 start.go:167] duration metric: libmachine.API.Create for "test-preload-013639" took 13.076746111s
I1025 01:36:52.841512 19054 start.go:300] post-start starting for "test-preload-013639" (driver="hyperkit")
I1025 01:36:52.841517 19054 start.go:328] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I1025 01:36:52.841527 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:52.841656 19054 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
I1025 01:36:52.841690 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:52.841779 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:52.841865 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:52.841946 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:52.842018 19054 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/id_rsa Username:docker}
I1025 01:36:52.888774 19054 ssh_runner.go:195] Run: cat /etc/os-release
I1025 01:36:52.891377 19054 info.go:137] Remote host: Buildroot 2021.02.12
I1025 01:36:52.891390 19054 filesync.go:126] Scanning /Users/jenkins/minikube-integration/14956-13627/.minikube/addons for local assets ...
I1025 01:36:52.891464 19054 filesync.go:126] Scanning /Users/jenkins/minikube-integration/14956-13627/.minikube/files for local assets ...
I1025 01:36:52.891595 19054 filesync.go:149] local asset: /Users/jenkins/minikube-integration/14956-13627/.minikube/files/etc/ssl/certs/147702.pem -> 147702.pem in /etc/ssl/certs
I1025 01:36:52.891743 19054 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I1025 01:36:52.897388 19054 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/14956-13627/.minikube/files/etc/ssl/certs/147702.pem --> /etc/ssl/certs/147702.pem (1708 bytes)
I1025 01:36:52.912978 19054 start.go:303] post-start completed in 71.459701ms
I1025 01:36:52.913019 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetConfigRaw
I1025 01:36:52.913577 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetIP
I1025 01:36:52.913730 19054 profile.go:148] Saving config to /Users/jenkins/minikube-integration/14956-13627/.minikube/profiles/test-preload-013639/config.json ...
I1025 01:36:52.914005 19054 start.go:128] duration metric: createHost completed in 13.20458214s
I1025 01:36:52.914021 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:52.914109 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:52.914200 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:52.914278 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:52.914357 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:52.914462 19054 main.go:134] libmachine: Using SSH client type: native
I1025 01:36:52.914589 19054 main.go:134] libmachine: &{{{<nil> 0 [] [] []} docker [0x13e6b40] 0x13e9cc0 <nil> [] 0s} 192.168.64.59 22 <nil> <nil>}
I1025 01:36:52.914597 19054 main.go:134] libmachine: About to run SSH command:
date +%s.%N
I1025 01:36:53.000089 19054 main.go:134] libmachine: SSH cmd err, output: <nil>: 1666687013.239497817
I1025 01:36:53.000100 19054 fix.go:207] guest clock: 1666687013.239497817
I1025 01:36:53.000106 19054 fix.go:220] Guest: 2022-10-25 01:36:53.239497817 -0700 PDT Remote: 2022-10-25 01:36:52.914014 -0700 PDT m=+13.591676806 (delta=325.483817ms)
I1025 01:36:53.000121 19054 fix.go:191] guest clock delta is within tolerance: 325.483817ms
I1025 01:36:53.000124 19054 start.go:83] releasing machines lock for "test-preload-013639", held for 13.290806033s
I1025 01:36:53.000156 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:53.000289 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetIP
I1025 01:36:53.000394 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:53.000724 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:53.000822 19054 main.go:134] libmachine: (test-preload-013639) Calling .DriverName
I1025 01:36:53.000882 19054 ssh_runner.go:195] Run: curl -sS -m 2 https://k8s.gcr.io/
I1025 01:36:53.000901 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:53.000969 19054 ssh_runner.go:195] Run: systemctl --version
I1025 01:36:53.000982 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHHostname
I1025 01:36:53.000991 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:53.001083 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHPort
I1025 01:36:53.001102 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:53.001165 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHKeyPath
I1025 01:36:53.001188 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:53.001271 19054 main.go:134] libmachine: (test-preload-013639) Calling .GetSSHUsername
I1025 01:36:53.001288 19054 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/id_rsa Username:docker}
I1025 01:36:53.001346 19054 sshutil.go:53] new ssh client: &{IP:192.168.64.59 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/14956-13627/.minikube/machines/test-preload-013639/id_rsa Username:docker}
I1025 01:36:53.046705 19054 preload.go:132] Checking if preload exists for k8s version v1.24.4 and runtime docker
I1025 01:36:53.046772 19054 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I1025 01:36:53.248929 19054 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1025 01:36:53.258764 19054 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1025 01:36:53.267337 19054 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I1025 01:36:53.294424 19054 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1025 01:36:53.303910 19054 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
image-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I1025 01:36:53.317229 19054 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I1025 01:36:53.404901 19054 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I1025 01:36:53.485521 19054 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1025 01:36:53.567684 19054 ssh_runner.go:195] Run: sudo systemctl restart docker
I1025 01:36:54.898771 19054 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.331083867s)
I1025 01:36:54.898828 19054 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1025 01:36:54.988660 19054 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1025 01:36:55.074538 19054 ssh_runner.go:195] Run: sudo systemctl start cri-docker.socket
I1025 01:36:55.083902 19054 start.go:451] Will wait 60s for socket path /var/run/cri-dockerd.sock
I1025 01:36:55.083960 19054 ssh_runner.go:195] Run: stat /var/run/cri-dockerd.sock
I1025 01:36:55.087296 19054 start.go:472] Will wait 60s for crictl version
I1025 01:36:55.087333 19054 ssh_runner.go:195] Run: sudo crictl version
I1025 01:36:57.109307 19054 ssh_runner.go:235] Completed: sudo crictl version: (2.021987843s)
I1025 01:36:57.109339 19054 retry.go:31] will retry after 11.04660288s: Temporary Error: sudo crictl version: Process exited with status 1
stdout:
stderr:
time="2022-10-25T08:36:57Z" level=fatal msg="connect: connect endpoint 'unix:///var/run/cri-dockerd.sock', make sure you are running as root and the endpoint has been started: context deadline exceeded"
I1025 01:37:08.156023 19054 ssh_runner.go:195] Run: sudo crictl version
I1025 01:37:10.176329 19054 ssh_runner.go:235] Completed: sudo crictl version: (2.020309918s)
I1025 01:37:10.176350 19054 retry.go:31] will retry after 21.607636321s: Temporary Error: sudo crictl version: Process exited with status 1
stdout:
stderr:
time="2022-10-25T08:37:10Z" level=fatal msg="connect: connect endpoint 'unix:///var/run/cri-dockerd.sock', make sure you are running as root and the endpoint has been started: context deadline exceeded"
I1025 01:37:31.785888 19054 ssh_runner.go:195] Run: sudo crictl version
I1025 01:37:33.806190 19054 ssh_runner.go:235] Completed: sudo crictl version: (2.020197792s)
I1025 01:37:33.828145 19054 out.go:177]
W1025 01:37:33.849810 19054 out.go:239] X Exiting due to RUNTIME_ENABLE: Temporary Error: sudo crictl version: Process exited with status 1
stdout:
stderr:
time="2022-10-25T08:37:34Z" level=fatal msg="connect: connect endpoint 'unix:///var/run/cri-dockerd.sock', make sure you are running as root and the endpoint has been started: context deadline exceeded"
X Exiting due to RUNTIME_ENABLE: Temporary Error: sudo crictl version: Process exited with status 1
stdout:
stderr:
time="2022-10-25T08:37:34Z" level=fatal msg="connect: connect endpoint 'unix:///var/run/cri-dockerd.sock', make sure you are running as root and the endpoint has been started: context deadline exceeded"
W1025 01:37:33.849828 19054 out.go:239] *
*
W1025 01:37:33.850506 19054 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I1025 01:37:33.913782 19054 out.go:177]
** /stderr **
preload_test.go:46: out/minikube-darwin-amd64 start -p test-preload-013639 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=hyperkit --kubernetes-version=v1.24.4 failed: exit status 90
panic.go:522: *** TestPreload FAILED at 2022-10-25 01:37:33.974405 -0700 PDT m=+2651.469230850
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p test-preload-013639 -n test-preload-013639
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p test-preload-013639 -n test-preload-013639: exit status 6 (141.855718ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E1025 01:37:34.107377 19151 status.go:415] kubeconfig endpoint: extract IP: "test-preload-013639" does not appear in /Users/jenkins/minikube-integration/14956-13627/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "test-preload-013639" 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-013639" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p test-preload-013639
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p test-preload-013639: (5.292464669s)
--- FAIL: TestPreload (60.09s)