=== RUN TestMinikubeProfile
minikube_profile_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p first-052000 --driver=hyperkit
E1211 01:42:31.927271 9393 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/functional-343000/client.crt: no such file or directory
E1211 01:42:42.167446 9393 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/functional-343000/client.crt: no such file or directory
E1211 01:42:46.261273 9393 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/addons-787000/client.crt: no such file or directory
E1211 01:43:02.647140 9393 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/functional-343000/client.crt: no such file or directory
minikube_profile_test.go:44: (dbg) Done: out/minikube-darwin-amd64 start -p first-052000 --driver=hyperkit : (36.405713635s)
minikube_profile_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p second-055000 --driver=hyperkit
minikube_profile_test.go:44: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p second-055000 --driver=hyperkit : exit status 90 (16.01655431s)
-- stdout --
* [second-055000] minikube v1.32.0 on Darwin 14.1.2
- MINIKUBE_LOCATION=17760
- KUBECONFIG=/Users/jenkins/minikube-integration/17760-8912/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/17760-8912/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting control plane node second-055000 in cluster second-055000
* Creating hyperkit VM (CPUs=2, Memory=6000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
sudo journalctl --no-pager -u cri-docker.socket:
-- stdout --
-- Journal begins at Mon 2023-12-11 09:43:16 UTC, ends at Mon 2023-12-11 09:43:21 UTC. --
Dec 11 09:43:17 minikube systemd[1]: Starting CRI Docker Socket for the API.
Dec 11 09:43:17 minikube systemd[1]: Listening on CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: cri-docker.socket: Succeeded.
Dec 11 09:43:19 second-055000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: Starting CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: Listening on CRI Docker Socket for the API.
Dec 11 09:43:21 second-055000 systemd[1]: cri-docker.socket: Succeeded.
Dec 11 09:43:21 second-055000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 11 09:43:21 second-055000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 11 09:43:21 second-055000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
Dec 11 09:43:21 second-055000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
-- /stdout --
*
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
** /stderr **
minikube_profile_test.go:46: test pre-condition failed. args "out/minikube-darwin-amd64 start -p second-055000 --driver=hyperkit ": exit status 90
panic.go:523: *** TestMinikubeProfile FAILED at 2023-12-11 01:43:22.446252 -0800 PST m=+859.114913426
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p second-055000 -n second-055000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p second-055000 -n second-055000: exit status 6 (150.040132ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E1211 01:43:22.585514 10983 status.go:415] kubeconfig endpoint: extract IP: "second-055000" does not appear in /Users/jenkins/minikube-integration/17760-8912/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "second-055000" 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 "second-055000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p second-055000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p second-055000: (5.285680125s)
panic.go:523: *** TestMinikubeProfile FAILED at 2023-12-11 01:43:27.882138 -0800 PST m=+864.550951241
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p first-052000 -n first-052000
helpers_test.go:244: <<< TestMinikubeProfile FAILED: start of post-mortem logs <<<
helpers_test.go:245: ======> post-mortem[TestMinikubeProfile]: minikube logs <======
helpers_test.go:247: (dbg) Run: out/minikube-darwin-amd64 -p first-052000 logs -n 25
helpers_test.go:247: (dbg) Done: out/minikube-darwin-amd64 -p first-052000 logs -n 25: (1.934531652s)
helpers_test.go:252: TestMinikubeProfile logs:
-- stdout --
*
* ==> Audit <==
* |---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
| delete | -p functional-343000 | functional-343000 | jenkins | v1.32.0 | 11 Dec 23 01:38 PST | 11 Dec 23 01:38 PST |
| start | -p image-367000 | image-367000 | jenkins | v1.32.0 | 11 Dec 23 01:38 PST | 11 Dec 23 01:39 PST |
| | --driver=hyperkit | | | | | |
| image | build -t aaa:latest | image-367000 | jenkins | v1.32.0 | 11 Dec 23 01:39 PST | 11 Dec 23 01:39 PST |
| | ./testdata/image-build/test-normal | | | | | |
| | -p image-367000 | | | | | |
| image | build -t aaa:latest | image-367000 | jenkins | v1.32.0 | 11 Dec 23 01:39 PST | 11 Dec 23 01:39 PST |
| | --build-opt=build-arg=ENV_A=test_env_str | | | | | |
| | --build-opt=no-cache | | | | | |
| | ./testdata/image-build/test-arg -p | | | | | |
| | image-367000 | | | | | |
| image | build -t aaa:latest | image-367000 | jenkins | v1.32.0 | 11 Dec 23 01:39 PST | 11 Dec 23 01:39 PST |
| | ./testdata/image-build/test-normal | | | | | |
| | --build-opt=no-cache -p | | | | | |
| | image-367000 | | | | | |
| image | build -t aaa:latest | image-367000 | jenkins | v1.32.0 | 11 Dec 23 01:39 PST | 11 Dec 23 01:39 PST |
| | -f inner/Dockerfile | | | | | |
| | ./testdata/image-build/test-f | | | | | |
| | -p image-367000 | | | | | |
| delete | -p image-367000 | image-367000 | jenkins | v1.32.0 | 11 Dec 23 01:39 PST | 11 Dec 23 01:39 PST |
| start | -p ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:39 PST | 11 Dec 23 01:40 PST |
| | --kubernetes-version=v1.18.20 | | | | | |
| | --memory=4096 --wait=true | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| | --driver=hyperkit | | | | | |
| addons | ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:40 PST | 11 Dec 23 01:40 PST |
| | addons enable ingress | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| addons | ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:40 PST | 11 Dec 23 01:40 PST |
| | addons enable ingress-dns | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| ssh | ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:41 PST | 11 Dec 23 01:41 PST |
| | ssh curl -s http://127.0.0.1/ | | | | | |
| | -H 'Host: nginx.example.com' | | | | | |
| ip | ingress-addon-legacy-335000 ip | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:41 PST | 11 Dec 23 01:41 PST |
| addons | ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:41 PST | 11 Dec 23 01:41 PST |
| | addons disable ingress-dns | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| addons | ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:41 PST | 11 Dec 23 01:41 PST |
| | addons disable ingress | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| delete | -p ingress-addon-legacy-335000 | ingress-addon-legacy-335000 | jenkins | v1.32.0 | 11 Dec 23 01:41 PST | 11 Dec 23 01:41 PST |
| start | -p json-output-096000 | json-output-096000 | testUser | v1.32.0 | 11 Dec 23 01:41 PST | 11 Dec 23 01:42 PST |
| | --output=json --user=testUser | | | | | |
| | --memory=2200 --wait=true | | | | | |
| | --driver=hyperkit | | | | | |
| pause | -p json-output-096000 | json-output-096000 | testUser | v1.32.0 | 11 Dec 23 01:42 PST | 11 Dec 23 01:42 PST |
| | --output=json --user=testUser | | | | | |
| unpause | -p json-output-096000 | json-output-096000 | testUser | v1.32.0 | 11 Dec 23 01:42 PST | 11 Dec 23 01:42 PST |
| | --output=json --user=testUser | | | | | |
| stop | -p json-output-096000 | json-output-096000 | testUser | v1.32.0 | 11 Dec 23 01:42 PST | 11 Dec 23 01:42 PST |
| | --output=json --user=testUser | | | | | |
| delete | -p json-output-096000 | json-output-096000 | jenkins | v1.32.0 | 11 Dec 23 01:42 PST | 11 Dec 23 01:42 PST |
| start | -p json-output-error-720000 | json-output-error-720000 | jenkins | v1.32.0 | 11 Dec 23 01:42 PST | |
| | --memory=2200 --output=json | | | | | |
| | --wait=true --driver=fail | | | | | |
| delete | -p json-output-error-720000 | json-output-error-720000 | jenkins | v1.32.0 | 11 Dec 23 01:42 PST | 11 Dec 23 01:42 PST |
| start | -p first-052000 | first-052000 | jenkins | v1.32.0 | 11 Dec 23 01:42 PST | 11 Dec 23 01:43 PST |
| | --driver=hyperkit | | | | | |
| start | -p second-055000 | second-055000 | jenkins | v1.32.0 | 11 Dec 23 01:43 PST | |
| | --driver=hyperkit | | | | | |
| delete | -p second-055000 | second-055000 | jenkins | v1.32.0 | 11 Dec 23 01:43 PST | 11 Dec 23 01:43 PST |
|---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
*
* ==> Last Start <==
* Log file created at: 2023/12/11 01:43:06
Running on machine: MacOS-Agent-2
Binary: Built with gc go1.21.5 for darwin/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I1211 01:43:06.482594 10968 out.go:296] Setting OutFile to fd 1 ...
I1211 01:43:06.482786 10968 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1211 01:43:06.482790 10968 out.go:309] Setting ErrFile to fd 2...
I1211 01:43:06.482792 10968 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1211 01:43:06.482971 10968 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/17760-8912/.minikube/bin
I1211 01:43:06.484374 10968 out.go:303] Setting JSON to false
I1211 01:43:06.506835 10968 start.go:128] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":6156,"bootTime":1702281630,"procs":427,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.1.2","kernelVersion":"23.1.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"2965c349-98a5-5970-aaa9-9eedd3ae5959"}
W1211 01:43:06.507002 10968 start.go:136] gopshost.Virtualization returned error: not implemented yet
I1211 01:43:06.528554 10968 out.go:177] * [second-055000] minikube v1.32.0 on Darwin 14.1.2
I1211 01:43:06.592408 10968 out.go:177] - MINIKUBE_LOCATION=17760
I1211 01:43:06.571737 10968 notify.go:220] Checking for updates...
I1211 01:43:06.634508 10968 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/17760-8912/kubeconfig
I1211 01:43:06.693498 10968 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1211 01:43:06.714554 10968 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I1211 01:43:06.736314 10968 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/17760-8912/.minikube
I1211 01:43:06.757558 10968 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I1211 01:43:06.779026 10968 config.go:182] Loaded profile config "first-052000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I1211 01:43:06.779140 10968 driver.go:392] Setting default libvirt URI to qemu:///system
I1211 01:43:06.808331 10968 out.go:177] * Using the hyperkit driver based on user configuration
I1211 01:43:06.850704 10968 start.go:298] selected driver: hyperkit
I1211 01:43:06.850719 10968 start.go:902] validating driver "hyperkit" against <nil>
I1211 01:43:06.850738 10968 start.go:913] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I1211 01:43:06.850984 10968 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1211 01:43:06.851170 10968 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/17760-8912/.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
I1211 01:43:06.860909 10968 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.32.0
I1211 01:43:06.864871 10968 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1211 01:43:06.864889 10968 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I1211 01:43:06.864919 10968 start_flags.go:309] no existing cluster config was found, will generate one from the flags
I1211 01:43:06.867655 10968 start_flags.go:394] Using suggested 6000MB memory alloc based on sys=32768MB, container=0MB
I1211 01:43:06.867809 10968 start_flags.go:913] Wait components to verify : map[apiserver:true system_pods:true]
I1211 01:43:06.867860 10968 cni.go:84] Creating CNI manager for ""
I1211 01:43:06.867874 10968 cni.go:158] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I1211 01:43:06.867883 10968 start_flags.go:318] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I1211 01:43:06.867891 10968 start_flags.go:323] config:
{Name:second-055000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1701974066-17719@sha256:cec630e7d143790c46e2dc54dbb8f39a22d8ede3e3c25e34638082e2c107a85c Memory:6000 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.28.4 ClusterName:second-055000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker
CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 AutoPauseInterval:1m0s GPUs:}
I1211 01:43:06.868046 10968 iso.go:125] acquiring lock: {Name:mkc51f6d85680a6ee4110bf750794f0b37cda350 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1211 01:43:06.910685 10968 out.go:177] * Starting control plane node second-055000 in cluster second-055000
I1211 01:43:06.932518 10968 preload.go:132] Checking if preload exists for k8s version v1.28.4 and runtime docker
I1211 01:43:06.932593 10968 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/17760-8912/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4
I1211 01:43:06.932615 10968 cache.go:56] Caching tarball of preloaded images
I1211 01:43:06.932788 10968 preload.go:174] Found /Users/jenkins/minikube-integration/17760-8912/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1211 01:43:06.932800 10968 cache.go:59] Finished verifying existence of preloaded tar for v1.28.4 on docker
I1211 01:43:06.932933 10968 profile.go:148] Saving config to /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/second-055000/config.json ...
I1211 01:43:06.932962 10968 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/second-055000/config.json: {Name:mk0671f0710c4b3f484b0912e0e4225b655a9daa Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1211 01:43:06.933626 10968 start.go:365] acquiring machines lock for second-055000: {Name:mk05e52f8f8e56dc062c6dd1be1aa8138d8ee982 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1211 01:43:06.933713 10968 start.go:369] acquired machines lock for "second-055000" in 72.688µs
I1211 01:43:06.933751 10968 start.go:93] Provisioning new machine with config: &{Name:second-055000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/17711/minikube-v1.32.1-1701788780-17711-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1701974066-17719@sha256:cec630e7d143790c46e2dc54dbb8f39a22d8ede3e3c25e34638082e2c107a85c Memory:6000 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 KubernetesCo
nfig:{KubernetesVersion:v1.28.4 ClusterName:second-055000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver: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: DisableO
ptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 AutoPauseInterval:1m0s GPUs:} &{Name: IP: Port:8443 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}
I1211 01:43:06.933823 10968 start.go:125] createHost starting for "" (driver="hyperkit")
I1211 01:43:06.955592 10968 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=6000MB, Disk=20000MB) ...
I1211 01:43:06.956021 10968 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1211 01:43:06.956090 10968 main.go:141] libmachine: Launching plugin server for driver hyperkit
I1211 01:43:06.965213 10968 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:58866
I1211 01:43:06.965598 10968 main.go:141] libmachine: () Calling .GetVersion
I1211 01:43:06.966030 10968 main.go:141] libmachine: Using API Version 1
I1211 01:43:06.966038 10968 main.go:141] libmachine: () Calling .SetConfigRaw
I1211 01:43:06.966269 10968 main.go:141] libmachine: () Calling .GetMachineName
I1211 01:43:06.966375 10968 main.go:141] libmachine: (second-055000) Calling .GetMachineName
I1211 01:43:06.966455 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:06.966553 10968 start.go:159] libmachine.API.Create for "second-055000" (driver="hyperkit")
I1211 01:43:06.966575 10968 client.go:168] LocalClient.Create starting
I1211 01:43:06.966606 10968 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca.pem
I1211 01:43:06.966641 10968 main.go:141] libmachine: Decoding PEM data...
I1211 01:43:06.966662 10968 main.go:141] libmachine: Parsing certificate...
I1211 01:43:06.966721 10968 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/17760-8912/.minikube/certs/cert.pem
I1211 01:43:06.966754 10968 main.go:141] libmachine: Decoding PEM data...
I1211 01:43:06.966763 10968 main.go:141] libmachine: Parsing certificate...
I1211 01:43:06.966784 10968 main.go:141] libmachine: Running pre-create checks...
I1211 01:43:06.966794 10968 main.go:141] libmachine: (second-055000) Calling .PreCreateCheck
I1211 01:43:06.966878 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:06.967025 10968 main.go:141] libmachine: (second-055000) Calling .GetConfigRaw
I1211 01:43:06.977077 10968 main.go:141] libmachine: Creating machine...
I1211 01:43:06.977100 10968 main.go:141] libmachine: (second-055000) Calling .Create
I1211 01:43:06.977255 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:06.977609 10968 main.go:141] libmachine: (second-055000) DBG | I1211 01:43:06.977238 10976 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/17760-8912/.minikube
I1211 01:43:06.977639 10968 main.go:141] libmachine: (second-055000) Downloading /Users/jenkins/minikube-integration/17760-8912/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/17760-8912/.minikube/cache/iso/amd64/minikube-v1.32.1-1701788780-17711-amd64.iso...
I1211 01:43:07.221232 10968 main.go:141] libmachine: (second-055000) DBG | I1211 01:43:07.221174 10976 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/id_rsa...
I1211 01:43:07.361536 10968 main.go:141] libmachine: (second-055000) DBG | I1211 01:43:07.361474 10976 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/second-055000.rawdisk...
I1211 01:43:07.361546 10968 main.go:141] libmachine: (second-055000) DBG | Writing magic tar header
I1211 01:43:07.361555 10968 main.go:141] libmachine: (second-055000) DBG | Writing SSH key tar header
I1211 01:43:07.362252 10968 main.go:141] libmachine: (second-055000) DBG | I1211 01:43:07.362218 10976 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000 ...
I1211 01:43:07.693393 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:07.693406 10968 main.go:141] libmachine: (second-055000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/hyperkit.pid
I1211 01:43:07.693459 10968 main.go:141] libmachine: (second-055000) DBG | Using UUID aff981f2-9809-11ee-8261-f01898ef957c
I1211 01:43:07.718836 10968 main.go:141] libmachine: (second-055000) DBG | Generated MAC 8e:15:38:8:b9:22
I1211 01:43:07.718848 10968 main.go:141] libmachine: (second-055000) 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=second-055000
I1211 01:43:07.718882 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"aff981f2-9809-11ee-8261-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc00009f1d0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/bzimage", Initrd:"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/initrd", Bootrom:"", CPUs:2, Memory:6000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1211 01:43:07.718911 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"aff981f2-9809-11ee-8261-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc00009f1d0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/bzimage", Initrd:"/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/initrd", Bootrom:"", CPUs:2, Memory:6000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1211 01:43:07.718955 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/hyperkit.pid", "-c", "2", "-m", "6000M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "aff981f2-9809-11ee-8261-f01898ef957c", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/second-055000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/tty,log=/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/bzimage,/Users/jenkins/minikube-integration/17760-8912/.minikube/machine
s/second-055000/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=second-055000"}
I1211 01:43:07.718988 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/hyperkit.pid -c 2 -m 6000M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U aff981f2-9809-11ee-8261-f01898ef957c -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/second-055000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/tty,log=/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/console-ring -f kexec,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/bzimage,/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/initrd,earlyprintk=serial loglevel=3 console=t
tyS0 console=tty0 noembed nomodeset norestore waitusb=10 systemd.legacy_systemd_cgroup_controller=yes random.trust_cpu=on hw_rng_model=virtio base host=second-055000"
I1211 01:43:07.719010 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I1211 01:43:07.721862 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 DEBUG: hyperkit: Pid is 10977
I1211 01:43:07.722285 10968 main.go:141] libmachine: (second-055000) DBG | Attempt 0
I1211 01:43:07.722300 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:07.722340 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:07.723212 10968 main.go:141] libmachine: (second-055000) DBG | Searching for 8e:15:38:8:b9:22 in /var/db/dhcpd_leases ...
I1211 01:43:07.723301 10968 main.go:141] libmachine: (second-055000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I1211 01:43:07.723310 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.52 HWAddress:46:ce:57:c6:c9:71 ID:1,46:ce:57:c6:c9:71 Lease:0x65782b10}
I1211 01:43:07.723318 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.51 HWAddress:56:7:4c:85:18:fd ID:1,56:7:4c:85:18:fd Lease:0x65782ad3}
I1211 01:43:07.723323 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.50 HWAddress:e:32:fe:8a:73:c0 ID:1,e:32:fe:8a:73:c0 Lease:0x65782a5f}
I1211 01:43:07.723335 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.49 HWAddress:a2:2a:29:94:23:b3 ID:1,a2:2a:29:94:23:b3 Lease:0x65782a28}
I1211 01:43:07.723342 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.48 HWAddress:ea:62:49:7a:83:40 ID:1,ea:62:49:7a:83:40 Lease:0x65782947}
I1211 01:43:07.723353 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.47 HWAddress:5a:25:f5:20:ec:bd ID:1,5a:25:f5:20:ec:bd Lease:0x65782919}
I1211 01:43:07.723358 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.46 HWAddress:d6:17:87:34:56:cc ID:1,d6:17:87:34:56:cc Lease:0x65782826}
I1211 01:43:07.723366 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.45 HWAddress:36:22:6f:86:da:b8 ID:1,36:22:6f:86:da:b8 Lease:0x657825d5}
I1211 01:43:07.723374 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.44 HWAddress:d6:62:4:19:df:78 ID:1,d6:62:4:19:df:78 Lease:0x6578251c}
I1211 01:43:07.723407 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.43 HWAddress:ca:59:c3:a8:3:1 ID:1,ca:59:c3:a8:3:1 Lease:0x6578245e}
I1211 01:43:07.723420 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.42 HWAddress:6a:0:b6:d4:74:8d ID:1,6a:0:b6:d4:74:8d Lease:0x657822c8}
I1211 01:43:07.723433 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.41 HWAddress:2a:58:3b:5c:ae:5a ID:1,2a:58:3b:5c:ae:5a Lease:0x657822e5}
I1211 01:43:07.723444 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.40 HWAddress:52:8d:e0:22:84:54 ID:1,52:8d:e0:22:84:54 Lease:0x65782216}
I1211 01:43:07.723451 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:2a:df:c1:16:db:3a ID:1,2a:df:c1:16:db:3a Lease:0x657821cc}
I1211 01:43:07.723455 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:3e:74:2e:1c:e:fd ID:1,3e:74:2e:1c:e:fd Lease:0x657821bd}
I1211 01:43:07.723468 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:ce:64:83:cb:be:60 ID:1,ce:64:83:cb:be:60 Lease:0x65782179}
I1211 01:43:07.723479 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:ae:84:c3:39:aa:a9 ID:1,ae:84:c3:39:aa:a9 Lease:0x6578216c}
I1211 01:43:07.723499 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:c2:ba:83:b6:8e:19 ID:1,c2:ba:83:b6:8e:19 Lease:0x6578212a}
I1211 01:43:07.723505 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:1a:1:58:26:a7:7e ID:1,1a:1:58:26:a7:7e Lease:0x6578210c}
I1211 01:43:07.723510 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:f2:28:26:f1:54:8e ID:1,f2:28:26:f1:54:8e Lease:0x657820cd}
I1211 01:43:07.723515 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:82:3d:31:c7:bc:82 ID:1,82:3d:31:c7:bc:82 Lease:0x6578207f}
I1211 01:43:07.723528 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:92:a7:75:ae:1d:c ID:1,92:a7:75:ae:1d:c Lease:0x6576cef5}
I1211 01:43:07.723539 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:a2:73:75:fe:d6:ce ID:1,a2:73:75:fe:d6:ce Lease:0x65782032}
I1211 01:43:07.723546 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:6:b3:d9:11:55:44 ID:1,6:b3:d9:11:55:44 Lease:0x65782019}
I1211 01:43:07.723554 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:f7:4a:66:1b:3a ID:1,b2:f7:4a:66:1b:3a Lease:0x65782009}
I1211 01:43:07.723560 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:be:6a:7b:49:73:ba ID:1,be:6a:7b:49:73:ba Lease:0x65781ff1}
I1211 01:43:07.723565 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:e2:fe:6:5c:ed:17 ID:1,e2:fe:6:5c:ed:17 Lease:0x6576ce6a}
I1211 01:43:07.723587 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:46:26:3f:b1:37:1e ID:1,46:26:3f:b1:37:1e Lease:0x6576ce45}
I1211 01:43:07.723596 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:42:d:46:3d:50:44 ID:1,42:d:46:3d:50:44 Lease:0x65781f8f}
I1211 01:43:07.723606 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:5a:33:d8:8b:e1:41 ID:1,5a:33:d8:8b:e1:41 Lease:0x65781f11}
I1211 01:43:07.723613 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:26:1c:58:7:5e:b3 ID:1,26:1c:58:7:5e:b3 Lease:0x65781f16}
I1211 01:43:07.723619 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:82:4e:9e:5c:5b:7b ID:1,82:4e:9e:5c:5b:7b Lease:0x65781ec6}
I1211 01:43:07.723623 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:46:db:ae:31:d3:41 ID:1,46:db:ae:31:d3:41 Lease:0x65781e6d}
I1211 01:43:07.723634 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:ca:fa:3b:79:92:6f ID:1,ca:fa:3b:79:92:6f Lease:0x65781e02}
I1211 01:43:07.723650 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:e6:91:83:cf:94:2f ID:1,e6:91:83:cf:94:2f Lease:0x65781d97}
I1211 01:43:07.723663 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:a:a7:9:63:b9:d7 ID:1,a:a7:9:63:b9:d7 Lease:0x65781d62}
I1211 01:43:07.723677 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:16:f1:7:c:fd:3d ID:1,16:f1:7:c:fd:3d Lease:0x65781c83}
I1211 01:43:07.723694 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:7e:5d:ca:b2:30:51 ID:1,7e:5d:ca:b2:30:51 Lease:0x6576ca6f}
I1211 01:43:07.723702 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:12:ea:29:39:8e:c3 ID:1,12:ea:29:39:8e:c3 Lease:0x65781c57}
I1211 01:43:07.723708 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:e2:54:1d:e6:cf:1e ID:1,e2:54:1d:e6:cf:1e Lease:0x65781c0a}
I1211 01:43:07.723716 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:1a:7f:b7:3c:b1:11 ID:1,1a:7f:b7:3c:b1:11 Lease:0x6576c918}
I1211 01:43:07.723722 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:ae:ad:f1:34:da:cf ID:1,ae:ad:f1:34:da:cf Lease:0x6576c902}
I1211 01:43:07.723729 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:e2:9b:14:3f:9a:20 ID:1,e2:9b:14:3f:9a:20 Lease:0x65781a4d}
I1211 01:43:07.723740 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:c6:6d:fb:b8:df:df ID:1,c6:6d:fb:b8:df:df Lease:0x65781a2a}
I1211 01:43:07.723749 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:22:54:a5:39:e3:b5 ID:1,22:54:a5:39:e3:b5 Lease:0x657819d0}
I1211 01:43:07.723754 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:ce:5f:dd:82:b8:f ID:1,ce:5f:dd:82:b8:f Lease:0x6578193b}
I1211 01:43:07.723760 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:de:9:78:a9:3a:9c ID:1,de:9:78:a9:3a:9c Lease:0x65781904}
I1211 01:43:07.723765 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6e:5:7c:c9:e8:d0 ID:1,6e:5:7c:c9:e8:d0 Lease:0x65781823}
I1211 01:43:07.723770 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:8e:e:99:b4:db:8 ID:1,8e:e:99:b4:db:8 Lease:0x657817f5}
I1211 01:43:07.723784 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:32:69:93:7b:73:d2 ID:1,32:69:93:7b:73:d2 Lease:0x657816e7}
I1211 01:43:07.723792 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:82:2b:f6:5b:7f:bf ID:1,82:2b:f6:5b:7f:bf Lease:0x657827cf}
I1211 01:43:07.729027 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I1211 01:43:07.740336 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I1211 01:43:07.741081 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I1211 01:43:07.741101 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1211 01:43:07.741109 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1211 01:43:07.741117 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:07 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1211 01:43:08.315156 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I1211 01:43:08.315168 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I1211 01:43:08.420177 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I1211 01:43:08.420188 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1211 01:43:08.420196 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1211 01:43:08.420203 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1211 01:43:08.421113 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I1211 01:43:08.421121 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:08 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I1211 01:43:09.723626 10968 main.go:141] libmachine: (second-055000) DBG | Attempt 1
I1211 01:43:09.723635 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:09.723665 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:09.724468 10968 main.go:141] libmachine: (second-055000) DBG | Searching for 8e:15:38:8:b9:22 in /var/db/dhcpd_leases ...
I1211 01:43:09.724575 10968 main.go:141] libmachine: (second-055000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I1211 01:43:09.724583 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.52 HWAddress:46:ce:57:c6:c9:71 ID:1,46:ce:57:c6:c9:71 Lease:0x65782b10}
I1211 01:43:09.724598 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.51 HWAddress:56:7:4c:85:18:fd ID:1,56:7:4c:85:18:fd Lease:0x65782ad3}
I1211 01:43:09.724606 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.50 HWAddress:e:32:fe:8a:73:c0 ID:1,e:32:fe:8a:73:c0 Lease:0x65782a5f}
I1211 01:43:09.724612 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.49 HWAddress:a2:2a:29:94:23:b3 ID:1,a2:2a:29:94:23:b3 Lease:0x65782a28}
I1211 01:43:09.724617 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.48 HWAddress:ea:62:49:7a:83:40 ID:1,ea:62:49:7a:83:40 Lease:0x65782947}
I1211 01:43:09.724641 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.47 HWAddress:5a:25:f5:20:ec:bd ID:1,5a:25:f5:20:ec:bd Lease:0x65782919}
I1211 01:43:09.724654 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.46 HWAddress:d6:17:87:34:56:cc ID:1,d6:17:87:34:56:cc Lease:0x65782826}
I1211 01:43:09.724677 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.45 HWAddress:36:22:6f:86:da:b8 ID:1,36:22:6f:86:da:b8 Lease:0x657825d5}
I1211 01:43:09.724692 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.44 HWAddress:d6:62:4:19:df:78 ID:1,d6:62:4:19:df:78 Lease:0x6578251c}
I1211 01:43:09.724706 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.43 HWAddress:ca:59:c3:a8:3:1 ID:1,ca:59:c3:a8:3:1 Lease:0x6578245e}
I1211 01:43:09.724715 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.42 HWAddress:6a:0:b6:d4:74:8d ID:1,6a:0:b6:d4:74:8d Lease:0x657822c8}
I1211 01:43:09.724726 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.41 HWAddress:2a:58:3b:5c:ae:5a ID:1,2a:58:3b:5c:ae:5a Lease:0x657822e5}
I1211 01:43:09.724733 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.40 HWAddress:52:8d:e0:22:84:54 ID:1,52:8d:e0:22:84:54 Lease:0x65782216}
I1211 01:43:09.724739 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:2a:df:c1:16:db:3a ID:1,2a:df:c1:16:db:3a Lease:0x657821cc}
I1211 01:43:09.724744 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:3e:74:2e:1c:e:fd ID:1,3e:74:2e:1c:e:fd Lease:0x657821bd}
I1211 01:43:09.724749 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:ce:64:83:cb:be:60 ID:1,ce:64:83:cb:be:60 Lease:0x65782179}
I1211 01:43:09.724754 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:ae:84:c3:39:aa:a9 ID:1,ae:84:c3:39:aa:a9 Lease:0x6578216c}
I1211 01:43:09.724759 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:c2:ba:83:b6:8e:19 ID:1,c2:ba:83:b6:8e:19 Lease:0x6578212a}
I1211 01:43:09.724764 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:1a:1:58:26:a7:7e ID:1,1a:1:58:26:a7:7e Lease:0x6578210c}
I1211 01:43:09.724770 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:f2:28:26:f1:54:8e ID:1,f2:28:26:f1:54:8e Lease:0x657820cd}
I1211 01:43:09.724775 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:82:3d:31:c7:bc:82 ID:1,82:3d:31:c7:bc:82 Lease:0x6578207f}
I1211 01:43:09.724780 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:92:a7:75:ae:1d:c ID:1,92:a7:75:ae:1d:c Lease:0x6576cef5}
I1211 01:43:09.724785 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:a2:73:75:fe:d6:ce ID:1,a2:73:75:fe:d6:ce Lease:0x65782032}
I1211 01:43:09.724792 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:6:b3:d9:11:55:44 ID:1,6:b3:d9:11:55:44 Lease:0x65782019}
I1211 01:43:09.724798 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:f7:4a:66:1b:3a ID:1,b2:f7:4a:66:1b:3a Lease:0x65782009}
I1211 01:43:09.724803 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:be:6a:7b:49:73:ba ID:1,be:6a:7b:49:73:ba Lease:0x65781ff1}
I1211 01:43:09.724808 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:e2:fe:6:5c:ed:17 ID:1,e2:fe:6:5c:ed:17 Lease:0x6576ce6a}
I1211 01:43:09.724814 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:46:26:3f:b1:37:1e ID:1,46:26:3f:b1:37:1e Lease:0x6576ce45}
I1211 01:43:09.724830 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:42:d:46:3d:50:44 ID:1,42:d:46:3d:50:44 Lease:0x65781f8f}
I1211 01:43:09.724843 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:5a:33:d8:8b:e1:41 ID:1,5a:33:d8:8b:e1:41 Lease:0x65781f11}
I1211 01:43:09.724853 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:26:1c:58:7:5e:b3 ID:1,26:1c:58:7:5e:b3 Lease:0x65781f16}
I1211 01:43:09.724859 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:82:4e:9e:5c:5b:7b ID:1,82:4e:9e:5c:5b:7b Lease:0x65781ec6}
I1211 01:43:09.724865 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:46:db:ae:31:d3:41 ID:1,46:db:ae:31:d3:41 Lease:0x65781e6d}
I1211 01:43:09.724871 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:ca:fa:3b:79:92:6f ID:1,ca:fa:3b:79:92:6f Lease:0x65781e02}
I1211 01:43:09.724878 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:e6:91:83:cf:94:2f ID:1,e6:91:83:cf:94:2f Lease:0x65781d97}
I1211 01:43:09.724888 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:a:a7:9:63:b9:d7 ID:1,a:a7:9:63:b9:d7 Lease:0x65781d62}
I1211 01:43:09.724902 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:16:f1:7:c:fd:3d ID:1,16:f1:7:c:fd:3d Lease:0x65781c83}
I1211 01:43:09.724908 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:7e:5d:ca:b2:30:51 ID:1,7e:5d:ca:b2:30:51 Lease:0x6576ca6f}
I1211 01:43:09.724913 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:12:ea:29:39:8e:c3 ID:1,12:ea:29:39:8e:c3 Lease:0x65781c57}
I1211 01:43:09.724919 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:e2:54:1d:e6:cf:1e ID:1,e2:54:1d:e6:cf:1e Lease:0x65781c0a}
I1211 01:43:09.724926 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:1a:7f:b7:3c:b1:11 ID:1,1a:7f:b7:3c:b1:11 Lease:0x6576c918}
I1211 01:43:09.724932 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:ae:ad:f1:34:da:cf ID:1,ae:ad:f1:34:da:cf Lease:0x6576c902}
I1211 01:43:09.724936 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:e2:9b:14:3f:9a:20 ID:1,e2:9b:14:3f:9a:20 Lease:0x65781a4d}
I1211 01:43:09.724941 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:c6:6d:fb:b8:df:df ID:1,c6:6d:fb:b8:df:df Lease:0x65781a2a}
I1211 01:43:09.724947 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:22:54:a5:39:e3:b5 ID:1,22:54:a5:39:e3:b5 Lease:0x657819d0}
I1211 01:43:09.724956 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:ce:5f:dd:82:b8:f ID:1,ce:5f:dd:82:b8:f Lease:0x6578193b}
I1211 01:43:09.724962 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:de:9:78:a9:3a:9c ID:1,de:9:78:a9:3a:9c Lease:0x65781904}
I1211 01:43:09.724968 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6e:5:7c:c9:e8:d0 ID:1,6e:5:7c:c9:e8:d0 Lease:0x65781823}
I1211 01:43:09.724976 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:8e:e:99:b4:db:8 ID:1,8e:e:99:b4:db:8 Lease:0x657817f5}
I1211 01:43:09.724984 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:32:69:93:7b:73:d2 ID:1,32:69:93:7b:73:d2 Lease:0x657816e7}
I1211 01:43:09.724994 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:82:2b:f6:5b:7f:bf ID:1,82:2b:f6:5b:7f:bf Lease:0x657827cf}
I1211 01:43:11.726227 10968 main.go:141] libmachine: (second-055000) DBG | Attempt 2
I1211 01:43:11.726238 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:11.726335 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:11.727140 10968 main.go:141] libmachine: (second-055000) DBG | Searching for 8e:15:38:8:b9:22 in /var/db/dhcpd_leases ...
I1211 01:43:11.727226 10968 main.go:141] libmachine: (second-055000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I1211 01:43:11.727233 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.52 HWAddress:46:ce:57:c6:c9:71 ID:1,46:ce:57:c6:c9:71 Lease:0x65782b10}
I1211 01:43:11.727241 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.51 HWAddress:56:7:4c:85:18:fd ID:1,56:7:4c:85:18:fd Lease:0x65782ad3}
I1211 01:43:11.727246 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.50 HWAddress:e:32:fe:8a:73:c0 ID:1,e:32:fe:8a:73:c0 Lease:0x65782a5f}
I1211 01:43:11.727256 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.49 HWAddress:a2:2a:29:94:23:b3 ID:1,a2:2a:29:94:23:b3 Lease:0x65782a28}
I1211 01:43:11.727261 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.48 HWAddress:ea:62:49:7a:83:40 ID:1,ea:62:49:7a:83:40 Lease:0x65782947}
I1211 01:43:11.727266 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.47 HWAddress:5a:25:f5:20:ec:bd ID:1,5a:25:f5:20:ec:bd Lease:0x65782919}
I1211 01:43:11.727271 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.46 HWAddress:d6:17:87:34:56:cc ID:1,d6:17:87:34:56:cc Lease:0x65782826}
I1211 01:43:11.727279 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.45 HWAddress:36:22:6f:86:da:b8 ID:1,36:22:6f:86:da:b8 Lease:0x657825d5}
I1211 01:43:11.727284 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.44 HWAddress:d6:62:4:19:df:78 ID:1,d6:62:4:19:df:78 Lease:0x6578251c}
I1211 01:43:11.727289 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.43 HWAddress:ca:59:c3:a8:3:1 ID:1,ca:59:c3:a8:3:1 Lease:0x6578245e}
I1211 01:43:11.727300 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.42 HWAddress:6a:0:b6:d4:74:8d ID:1,6a:0:b6:d4:74:8d Lease:0x657822c8}
I1211 01:43:11.727306 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.41 HWAddress:2a:58:3b:5c:ae:5a ID:1,2a:58:3b:5c:ae:5a Lease:0x657822e5}
I1211 01:43:11.727311 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.40 HWAddress:52:8d:e0:22:84:54 ID:1,52:8d:e0:22:84:54 Lease:0x65782216}
I1211 01:43:11.727320 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:2a:df:c1:16:db:3a ID:1,2a:df:c1:16:db:3a Lease:0x657821cc}
I1211 01:43:11.727326 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:3e:74:2e:1c:e:fd ID:1,3e:74:2e:1c:e:fd Lease:0x657821bd}
I1211 01:43:11.727330 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:ce:64:83:cb:be:60 ID:1,ce:64:83:cb:be:60 Lease:0x65782179}
I1211 01:43:11.727336 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:ae:84:c3:39:aa:a9 ID:1,ae:84:c3:39:aa:a9 Lease:0x6578216c}
I1211 01:43:11.727341 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:c2:ba:83:b6:8e:19 ID:1,c2:ba:83:b6:8e:19 Lease:0x6578212a}
I1211 01:43:11.727346 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:1a:1:58:26:a7:7e ID:1,1a:1:58:26:a7:7e Lease:0x6578210c}
I1211 01:43:11.727352 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:f2:28:26:f1:54:8e ID:1,f2:28:26:f1:54:8e Lease:0x657820cd}
I1211 01:43:11.727357 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:82:3d:31:c7:bc:82 ID:1,82:3d:31:c7:bc:82 Lease:0x6578207f}
I1211 01:43:11.727362 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:92:a7:75:ae:1d:c ID:1,92:a7:75:ae:1d:c Lease:0x6576cef5}
I1211 01:43:11.727369 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:a2:73:75:fe:d6:ce ID:1,a2:73:75:fe:d6:ce Lease:0x65782032}
I1211 01:43:11.727376 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:6:b3:d9:11:55:44 ID:1,6:b3:d9:11:55:44 Lease:0x65782019}
I1211 01:43:11.727382 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:f7:4a:66:1b:3a ID:1,b2:f7:4a:66:1b:3a Lease:0x65782009}
I1211 01:43:11.727387 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:be:6a:7b:49:73:ba ID:1,be:6a:7b:49:73:ba Lease:0x65781ff1}
I1211 01:43:11.727392 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:e2:fe:6:5c:ed:17 ID:1,e2:fe:6:5c:ed:17 Lease:0x6576ce6a}
I1211 01:43:11.727397 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:46:26:3f:b1:37:1e ID:1,46:26:3f:b1:37:1e Lease:0x6576ce45}
I1211 01:43:11.727405 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:42:d:46:3d:50:44 ID:1,42:d:46:3d:50:44 Lease:0x65781f8f}
I1211 01:43:11.727413 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:5a:33:d8:8b:e1:41 ID:1,5a:33:d8:8b:e1:41 Lease:0x65781f11}
I1211 01:43:11.727418 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:26:1c:58:7:5e:b3 ID:1,26:1c:58:7:5e:b3 Lease:0x65781f16}
I1211 01:43:11.727423 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:82:4e:9e:5c:5b:7b ID:1,82:4e:9e:5c:5b:7b Lease:0x65781ec6}
I1211 01:43:11.727428 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:46:db:ae:31:d3:41 ID:1,46:db:ae:31:d3:41 Lease:0x65781e6d}
I1211 01:43:11.727434 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:ca:fa:3b:79:92:6f ID:1,ca:fa:3b:79:92:6f Lease:0x65781e02}
I1211 01:43:11.727441 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:e6:91:83:cf:94:2f ID:1,e6:91:83:cf:94:2f Lease:0x65781d97}
I1211 01:43:11.727447 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:a:a7:9:63:b9:d7 ID:1,a:a7:9:63:b9:d7 Lease:0x65781d62}
I1211 01:43:11.727454 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:16:f1:7:c:fd:3d ID:1,16:f1:7:c:fd:3d Lease:0x65781c83}
I1211 01:43:11.727460 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:7e:5d:ca:b2:30:51 ID:1,7e:5d:ca:b2:30:51 Lease:0x6576ca6f}
I1211 01:43:11.727467 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:12:ea:29:39:8e:c3 ID:1,12:ea:29:39:8e:c3 Lease:0x65781c57}
I1211 01:43:11.727476 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:e2:54:1d:e6:cf:1e ID:1,e2:54:1d:e6:cf:1e Lease:0x65781c0a}
I1211 01:43:11.727483 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:1a:7f:b7:3c:b1:11 ID:1,1a:7f:b7:3c:b1:11 Lease:0x6576c918}
I1211 01:43:11.727490 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:ae:ad:f1:34:da:cf ID:1,ae:ad:f1:34:da:cf Lease:0x6576c902}
I1211 01:43:11.727495 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:e2:9b:14:3f:9a:20 ID:1,e2:9b:14:3f:9a:20 Lease:0x65781a4d}
I1211 01:43:11.727500 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:c6:6d:fb:b8:df:df ID:1,c6:6d:fb:b8:df:df Lease:0x65781a2a}
I1211 01:43:11.727507 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:22:54:a5:39:e3:b5 ID:1,22:54:a5:39:e3:b5 Lease:0x657819d0}
I1211 01:43:11.727512 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:ce:5f:dd:82:b8:f ID:1,ce:5f:dd:82:b8:f Lease:0x6578193b}
I1211 01:43:11.727520 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:de:9:78:a9:3a:9c ID:1,de:9:78:a9:3a:9c Lease:0x65781904}
I1211 01:43:11.727525 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6e:5:7c:c9:e8:d0 ID:1,6e:5:7c:c9:e8:d0 Lease:0x65781823}
I1211 01:43:11.727530 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:8e:e:99:b4:db:8 ID:1,8e:e:99:b4:db:8 Lease:0x657817f5}
I1211 01:43:11.727535 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:32:69:93:7b:73:d2 ID:1,32:69:93:7b:73:d2 Lease:0x657816e7}
I1211 01:43:11.727543 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:82:2b:f6:5b:7f:bf ID:1,82:2b:f6:5b:7f:bf Lease:0x657827cf}
I1211 01:43:13.429377 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:13 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I1211 01:43:13.429442 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:13 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I1211 01:43:13.429449 10968 main.go:141] libmachine: (second-055000) DBG | 2023/12/11 01:43:13 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I1211 01:43:13.729470 10968 main.go:141] libmachine: (second-055000) DBG | Attempt 3
I1211 01:43:13.729482 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:13.729609 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:13.730616 10968 main.go:141] libmachine: (second-055000) DBG | Searching for 8e:15:38:8:b9:22 in /var/db/dhcpd_leases ...
I1211 01:43:13.730702 10968 main.go:141] libmachine: (second-055000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I1211 01:43:13.730711 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.52 HWAddress:46:ce:57:c6:c9:71 ID:1,46:ce:57:c6:c9:71 Lease:0x65782b10}
I1211 01:43:13.730724 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.51 HWAddress:56:7:4c:85:18:fd ID:1,56:7:4c:85:18:fd Lease:0x65782ad3}
I1211 01:43:13.730730 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.50 HWAddress:e:32:fe:8a:73:c0 ID:1,e:32:fe:8a:73:c0 Lease:0x65782a5f}
I1211 01:43:13.730749 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.49 HWAddress:a2:2a:29:94:23:b3 ID:1,a2:2a:29:94:23:b3 Lease:0x65782a28}
I1211 01:43:13.730765 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.48 HWAddress:ea:62:49:7a:83:40 ID:1,ea:62:49:7a:83:40 Lease:0x65782947}
I1211 01:43:13.730774 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.47 HWAddress:5a:25:f5:20:ec:bd ID:1,5a:25:f5:20:ec:bd Lease:0x65782919}
I1211 01:43:13.730783 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.46 HWAddress:d6:17:87:34:56:cc ID:1,d6:17:87:34:56:cc Lease:0x65782826}
I1211 01:43:13.730796 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.45 HWAddress:36:22:6f:86:da:b8 ID:1,36:22:6f:86:da:b8 Lease:0x657825d5}
I1211 01:43:13.730802 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.44 HWAddress:d6:62:4:19:df:78 ID:1,d6:62:4:19:df:78 Lease:0x6578251c}
I1211 01:43:13.730818 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.43 HWAddress:ca:59:c3:a8:3:1 ID:1,ca:59:c3:a8:3:1 Lease:0x6578245e}
I1211 01:43:13.730828 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.42 HWAddress:6a:0:b6:d4:74:8d ID:1,6a:0:b6:d4:74:8d Lease:0x657822c8}
I1211 01:43:13.730836 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.41 HWAddress:2a:58:3b:5c:ae:5a ID:1,2a:58:3b:5c:ae:5a Lease:0x657822e5}
I1211 01:43:13.730849 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.40 HWAddress:52:8d:e0:22:84:54 ID:1,52:8d:e0:22:84:54 Lease:0x65782216}
I1211 01:43:13.730880 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:2a:df:c1:16:db:3a ID:1,2a:df:c1:16:db:3a Lease:0x657821cc}
I1211 01:43:13.730899 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:3e:74:2e:1c:e:fd ID:1,3e:74:2e:1c:e:fd Lease:0x657821bd}
I1211 01:43:13.730910 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:ce:64:83:cb:be:60 ID:1,ce:64:83:cb:be:60 Lease:0x65782179}
I1211 01:43:13.730919 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:ae:84:c3:39:aa:a9 ID:1,ae:84:c3:39:aa:a9 Lease:0x6578216c}
I1211 01:43:13.730928 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:c2:ba:83:b6:8e:19 ID:1,c2:ba:83:b6:8e:19 Lease:0x6578212a}
I1211 01:43:13.730937 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:1a:1:58:26:a7:7e ID:1,1a:1:58:26:a7:7e Lease:0x6578210c}
I1211 01:43:13.730955 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:f2:28:26:f1:54:8e ID:1,f2:28:26:f1:54:8e Lease:0x657820cd}
I1211 01:43:13.730965 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:82:3d:31:c7:bc:82 ID:1,82:3d:31:c7:bc:82 Lease:0x6578207f}
I1211 01:43:13.730976 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:92:a7:75:ae:1d:c ID:1,92:a7:75:ae:1d:c Lease:0x6576cef5}
I1211 01:43:13.730989 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:a2:73:75:fe:d6:ce ID:1,a2:73:75:fe:d6:ce Lease:0x65782032}
I1211 01:43:13.730997 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:6:b3:d9:11:55:44 ID:1,6:b3:d9:11:55:44 Lease:0x65782019}
I1211 01:43:13.731018 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:f7:4a:66:1b:3a ID:1,b2:f7:4a:66:1b:3a Lease:0x65782009}
I1211 01:43:13.731039 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:be:6a:7b:49:73:ba ID:1,be:6a:7b:49:73:ba Lease:0x65781ff1}
I1211 01:43:13.731051 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:e2:fe:6:5c:ed:17 ID:1,e2:fe:6:5c:ed:17 Lease:0x6576ce6a}
I1211 01:43:13.731073 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:46:26:3f:b1:37:1e ID:1,46:26:3f:b1:37:1e Lease:0x6576ce45}
I1211 01:43:13.731086 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:42:d:46:3d:50:44 ID:1,42:d:46:3d:50:44 Lease:0x65781f8f}
I1211 01:43:13.731096 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:5a:33:d8:8b:e1:41 ID:1,5a:33:d8:8b:e1:41 Lease:0x65781f11}
I1211 01:43:13.731105 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:26:1c:58:7:5e:b3 ID:1,26:1c:58:7:5e:b3 Lease:0x65781f16}
I1211 01:43:13.731113 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:82:4e:9e:5c:5b:7b ID:1,82:4e:9e:5c:5b:7b Lease:0x65781ec6}
I1211 01:43:13.731122 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:46:db:ae:31:d3:41 ID:1,46:db:ae:31:d3:41 Lease:0x65781e6d}
I1211 01:43:13.731130 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:ca:fa:3b:79:92:6f ID:1,ca:fa:3b:79:92:6f Lease:0x65781e02}
I1211 01:43:13.731139 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:e6:91:83:cf:94:2f ID:1,e6:91:83:cf:94:2f Lease:0x65781d97}
I1211 01:43:13.731147 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:a:a7:9:63:b9:d7 ID:1,a:a7:9:63:b9:d7 Lease:0x65781d62}
I1211 01:43:13.731156 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:16:f1:7:c:fd:3d ID:1,16:f1:7:c:fd:3d Lease:0x65781c83}
I1211 01:43:13.731164 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:7e:5d:ca:b2:30:51 ID:1,7e:5d:ca:b2:30:51 Lease:0x6576ca6f}
I1211 01:43:13.731171 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:12:ea:29:39:8e:c3 ID:1,12:ea:29:39:8e:c3 Lease:0x65781c57}
I1211 01:43:13.731181 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:e2:54:1d:e6:cf:1e ID:1,e2:54:1d:e6:cf:1e Lease:0x65781c0a}
I1211 01:43:13.731194 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:1a:7f:b7:3c:b1:11 ID:1,1a:7f:b7:3c:b1:11 Lease:0x6576c918}
I1211 01:43:13.731204 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:ae:ad:f1:34:da:cf ID:1,ae:ad:f1:34:da:cf Lease:0x6576c902}
I1211 01:43:13.731212 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:e2:9b:14:3f:9a:20 ID:1,e2:9b:14:3f:9a:20 Lease:0x65781a4d}
I1211 01:43:13.731239 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:c6:6d:fb:b8:df:df ID:1,c6:6d:fb:b8:df:df Lease:0x65781a2a}
I1211 01:43:13.731245 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:22:54:a5:39:e3:b5 ID:1,22:54:a5:39:e3:b5 Lease:0x657819d0}
I1211 01:43:13.731254 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:ce:5f:dd:82:b8:f ID:1,ce:5f:dd:82:b8:f Lease:0x6578193b}
I1211 01:43:13.731261 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:de:9:78:a9:3a:9c ID:1,de:9:78:a9:3a:9c Lease:0x65781904}
I1211 01:43:13.731267 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6e:5:7c:c9:e8:d0 ID:1,6e:5:7c:c9:e8:d0 Lease:0x65781823}
I1211 01:43:13.731274 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:8e:e:99:b4:db:8 ID:1,8e:e:99:b4:db:8 Lease:0x657817f5}
I1211 01:43:13.731281 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:32:69:93:7b:73:d2 ID:1,32:69:93:7b:73:d2 Lease:0x657816e7}
I1211 01:43:13.731289 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:82:2b:f6:5b:7f:bf ID:1,82:2b:f6:5b:7f:bf Lease:0x657827cf}
I1211 01:43:15.731412 10968 main.go:141] libmachine: (second-055000) DBG | Attempt 4
I1211 01:43:15.731425 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:15.731509 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:15.732302 10968 main.go:141] libmachine: (second-055000) DBG | Searching for 8e:15:38:8:b9:22 in /var/db/dhcpd_leases ...
I1211 01:43:15.732389 10968 main.go:141] libmachine: (second-055000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I1211 01:43:15.732411 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.52 HWAddress:46:ce:57:c6:c9:71 ID:1,46:ce:57:c6:c9:71 Lease:0x65782b10}
I1211 01:43:15.732417 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.51 HWAddress:56:7:4c:85:18:fd ID:1,56:7:4c:85:18:fd Lease:0x65782ad3}
I1211 01:43:15.732428 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.50 HWAddress:e:32:fe:8a:73:c0 ID:1,e:32:fe:8a:73:c0 Lease:0x65782a5f}
I1211 01:43:15.732433 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.49 HWAddress:a2:2a:29:94:23:b3 ID:1,a2:2a:29:94:23:b3 Lease:0x65782a28}
I1211 01:43:15.732438 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.48 HWAddress:ea:62:49:7a:83:40 ID:1,ea:62:49:7a:83:40 Lease:0x65782947}
I1211 01:43:15.732444 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.47 HWAddress:5a:25:f5:20:ec:bd ID:1,5a:25:f5:20:ec:bd Lease:0x65782919}
I1211 01:43:15.732449 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.46 HWAddress:d6:17:87:34:56:cc ID:1,d6:17:87:34:56:cc Lease:0x65782826}
I1211 01:43:15.732457 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.45 HWAddress:36:22:6f:86:da:b8 ID:1,36:22:6f:86:da:b8 Lease:0x657825d5}
I1211 01:43:15.732465 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.44 HWAddress:d6:62:4:19:df:78 ID:1,d6:62:4:19:df:78 Lease:0x6578251c}
I1211 01:43:15.732473 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.43 HWAddress:ca:59:c3:a8:3:1 ID:1,ca:59:c3:a8:3:1 Lease:0x6578245e}
I1211 01:43:15.732478 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.42 HWAddress:6a:0:b6:d4:74:8d ID:1,6a:0:b6:d4:74:8d Lease:0x657822c8}
I1211 01:43:15.732483 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.41 HWAddress:2a:58:3b:5c:ae:5a ID:1,2a:58:3b:5c:ae:5a Lease:0x657822e5}
I1211 01:43:15.732488 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.40 HWAddress:52:8d:e0:22:84:54 ID:1,52:8d:e0:22:84:54 Lease:0x65782216}
I1211 01:43:15.732493 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.39 HWAddress:2a:df:c1:16:db:3a ID:1,2a:df:c1:16:db:3a Lease:0x657821cc}
I1211 01:43:15.732502 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.38 HWAddress:3e:74:2e:1c:e:fd ID:1,3e:74:2e:1c:e:fd Lease:0x657821bd}
I1211 01:43:15.732508 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.37 HWAddress:ce:64:83:cb:be:60 ID:1,ce:64:83:cb:be:60 Lease:0x65782179}
I1211 01:43:15.732514 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.36 HWAddress:ae:84:c3:39:aa:a9 ID:1,ae:84:c3:39:aa:a9 Lease:0x6578216c}
I1211 01:43:15.732519 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.35 HWAddress:c2:ba:83:b6:8e:19 ID:1,c2:ba:83:b6:8e:19 Lease:0x6578212a}
I1211 01:43:15.732532 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.34 HWAddress:1a:1:58:26:a7:7e ID:1,1a:1:58:26:a7:7e Lease:0x6578210c}
I1211 01:43:15.732541 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.33 HWAddress:f2:28:26:f1:54:8e ID:1,f2:28:26:f1:54:8e Lease:0x657820cd}
I1211 01:43:15.732547 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.32 HWAddress:82:3d:31:c7:bc:82 ID:1,82:3d:31:c7:bc:82 Lease:0x6578207f}
I1211 01:43:15.732556 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.31 HWAddress:92:a7:75:ae:1d:c ID:1,92:a7:75:ae:1d:c Lease:0x6576cef5}
I1211 01:43:15.732571 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.30 HWAddress:a2:73:75:fe:d6:ce ID:1,a2:73:75:fe:d6:ce Lease:0x65782032}
I1211 01:43:15.732578 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.29 HWAddress:6:b3:d9:11:55:44 ID:1,6:b3:d9:11:55:44 Lease:0x65782019}
I1211 01:43:15.732584 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.28 HWAddress:b2:f7:4a:66:1b:3a ID:1,b2:f7:4a:66:1b:3a Lease:0x65782009}
I1211 01:43:15.732589 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.27 HWAddress:be:6a:7b:49:73:ba ID:1,be:6a:7b:49:73:ba Lease:0x65781ff1}
I1211 01:43:15.732597 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.26 HWAddress:e2:fe:6:5c:ed:17 ID:1,e2:fe:6:5c:ed:17 Lease:0x6576ce6a}
I1211 01:43:15.732603 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.25 HWAddress:46:26:3f:b1:37:1e ID:1,46:26:3f:b1:37:1e Lease:0x6576ce45}
I1211 01:43:15.732608 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.24 HWAddress:42:d:46:3d:50:44 ID:1,42:d:46:3d:50:44 Lease:0x65781f8f}
I1211 01:43:15.732620 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.23 HWAddress:5a:33:d8:8b:e1:41 ID:1,5a:33:d8:8b:e1:41 Lease:0x65781f11}
I1211 01:43:15.732630 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.22 HWAddress:26:1c:58:7:5e:b3 ID:1,26:1c:58:7:5e:b3 Lease:0x65781f16}
I1211 01:43:15.732641 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.21 HWAddress:82:4e:9e:5c:5b:7b ID:1,82:4e:9e:5c:5b:7b Lease:0x65781ec6}
I1211 01:43:15.732655 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.20 HWAddress:46:db:ae:31:d3:41 ID:1,46:db:ae:31:d3:41 Lease:0x65781e6d}
I1211 01:43:15.732661 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.19 HWAddress:ca:fa:3b:79:92:6f ID:1,ca:fa:3b:79:92:6f Lease:0x65781e02}
I1211 01:43:15.732669 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.18 HWAddress:e6:91:83:cf:94:2f ID:1,e6:91:83:cf:94:2f Lease:0x65781d97}
I1211 01:43:15.732675 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.17 HWAddress:a:a7:9:63:b9:d7 ID:1,a:a7:9:63:b9:d7 Lease:0x65781d62}
I1211 01:43:15.732680 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.16 HWAddress:16:f1:7:c:fd:3d ID:1,16:f1:7:c:fd:3d Lease:0x65781c83}
I1211 01:43:15.732687 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.15 HWAddress:7e:5d:ca:b2:30:51 ID:1,7e:5d:ca:b2:30:51 Lease:0x6576ca6f}
I1211 01:43:15.732693 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.14 HWAddress:12:ea:29:39:8e:c3 ID:1,12:ea:29:39:8e:c3 Lease:0x65781c57}
I1211 01:43:15.732698 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.13 HWAddress:e2:54:1d:e6:cf:1e ID:1,e2:54:1d:e6:cf:1e Lease:0x65781c0a}
I1211 01:43:15.732703 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.12 HWAddress:1a:7f:b7:3c:b1:11 ID:1,1a:7f:b7:3c:b1:11 Lease:0x6576c918}
I1211 01:43:15.732708 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.11 HWAddress:ae:ad:f1:34:da:cf ID:1,ae:ad:f1:34:da:cf Lease:0x6576c902}
I1211 01:43:15.732717 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.10 HWAddress:e2:9b:14:3f:9a:20 ID:1,e2:9b:14:3f:9a:20 Lease:0x65781a4d}
I1211 01:43:15.732722 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.9 HWAddress:c6:6d:fb:b8:df:df ID:1,c6:6d:fb:b8:df:df Lease:0x65781a2a}
I1211 01:43:15.732741 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.8 HWAddress:22:54:a5:39:e3:b5 ID:1,22:54:a5:39:e3:b5 Lease:0x657819d0}
I1211 01:43:15.732748 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.7 HWAddress:ce:5f:dd:82:b8:f ID:1,ce:5f:dd:82:b8:f Lease:0x6578193b}
I1211 01:43:15.732754 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.6 HWAddress:de:9:78:a9:3a:9c ID:1,de:9:78:a9:3a:9c Lease:0x65781904}
I1211 01:43:15.732759 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.5 HWAddress:6e:5:7c:c9:e8:d0 ID:1,6e:5:7c:c9:e8:d0 Lease:0x65781823}
I1211 01:43:15.732772 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.4 HWAddress:8e:e:99:b4:db:8 ID:1,8e:e:99:b4:db:8 Lease:0x657817f5}
I1211 01:43:15.732781 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.3 HWAddress:32:69:93:7b:73:d2 ID:1,32:69:93:7b:73:d2 Lease:0x657816e7}
I1211 01:43:15.732790 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name: IPAddress:192.169.0.2 HWAddress:82:2b:f6:5b:7f:bf ID:1,82:2b:f6:5b:7f:bf Lease:0x657827cf}
I1211 01:43:17.733036 10968 main.go:141] libmachine: (second-055000) DBG | Attempt 5
I1211 01:43:17.733050 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:17.733135 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:17.733946 10968 main.go:141] libmachine: (second-055000) DBG | Searching for 8e:15:38:8:b9:22 in /var/db/dhcpd_leases ...
I1211 01:43:17.734047 10968 main.go:141] libmachine: (second-055000) DBG | Found 52 entries in /var/db/dhcpd_leases!
I1211 01:43:17.734054 10968 main.go:141] libmachine: (second-055000) DBG | dhcp entry: {Name:minikube IPAddress:192.169.0.53 HWAddress:8e:15:38:8:b9:22 ID:1,8e:15:38:8:b9:22 Lease:0x65782b35}
I1211 01:43:17.734063 10968 main.go:141] libmachine: (second-055000) DBG | Found match: 8e:15:38:8:b9:22
I1211 01:43:17.734067 10968 main.go:141] libmachine: (second-055000) DBG | IP: 192.169.0.53
I1211 01:43:17.734146 10968 main.go:141] libmachine: (second-055000) Calling .GetConfigRaw
I1211 01:43:17.734722 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:17.734831 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:17.734915 10968 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I1211 01:43:17.734925 10968 main.go:141] libmachine: (second-055000) Calling .GetState
I1211 01:43:17.735021 10968 main.go:141] libmachine: (second-055000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1211 01:43:17.735072 10968 main.go:141] libmachine: (second-055000) DBG | hyperkit pid from json: 10977
I1211 01:43:17.735920 10968 main.go:141] libmachine: Detecting operating system of created instance...
I1211 01:43:17.735928 10968 main.go:141] libmachine: Waiting for SSH to be available...
I1211 01:43:17.735931 10968 main.go:141] libmachine: Getting to WaitForSSH function...
I1211 01:43:17.735937 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:17.736037 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:17.736173 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:17.736278 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:17.736387 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:17.736526 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:17.736831 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:17.736836 10968 main.go:141] libmachine: About to run SSH command:
exit 0
I1211 01:43:18.804300 10968 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1211 01:43:18.804308 10968 main.go:141] libmachine: Detecting the provisioner...
I1211 01:43:18.804313 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:18.804439 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:18.804533 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:18.804608 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:18.804691 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:18.804814 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:18.805067 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:18.805072 10968 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I1211 01:43:18.872096 10968 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-ge2b7375-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I1211 01:43:18.872144 10968 main.go:141] libmachine: found compatible host: buildroot
I1211 01:43:18.872148 10968 main.go:141] libmachine: Provisioning with buildroot...
I1211 01:43:18.872154 10968 main.go:141] libmachine: (second-055000) Calling .GetMachineName
I1211 01:43:18.872280 10968 buildroot.go:166] provisioning hostname "second-055000"
I1211 01:43:18.872286 10968 main.go:141] libmachine: (second-055000) Calling .GetMachineName
I1211 01:43:18.872370 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:18.872462 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:18.872547 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:18.872617 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:18.872695 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:18.872816 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:18.873068 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:18.873073 10968 main.go:141] libmachine: About to run SSH command:
sudo hostname second-055000 && echo "second-055000" | sudo tee /etc/hostname
I1211 01:43:18.948324 10968 main.go:141] libmachine: SSH cmd err, output: <nil>: second-055000
I1211 01:43:18.948343 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:18.948476 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:18.948555 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:18.948640 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:18.948732 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:18.948858 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:18.949094 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:18.949102 10968 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\ssecond-055000' /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 second-055000/g' /etc/hosts;
else
echo '127.0.1.1 second-055000' | sudo tee -a /etc/hosts;
fi
fi
I1211 01:43:19.021027 10968 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1211 01:43:19.021040 10968 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/17760-8912/.minikube CaCertPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/17760-8912/.minikube}
I1211 01:43:19.021048 10968 buildroot.go:174] setting up certificates
I1211 01:43:19.021060 10968 provision.go:83] configureAuth start
I1211 01:43:19.021064 10968 main.go:141] libmachine: (second-055000) Calling .GetMachineName
I1211 01:43:19.021185 10968 main.go:141] libmachine: (second-055000) Calling .GetIP
I1211 01:43:19.021266 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.021350 10968 provision.go:138] copyHostCerts
I1211 01:43:19.021437 10968 exec_runner.go:144] found /Users/jenkins/minikube-integration/17760-8912/.minikube/ca.pem, removing ...
I1211 01:43:19.021443 10968 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/17760-8912/.minikube/ca.pem
I1211 01:43:19.021573 10968 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/17760-8912/.minikube/ca.pem (1082 bytes)
I1211 01:43:19.021789 10968 exec_runner.go:144] found /Users/jenkins/minikube-integration/17760-8912/.minikube/cert.pem, removing ...
I1211 01:43:19.021792 10968 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/17760-8912/.minikube/cert.pem
I1211 01:43:19.021877 10968 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17760-8912/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/17760-8912/.minikube/cert.pem (1123 bytes)
I1211 01:43:19.022038 10968 exec_runner.go:144] found /Users/jenkins/minikube-integration/17760-8912/.minikube/key.pem, removing ...
I1211 01:43:19.022041 10968 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/17760-8912/.minikube/key.pem
I1211 01:43:19.022109 10968 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17760-8912/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/17760-8912/.minikube/key.pem (1679 bytes)
I1211 01:43:19.022235 10968 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca-key.pem org=jenkins.second-055000 san=[192.169.0.53 192.169.0.53 localhost 127.0.0.1 minikube second-055000]
I1211 01:43:19.120823 10968 provision.go:172] copyRemoteCerts
I1211 01:43:19.120876 10968 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I1211 01:43:19.120889 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.121035 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.121129 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.121239 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.121330 10968 sshutil.go:53] new ssh client: &{IP:192.169.0.53 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/id_rsa Username:docker}
I1211 01:43:19.161403 10968 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I1211 01:43:19.176664 10968 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17760-8912/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I1211 01:43:19.191871 10968 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17760-8912/.minikube/machines/server.pem --> /etc/docker/server.pem (1216 bytes)
I1211 01:43:19.207493 10968 provision.go:86] duration metric: configureAuth took 186.426001ms
I1211 01:43:19.207503 10968 buildroot.go:189] setting minikube options for container-runtime
I1211 01:43:19.207633 10968 config.go:182] Loaded profile config "second-055000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I1211 01:43:19.207643 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:19.207789 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.207870 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.207948 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.208031 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.208099 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.208206 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:19.208435 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:19.208440 10968 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I1211 01:43:19.275844 10968 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I1211 01:43:19.275855 10968 buildroot.go:70] root file system type: tmpfs
I1211 01:43:19.275929 10968 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I1211 01:43:19.275939 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.276060 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.276151 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.276232 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.276307 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.276426 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:19.276669 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:19.276715 10968 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %!s(MISSING) "[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
I1211 01:43:19.352273 10968 main.go:141] libmachine: SSH cmd err, output: <nil>: [Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP $MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
I1211 01:43:19.352294 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.352417 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.352500 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.352579 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.352659 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.352786 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:19.353048 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:19.353057 10968 main.go:141] libmachine: About to run SSH command:
sudo diff -u /lib/systemd/system/docker.service /lib/systemd/system/docker.service.new || { sudo mv /lib/systemd/system/docker.service.new /lib/systemd/system/docker.service; sudo systemctl -f daemon-reload && sudo systemctl -f enable docker && sudo systemctl -f restart docker; }
I1211 01:43:19.844113 10968 main.go:141] libmachine: SSH cmd err, output: <nil>: diff: can't stat '/lib/systemd/system/docker.service': No such file or directory
Created symlink /etc/systemd/system/multi-user.target.wants/docker.service → /usr/lib/systemd/system/docker.service.
I1211 01:43:19.844135 10968 main.go:141] libmachine: Checking connection to Docker...
I1211 01:43:19.844141 10968 main.go:141] libmachine: (second-055000) Calling .GetURL
I1211 01:43:19.844273 10968 main.go:141] libmachine: Docker is up and running!
I1211 01:43:19.844278 10968 main.go:141] libmachine: Reticulating splines...
I1211 01:43:19.844282 10968 client.go:171] LocalClient.Create took 12.878062233s
I1211 01:43:19.844292 10968 start.go:167] duration metric: libmachine.API.Create for "second-055000" took 12.878100206s
I1211 01:43:19.844300 10968 start.go:300] post-start starting for "second-055000" (driver="hyperkit")
I1211 01:43:19.844308 10968 start.go:329] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I1211 01:43:19.844322 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:19.844462 10968 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
I1211 01:43:19.844473 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.844555 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.844647 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.844720 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.844791 10968 sshutil.go:53] new ssh client: &{IP:192.169.0.53 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/id_rsa Username:docker}
I1211 01:43:19.885510 10968 ssh_runner.go:195] Run: cat /etc/os-release
I1211 01:43:19.888377 10968 info.go:137] Remote host: Buildroot 2021.02.12
I1211 01:43:19.888407 10968 filesync.go:126] Scanning /Users/jenkins/minikube-integration/17760-8912/.minikube/addons for local assets ...
I1211 01:43:19.888517 10968 filesync.go:126] Scanning /Users/jenkins/minikube-integration/17760-8912/.minikube/files for local assets ...
I1211 01:43:19.888697 10968 filesync.go:149] local asset: /Users/jenkins/minikube-integration/17760-8912/.minikube/files/etc/ssl/certs/93932.pem -> 93932.pem in /etc/ssl/certs
I1211 01:43:19.888889 10968 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I1211 01:43:19.895608 10968 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17760-8912/.minikube/files/etc/ssl/certs/93932.pem --> /etc/ssl/certs/93932.pem (1708 bytes)
I1211 01:43:19.911940 10968 start.go:303] post-start completed in 67.63487ms
I1211 01:43:19.911959 10968 main.go:141] libmachine: (second-055000) Calling .GetConfigRaw
I1211 01:43:19.912537 10968 main.go:141] libmachine: (second-055000) Calling .GetIP
I1211 01:43:19.912680 10968 profile.go:148] Saving config to /Users/jenkins/minikube-integration/17760-8912/.minikube/profiles/second-055000/config.json ...
I1211 01:43:19.912996 10968 start.go:128] duration metric: createHost completed in 12.97952304s
I1211 01:43:19.913009 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.913100 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.913187 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.913264 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.913346 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.913448 10968 main.go:141] libmachine: Using SSH client type: native
I1211 01:43:19.913678 10968 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.53 22 <nil> <nil>}
I1211 01:43:19.913683 10968 main.go:141] libmachine: About to run SSH command:
date +%!s(MISSING).%!N(MISSING)
I1211 01:43:19.980986 10968 main.go:141] libmachine: SSH cmd err, output: <nil>: 1702287799.641189576
I1211 01:43:19.981003 10968 fix.go:206] guest clock: 1702287799.641189576
I1211 01:43:19.981007 10968 fix.go:219] Guest: 2023-12-11 01:43:19.641189576 -0800 PST Remote: 2023-12-11 01:43:19.913002 -0800 PST m=+13.474334577 (delta=-271.812424ms)
I1211 01:43:19.981020 10968 fix.go:190] guest clock delta is within tolerance: -271.812424ms
I1211 01:43:19.981022 10968 start.go:83] releasing machines lock for "second-055000", held for 13.047666704s
I1211 01:43:19.981038 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:19.981160 10968 main.go:141] libmachine: (second-055000) Calling .GetIP
I1211 01:43:19.981245 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:19.981532 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:19.981630 10968 main.go:141] libmachine: (second-055000) Calling .DriverName
I1211 01:43:19.981700 10968 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I1211 01:43:19.981722 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.981750 10968 ssh_runner.go:195] Run: cat /version.json
I1211 01:43:19.981757 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHHostname
I1211 01:43:19.981820 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.981848 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHPort
I1211 01:43:19.981898 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.981921 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHKeyPath
I1211 01:43:19.981993 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.982006 10968 main.go:141] libmachine: (second-055000) Calling .GetSSHUsername
I1211 01:43:19.982086 10968 sshutil.go:53] new ssh client: &{IP:192.169.0.53 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/id_rsa Username:docker}
I1211 01:43:19.982098 10968 sshutil.go:53] new ssh client: &{IP:192.169.0.53 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17760-8912/.minikube/machines/second-055000/id_rsa Username:docker}
I1211 01:43:20.063125 10968 ssh_runner.go:195] Run: systemctl --version
I1211 01:43:20.066916 10968 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W1211 01:43:20.070380 10968 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I1211 01:43:20.070422 10968 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f ( ( -name *bridge* -or -name *podman* ) -and -not -name *.mk_disabled ) -printf "%!p(MISSING), " -exec sh -c "sudo mv {} {}.mk_disabled" ;
I1211 01:43:20.081567 10968 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I1211 01:43:20.081576 10968 start.go:475] detecting cgroup driver to use...
I1211 01:43:20.081673 10968 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %!s(MISSING) "runtime-endpoint: unix:///run/containerd/containerd.sock
" | sudo tee /etc/crictl.yaml"
I1211 01:43:20.095357 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.9"|' /etc/containerd/config.toml"
I1211 01:43:20.101816 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)restrict_oom_score_adj = .*$|\1restrict_oom_score_adj = false|' /etc/containerd/config.toml"
I1211 01:43:20.108340 10968 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I1211 01:43:20.108379 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I1211 01:43:20.114879 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runtime.v1.linux"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I1211 01:43:20.121345 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I1211 01:43:20.127740 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I1211 01:43:20.134344 10968 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I1211 01:43:20.140931 10968 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)conf_dir = .*$|\1conf_dir = "/etc/cni/net.d"|g' /etc/containerd/config.toml"
I1211 01:43:20.147363 10968 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I1211 01:43:20.153175 10968 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I1211 01:43:20.158998 10968 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1211 01:43:20.241324 10968 ssh_runner.go:195] Run: sudo systemctl restart containerd
I1211 01:43:20.252422 10968 start.go:475] detecting cgroup driver to use...
I1211 01:43:20.252489 10968 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I1211 01:43:20.265963 10968 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1211 01:43:20.281502 10968 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I1211 01:43:20.296643 10968 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1211 01:43:20.304844 10968 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1211 01:43:20.313302 10968 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I1211 01:43:20.333017 10968 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1211 01:43:20.341689 10968 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %!s(MISSING) "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I1211 01:43:20.353657 10968 ssh_runner.go:195] Run: which cri-dockerd
I1211 01:43:20.356179 10968 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I1211 01:43:20.361673 10968 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I1211 01:43:20.372815 10968 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I1211 01:43:20.456644 10968 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I1211 01:43:20.551116 10968 docker.go:560] configuring docker to use "cgroupfs" as cgroup driver...
I1211 01:43:20.551199 10968 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I1211 01:43:20.563052 10968 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1211 01:43:20.644306 10968 ssh_runner.go:195] Run: sudo systemctl restart docker
I1211 01:43:21.926269 10968 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.281984764s)
I1211 01:43:21.926330 10968 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1211 01:43:22.011750 10968 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I1211 01:43:22.102106 10968 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1211 01:43:22.188881 10968 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1211 01:43:22.275823 10968 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I1211 01:43:22.286749 10968 ssh_runner.go:195] Run: sudo journalctl --no-pager -u cri-docker.socket
I1211 01:43:22.315770 10968 out.go:177]
W1211 01:43:22.338633 10968 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
sudo journalctl --no-pager -u cri-docker.socket:
-- stdout --
-- Journal begins at Mon 2023-12-11 09:43:16 UTC, ends at Mon 2023-12-11 09:43:21 UTC. --
Dec 11 09:43:17 minikube systemd[1]: Starting CRI Docker Socket for the API.
Dec 11 09:43:17 minikube systemd[1]: Listening on CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: cri-docker.socket: Succeeded.
Dec 11 09:43:19 second-055000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: Starting CRI Docker Socket for the API.
Dec 11 09:43:19 second-055000 systemd[1]: Listening on CRI Docker Socket for the API.
Dec 11 09:43:21 second-055000 systemd[1]: cri-docker.socket: Succeeded.
Dec 11 09:43:21 second-055000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 11 09:43:21 second-055000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 11 09:43:21 second-055000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
Dec 11 09:43:21 second-055000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
-- /stdout --
W1211 01:43:22.338650 10968 out.go:239] *
W1211 01:43:22.339260 10968 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I1211 01:43:22.401468 10968 out.go:177]
*
* ==> Docker <==
* -- Journal begins at Mon 2023-12-11 09:42:40 UTC, ends at Mon 2023-12-11 09:43:28 UTC. --
Dec 11 09:43:17 first-052000 dockerd[1187]: time="2023-12-11T09:43:17.646324235Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:17 first-052000 cri-dockerd[1072]: time="2023-12-11T09:43:17Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/866c444a1ba82c844fe54cd76ef20834c1977a9ba702fa370b9dd71303fbaf24/resolv.conf as [nameserver 192.169.0.1]"
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.014088994Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.014129271Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.014142894Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.014178833Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.413898741Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.413981202Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.414174016Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.414211706Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 cri-dockerd[1072]: time="2023-12-11T09:43:18Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/122d8d0ce43f16a6b66eded0ad451e0061e0e0bc8f2aade10f7bd0eb2d02c30f/resolv.conf as [nameserver 192.169.0.1]"
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.527302085Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.527395110Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.527414460Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.527425138Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.537308815Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.537401215Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.537415551Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.537439974Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 cri-dockerd[1072]: time="2023-12-11T09:43:18Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/2e739111ccc9445226e9021a842281491be4cbd6944c56477f177df22ef4fd1e/resolv.conf as [nameserver 192.169.0.1]"
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.978113592Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.978184236Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.978202247Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Dec 11 09:43:18 first-052000 dockerd[1187]: time="2023-12-11T09:43:18.978213296Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Dec 11 09:43:25 first-052000 cri-dockerd[1072]: time="2023-12-11T09:43:25Z" level=info msg="Docker cri received runtime config &RuntimeConfig{NetworkConfig:&NetworkConfig{PodCidr:10.244.0.0/24,},}"
*
* ==> container status <==
* CONTAINER IMAGE CREATED STATE NAME ATTEMPT POD ID POD
43e79dfaaebbe ead0a4a53df89 10 seconds ago Running coredns 0 2e739111ccc94 coredns-5dd5756b68-8sk6l
632d0b85093c8 83f6cc407eed8 10 seconds ago Running kube-proxy 0 122d8d0ce43f1 kube-proxy-g5m6v
f48c500cf6050 6e38f40d628db 11 seconds ago Running storage-provisioner 0 866c444a1ba82 storage-provisioner
ead682b8e8e90 73deb9a3f7025 28 seconds ago Running etcd 0 117bb43a90382 etcd-first-052000
1461e43d3e6d2 d058aa5ab969c 28 seconds ago Running kube-controller-manager 0 094a3e2de8d5a kube-controller-manager-first-052000
9776a2470c7cc e3db313c6dbc0 29 seconds ago Running kube-scheduler 0 cb8a56786cc97 kube-scheduler-first-052000
96ba8f6c85ec9 7fe0e6f37db33 29 seconds ago Running kube-apiserver 0 5e63acc171221 kube-apiserver-first-052000
*
* ==> coredns [43e79dfaaebb] <==
* .:53
[INFO] plugin/reload: Running configuration SHA512 = 257e111468ef6f1e36f10df061303186c353cd0e51aed8f50f4e4fd21cec02687aef97084fe1f82262f5cee88179d311670a6ae21ae185759728216fc264125f
CoreDNS-1.10.1
linux/amd64, go1.20, 055b2c3
[INFO] 127.0.0.1:41758 - 6529 "HINFO IN 3348101294777432445.8291898924976570158. udp 57 false 512" NXDOMAIN qr,rd,ra 132 0.011094984s
*
* ==> describe nodes <==
* Name: first-052000
Roles: control-plane
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=first-052000
kubernetes.io/os=linux
minikube.k8s.io/commit=ce2e6a1b94c6f5020a47dd2692a1fbe18017a6d1
minikube.k8s.io/name=first-052000
minikube.k8s.io/primary=true
minikube.k8s.io/updated_at=2023_12_11T01_43_05_0700
minikube.k8s.io/version=v1.32.0
node-role.kubernetes.io/control-plane=
node.kubernetes.io/exclude-from-external-load-balancers=
Annotations: kubeadm.alpha.kubernetes.io/cri-socket: unix:///var/run/cri-dockerd.sock
node.alpha.kubernetes.io/ttl: 0
volumes.kubernetes.io/controller-managed-attach-detach: true
CreationTimestamp: Mon, 11 Dec 2023 09:43:02 +0000
Taints: <none>
Unschedulable: false
Lease:
HolderIdentity: first-052000
AcquireTime: <unset>
RenewTime: Mon, 11 Dec 2023 09:43:25 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Mon, 11 Dec 2023 09:43:25 +0000 Mon, 11 Dec 2023 09:43:00 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Mon, 11 Dec 2023 09:43:25 +0000 Mon, 11 Dec 2023 09:43:00 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Mon, 11 Dec 2023 09:43:25 +0000 Mon, 11 Dec 2023 09:43:00 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready True Mon, 11 Dec 2023 09:43:25 +0000 Mon, 11 Dec 2023 09:43:06 +0000 KubeletReady kubelet is posting ready status
Addresses:
InternalIP: 192.169.0.52
Hostname: first-052000
Capacity:
cpu: 2
ephemeral-storage: 17784752Ki
hugepages-2Mi: 0
memory: 5925796Ki
pods: 110
Allocatable:
cpu: 2
ephemeral-storage: 17784752Ki
hugepages-2Mi: 0
memory: 5925796Ki
pods: 110
System Info:
Machine ID: 320a93f12c3b440898b9c9ca06eca849
System UUID: 9a4011ee-0000-0000-8e0f-f01898ef957c
Boot ID: 73f8730a-bf2d-4a93-a7b8-cf3b7a710446
Kernel Version: 5.10.57
OS Image: Buildroot 2021.02.12
Operating System: linux
Architecture: amd64
Container Runtime Version: docker://24.0.7
Kubelet Version: v1.28.4
Kube-Proxy Version: v1.28.4
PodCIDR: 10.244.0.0/24
PodCIDRs: 10.244.0.0/24
Non-terminated Pods: (7 in total)
Namespace Name CPU Requests CPU Limits Memory Requests Memory Limits Age
--------- ---- ------------ ---------- --------------- ------------- ---
kube-system coredns-5dd5756b68-8sk6l 100m (5%!)(MISSING) 0 (0%!)(MISSING) 70Mi (1%!)(MISSING) 170Mi (2%!)(MISSING) 11s
kube-system etcd-first-052000 100m (5%!)(MISSING) 0 (0%!)(MISSING) 100Mi (1%!)(MISSING) 0 (0%!)(MISSING) 26s
kube-system kube-apiserver-first-052000 250m (12%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 24s
kube-system kube-controller-manager-first-052000 200m (10%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 24s
kube-system kube-proxy-g5m6v 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 11s
kube-system kube-scheduler-first-052000 100m (5%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 24s
kube-system storage-provisioner 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 23s
Allocated resources:
(Total limits may be over 100 percent, i.e., overcommitted.)
Resource Requests Limits
-------- -------- ------
cpu 750m (37%!)(MISSING) 0 (0%!)(MISSING)
memory 170Mi (2%!)(MISSING) 170Mi (2%!)(MISSING)
ephemeral-storage 0 (0%!)(MISSING) 0 (0%!)(MISSING)
hugepages-2Mi 0 (0%!)(MISSING) 0 (0%!)(MISSING)
Events:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal Starting 10s kube-proxy
Normal Starting 31s kubelet Starting kubelet.
Normal NodeAllocatableEnforced 31s kubelet Updated Node Allocatable limit across pods
Normal NodeHasSufficientMemory 30s (x8 over 31s) kubelet Node first-052000 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 30s (x8 over 31s) kubelet Node first-052000 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 30s (x7 over 31s) kubelet Node first-052000 status is now: NodeHasSufficientPID
Normal Starting 24s kubelet Starting kubelet.
Normal NodeAllocatableEnforced 24s kubelet Updated Node Allocatable limit across pods
Normal NodeHasSufficientMemory 24s kubelet Node first-052000 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 24s kubelet Node first-052000 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 24s kubelet Node first-052000 status is now: NodeHasSufficientPID
Normal NodeReady 23s kubelet Node first-052000 status is now: NodeReady
Normal RegisteredNode 12s node-controller Node first-052000 event: Registered Node first-052000 in Controller
*
* ==> dmesg <==
* [ +5.103271] ACPI Error: Could not enable RealTimeClock event (20200925/evxfevnt-182)
[ +0.000002] ACPI Warning: Could not enable fixed event - RealTimeClock (4) (20200925/evxface-618)
[ +0.009108] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ +3.069984] systemd-fstab-generator[125]: Ignoring "noauto" for root device
[ +0.040657] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[ +0.000001] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[ +1.910014] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ +0.000003] NFSD: unable to find recovery directory /var/lib/nfs/v4recovery
[ +0.000001] NFSD: Unable to initialize client recovery tracking! (-2)
[ +1.129314] systemd-fstab-generator[546]: Ignoring "noauto" for root device
[ +0.081750] systemd-fstab-generator[557]: Ignoring "noauto" for root device
[ +0.791456] systemd-fstab-generator[735]: Ignoring "noauto" for root device
[ +0.235555] systemd-fstab-generator[773]: Ignoring "noauto" for root device
[ +0.087898] systemd-fstab-generator[784]: Ignoring "noauto" for root device
[ +0.096344] systemd-fstab-generator[797]: Ignoring "noauto" for root device
[ +1.441966] systemd-fstab-generator[961]: Ignoring "noauto" for root device
[ +0.092201] systemd-fstab-generator[996]: Ignoring "noauto" for root device
[ +0.082290] systemd-fstab-generator[1008]: Ignoring "noauto" for root device
[ +0.092022] systemd-fstab-generator[1019]: Ignoring "noauto" for root device
[ +0.106577] systemd-fstab-generator[1038]: Ignoring "noauto" for root device
[ +5.616979] systemd-fstab-generator[1172]: Ignoring "noauto" for root device
[ +1.670733] kauditd_printk_skb: 55 callbacks suppressed
[ +4.192852] systemd-fstab-generator[1550]: Ignoring "noauto" for root device
[Dec11 09:43] systemd-fstab-generator[2404]: Ignoring "noauto" for root device
[ +13.879895] kauditd_printk_skb: 39 callbacks suppressed
*
* ==> etcd [ead682b8e8e9] <==
* {"level":"info","ts":"2023-12-11T09:43:00.622096Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /var/lib/minikube/certs/etcd/server.crt, key = /var/lib/minikube/certs/etcd/server.key, client-cert=, client-key=, trusted-ca = /var/lib/minikube/certs/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2023-12-11T09:43:00.63413Z","caller":"embed/etcd.go:278","msg":"now serving peer/client/metrics","local-member-id":"61a8760c9549acd6","initial-advertise-peer-urls":["https://192.169.0.52:2380"],"listen-peer-urls":["https://192.169.0.52:2380"],"advertise-client-urls":["https://192.169.0.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://192.169.0.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2023-12-11T09:43:00.640434Z","caller":"embed/etcd.go:855","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2023-12-11T09:43:00.640505Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"192.169.0.52:2380"}
{"level":"info","ts":"2023-12-11T09:43:00.640552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"192.169.0.52:2380"}
{"level":"info","ts":"2023-12-11T09:43:00.752153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a8760c9549acd6 is starting a new election at term 1"}
{"level":"info","ts":"2023-12-11T09:43:00.752193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a8760c9549acd6 became pre-candidate at term 1"}
{"level":"info","ts":"2023-12-11T09:43:00.752209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a8760c9549acd6 received MsgPreVoteResp from 61a8760c9549acd6 at term 1"}
{"level":"info","ts":"2023-12-11T09:43:00.752219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a8760c9549acd6 became candidate at term 2"}
{"level":"info","ts":"2023-12-11T09:43:00.752224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a8760c9549acd6 received MsgVoteResp from 61a8760c9549acd6 at term 2"}
{"level":"info","ts":"2023-12-11T09:43:00.752394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61a8760c9549acd6 became leader at term 2"}
{"level":"info","ts":"2023-12-11T09:43:00.752404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61a8760c9549acd6 elected leader 61a8760c9549acd6 at term 2"}
{"level":"info","ts":"2023-12-11T09:43:00.756365Z","caller":"etcdserver/server.go:2571","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2023-12-11T09:43:00.758633Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f2fafc351031eea","local-member-id":"61a8760c9549acd6","cluster-version":"3.5"}
{"level":"info","ts":"2023-12-11T09:43:00.758691Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2023-12-11T09:43:00.758787Z","caller":"etcdserver/server.go:2595","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2023-12-11T09:43:00.758579Z","caller":"etcdserver/server.go:2062","msg":"published local member to cluster through raft","local-member-id":"61a8760c9549acd6","local-member-attributes":"{Name:first-052000 ClientURLs:[https://192.169.0.52:2379]}","request-path":"/0/members/61a8760c9549acd6/attributes","cluster-id":"3f2fafc351031eea","publish-timeout":"7s"}
{"level":"info","ts":"2023-12-11T09:43:00.759022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2023-12-11T09:43:00.759236Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2023-12-11T09:43:00.759258Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2023-12-11T09:43:00.759915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2023-12-11T09:43:00.759964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2023-12-11T09:43:00.762911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"192.169.0.52:2379"}
{"level":"info","ts":"2023-12-11T09:43:17.407829Z","caller":"traceutil/trace.go:171","msg":"trace[237596189] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"105.388695ms","start":"2023-12-11T09:43:17.302429Z","end":"2023-12-11T09:43:17.407818Z","steps":["trace[237596189] 'process raft request' (duration: 105.225127ms)"],"step_count":1}
{"level":"info","ts":"2023-12-11T09:43:17.408119Z","caller":"traceutil/trace.go:171","msg":"trace[908225841] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"109.793043ms","start":"2023-12-11T09:43:17.29832Z","end":"2023-12-11T09:43:17.408113Z","steps":["trace[908225841] 'process raft request' (duration: 33.970238ms)","trace[908225841] 'compare' (duration: 75.19344ms)"],"step_count":2}
*
* ==> kernel <==
* 09:43:29 up 0 min, 0 users, load average: 0.34, 0.12, 0.04
Linux first-052000 5.10.57 #1 SMP Tue Dec 5 18:34:51 UTC 2023 x86_64 GNU/Linux
PRETTY_NAME="Buildroot 2021.02.12"
*
* ==> kube-apiserver [96ba8f6c85ec] <==
* I1211 09:43:02.324092 1 apf_controller.go:380] Running API Priority and Fairness periodic rebalancing process
I1211 09:43:02.324262 1 cache.go:39] Caches are synced for AvailableConditionController controller
I1211 09:43:02.327384 1 shared_informer.go:318] Caches are synced for configmaps
I1211 09:43:02.327811 1 cache.go:39] Caches are synced for APIServiceRegistrationController controller
I1211 09:43:02.356501 1 controller.go:624] quota admission added evaluator for: leases.coordination.k8s.io
I1211 09:43:02.362423 1 shared_informer.go:318] Caches are synced for crd-autoregister
I1211 09:43:02.362442 1 aggregator.go:166] initial CRD sync complete...
I1211 09:43:02.362447 1 autoregister_controller.go:141] Starting autoregister controller
I1211 09:43:02.362451 1 cache.go:32] Waiting for caches to sync for autoregister controller
I1211 09:43:02.362455 1 cache.go:39] Caches are synced for autoregister controller
I1211 09:43:03.232062 1 storage_scheduling.go:95] created PriorityClass system-node-critical with value 2000001000
I1211 09:43:03.235811 1 storage_scheduling.go:95] created PriorityClass system-cluster-critical with value 2000000000
I1211 09:43:03.235842 1 storage_scheduling.go:111] all system priority classes are created successfully or already exist.
I1211 09:43:03.523755 1 controller.go:624] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I1211 09:43:03.543917 1 controller.go:624] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
I1211 09:43:03.631567 1 alloc.go:330] "allocated clusterIPs" service="default/kubernetes" clusterIPs={"IPv4":"10.96.0.1"}
W1211 09:43:03.636447 1 lease.go:263] Resetting endpoints for master service "kubernetes" to [192.169.0.52]
I1211 09:43:03.637041 1 controller.go:624] quota admission added evaluator for: endpoints
I1211 09:43:03.642213 1 controller.go:624] quota admission added evaluator for: endpointslices.discovery.k8s.io
I1211 09:43:04.279016 1 controller.go:624] quota admission added evaluator for: serviceaccounts
I1211 09:43:04.940363 1 controller.go:624] quota admission added evaluator for: deployments.apps
I1211 09:43:04.946887 1 alloc.go:330] "allocated clusterIPs" service="kube-system/kube-dns" clusterIPs={"IPv4":"10.96.0.10"}
I1211 09:43:04.952971 1 controller.go:624] quota admission added evaluator for: daemonsets.apps
I1211 09:43:17.987939 1 controller.go:624] quota admission added evaluator for: replicasets.apps
I1211 09:43:18.036471 1 controller.go:624] quota admission added evaluator for: controllerrevisions.apps
*
* ==> kube-controller-manager [1461e43d3e6d] <==
* I1211 09:43:17.265302 1 shared_informer.go:318] Caches are synced for resource quota
I1211 09:43:17.281447 1 shared_informer.go:318] Caches are synced for deployment
I1211 09:43:17.282527 1 shared_informer.go:318] Caches are synced for taint
I1211 09:43:17.282623 1 node_lifecycle_controller.go:1225] "Initializing eviction metric for zone" zone=""
I1211 09:43:17.282742 1 node_lifecycle_controller.go:877] "Missing timestamp for Node. Assuming now as a timestamp" node="first-052000"
I1211 09:43:17.282824 1 node_lifecycle_controller.go:1071] "Controller detected that zone is now in new state" zone="" newState="Normal"
I1211 09:43:17.283022 1 taint_manager.go:205] "Starting NoExecuteTaintManager"
I1211 09:43:17.283100 1 taint_manager.go:210] "Sending events to api server"
I1211 09:43:17.283199 1 event.go:307] "Event occurred" object="first-052000" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node first-052000 event: Registered Node first-052000 in Controller"
I1211 09:43:17.283965 1 shared_informer.go:318] Caches are synced for daemon sets
I1211 09:43:17.289926 1 shared_informer.go:318] Caches are synced for disruption
I1211 09:43:17.662517 1 shared_informer.go:318] Caches are synced for garbage collector
I1211 09:43:17.682356 1 shared_informer.go:318] Caches are synced for garbage collector
I1211 09:43:17.682464 1 garbagecollector.go:166] "All resource monitors have synced. Proceeding to collect garbage"
I1211 09:43:17.990922 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-5dd5756b68 to 1"
I1211 09:43:18.043777 1 event.go:307] "Event occurred" object="kube-system/kube-proxy" fieldPath="" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-g5m6v"
I1211 09:43:18.145797 1 event.go:307] "Event occurred" object="kube-system/coredns-5dd5756b68" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-5dd5756b68-8sk6l"
I1211 09:43:18.155062 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="164.634288ms"
I1211 09:43:18.162089 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="6.921227ms"
I1211 09:43:18.162163 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="30.374µs"
I1211 09:43:18.162224 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="31.701µs"
I1211 09:43:18.174965 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="38.207µs"
I1211 09:43:19.163150 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="121.452µs"
I1211 09:43:19.180081 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="6.324588ms"
I1211 09:43:19.180290 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-5dd5756b68" duration="45.909µs"
*
* ==> kube-proxy [632d0b85093c] <==
* I1211 09:43:18.636241 1 server_others.go:69] "Using iptables proxy"
I1211 09:43:18.645741 1 node.go:141] Successfully retrieved node IP: 192.169.0.52
I1211 09:43:18.694385 1 server_others.go:121] "No iptables support for family" ipFamily="IPv6"
I1211 09:43:18.694401 1 server.go:634] "kube-proxy running in single-stack mode" ipFamily="IPv4"
I1211 09:43:18.696856 1 server_others.go:152] "Using iptables Proxier"
I1211 09:43:18.697148 1 proxier.go:251] "Setting route_localnet=1 to allow node-ports on localhost; to change this either disable iptables.localhostNodePorts (--iptables-localhost-nodeports) or set nodePortAddresses (--nodeport-addresses) to filter loopback addresses"
I1211 09:43:18.697517 1 server.go:846] "Version info" version="v1.28.4"
I1211 09:43:18.697592 1 server.go:848] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
I1211 09:43:18.698351 1 config.go:188] "Starting service config controller"
I1211 09:43:18.698511 1 shared_informer.go:311] Waiting for caches to sync for service config
I1211 09:43:18.698628 1 config.go:97] "Starting endpoint slice config controller"
I1211 09:43:18.698670 1 shared_informer.go:311] Waiting for caches to sync for endpoint slice config
I1211 09:43:18.700442 1 config.go:315] "Starting node config controller"
I1211 09:43:18.700496 1 shared_informer.go:311] Waiting for caches to sync for node config
I1211 09:43:18.799355 1 shared_informer.go:318] Caches are synced for endpoint slice config
I1211 09:43:18.799420 1 shared_informer.go:318] Caches are synced for service config
I1211 09:43:18.807899 1 shared_informer.go:318] Caches are synced for node config
*
* ==> kube-scheduler [9776a2470c7c] <==
* E1211 09:43:02.322046 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.ReplicationController: failed to list *v1.ReplicationController: replicationcontrollers is forbidden: User "system:kube-scheduler" cannot list resource "replicationcontrollers" in API group "" at the cluster scope
W1211 09:43:02.322122 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E1211 09:43:02.322449 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.PodDisruptionBudget: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E1211 09:43:02.321962 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Pod: failed to list *v1.Pod: pods is forbidden: User "system:kube-scheduler" cannot list resource "pods" in API group "" at the cluster scope
W1211 09:43:02.322279 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
E1211 09:43:02.323691 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
W1211 09:43:02.321938 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
W1211 09:43:02.321932 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
E1211 09:43:02.324002 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.PersistentVolume: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
E1211 09:43:02.324204 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Node: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
W1211 09:43:03.237302 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E1211 09:43:03.237340 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
W1211 09:43:03.246886 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
E1211 09:43:03.247003 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
W1211 09:43:03.269675 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSINode: csinodes.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csinodes" in API group "storage.k8s.io" at the cluster scope
E1211 09:43:03.269750 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSINode: failed to list *v1.CSINode: csinodes.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csinodes" in API group "storage.k8s.io" at the cluster scope
W1211 09:43:03.275854 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User "system:kube-scheduler" cannot list resource "replicasets" in API group "apps" at the cluster scope
E1211 09:43:03.275891 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.ReplicaSet: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User "system:kube-scheduler" cannot list resource "replicasets" in API group "apps" at the cluster scope
W1211 09:43:03.302385 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
E1211 09:43:03.302457 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Namespace: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
W1211 09:43:03.331296 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.ReplicationController: replicationcontrollers is forbidden: User "system:kube-scheduler" cannot list resource "replicationcontrollers" in API group "" at the cluster scope
E1211 09:43:03.331385 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.ReplicationController: failed to list *v1.ReplicationController: replicationcontrollers is forbidden: User "system:kube-scheduler" cannot list resource "replicationcontrollers" in API group "" at the cluster scope
W1211 09:43:03.396840 1 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
E1211 09:43:03.396877 1 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
I1211 09:43:03.714988 1 shared_informer.go:318] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
*
* ==> kubelet <==
* -- Journal begins at Mon 2023-12-11 09:42:40 UTC, ends at Mon 2023-12-11 09:43:30 UTC. --
Dec 11 09:43:05 first-052000 kubelet[2412]: I1211 09:43:05.240028 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/1bb402a2e6a6ecfccebfb75e5fd886bf-kubeconfig\") pod \"kube-controller-manager-first-052000\" (UID: \"1bb402a2e6a6ecfccebfb75e5fd886bf\") " pod="kube-system/kube-controller-manager-first-052000"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.021096 2412 apiserver.go:52] "Watching apiserver"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.038725 2412 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world"
Dec 11 09:43:06 first-052000 kubelet[2412]: E1211 09:43:06.102649 2412 kubelet.go:1890] "Failed creating a mirror pod for" err="pods \"kube-apiserver-first-052000\" already exists" pod="kube-system/kube-apiserver-first-052000"
Dec 11 09:43:06 first-052000 kubelet[2412]: E1211 09:43:06.103429 2412 kubelet.go:1890] "Failed creating a mirror pod for" err="pods \"etcd-first-052000\" already exists" pod="kube-system/etcd-first-052000"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.120365 2412 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-scheduler-first-052000" podStartSLOduration=1.1203296 podCreationTimestamp="2023-12-11 09:43:05 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-12-11 09:43:06.114789656 +0000 UTC m=+1.191649460" watchObservedRunningTime="2023-12-11 09:43:06.1203296 +0000 UTC m=+1.197189399"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.126742 2412 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/etcd-first-052000" podStartSLOduration=3.126718402 podCreationTimestamp="2023-12-11 09:43:03 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-12-11 09:43:06.120531588 +0000 UTC m=+1.197391393" watchObservedRunningTime="2023-12-11 09:43:06.126718402 +0000 UTC m=+1.203578201"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.126804 2412 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-apiserver-first-052000" podStartSLOduration=1.126789695 podCreationTimestamp="2023-12-11 09:43:05 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-12-11 09:43:06.126576581 +0000 UTC m=+1.203436385" watchObservedRunningTime="2023-12-11 09:43:06.126789695 +0000 UTC m=+1.203649500"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.133059 2412 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-controller-manager-first-052000" podStartSLOduration=1.132992094 podCreationTimestamp="2023-12-11 09:43:05 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-12-11 09:43:06.132752102 +0000 UTC m=+1.209611906" watchObservedRunningTime="2023-12-11 09:43:06.132992094 +0000 UTC m=+1.209851900"
Dec 11 09:43:06 first-052000 kubelet[2412]: I1211 09:43:06.764057 2412 kubelet_node_status.go:493] "Fast updating node status as it just became ready"
Dec 11 09:43:17 first-052000 kubelet[2412]: I1211 09:43:17.294106 2412 topology_manager.go:215] "Topology Admit Handler" podUID="71e15eab-ab9f-4bd2-8971-625900cbe776" podNamespace="kube-system" podName="storage-provisioner"
Dec 11 09:43:17 first-052000 kubelet[2412]: I1211 09:43:17.367465 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"tmp\" (UniqueName: \"kubernetes.io/host-path/71e15eab-ab9f-4bd2-8971-625900cbe776-tmp\") pod \"storage-provisioner\" (UID: \"71e15eab-ab9f-4bd2-8971-625900cbe776\") " pod="kube-system/storage-provisioner"
Dec 11 09:43:17 first-052000 kubelet[2412]: I1211 09:43:17.367535 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-v2vsh\" (UniqueName: \"kubernetes.io/projected/71e15eab-ab9f-4bd2-8971-625900cbe776-kube-api-access-v2vsh\") pod \"storage-provisioner\" (UID: \"71e15eab-ab9f-4bd2-8971-625900cbe776\") " pod="kube-system/storage-provisioner"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.052239 2412 topology_manager.go:215] "Topology Admit Handler" podUID="49b551c1-7ac5-4205-ab5e-8c5416b5de3b" podNamespace="kube-system" podName="kube-proxy-g5m6v"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.152974 2412 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/storage-provisioner" podStartSLOduration=12.152945741 podCreationTimestamp="2023-12-11 09:43:06 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-12-11 09:43:18.149965422 +0000 UTC m=+13.226825231" watchObservedRunningTime="2023-12-11 09:43:18.152945741 +0000 UTC m=+13.229805544"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.153133 2412 topology_manager.go:215] "Topology Admit Handler" podUID="511d0ef5-aa69-40b6-b8fc-cacb91400e7a" podNamespace="kube-system" podName="coredns-5dd5756b68-8sk6l"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.173263 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pn67s\" (UniqueName: \"kubernetes.io/projected/49b551c1-7ac5-4205-ab5e-8c5416b5de3b-kube-api-access-pn67s\") pod \"kube-proxy-g5m6v\" (UID: \"49b551c1-7ac5-4205-ab5e-8c5416b5de3b\") " pod="kube-system/kube-proxy-g5m6v"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.173336 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/49b551c1-7ac5-4205-ab5e-8c5416b5de3b-xtables-lock\") pod \"kube-proxy-g5m6v\" (UID: \"49b551c1-7ac5-4205-ab5e-8c5416b5de3b\") " pod="kube-system/kube-proxy-g5m6v"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.173360 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/49b551c1-7ac5-4205-ab5e-8c5416b5de3b-kube-proxy\") pod \"kube-proxy-g5m6v\" (UID: \"49b551c1-7ac5-4205-ab5e-8c5416b5de3b\") " pod="kube-system/kube-proxy-g5m6v"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.173374 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/49b551c1-7ac5-4205-ab5e-8c5416b5de3b-lib-modules\") pod \"kube-proxy-g5m6v\" (UID: \"49b551c1-7ac5-4205-ab5e-8c5416b5de3b\") " pod="kube-system/kube-proxy-g5m6v"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.273845 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"config-volume\" (UniqueName: \"kubernetes.io/configmap/511d0ef5-aa69-40b6-b8fc-cacb91400e7a-config-volume\") pod \"coredns-5dd5756b68-8sk6l\" (UID: \"511d0ef5-aa69-40b6-b8fc-cacb91400e7a\") " pod="kube-system/coredns-5dd5756b68-8sk6l"
Dec 11 09:43:18 first-052000 kubelet[2412]: I1211 09:43:18.273941 2412 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qqhnd\" (UniqueName: \"kubernetes.io/projected/511d0ef5-aa69-40b6-b8fc-cacb91400e7a-kube-api-access-qqhnd\") pod \"coredns-5dd5756b68-8sk6l\" (UID: \"511d0ef5-aa69-40b6-b8fc-cacb91400e7a\") " pod="kube-system/coredns-5dd5756b68-8sk6l"
Dec 11 09:43:19 first-052000 kubelet[2412]: I1211 09:43:19.178546 2412 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/coredns-5dd5756b68-8sk6l" podStartSLOduration=1.178521447 podCreationTimestamp="2023-12-11 09:43:18 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-12-11 09:43:19.162252147 +0000 UTC m=+14.239111951" watchObservedRunningTime="2023-12-11 09:43:19.178521447 +0000 UTC m=+14.255381247"
Dec 11 09:43:25 first-052000 kubelet[2412]: I1211 09:43:25.599285 2412 kuberuntime_manager.go:1528] "Updating runtime config through cri with podcidr" CIDR="10.244.0.0/24"
Dec 11 09:43:25 first-052000 kubelet[2412]: I1211 09:43:25.600125 2412 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="10.244.0.0/24"
*
* ==> storage-provisioner [f48c500cf605] <==
* I1211 09:43:18.122331 1 storage_provisioner.go:116] Initializing the minikube storage provisioner...
-- /stdout --
helpers_test.go:254: (dbg) Run: out/minikube-darwin-amd64 status --format={{.APIServer}} -p first-052000 -n first-052000
helpers_test.go:261: (dbg) Run: kubectl --context first-052000 get po -o=jsonpath={.items[*].metadata.name} -A --field-selector=status.phase!=Running
helpers_test.go:285: <<< TestMinikubeProfile FAILED: end of post-mortem logs <<<
helpers_test.go:286: ---------------------/post-mortem---------------------------------
helpers_test.go:175: Cleaning up "first-052000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p first-052000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p first-052000: (5.26511301s)
--- FAIL: TestMinikubeProfile (65.49s)