=== RUN TestMinikubeProfile
minikube_profile_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p first-656000 --driver=hyperkit
E0331 10:34:51.835605 14632 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/16144-14174/.minikube/profiles/functional-762000/client.crt: no such file or directory
minikube_profile_test.go:44: (dbg) Done: out/minikube-darwin-amd64 start -p first-656000 --driver=hyperkit : (37.77918333s)
minikube_profile_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p second-658000 --driver=hyperkit
E0331 10:36:13.756483 14632 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/16144-14174/.minikube/profiles/functional-762000/client.crt: no such file or directory
minikube_profile_test.go:44: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p second-658000 --driver=hyperkit : exit status 90 (1m21.598130072s)
-- stdout --
* [second-658000] minikube v1.29.0 on Darwin 13.3
- MINIKUBE_LOCATION=16144
- KUBECONFIG=/Users/jenkins/minikube-integration/16144-14174/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/16144-14174/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting control plane node second-658000 in cluster second-658000
* 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 docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xe" for details.
*
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * 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-658000 --driver=hyperkit ": exit status 90
panic.go:522: *** TestMinikubeProfile FAILED at 2023-03-31 10:36:17.691714 -0700 PDT m=+988.710417301
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p second-658000 -n second-658000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p second-658000 -n second-658000: exit status 6 (137.840731ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0331 10:36:17.818223 17040 status.go:415] kubeconfig endpoint: extract IP: "second-658000" does not appear in /Users/jenkins/minikube-integration/16144-14174/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "second-658000" 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-658000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p second-658000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p second-658000: (5.279580243s)
panic.go:522: *** TestMinikubeProfile FAILED at 2023-03-31 10:36:23.109361 -0700 PDT m=+994.128143945
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p first-656000 -n first-656000
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-656000 logs -n 25
helpers_test.go:247: (dbg) Done: out/minikube-darwin-amd64 -p first-656000 logs -n 25: (1.899029331s)
helpers_test.go:252: TestMinikubeProfile logs:
-- stdout --
*
* ==> Audit <==
* |---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
| delete | -p functional-762000 | functional-762000 | jenkins | v1.29.0 | 31 Mar 23 10:29 PDT | 31 Mar 23 10:29 PDT |
| start | -p image-000000 | image-000000 | jenkins | v1.29.0 | 31 Mar 23 10:29 PDT | 31 Mar 23 10:30 PDT |
| | --driver=hyperkit | | | | | |
| image | build -t aaa:latest | image-000000 | jenkins | v1.29.0 | 31 Mar 23 10:30 PDT | 31 Mar 23 10:30 PDT |
| | ./testdata/image-build/test-normal | | | | | |
| | -p image-000000 | | | | | |
| image | build -t aaa:latest | image-000000 | jenkins | v1.29.0 | 31 Mar 23 10:30 PDT | 31 Mar 23 10:30 PDT |
| | --build-opt=build-arg=ENV_A=test_env_str | | | | | |
| | --build-opt=no-cache | | | | | |
| | ./testdata/image-build/test-arg -p | | | | | |
| | image-000000 | | | | | |
| image | build -t aaa:latest | image-000000 | jenkins | v1.29.0 | 31 Mar 23 10:30 PDT | 31 Mar 23 10:30 PDT |
| | ./testdata/image-build/test-normal | | | | | |
| | --build-opt=no-cache -p | | | | | |
| | image-000000 | | | | | |
| image | build -t aaa:latest | image-000000 | jenkins | v1.29.0 | 31 Mar 23 10:30 PDT | 31 Mar 23 10:30 PDT |
| | -f inner/Dockerfile | | | | | |
| | ./testdata/image-build/test-f | | | | | |
| | -p image-000000 | | | | | |
| delete | -p image-000000 | image-000000 | jenkins | v1.29.0 | 31 Mar 23 10:30 PDT | 31 Mar 23 10:30 PDT |
| start | -p ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:30 PDT | 31 Mar 23 10:32 PDT |
| | --kubernetes-version=v1.18.20 | | | | | |
| | --memory=4096 --wait=true | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| | --driver=hyperkit | | | | | |
| addons | ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:32 PDT | 31 Mar 23 10:32 PDT |
| | addons enable ingress | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| addons | ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:32 PDT | 31 Mar 23 10:32 PDT |
| | addons enable ingress-dns | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| ssh | ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:33 PDT | 31 Mar 23 10:33 PDT |
| | ssh curl -s http://127.0.0.1/ | | | | | |
| | -H 'Host: nginx.example.com' | | | | | |
| ip | ingress-addon-legacy-074000 ip | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:33 PDT | 31 Mar 23 10:33 PDT |
| addons | ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:33 PDT | 31 Mar 23 10:33 PDT |
| | addons disable ingress-dns | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| addons | ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:33 PDT | 31 Mar 23 10:33 PDT |
| | addons disable ingress | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| delete | -p ingress-addon-legacy-074000 | ingress-addon-legacy-074000 | jenkins | v1.29.0 | 31 Mar 23 10:33 PDT | 31 Mar 23 10:33 PDT |
| start | -p json-output-990000 | json-output-990000 | testUser | v1.29.0 | 31 Mar 23 10:33 PDT | 31 Mar 23 10:34 PDT |
| | --output=json --user=testUser | | | | | |
| | --memory=2200 --wait=true | | | | | |
| | --driver=hyperkit | | | | | |
| pause | -p json-output-990000 | json-output-990000 | testUser | v1.29.0 | 31 Mar 23 10:34 PDT | 31 Mar 23 10:34 PDT |
| | --output=json --user=testUser | | | | | |
| unpause | -p json-output-990000 | json-output-990000 | testUser | v1.29.0 | 31 Mar 23 10:34 PDT | 31 Mar 23 10:34 PDT |
| | --output=json --user=testUser | | | | | |
| stop | -p json-output-990000 | json-output-990000 | testUser | v1.29.0 | 31 Mar 23 10:34 PDT | 31 Mar 23 10:34 PDT |
| | --output=json --user=testUser | | | | | |
| delete | -p json-output-990000 | json-output-990000 | jenkins | v1.29.0 | 31 Mar 23 10:34 PDT | 31 Mar 23 10:34 PDT |
| start | -p json-output-error-817000 | json-output-error-817000 | jenkins | v1.29.0 | 31 Mar 23 10:34 PDT | |
| | --memory=2200 --output=json | | | | | |
| | --wait=true --driver=fail | | | | | |
| delete | -p json-output-error-817000 | json-output-error-817000 | jenkins | v1.29.0 | 31 Mar 23 10:34 PDT | 31 Mar 23 10:34 PDT |
| start | -p first-656000 | first-656000 | jenkins | v1.29.0 | 31 Mar 23 10:34 PDT | 31 Mar 23 10:34 PDT |
| | --driver=hyperkit | | | | | |
| start | -p second-658000 | second-658000 | jenkins | v1.29.0 | 31 Mar 23 10:34 PDT | |
| | --driver=hyperkit | | | | | |
| delete | -p second-658000 | second-658000 | jenkins | v1.29.0 | 31 Mar 23 10:36 PDT | 31 Mar 23 10:36 PDT |
|---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
*
* ==> Last Start <==
* Log file created at: 2023/03/31 10:34:56
Running on machine: MacOS-Agent-2
Binary: Built with gc go1.20.2 for darwin/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I0331 10:34:56.136305 16956 out.go:296] Setting OutFile to fd 1 ...
I0331 10:34:56.136484 16956 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0331 10:34:56.136487 16956 out.go:309] Setting ErrFile to fd 2...
I0331 10:34:56.136489 16956 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0331 10:34:56.136595 16956 root.go:336] Updating PATH: /Users/jenkins/minikube-integration/16144-14174/.minikube/bin
I0331 10:34:56.137984 16956 out.go:303] Setting JSON to false
I0331 10:34:56.158746 16956 start.go:125] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":5664,"bootTime":1680278432,"procs":429,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"13.3","kernelVersion":"22.4.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"2965c349-98a5-5970-aaa9-9eedd3ae5959"}
W0331 10:34:56.158841 16956 start.go:133] gopshost.Virtualization returned error: not implemented yet
I0331 10:34:56.180727 16956 out.go:177] * [second-658000] minikube v1.29.0 on Darwin 13.3
I0331 10:34:56.222355 16956 notify.go:220] Checking for updates...
I0331 10:34:56.222364 16956 out.go:177] - MINIKUBE_LOCATION=16144
I0331 10:34:56.243384 16956 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/16144-14174/kubeconfig
I0331 10:34:56.285041 16956 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0331 10:34:56.327282 16956 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0331 10:34:56.385388 16956 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/16144-14174/.minikube
I0331 10:34:56.426998 16956 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0331 10:34:56.469982 16956 config.go:182] Loaded profile config "first-656000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.26.3
I0331 10:34:56.470059 16956 driver.go:365] Setting default libvirt URI to qemu:///system
I0331 10:34:56.511641 16956 out.go:177] * Using the hyperkit driver based on user configuration
I0331 10:34:56.553072 16956 start.go:295] selected driver: hyperkit
I0331 10:34:56.553084 16956 start.go:859] validating driver "hyperkit" against <nil>
I0331 10:34:56.553097 16956 start.go:870] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0331 10:34:56.553196 16956 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0331 10:34:56.553343 16956 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/16144-14174/.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
I0331 10:34:56.561445 16956 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.29.0
I0331 10:34:56.564969 16956 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0331 10:34:56.564985 16956 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0331 10:34:56.565031 16956 start_flags.go:305] no existing cluster config was found, will generate one from the flags
I0331 10:34:56.567420 16956 start_flags.go:386] Using suggested 6000MB memory alloc based on sys=32768MB, container=0MB
I0331 10:34:56.567560 16956 start_flags.go:901] Wait components to verify : map[apiserver:true system_pods:true]
I0331 10:34:56.567577 16956 cni.go:84] Creating CNI manager for ""
I0331 10:34:56.567588 16956 cni.go:157] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0331 10:34:56.567593 16956 start_flags.go:314] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0331 10:34:56.567601 16956 start_flags.go:319] config:
{Name:second-658000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.38@sha256:516db0892e1cd79b6781fc1a102fca4bf392576bbf3ca0fa01a467cb6cc0af55 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.26.3 ClusterName:second-658000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugi
n: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:}
I0331 10:34:56.567705 16956 iso.go:125] acquiring lock: {Name:mk0dbe8a9c4eaed2f8ee49c1a33e8766c2afdda7 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0331 10:34:56.589231 16956 out.go:177] * Starting control plane node second-658000 in cluster second-658000
I0331 10:34:56.610371 16956 preload.go:132] Checking if preload exists for k8s version v1.26.3 and runtime docker
I0331 10:34:56.610504 16956 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/16144-14174/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.26.3-docker-overlay2-amd64.tar.lz4
I0331 10:34:56.610531 16956 cache.go:57] Caching tarball of preloaded images
I0331 10:34:56.610695 16956 preload.go:174] Found /Users/jenkins/minikube-integration/16144-14174/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.26.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0331 10:34:56.610709 16956 cache.go:60] Finished verifying existence of preloaded tar for v1.26.3 on docker
I0331 10:34:56.610841 16956 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16144-14174/.minikube/profiles/second-658000/config.json ...
I0331 10:34:56.610876 16956 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/16144-14174/.minikube/profiles/second-658000/config.json: {Name:mkc945c724a80ef06596669c1b71778730e50806 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0331 10:34:56.611380 16956 cache.go:193] Successfully downloaded all kic artifacts
I0331 10:34:56.611427 16956 start.go:364] acquiring machines lock for second-658000: {Name:mkd95c60c8dae9de7c878f564be2a8ecf0eeb71a Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0331 10:34:56.611504 16956 start.go:368] acquired machines lock for "second-658000" in 65.966µs
I0331 10:34:56.611543 16956 start.go:93] Provisioning new machine with config: &{Name:second-658000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/16191/minikube-v1.29.0-1680115329-16191-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.38@sha256:516db0892e1cd79b6781fc1a102fca4bf392576bbf3ca0fa01a467cb6cc0af55 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.26.3 ClusterName:second-658000 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.26.3 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: DisableOptimizations:false Disab
leMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP:} &{Name: IP: Port:8443 KubernetesVersion:v1.26.3 ContainerRuntime:docker ControlPlane:true Worker:true}
I0331 10:34:56.611624 16956 start.go:125] createHost starting for "" (driver="hyperkit")
I0331 10:34:56.654482 16956 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=6000MB, Disk=20000MB) ...
I0331 10:34:56.654821 16956 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0331 10:34:56.654847 16956 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0331 10:34:56.662768 16956 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:58454
I0331 10:34:56.663102 16956 main.go:141] libmachine: () Calling .GetVersion
I0331 10:34:56.663540 16956 main.go:141] libmachine: Using API Version 1
I0331 10:34:56.663548 16956 main.go:141] libmachine: () Calling .SetConfigRaw
I0331 10:34:56.663774 16956 main.go:141] libmachine: () Calling .GetMachineName
I0331 10:34:56.663875 16956 main.go:141] libmachine: (second-658000) Calling .GetMachineName
I0331 10:34:56.663956 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:34:56.664039 16956 start.go:159] libmachine.API.Create for "second-658000" (driver="hyperkit")
I0331 10:34:56.664055 16956 client.go:168] LocalClient.Create starting
I0331 10:34:56.664092 16956 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca.pem
I0331 10:34:56.664122 16956 main.go:141] libmachine: Decoding PEM data...
I0331 10:34:56.664133 16956 main.go:141] libmachine: Parsing certificate...
I0331 10:34:56.664166 16956 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16144-14174/.minikube/certs/cert.pem
I0331 10:34:56.664187 16956 main.go:141] libmachine: Decoding PEM data...
I0331 10:34:56.664194 16956 main.go:141] libmachine: Parsing certificate...
I0331 10:34:56.664214 16956 main.go:141] libmachine: Running pre-create checks...
I0331 10:34:56.664220 16956 main.go:141] libmachine: (second-658000) Calling .PreCreateCheck
I0331 10:34:56.664295 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:34:56.664465 16956 main.go:141] libmachine: (second-658000) Calling .GetConfigRaw
I0331 10:34:56.664862 16956 main.go:141] libmachine: Creating machine...
I0331 10:34:56.664868 16956 main.go:141] libmachine: (second-658000) Calling .Create
I0331 10:34:56.664948 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:34:56.665058 16956 main.go:141] libmachine: (second-658000) DBG | I0331 10:34:56.664935 16964 common.go:116] Making disk image using store path: /Users/jenkins/minikube-integration/16144-14174/.minikube
I0331 10:34:56.665109 16956 main.go:141] libmachine: (second-658000) Downloading /Users/jenkins/minikube-integration/16144-14174/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/16144-14174/.minikube/cache/iso/amd64/minikube-v1.29.0-1680115329-16191-amd64.iso...
I0331 10:34:56.846512 16956 main.go:141] libmachine: (second-658000) DBG | I0331 10:34:56.846385 16964 common.go:123] Creating ssh key: /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/id_rsa...
I0331 10:34:56.944319 16956 main.go:141] libmachine: (second-658000) DBG | I0331 10:34:56.944255 16964 common.go:129] Creating raw disk image: /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/second-658000.rawdisk...
I0331 10:34:56.944332 16956 main.go:141] libmachine: (second-658000) DBG | Writing magic tar header
I0331 10:34:56.944340 16956 main.go:141] libmachine: (second-658000) DBG | Writing SSH key tar header
I0331 10:34:56.944883 16956 main.go:141] libmachine: (second-658000) DBG | I0331 10:34:56.944849 16964 common.go:143] Fixing permissions on /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000 ...
I0331 10:34:57.294560 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:34:57.294591 16956 main.go:141] libmachine: (second-658000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/hyperkit.pid
I0331 10:34:57.294637 16956 main.go:141] libmachine: (second-658000) DBG | Using UUID 5a887eea-cfea-11ed-a327-f01898ef957c
I0331 10:34:57.319013 16956 main.go:141] libmachine: (second-658000) DBG | Generated MAC c2:2:18:dc:42:84
I0331 10:34:57.319026 16956 main.go:141] libmachine: (second-658000) 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-658000
I0331 10:34:57.319082 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"5a887eea-cfea-11ed-a327-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0002fa3c0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/initrd", Bootrom:"", CPUs:2, Memory:6000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0331 10:34:57.319108 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"5a887eea-cfea-11ed-a327-f01898ef957c", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0002fa3c0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/initrd", Bootrom:"", CPUs:2, Memory:6000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0331 10:34:57.319151 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/hyperkit.pid", "-c", "2", "-m", "6000M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "5a887eea-cfea-11ed-a327-f01898ef957c", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/second-658000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/tty,log=/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/bzimage,/Users/jenkins/minikube-integration/16144-14174/.minikube/
machines/second-658000/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-658000"}
I0331 10:34:57.319185 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/hyperkit.pid -c 2 -m 6000M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 5a887eea-cfea-11ed-a327-f01898ef957c -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/second-658000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/tty,log=/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/console-ring -f kexec,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/bzimage,/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/initrd,earlyprintk=serial loglevel=3 co
nsole=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-658000"
I0331 10:34:57.319214 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0331 10:34:57.321779 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 DEBUG: hyperkit: Pid is 16965
I0331 10:34:57.322169 16956 main.go:141] libmachine: (second-658000) DBG | Attempt 0
I0331 10:34:57.322180 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:34:57.322299 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:34:57.323180 16956 main.go:141] libmachine: (second-658000) DBG | Searching for c2:2:18:dc:42:84 in /var/db/dhcpd_leases ...
I0331 10:34:57.323278 16956 main.go:141] libmachine: (second-658000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0331 10:34:57.323289 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:7a:4a:ce:87:60:bd ID:1,7a:4a:ce:87:60:bd Lease:0x64286b24}
I0331 10:34:57.323298 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:f2:1:6d:4f:f9:9d ID:1,f2:1:6d:4f:f9:9d Lease:0x64286ae5}
I0331 10:34:57.323303 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:24:8c:13:d2:d0 ID:1,56:24:8c:13:d2:d0 Lease:0x64286a4d}
I0331 10:34:57.323311 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:5e:d7:a8:d5:45:36 ID:1,5e:d7:a8:d5:45:36 Lease:0x642718b1}
I0331 10:34:57.323315 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:5e:52:75:ec:c2:50 ID:1,5e:52:75:ec:c2:50 Lease:0x64286922}
I0331 10:34:57.323322 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:62:89:f4:67:75:3 ID:1,62:89:f4:67:75:3 Lease:0x64271797}
I0331 10:34:57.323330 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:86:69:94:bc:b4:68 ID:1,86:69:94:bc:b4:68 Lease:0x64286800}
I0331 10:34:57.323336 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:e2:9d:d2:dc:cf:f8 ID:1,e2:9d:d2:dc:cf:f8 Lease:0x642715dc}
I0331 10:34:57.323341 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2:a5:d3:32:bd:e8 ID:1,2:a5:d3:32:bd:e8 Lease:0x642865c4}
I0331 10:34:57.323346 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:9e:df:55:91:13:38 ID:1,9e:df:55:91:13:38 Lease:0x642864ec}
I0331 10:34:57.323354 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:2e:ff:dc:12:1e:d7 ID:1,2e:ff:dc:12:1e:d7 Lease:0x64286368}
I0331 10:34:57.323363 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:aa:18:45:b7:5d:44 ID:1,aa:18:45:b7:5d:44 Lease:0x642713ef}
I0331 10:34:57.323368 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:6a:e5:18:83:86:6f ID:1,6a:e5:18:83:86:6f Lease:0x6428629b}
I0331 10:34:57.323376 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:96:ff:65:b4:4b:ce ID:1,96:ff:65:b4:4b:ce Lease:0x64286279}
I0331 10:34:57.323383 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:fa:72:91:be:e9:7a ID:1,fa:72:91:be:e9:7a Lease:0x64286238}
I0331 10:34:57.323389 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:2a:4d:b1:17:ec:a8 ID:1,2a:4d:b1:17:ec:a8 Lease:0x64286223}
I0331 10:34:57.323394 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:7a:ba:a2:26:1d:98 ID:1,7a:ba:a2:26:1d:98 Lease:0x642861e3}
I0331 10:34:57.323405 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:8a:8d:3f:da:a2:64 ID:1,8a:8d:3f:da:a2:64 Lease:0x642861c8}
I0331 10:34:57.323412 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:8a:59:59:8a:91 ID:1,e:8a:59:59:8a:91 Lease:0x64286178}
I0331 10:34:57.323418 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:8a:c0:f9:68:6b:c8 ID:1,8a:c0:f9:68:6b:c8 Lease:0x64286168}
I0331 10:34:57.323423 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:ba:1b:c9:82:e2:11 ID:1,ba:1b:c9:82:e2:11 Lease:0x6428611f}
I0331 10:34:57.323433 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:c6:cd:53:4a:c5:ac ID:1,c6:cd:53:4a:c5:ac Lease:0x64270f94}
I0331 10:34:57.323442 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:66:e7:5d:76:7a:69 ID:1,66:e7:5d:76:7a:69 Lease:0x64270f6b}
I0331 10:34:57.323448 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:c2:af:18:d3:b9:98 ID:1,c2:af:18:d3:b9:98 Lease:0x642860b4}
I0331 10:34:57.323453 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:62:b4:12:e2:cf:de ID:1,62:b4:12:e2:cf:de Lease:0x64286083}
I0331 10:34:57.323469 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:4e:cc:5b:5f:33:8a ID:1,4e:cc:5b:5f:33:8a Lease:0x64286067}
I0331 10:34:57.323480 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:f6:6b:fa:e6:3f:f9 ID:1,f6:6b:fa:e6:3f:f9 Lease:0x64285f6c}
I0331 10:34:57.323494 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:d6:3c:de:98:38:6b ID:1,d6:3c:de:98:38:6b Lease:0x64285f2e}
I0331 10:34:57.323499 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:fe:83:15:fa:aa:ec ID:1,fe:83:15:fa:aa:ec Lease:0x64285f12}
I0331 10:34:57.323504 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:a6:35:cb:98:65:c1 ID:1,a6:35:cb:98:65:c1 Lease:0x64285f07}
I0331 10:34:57.323511 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:36:b6:68:bc:e4:38 ID:1,36:b6:68:bc:e4:38 Lease:0x64270d7d}
I0331 10:34:57.323516 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:e2:a4:81:23:a4:3d ID:1,e2:a4:81:23:a4:3d Lease:0x64270d54}
I0331 10:34:57.323525 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:8a:b:c4:df:a3:11 ID:1,8a:b:c4:df:a3:11 Lease:0x64285ea2}
I0331 10:34:57.323530 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:b2:c2:28:ad:31:3a ID:1,b2:c2:28:ad:31:3a Lease:0x64285e72}
I0331 10:34:57.323535 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:a6:70:a4:73:28:cb ID:1,a6:70:a4:73:28:cb Lease:0x64285e02}
I0331 10:34:57.323547 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:26:b4:b0:4b:14:90 ID:1,26:b4:b0:4b:14:90 Lease:0x64285dca}
I0331 10:34:57.323552 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:5e:b0:80:42:21:3a ID:1,5e:b0:80:42:21:3a Lease:0x64285d35}
I0331 10:34:57.323560 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:6e:69:20:25:fe:f5 ID:1,6e:69:20:25:fe:f5 Lease:0x64270b49}
I0331 10:34:57.323567 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:46:91:96:71:9b:28 ID:1,46:91:96:71:9b:28 Lease:0x64285d17}
I0331 10:34:57.323573 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:a:89:dd:2:e4:c9 ID:1,a:89:dd:2:e4:c9 Lease:0x64285ce4}
I0331 10:34:57.323578 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:ba:a3:78:bc:50:c3 ID:1,ba:a3:78:bc:50:c3 Lease:0x64270a0b}
I0331 10:34:57.323585 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:56:76:69:2e:66:a3 ID:1,56:76:69:2e:66:a3 Lease:0x642709f5}
I0331 10:34:57.323592 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:de:44:3e:bc:31:2f ID:1,de:44:3e:bc:31:2f Lease:0x64285b29}
I0331 10:34:57.323597 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:62:83:36:11:24:8d ID:1,62:83:36:11:24:8d Lease:0x64285b04}
I0331 10:34:57.323602 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:f6:3b:d8:7f:c6:15 ID:1,f6:3b:d8:7f:c6:15 Lease:0x64285ac7}
I0331 10:34:57.323607 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:12:6f:78:18:c8:4c ID:1,12:6f:78:18:c8:4c Lease:0x64285a22}
I0331 10:34:57.323612 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:a:3f:c8:bd:27:e6 ID:1,a:3f:c8:bd:27:e6 Lease:0x642859e3}
I0331 10:34:57.323617 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:96:e7:a7:97:b3:81 ID:1,96:e7:a7:97:b3:81 Lease:0x642858f3}
I0331 10:34:57.323629 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:8a:15:42:72:2b:2b ID:1,8a:15:42:72:2b:2b Lease:0x64270768}
I0331 10:34:57.323637 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:aa:7f:77:fb:ae:d8 ID:1,aa:7f:77:fb:ae:d8 Lease:0x642857d5}
I0331 10:34:57.329002 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0331 10:34:57.338202 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0331 10:34:57.338807 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0331 10:34:57.338820 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0331 10:34:57.338826 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0331 10:34:57.338831 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0331 10:34:57.903842 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0331 10:34:57.903853 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:57 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0331 10:34:58.008845 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:58 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0331 10:34:58.008860 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:58 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0331 10:34:58.008868 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:58 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0331 10:34:58.008873 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:58 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0331 10:34:58.009757 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:58 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0331 10:34:58.009764 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:34:58 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0331 10:34:59.324614 16956 main.go:141] libmachine: (second-658000) DBG | Attempt 1
I0331 10:34:59.324622 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:34:59.324684 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:34:59.325494 16956 main.go:141] libmachine: (second-658000) DBG | Searching for c2:2:18:dc:42:84 in /var/db/dhcpd_leases ...
I0331 10:34:59.325573 16956 main.go:141] libmachine: (second-658000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0331 10:34:59.325588 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:7a:4a:ce:87:60:bd ID:1,7a:4a:ce:87:60:bd Lease:0x64286b24}
I0331 10:34:59.325611 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:f2:1:6d:4f:f9:9d ID:1,f2:1:6d:4f:f9:9d Lease:0x64286ae5}
I0331 10:34:59.325625 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:24:8c:13:d2:d0 ID:1,56:24:8c:13:d2:d0 Lease:0x64286a4d}
I0331 10:34:59.325638 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:5e:d7:a8:d5:45:36 ID:1,5e:d7:a8:d5:45:36 Lease:0x642718b1}
I0331 10:34:59.325644 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:5e:52:75:ec:c2:50 ID:1,5e:52:75:ec:c2:50 Lease:0x64286922}
I0331 10:34:59.325649 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:62:89:f4:67:75:3 ID:1,62:89:f4:67:75:3 Lease:0x64271797}
I0331 10:34:59.325656 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:86:69:94:bc:b4:68 ID:1,86:69:94:bc:b4:68 Lease:0x64286800}
I0331 10:34:59.325669 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:e2:9d:d2:dc:cf:f8 ID:1,e2:9d:d2:dc:cf:f8 Lease:0x642715dc}
I0331 10:34:59.325683 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2:a5:d3:32:bd:e8 ID:1,2:a5:d3:32:bd:e8 Lease:0x642865c4}
I0331 10:34:59.325690 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:9e:df:55:91:13:38 ID:1,9e:df:55:91:13:38 Lease:0x642864ec}
I0331 10:34:59.325698 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:2e:ff:dc:12:1e:d7 ID:1,2e:ff:dc:12:1e:d7 Lease:0x64286368}
I0331 10:34:59.325706 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:aa:18:45:b7:5d:44 ID:1,aa:18:45:b7:5d:44 Lease:0x642713ef}
I0331 10:34:59.325717 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:6a:e5:18:83:86:6f ID:1,6a:e5:18:83:86:6f Lease:0x6428629b}
I0331 10:34:59.325727 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:96:ff:65:b4:4b:ce ID:1,96:ff:65:b4:4b:ce Lease:0x64286279}
I0331 10:34:59.325744 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:fa:72:91:be:e9:7a ID:1,fa:72:91:be:e9:7a Lease:0x64286238}
I0331 10:34:59.325754 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:2a:4d:b1:17:ec:a8 ID:1,2a:4d:b1:17:ec:a8 Lease:0x64286223}
I0331 10:34:59.325764 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:7a:ba:a2:26:1d:98 ID:1,7a:ba:a2:26:1d:98 Lease:0x642861e3}
I0331 10:34:59.325769 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:8a:8d:3f:da:a2:64 ID:1,8a:8d:3f:da:a2:64 Lease:0x642861c8}
I0331 10:34:59.325776 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:8a:59:59:8a:91 ID:1,e:8a:59:59:8a:91 Lease:0x64286178}
I0331 10:34:59.325781 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:8a:c0:f9:68:6b:c8 ID:1,8a:c0:f9:68:6b:c8 Lease:0x64286168}
I0331 10:34:59.325788 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:ba:1b:c9:82:e2:11 ID:1,ba:1b:c9:82:e2:11 Lease:0x6428611f}
I0331 10:34:59.325793 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:c6:cd:53:4a:c5:ac ID:1,c6:cd:53:4a:c5:ac Lease:0x64270f94}
I0331 10:34:59.325799 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:66:e7:5d:76:7a:69 ID:1,66:e7:5d:76:7a:69 Lease:0x64270f6b}
I0331 10:34:59.325804 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:c2:af:18:d3:b9:98 ID:1,c2:af:18:d3:b9:98 Lease:0x642860b4}
I0331 10:34:59.325816 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:62:b4:12:e2:cf:de ID:1,62:b4:12:e2:cf:de Lease:0x64286083}
I0331 10:34:59.325825 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:4e:cc:5b:5f:33:8a ID:1,4e:cc:5b:5f:33:8a Lease:0x64286067}
I0331 10:34:59.325835 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:f6:6b:fa:e6:3f:f9 ID:1,f6:6b:fa:e6:3f:f9 Lease:0x64285f6c}
I0331 10:34:59.325842 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:d6:3c:de:98:38:6b ID:1,d6:3c:de:98:38:6b Lease:0x64285f2e}
I0331 10:34:59.325851 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:fe:83:15:fa:aa:ec ID:1,fe:83:15:fa:aa:ec Lease:0x64285f12}
I0331 10:34:59.325857 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:a6:35:cb:98:65:c1 ID:1,a6:35:cb:98:65:c1 Lease:0x64285f07}
I0331 10:34:59.325868 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:36:b6:68:bc:e4:38 ID:1,36:b6:68:bc:e4:38 Lease:0x64270d7d}
I0331 10:34:59.325875 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:e2:a4:81:23:a4:3d ID:1,e2:a4:81:23:a4:3d Lease:0x64270d54}
I0331 10:34:59.325880 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:8a:b:c4:df:a3:11 ID:1,8a:b:c4:df:a3:11 Lease:0x64285ea2}
I0331 10:34:59.325885 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:b2:c2:28:ad:31:3a ID:1,b2:c2:28:ad:31:3a Lease:0x64285e72}
I0331 10:34:59.325889 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:a6:70:a4:73:28:cb ID:1,a6:70:a4:73:28:cb Lease:0x64285e02}
I0331 10:34:59.325897 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:26:b4:b0:4b:14:90 ID:1,26:b4:b0:4b:14:90 Lease:0x64285dca}
I0331 10:34:59.325904 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:5e:b0:80:42:21:3a ID:1,5e:b0:80:42:21:3a Lease:0x64285d35}
I0331 10:34:59.325909 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:6e:69:20:25:fe:f5 ID:1,6e:69:20:25:fe:f5 Lease:0x64270b49}
I0331 10:34:59.325915 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:46:91:96:71:9b:28 ID:1,46:91:96:71:9b:28 Lease:0x64285d17}
I0331 10:34:59.325921 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:a:89:dd:2:e4:c9 ID:1,a:89:dd:2:e4:c9 Lease:0x64285ce4}
I0331 10:34:59.325925 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:ba:a3:78:bc:50:c3 ID:1,ba:a3:78:bc:50:c3 Lease:0x64270a0b}
I0331 10:34:59.325931 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:56:76:69:2e:66:a3 ID:1,56:76:69:2e:66:a3 Lease:0x642709f5}
I0331 10:34:59.325937 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:de:44:3e:bc:31:2f ID:1,de:44:3e:bc:31:2f Lease:0x64285b29}
I0331 10:34:59.325942 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:62:83:36:11:24:8d ID:1,62:83:36:11:24:8d Lease:0x64285b04}
I0331 10:34:59.325952 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:f6:3b:d8:7f:c6:15 ID:1,f6:3b:d8:7f:c6:15 Lease:0x64285ac7}
I0331 10:34:59.325963 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:12:6f:78:18:c8:4c ID:1,12:6f:78:18:c8:4c Lease:0x64285a22}
I0331 10:34:59.325969 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:a:3f:c8:bd:27:e6 ID:1,a:3f:c8:bd:27:e6 Lease:0x642859e3}
I0331 10:34:59.325975 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:96:e7:a7:97:b3:81 ID:1,96:e7:a7:97:b3:81 Lease:0x642858f3}
I0331 10:34:59.325989 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:8a:15:42:72:2b:2b ID:1,8a:15:42:72:2b:2b Lease:0x64270768}
I0331 10:34:59.325998 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:aa:7f:77:fb:ae:d8 ID:1,aa:7f:77:fb:ae:d8 Lease:0x642857d5}
I0331 10:35:01.326946 16956 main.go:141] libmachine: (second-658000) DBG | Attempt 2
I0331 10:35:01.326958 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:35:01.327575 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:35:01.328330 16956 main.go:141] libmachine: (second-658000) DBG | Searching for c2:2:18:dc:42:84 in /var/db/dhcpd_leases ...
I0331 10:35:01.328415 16956 main.go:141] libmachine: (second-658000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0331 10:35:01.328428 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:7a:4a:ce:87:60:bd ID:1,7a:4a:ce:87:60:bd Lease:0x64286b24}
I0331 10:35:01.328437 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:f2:1:6d:4f:f9:9d ID:1,f2:1:6d:4f:f9:9d Lease:0x64286ae5}
I0331 10:35:01.328442 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:24:8c:13:d2:d0 ID:1,56:24:8c:13:d2:d0 Lease:0x64286a4d}
I0331 10:35:01.328447 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:5e:d7:a8:d5:45:36 ID:1,5e:d7:a8:d5:45:36 Lease:0x642718b1}
I0331 10:35:01.328452 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:5e:52:75:ec:c2:50 ID:1,5e:52:75:ec:c2:50 Lease:0x64286922}
I0331 10:35:01.328468 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:62:89:f4:67:75:3 ID:1,62:89:f4:67:75:3 Lease:0x64271797}
I0331 10:35:01.328475 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:86:69:94:bc:b4:68 ID:1,86:69:94:bc:b4:68 Lease:0x64286800}
I0331 10:35:01.328481 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:e2:9d:d2:dc:cf:f8 ID:1,e2:9d:d2:dc:cf:f8 Lease:0x642715dc}
I0331 10:35:01.328486 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2:a5:d3:32:bd:e8 ID:1,2:a5:d3:32:bd:e8 Lease:0x642865c4}
I0331 10:35:01.328495 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:9e:df:55:91:13:38 ID:1,9e:df:55:91:13:38 Lease:0x642864ec}
I0331 10:35:01.328500 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:2e:ff:dc:12:1e:d7 ID:1,2e:ff:dc:12:1e:d7 Lease:0x64286368}
I0331 10:35:01.328520 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:aa:18:45:b7:5d:44 ID:1,aa:18:45:b7:5d:44 Lease:0x642713ef}
I0331 10:35:01.328526 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:6a:e5:18:83:86:6f ID:1,6a:e5:18:83:86:6f Lease:0x6428629b}
I0331 10:35:01.328533 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:96:ff:65:b4:4b:ce ID:1,96:ff:65:b4:4b:ce Lease:0x64286279}
I0331 10:35:01.328537 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:fa:72:91:be:e9:7a ID:1,fa:72:91:be:e9:7a Lease:0x64286238}
I0331 10:35:01.328543 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:2a:4d:b1:17:ec:a8 ID:1,2a:4d:b1:17:ec:a8 Lease:0x64286223}
I0331 10:35:01.328548 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:7a:ba:a2:26:1d:98 ID:1,7a:ba:a2:26:1d:98 Lease:0x642861e3}
I0331 10:35:01.328553 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:8a:8d:3f:da:a2:64 ID:1,8a:8d:3f:da:a2:64 Lease:0x642861c8}
I0331 10:35:01.328558 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:8a:59:59:8a:91 ID:1,e:8a:59:59:8a:91 Lease:0x64286178}
I0331 10:35:01.328563 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:8a:c0:f9:68:6b:c8 ID:1,8a:c0:f9:68:6b:c8 Lease:0x64286168}
I0331 10:35:01.328568 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:ba:1b:c9:82:e2:11 ID:1,ba:1b:c9:82:e2:11 Lease:0x6428611f}
I0331 10:35:01.328573 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:c6:cd:53:4a:c5:ac ID:1,c6:cd:53:4a:c5:ac Lease:0x64270f94}
I0331 10:35:01.328582 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:66:e7:5d:76:7a:69 ID:1,66:e7:5d:76:7a:69 Lease:0x64270f6b}
I0331 10:35:01.328591 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:c2:af:18:d3:b9:98 ID:1,c2:af:18:d3:b9:98 Lease:0x642860b4}
I0331 10:35:01.328597 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:62:b4:12:e2:cf:de ID:1,62:b4:12:e2:cf:de Lease:0x64286083}
I0331 10:35:01.328607 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:4e:cc:5b:5f:33:8a ID:1,4e:cc:5b:5f:33:8a Lease:0x64286067}
I0331 10:35:01.328613 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:f6:6b:fa:e6:3f:f9 ID:1,f6:6b:fa:e6:3f:f9 Lease:0x64285f6c}
I0331 10:35:01.328620 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:d6:3c:de:98:38:6b ID:1,d6:3c:de:98:38:6b Lease:0x64285f2e}
I0331 10:35:01.328625 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:fe:83:15:fa:aa:ec ID:1,fe:83:15:fa:aa:ec Lease:0x64285f12}
I0331 10:35:01.328630 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:a6:35:cb:98:65:c1 ID:1,a6:35:cb:98:65:c1 Lease:0x64285f07}
I0331 10:35:01.328635 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:36:b6:68:bc:e4:38 ID:1,36:b6:68:bc:e4:38 Lease:0x64270d7d}
I0331 10:35:01.328640 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:e2:a4:81:23:a4:3d ID:1,e2:a4:81:23:a4:3d Lease:0x64270d54}
I0331 10:35:01.328645 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:8a:b:c4:df:a3:11 ID:1,8a:b:c4:df:a3:11 Lease:0x64285ea2}
I0331 10:35:01.328652 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:b2:c2:28:ad:31:3a ID:1,b2:c2:28:ad:31:3a Lease:0x64285e72}
I0331 10:35:01.328659 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:a6:70:a4:73:28:cb ID:1,a6:70:a4:73:28:cb Lease:0x64285e02}
I0331 10:35:01.328671 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:26:b4:b0:4b:14:90 ID:1,26:b4:b0:4b:14:90 Lease:0x64285dca}
I0331 10:35:01.328677 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:5e:b0:80:42:21:3a ID:1,5e:b0:80:42:21:3a Lease:0x64285d35}
I0331 10:35:01.328683 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:6e:69:20:25:fe:f5 ID:1,6e:69:20:25:fe:f5 Lease:0x64270b49}
I0331 10:35:01.328688 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:46:91:96:71:9b:28 ID:1,46:91:96:71:9b:28 Lease:0x64285d17}
I0331 10:35:01.328693 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:a:89:dd:2:e4:c9 ID:1,a:89:dd:2:e4:c9 Lease:0x64285ce4}
I0331 10:35:01.328697 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:ba:a3:78:bc:50:c3 ID:1,ba:a3:78:bc:50:c3 Lease:0x64270a0b}
I0331 10:35:01.328703 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:56:76:69:2e:66:a3 ID:1,56:76:69:2e:66:a3 Lease:0x642709f5}
I0331 10:35:01.328709 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:de:44:3e:bc:31:2f ID:1,de:44:3e:bc:31:2f Lease:0x64285b29}
I0331 10:35:01.328718 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:62:83:36:11:24:8d ID:1,62:83:36:11:24:8d Lease:0x64285b04}
I0331 10:35:01.328725 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:f6:3b:d8:7f:c6:15 ID:1,f6:3b:d8:7f:c6:15 Lease:0x64285ac7}
I0331 10:35:01.328730 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:12:6f:78:18:c8:4c ID:1,12:6f:78:18:c8:4c Lease:0x64285a22}
I0331 10:35:01.328735 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:a:3f:c8:bd:27:e6 ID:1,a:3f:c8:bd:27:e6 Lease:0x642859e3}
I0331 10:35:01.328740 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:96:e7:a7:97:b3:81 ID:1,96:e7:a7:97:b3:81 Lease:0x642858f3}
I0331 10:35:01.328746 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:8a:15:42:72:2b:2b ID:1,8a:15:42:72:2b:2b Lease:0x64270768}
I0331 10:35:01.328752 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:aa:7f:77:fb:ae:d8 ID:1,aa:7f:77:fb:ae:d8 Lease:0x642857d5}
I0331 10:35:02.594431 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:35:02 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I0331 10:35:02.594500 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:35:02 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I0331 10:35:02.594506 16956 main.go:141] libmachine: (second-658000) DBG | 2023/03/31 10:35:02 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I0331 10:35:03.330538 16956 main.go:141] libmachine: (second-658000) DBG | Attempt 3
I0331 10:35:03.330549 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:35:03.330609 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:35:03.331407 16956 main.go:141] libmachine: (second-658000) DBG | Searching for c2:2:18:dc:42:84 in /var/db/dhcpd_leases ...
I0331 10:35:03.331489 16956 main.go:141] libmachine: (second-658000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0331 10:35:03.331497 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:7a:4a:ce:87:60:bd ID:1,7a:4a:ce:87:60:bd Lease:0x64286b24}
I0331 10:35:03.331510 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:f2:1:6d:4f:f9:9d ID:1,f2:1:6d:4f:f9:9d Lease:0x64286ae5}
I0331 10:35:03.331518 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:24:8c:13:d2:d0 ID:1,56:24:8c:13:d2:d0 Lease:0x64286a4d}
I0331 10:35:03.331525 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:5e:d7:a8:d5:45:36 ID:1,5e:d7:a8:d5:45:36 Lease:0x642718b1}
I0331 10:35:03.331530 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:5e:52:75:ec:c2:50 ID:1,5e:52:75:ec:c2:50 Lease:0x64286922}
I0331 10:35:03.331536 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:62:89:f4:67:75:3 ID:1,62:89:f4:67:75:3 Lease:0x64271797}
I0331 10:35:03.331541 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:86:69:94:bc:b4:68 ID:1,86:69:94:bc:b4:68 Lease:0x64286800}
I0331 10:35:03.331559 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:e2:9d:d2:dc:cf:f8 ID:1,e2:9d:d2:dc:cf:f8 Lease:0x642715dc}
I0331 10:35:03.331567 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2:a5:d3:32:bd:e8 ID:1,2:a5:d3:32:bd:e8 Lease:0x642865c4}
I0331 10:35:03.331573 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:9e:df:55:91:13:38 ID:1,9e:df:55:91:13:38 Lease:0x642864ec}
I0331 10:35:03.331578 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:2e:ff:dc:12:1e:d7 ID:1,2e:ff:dc:12:1e:d7 Lease:0x64286368}
I0331 10:35:03.331583 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:aa:18:45:b7:5d:44 ID:1,aa:18:45:b7:5d:44 Lease:0x642713ef}
I0331 10:35:03.331588 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:6a:e5:18:83:86:6f ID:1,6a:e5:18:83:86:6f Lease:0x6428629b}
I0331 10:35:03.331593 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:96:ff:65:b4:4b:ce ID:1,96:ff:65:b4:4b:ce Lease:0x64286279}
I0331 10:35:03.331598 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:fa:72:91:be:e9:7a ID:1,fa:72:91:be:e9:7a Lease:0x64286238}
I0331 10:35:03.331603 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:2a:4d:b1:17:ec:a8 ID:1,2a:4d:b1:17:ec:a8 Lease:0x64286223}
I0331 10:35:03.331608 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:7a:ba:a2:26:1d:98 ID:1,7a:ba:a2:26:1d:98 Lease:0x642861e3}
I0331 10:35:03.331619 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:8a:8d:3f:da:a2:64 ID:1,8a:8d:3f:da:a2:64 Lease:0x642861c8}
I0331 10:35:03.331627 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:8a:59:59:8a:91 ID:1,e:8a:59:59:8a:91 Lease:0x64286178}
I0331 10:35:03.331633 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:8a:c0:f9:68:6b:c8 ID:1,8a:c0:f9:68:6b:c8 Lease:0x64286168}
I0331 10:35:03.331639 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:ba:1b:c9:82:e2:11 ID:1,ba:1b:c9:82:e2:11 Lease:0x6428611f}
I0331 10:35:03.331644 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:c6:cd:53:4a:c5:ac ID:1,c6:cd:53:4a:c5:ac Lease:0x64270f94}
I0331 10:35:03.331650 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:66:e7:5d:76:7a:69 ID:1,66:e7:5d:76:7a:69 Lease:0x64270f6b}
I0331 10:35:03.331654 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:c2:af:18:d3:b9:98 ID:1,c2:af:18:d3:b9:98 Lease:0x642860b4}
I0331 10:35:03.331661 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:62:b4:12:e2:cf:de ID:1,62:b4:12:e2:cf:de Lease:0x64286083}
I0331 10:35:03.331673 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:4e:cc:5b:5f:33:8a ID:1,4e:cc:5b:5f:33:8a Lease:0x64286067}
I0331 10:35:03.331678 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:f6:6b:fa:e6:3f:f9 ID:1,f6:6b:fa:e6:3f:f9 Lease:0x64285f6c}
I0331 10:35:03.331694 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:d6:3c:de:98:38:6b ID:1,d6:3c:de:98:38:6b Lease:0x64285f2e}
I0331 10:35:03.331701 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:fe:83:15:fa:aa:ec ID:1,fe:83:15:fa:aa:ec Lease:0x64285f12}
I0331 10:35:03.331707 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:a6:35:cb:98:65:c1 ID:1,a6:35:cb:98:65:c1 Lease:0x64285f07}
I0331 10:35:03.331712 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:36:b6:68:bc:e4:38 ID:1,36:b6:68:bc:e4:38 Lease:0x64270d7d}
I0331 10:35:03.331725 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:e2:a4:81:23:a4:3d ID:1,e2:a4:81:23:a4:3d Lease:0x64270d54}
I0331 10:35:03.331733 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:8a:b:c4:df:a3:11 ID:1,8a:b:c4:df:a3:11 Lease:0x64285ea2}
I0331 10:35:03.331741 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:b2:c2:28:ad:31:3a ID:1,b2:c2:28:ad:31:3a Lease:0x64285e72}
I0331 10:35:03.331746 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:a6:70:a4:73:28:cb ID:1,a6:70:a4:73:28:cb Lease:0x64285e02}
I0331 10:35:03.331752 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:26:b4:b0:4b:14:90 ID:1,26:b4:b0:4b:14:90 Lease:0x64285dca}
I0331 10:35:03.331758 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:5e:b0:80:42:21:3a ID:1,5e:b0:80:42:21:3a Lease:0x64285d35}
I0331 10:35:03.331764 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:6e:69:20:25:fe:f5 ID:1,6e:69:20:25:fe:f5 Lease:0x64270b49}
I0331 10:35:03.331769 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:46:91:96:71:9b:28 ID:1,46:91:96:71:9b:28 Lease:0x64285d17}
I0331 10:35:03.331779 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:a:89:dd:2:e4:c9 ID:1,a:89:dd:2:e4:c9 Lease:0x64285ce4}
I0331 10:35:03.331788 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:ba:a3:78:bc:50:c3 ID:1,ba:a3:78:bc:50:c3 Lease:0x64270a0b}
I0331 10:35:03.331794 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:56:76:69:2e:66:a3 ID:1,56:76:69:2e:66:a3 Lease:0x642709f5}
I0331 10:35:03.331799 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:de:44:3e:bc:31:2f ID:1,de:44:3e:bc:31:2f Lease:0x64285b29}
I0331 10:35:03.331809 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:62:83:36:11:24:8d ID:1,62:83:36:11:24:8d Lease:0x64285b04}
I0331 10:35:03.331819 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:f6:3b:d8:7f:c6:15 ID:1,f6:3b:d8:7f:c6:15 Lease:0x64285ac7}
I0331 10:35:03.331825 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:12:6f:78:18:c8:4c ID:1,12:6f:78:18:c8:4c Lease:0x64285a22}
I0331 10:35:03.331830 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:a:3f:c8:bd:27:e6 ID:1,a:3f:c8:bd:27:e6 Lease:0x642859e3}
I0331 10:35:03.331840 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:96:e7:a7:97:b3:81 ID:1,96:e7:a7:97:b3:81 Lease:0x642858f3}
I0331 10:35:03.331849 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:8a:15:42:72:2b:2b ID:1,8a:15:42:72:2b:2b Lease:0x64270768}
I0331 10:35:03.331858 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:aa:7f:77:fb:ae:d8 ID:1,aa:7f:77:fb:ae:d8 Lease:0x642857d5}
I0331 10:35:05.332919 16956 main.go:141] libmachine: (second-658000) DBG | Attempt 4
I0331 10:35:05.332936 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:35:05.332992 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:35:05.333778 16956 main.go:141] libmachine: (second-658000) DBG | Searching for c2:2:18:dc:42:84 in /var/db/dhcpd_leases ...
I0331 10:35:05.333879 16956 main.go:141] libmachine: (second-658000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0331 10:35:05.333893 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:7a:4a:ce:87:60:bd ID:1,7a:4a:ce:87:60:bd Lease:0x64286b24}
I0331 10:35:05.333900 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:f2:1:6d:4f:f9:9d ID:1,f2:1:6d:4f:f9:9d Lease:0x64286ae5}
I0331 10:35:05.333908 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:56:24:8c:13:d2:d0 ID:1,56:24:8c:13:d2:d0 Lease:0x64286a4d}
I0331 10:35:05.333914 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:5e:d7:a8:d5:45:36 ID:1,5e:d7:a8:d5:45:36 Lease:0x642718b1}
I0331 10:35:05.333921 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:5e:52:75:ec:c2:50 ID:1,5e:52:75:ec:c2:50 Lease:0x64286922}
I0331 10:35:05.333928 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:62:89:f4:67:75:3 ID:1,62:89:f4:67:75:3 Lease:0x64271797}
I0331 10:35:05.333933 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:86:69:94:bc:b4:68 ID:1,86:69:94:bc:b4:68 Lease:0x64286800}
I0331 10:35:05.333939 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:e2:9d:d2:dc:cf:f8 ID:1,e2:9d:d2:dc:cf:f8 Lease:0x642715dc}
I0331 10:35:05.333944 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:2:a5:d3:32:bd:e8 ID:1,2:a5:d3:32:bd:e8 Lease:0x642865c4}
I0331 10:35:05.333949 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:9e:df:55:91:13:38 ID:1,9e:df:55:91:13:38 Lease:0x642864ec}
I0331 10:35:05.333954 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:2e:ff:dc:12:1e:d7 ID:1,2e:ff:dc:12:1e:d7 Lease:0x64286368}
I0331 10:35:05.333959 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:aa:18:45:b7:5d:44 ID:1,aa:18:45:b7:5d:44 Lease:0x642713ef}
I0331 10:35:05.333964 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:6a:e5:18:83:86:6f ID:1,6a:e5:18:83:86:6f Lease:0x6428629b}
I0331 10:35:05.333983 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:96:ff:65:b4:4b:ce ID:1,96:ff:65:b4:4b:ce Lease:0x64286279}
I0331 10:35:05.333996 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:fa:72:91:be:e9:7a ID:1,fa:72:91:be:e9:7a Lease:0x64286238}
I0331 10:35:05.334003 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:2a:4d:b1:17:ec:a8 ID:1,2a:4d:b1:17:ec:a8 Lease:0x64286223}
I0331 10:35:05.334014 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:7a:ba:a2:26:1d:98 ID:1,7a:ba:a2:26:1d:98 Lease:0x642861e3}
I0331 10:35:05.334026 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:8a:8d:3f:da:a2:64 ID:1,8a:8d:3f:da:a2:64 Lease:0x642861c8}
I0331 10:35:05.334035 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:8a:59:59:8a:91 ID:1,e:8a:59:59:8a:91 Lease:0x64286178}
I0331 10:35:05.334044 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:8a:c0:f9:68:6b:c8 ID:1,8a:c0:f9:68:6b:c8 Lease:0x64286168}
I0331 10:35:05.334050 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:ba:1b:c9:82:e2:11 ID:1,ba:1b:c9:82:e2:11 Lease:0x6428611f}
I0331 10:35:05.334057 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:c6:cd:53:4a:c5:ac ID:1,c6:cd:53:4a:c5:ac Lease:0x64270f94}
I0331 10:35:05.334063 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:66:e7:5d:76:7a:69 ID:1,66:e7:5d:76:7a:69 Lease:0x64270f6b}
I0331 10:35:05.334069 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:c2:af:18:d3:b9:98 ID:1,c2:af:18:d3:b9:98 Lease:0x642860b4}
I0331 10:35:05.334074 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:62:b4:12:e2:cf:de ID:1,62:b4:12:e2:cf:de Lease:0x64286083}
I0331 10:35:05.334085 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:4e:cc:5b:5f:33:8a ID:1,4e:cc:5b:5f:33:8a Lease:0x64286067}
I0331 10:35:05.334092 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:f6:6b:fa:e6:3f:f9 ID:1,f6:6b:fa:e6:3f:f9 Lease:0x64285f6c}
I0331 10:35:05.334098 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:d6:3c:de:98:38:6b ID:1,d6:3c:de:98:38:6b Lease:0x64285f2e}
I0331 10:35:05.334102 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:fe:83:15:fa:aa:ec ID:1,fe:83:15:fa:aa:ec Lease:0x64285f12}
I0331 10:35:05.334107 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:a6:35:cb:98:65:c1 ID:1,a6:35:cb:98:65:c1 Lease:0x64285f07}
I0331 10:35:05.334118 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:36:b6:68:bc:e4:38 ID:1,36:b6:68:bc:e4:38 Lease:0x64270d7d}
I0331 10:35:05.334124 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:e2:a4:81:23:a4:3d ID:1,e2:a4:81:23:a4:3d Lease:0x64270d54}
I0331 10:35:05.334130 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:8a:b:c4:df:a3:11 ID:1,8a:b:c4:df:a3:11 Lease:0x64285ea2}
I0331 10:35:05.334135 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:b2:c2:28:ad:31:3a ID:1,b2:c2:28:ad:31:3a Lease:0x64285e72}
I0331 10:35:05.334146 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:a6:70:a4:73:28:cb ID:1,a6:70:a4:73:28:cb Lease:0x64285e02}
I0331 10:35:05.334159 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:26:b4:b0:4b:14:90 ID:1,26:b4:b0:4b:14:90 Lease:0x64285dca}
I0331 10:35:05.334165 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:5e:b0:80:42:21:3a ID:1,5e:b0:80:42:21:3a Lease:0x64285d35}
I0331 10:35:05.334173 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:6e:69:20:25:fe:f5 ID:1,6e:69:20:25:fe:f5 Lease:0x64270b49}
I0331 10:35:05.334178 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:46:91:96:71:9b:28 ID:1,46:91:96:71:9b:28 Lease:0x64285d17}
I0331 10:35:05.334185 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:a:89:dd:2:e4:c9 ID:1,a:89:dd:2:e4:c9 Lease:0x64285ce4}
I0331 10:35:05.334190 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:ba:a3:78:bc:50:c3 ID:1,ba:a3:78:bc:50:c3 Lease:0x64270a0b}
I0331 10:35:05.334197 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:56:76:69:2e:66:a3 ID:1,56:76:69:2e:66:a3 Lease:0x642709f5}
I0331 10:35:05.334207 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:de:44:3e:bc:31:2f ID:1,de:44:3e:bc:31:2f Lease:0x64285b29}
I0331 10:35:05.334211 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:62:83:36:11:24:8d ID:1,62:83:36:11:24:8d Lease:0x64285b04}
I0331 10:35:05.334216 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:f6:3b:d8:7f:c6:15 ID:1,f6:3b:d8:7f:c6:15 Lease:0x64285ac7}
I0331 10:35:05.334222 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:12:6f:78:18:c8:4c ID:1,12:6f:78:18:c8:4c Lease:0x64285a22}
I0331 10:35:05.334227 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:a:3f:c8:bd:27:e6 ID:1,a:3f:c8:bd:27:e6 Lease:0x642859e3}
I0331 10:35:05.334231 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:96:e7:a7:97:b3:81 ID:1,96:e7:a7:97:b3:81 Lease:0x642858f3}
I0331 10:35:05.334237 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:8a:15:42:72:2b:2b ID:1,8a:15:42:72:2b:2b Lease:0x64270768}
I0331 10:35:05.334243 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:aa:7f:77:fb:ae:d8 ID:1,aa:7f:77:fb:ae:d8 Lease:0x642857d5}
I0331 10:35:07.336260 16956 main.go:141] libmachine: (second-658000) DBG | Attempt 5
I0331 10:35:07.336299 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:35:07.336387 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:35:07.337870 16956 main.go:141] libmachine: (second-658000) DBG | Searching for c2:2:18:dc:42:84 in /var/db/dhcpd_leases ...
I0331 10:35:07.338030 16956 main.go:141] libmachine: (second-658000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I0331 10:35:07.338044 16956 main.go:141] libmachine: (second-658000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:c2:2:18:dc:42:84 ID:1,c2:2:18:dc:42:84 Lease:0x64286b4a}
I0331 10:35:07.338062 16956 main.go:141] libmachine: (second-658000) DBG | Found match: c2:2:18:dc:42:84
I0331 10:35:07.338073 16956 main.go:141] libmachine: (second-658000) DBG | IP: 192.168.64.52
I0331 10:35:07.338138 16956 main.go:141] libmachine: (second-658000) Calling .GetConfigRaw
I0331 10:35:07.338874 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:07.339021 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:07.339154 16956 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0331 10:35:07.339167 16956 main.go:141] libmachine: (second-658000) Calling .GetState
I0331 10:35:07.339271 16956 main.go:141] libmachine: (second-658000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0331 10:35:07.339336 16956 main.go:141] libmachine: (second-658000) DBG | hyperkit pid from json: 16965
I0331 10:35:07.340337 16956 main.go:141] libmachine: Detecting operating system of created instance...
I0331 10:35:07.340345 16956 main.go:141] libmachine: Waiting for SSH to be available...
I0331 10:35:07.340349 16956 main.go:141] libmachine: Getting to WaitForSSH function...
I0331 10:35:07.340354 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.340432 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.340529 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.340626 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.340705 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.340826 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.341173 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.341178 16956 main.go:141] libmachine: About to run SSH command:
exit 0
I0331 10:35:07.412308 16956 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0331 10:35:07.412317 16956 main.go:141] libmachine: Detecting the provisioner...
I0331 10:35:07.412321 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.412446 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.412521 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.412604 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.412694 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.412814 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.413126 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.413131 16956 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0331 10:35:07.482963 16956 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-gb59f5ab-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I0331 10:35:07.483011 16956 main.go:141] libmachine: found compatible host: buildroot
I0331 10:35:07.483015 16956 main.go:141] libmachine: Provisioning with buildroot...
I0331 10:35:07.483020 16956 main.go:141] libmachine: (second-658000) Calling .GetMachineName
I0331 10:35:07.483161 16956 buildroot.go:166] provisioning hostname "second-658000"
I0331 10:35:07.483170 16956 main.go:141] libmachine: (second-658000) Calling .GetMachineName
I0331 10:35:07.483277 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.483361 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.483455 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.483528 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.483616 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.483739 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.484042 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.484050 16956 main.go:141] libmachine: About to run SSH command:
sudo hostname second-658000 && echo "second-658000" | sudo tee /etc/hostname
I0331 10:35:07.562792 16956 main.go:141] libmachine: SSH cmd err, output: <nil>: second-658000
I0331 10:35:07.562806 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.562943 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.563060 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.563145 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.563224 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.563364 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.563676 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.563684 16956 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\ssecond-658000' /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-658000/g' /etc/hosts;
else
echo '127.0.1.1 second-658000' | sudo tee -a /etc/hosts;
fi
fi
I0331 10:35:07.643397 16956 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0331 10:35:07.643412 16956 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/16144-14174/.minikube CaCertPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/16144-14174/.minikube}
I0331 10:35:07.643425 16956 buildroot.go:174] setting up certificates
I0331 10:35:07.643436 16956 provision.go:83] configureAuth start
I0331 10:35:07.643441 16956 main.go:141] libmachine: (second-658000) Calling .GetMachineName
I0331 10:35:07.643574 16956 main.go:141] libmachine: (second-658000) Calling .GetIP
I0331 10:35:07.643664 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.643735 16956 provision.go:138] copyHostCerts
I0331 10:35:07.643813 16956 exec_runner.go:144] found /Users/jenkins/minikube-integration/16144-14174/.minikube/ca.pem, removing ...
I0331 10:35:07.643820 16956 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/16144-14174/.minikube/ca.pem
I0331 10:35:07.643929 16956 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/16144-14174/.minikube/ca.pem (1078 bytes)
I0331 10:35:07.644123 16956 exec_runner.go:144] found /Users/jenkins/minikube-integration/16144-14174/.minikube/cert.pem, removing ...
I0331 10:35:07.644126 16956 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/16144-14174/.minikube/cert.pem
I0331 10:35:07.644183 16956 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16144-14174/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/16144-14174/.minikube/cert.pem (1123 bytes)
I0331 10:35:07.644331 16956 exec_runner.go:144] found /Users/jenkins/minikube-integration/16144-14174/.minikube/key.pem, removing ...
I0331 10:35:07.644334 16956 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/16144-14174/.minikube/key.pem
I0331 10:35:07.644399 16956 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16144-14174/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/16144-14174/.minikube/key.pem (1679 bytes)
I0331 10:35:07.644533 16956 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca-key.pem org=jenkins.second-658000 san=[192.168.64.52 192.168.64.52 localhost 127.0.0.1 minikube second-658000]
I0331 10:35:07.682642 16956 provision.go:172] copyRemoteCerts
I0331 10:35:07.682691 16956 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0331 10:35:07.682707 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.682849 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.682985 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.683091 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.683168 16956 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/id_rsa Username:docker}
I0331 10:35:07.725609 16956 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16144-14174/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0331 10:35:07.741465 16956 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/server.pem --> /etc/docker/server.pem (1216 bytes)
I0331 10:35:07.757444 16956 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16144-14174/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0331 10:35:07.772559 16956 provision.go:86] duration metric: configureAuth took 129.116233ms
I0331 10:35:07.772566 16956 buildroot.go:189] setting minikube options for container-runtime
I0331 10:35:07.772688 16956 config.go:182] Loaded profile config "second-658000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.26.3
I0331 10:35:07.772698 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:07.772826 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.772898 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.772969 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.773057 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.773136 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.773255 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.773563 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.773568 16956 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0331 10:35:07.846127 16956 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0331 10:35:07.846134 16956 buildroot.go:70] root file system type: tmpfs
I0331 10:35:07.846215 16956 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0331 10:35:07.846229 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.846357 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.846445 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.846534 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.846601 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.846720 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.847014 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.847055 16956 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
I0331 10:35:07.926576 16956 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
I0331 10:35:07.926597 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:07.926731 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:07.926824 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.926899 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:07.926987 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:07.927105 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:07.927404 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:07.927417 16956 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; }
I0331 10:35:08.398665 16956 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.
I0331 10:35:08.398681 16956 main.go:141] libmachine: Checking connection to Docker...
I0331 10:35:08.398686 16956 main.go:141] libmachine: (second-658000) Calling .GetURL
I0331 10:35:08.398839 16956 main.go:141] libmachine: Docker is up and running!
I0331 10:35:08.398845 16956 main.go:141] libmachine: Reticulating splines...
I0331 10:35:08.398848 16956 client.go:171] LocalClient.Create took 11.735087314s
I0331 10:35:08.398856 16956 start.go:167] duration metric: libmachine.API.Create for "second-658000" took 11.735115002s
I0331 10:35:08.398864 16956 start.go:300] post-start starting for "second-658000" (driver="hyperkit")
I0331 10:35:08.398867 16956 start.go:328] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I0331 10:35:08.398879 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:08.399034 16956 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
I0331 10:35:08.399042 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:08.399126 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:08.399207 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:08.399292 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:08.399391 16956 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/id_rsa Username:docker}
I0331 10:35:08.443866 16956 ssh_runner.go:195] Run: cat /etc/os-release
I0331 10:35:08.446403 16956 info.go:137] Remote host: Buildroot 2021.02.12
I0331 10:35:08.446410 16956 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16144-14174/.minikube/addons for local assets ...
I0331 10:35:08.446495 16956 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16144-14174/.minikube/files for local assets ...
I0331 10:35:08.446674 16956 filesync.go:149] local asset: /Users/jenkins/minikube-integration/16144-14174/.minikube/files/etc/ssl/certs/146322.pem -> 146322.pem in /etc/ssl/certs
I0331 10:35:08.446839 16956 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0331 10:35:08.452297 16956 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16144-14174/.minikube/files/etc/ssl/certs/146322.pem --> /etc/ssl/certs/146322.pem (1708 bytes)
I0331 10:35:08.468787 16956 start.go:303] post-start completed in 69.918053ms
I0331 10:35:08.468817 16956 main.go:141] libmachine: (second-658000) Calling .GetConfigRaw
I0331 10:35:08.469361 16956 main.go:141] libmachine: (second-658000) Calling .GetIP
I0331 10:35:08.469517 16956 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16144-14174/.minikube/profiles/second-658000/config.json ...
I0331 10:35:08.469813 16956 start.go:128] duration metric: createHost completed in 11.8584844s
I0331 10:35:08.469826 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:08.469926 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:08.470010 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:08.470098 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:08.470170 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:08.470273 16956 main.go:141] libmachine: Using SSH client type: native
I0331 10:35:08.470564 16956 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140cc00] 0x140fca0 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0331 10:35:08.470569 16956 main.go:141] libmachine: About to run SSH command:
date +%!s(MISSING).%!N(MISSING)
I0331 10:35:08.541322 16956 main.go:141] libmachine: SSH cmd err, output: <nil>: 1680284107.614799863
I0331 10:35:08.541328 16956 fix.go:207] guest clock: 1680284107.614799863
I0331 10:35:08.541332 16956 fix.go:220] Guest: 2023-03-31 10:35:07.614799863 -0700 PDT Remote: 2023-03-31 10:35:08.469819 -0700 PDT m=+12.366624464 (delta=-855.019137ms)
I0331 10:35:08.541347 16956 fix.go:191] guest clock delta is within tolerance: -855.019137ms
I0331 10:35:08.541349 16956 start.go:83] releasing machines lock for "second-658000", held for 11.930141575s
I0331 10:35:08.541362 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:08.541490 16956 main.go:141] libmachine: (second-658000) Calling .GetIP
I0331 10:35:08.541581 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:08.541870 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:08.541958 16956 main.go:141] libmachine: (second-658000) Calling .DriverName
I0331 10:35:08.542025 16956 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0331 10:35:08.542049 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:08.542082 16956 ssh_runner.go:195] Run: cat /version.json
I0331 10:35:08.542089 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHHostname
I0331 10:35:08.542137 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:08.542195 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHPort
I0331 10:35:08.542237 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:08.542280 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHKeyPath
I0331 10:35:08.542326 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:08.542358 16956 main.go:141] libmachine: (second-658000) Calling .GetSSHUsername
I0331 10:35:08.542403 16956 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/id_rsa Username:docker}
I0331 10:35:08.542435 16956 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16144-14174/.minikube/machines/second-658000/id_rsa Username:docker}
I0331 10:35:08.579294 16956 ssh_runner.go:195] Run: systemctl --version
I0331 10:35:08.583877 16956 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0331 10:35:08.622440 16956 cni.go:208] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0331 10:35:08.622542 16956 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" ;
I0331 10:35:08.640087 16956 cni.go:261] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0331 10:35:08.640097 16956 preload.go:132] Checking if preload exists for k8s version v1.26.3 and runtime docker
I0331 10:35:08.640189 16956 ssh_runner.go:195] Run: docker images --format {{.Repository}}:{{.Tag}}
I0331 10:35:08.655271 16956 docker.go:639] Got preloaded images:
I0331 10:35:08.655279 16956 docker.go:645] registry.k8s.io/kube-apiserver:v1.26.3 wasn't preloaded
I0331 10:35:08.655331 16956 ssh_runner.go:195] Run: sudo cat /var/lib/docker/image/overlay2/repositories.json
I0331 10:35:08.663607 16956 ssh_runner.go:195] Run: which lz4
I0331 10:35:08.667104 16956 ssh_runner.go:195] Run: stat -c "%!s(MISSING) %!y(MISSING)" /preloaded.tar.lz4
I0331 10:35:08.671617 16956 ssh_runner.go:352] existence check for /preloaded.tar.lz4: stat -c "%!s(MISSING) %!y(MISSING)" /preloaded.tar.lz4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/preloaded.tar.lz4': No such file or directory
I0331 10:35:08.671643 16956 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16144-14174/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.26.3-docker-overlay2-amd64.tar.lz4 --> /preloaded.tar.lz4 (416308310 bytes)
I0331 10:35:10.113492 16956 docker.go:603] Took 1.446484 seconds to copy over tarball
I0331 10:35:10.113546 16956 ssh_runner.go:195] Run: sudo tar -I lz4 -C /var -xf /preloaded.tar.lz4
I0331 10:35:14.234835 16956 ssh_runner.go:235] Completed: sudo tar -I lz4 -C /var -xf /preloaded.tar.lz4: (4.121358607s)
I0331 10:35:14.234848 16956 ssh_runner.go:146] rm: /preloaded.tar.lz4
I0331 10:35:14.261698 16956 ssh_runner.go:195] Run: sudo cat /var/lib/docker/image/overlay2/repositories.json
I0331 10:35:14.268869 16956 ssh_runner.go:362] scp memory --> /var/lib/docker/image/overlay2/repositories.json (2628 bytes)
I0331 10:35:14.280382 16956 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0331 10:35:14.376324 16956 ssh_runner.go:195] Run: sudo systemctl restart docker
I0331 10:35:15.955793 16956 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.579485172s)
I0331 10:35:15.955817 16956 start.go:481] detecting cgroup driver to use...
I0331 10:35:15.955910 16956 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"
I0331 10:35:15.971619 16956 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"
I0331 10:35:15.978732 16956 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"
I0331 10:35:15.985010 16956 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I0331 10:35:15.985054 16956 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0331 10:35:15.991284 16956 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"
I0331 10:35:15.997638 16956 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0331 10:35:16.003881 16956 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0331 10:35:16.010244 16956 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0331 10:35:16.016987 16956 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"
I0331 10:35:16.023902 16956 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0331 10:35:16.029495 16956 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0331 10:35:16.035293 16956 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0331 10:35:16.114637 16956 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0331 10:35:16.126041 16956 start.go:481] detecting cgroup driver to use...
I0331 10:35:16.126110 16956 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0331 10:35:16.136418 16956 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0331 10:35:16.180263 16956 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0331 10:35:16.195087 16956 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0331 10:35:16.203941 16956 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0331 10:35:16.213093 16956 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0331 10:35:16.241470 16956 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0331 10:35:16.250867 16956 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"
I0331 10:35:16.262401 16956 ssh_runner.go:195] Run: which cri-dockerd
I0331 10:35:16.264760 16956 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0331 10:35:16.271040 16956 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0331 10:35:16.282486 16956 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0331 10:35:16.376024 16956 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0331 10:35:16.464488 16956 docker.go:538] configuring docker to use "cgroupfs" as cgroup driver...
I0331 10:35:16.464498 16956 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (144 bytes)
I0331 10:35:16.475668 16956 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0331 10:35:16.559216 16956 ssh_runner.go:195] Run: sudo systemctl restart docker
I0331 10:36:17.582585 16956 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.024311496s)
I0331 10:36:17.604211 16956 out.go:177]
W0331 10:36:17.626224 16956 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xe" for details.
W0331 10:36:17.626252 16956 out.go:239] *
W0331 10:36:17.627515 16956 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0331 10:36:17.667896 16956 out.go:177]
*
* ==> Docker <==
* -- Journal begins at Fri 2023-03-31 17:34:28 UTC, ends at Fri 2023-03-31 17:36:23 UTC. --
Mar 31 17:35:08 first-656000 dockerd[1012]: time="2023-03-31T17:35:08.911687528Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:08 first-656000 dockerd[1012]: time="2023-03-31T17:35:08.933319342Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Mar 31 17:35:08 first-656000 dockerd[1012]: time="2023-03-31T17:35:08.933422864Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:08 first-656000 dockerd[1012]: time="2023-03-31T17:35:08.933444664Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Mar 31 17:35:08 first-656000 dockerd[1012]: time="2023-03-31T17:35:08.933455631Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.141768770Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.145733765Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.145977556Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.146039252Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 cri-dockerd[1232]: time="2023-03-31T17:35:09Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/795858a0a690d4e25e3abc58ac813c82292f6236415749d38c35317084b8538c/resolv.conf as [nameserver 192.168.64.1]"
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.315730020Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.315795416Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.315833991Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.315847147Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 cri-dockerd[1232]: time="2023-03-31T17:35:09Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/6125bc49fd9eda8b18280c63debdb25e1dd1439518cf16d76f402a94625b82d8/resolv.conf as [nameserver 192.168.64.1]"
Mar 31 17:35:09 first-656000 cri-dockerd[1232]: time="2023-03-31T17:35:09Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/2f8d289b19d1bf80ffb48aa403f9857d72806b477bd0e602522d9ff628a9c373/resolv.conf as [nameserver 192.168.64.1]"
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.687421932Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.687483624Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.687502135Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.687517936Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.783802766Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.783884462Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.783956511Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Mar 31 17:35:09 first-656000 dockerd[1012]: time="2023-03-31T17:35:09.783976345Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Mar 31 17:35:15 first-656000 cri-dockerd[1232]: time="2023-03-31T17:35:15Z" 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
b1752c0d07a44 6e38f40d628db About a minute ago Running storage-provisioner 0 2f8d289b19d1b
5b3a66f531a20 5185b96f0becf About a minute ago Running coredns 0 6125bc49fd9ed
7569d05cf58be 92ed2bec97a63 About a minute ago Running kube-proxy 0 795858a0a690d
754a7384e5c4f 5a79047369329 About a minute ago Running kube-scheduler 0 f925225a58daf
f6551016a6d11 fce326961ae2d About a minute ago Running etcd 0 2257df2549f3f
45f91bf085e25 ce8c2293ef09c About a minute ago Running kube-controller-manager 0 a009bf0a7928a
30064c0acbea9 1d9b3cbae03ce About a minute ago Running kube-apiserver 0 c31e4f8328976
*
* ==> coredns [5b3a66f531a2] <==
* [INFO] plugin/ready: Still waiting on: "kubernetes"
.:53
[INFO] plugin/reload: Running configuration SHA512 = 82b95b61957b89eeea31bdaf6987f010031330ef97d5f8469dbdaa80b119a5b0c9955b961009dd5b77ee3ada002b456836be781510516cbd9d015b1a704a24ea
CoreDNS-1.9.3
linux/amd64, go1.18.2, 45b0a11
[INFO] 127.0.0.1:45335 - 24543 "HINFO IN 7904055142446188228.5630604799378591293. udp 57 false 512" NXDOMAIN qr,rd,ra 132 0.008337945s
*
* ==> describe nodes <==
* Name: first-656000
Roles: control-plane
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=first-656000
kubernetes.io/os=linux
minikube.k8s.io/commit=945b3fc45ee9ac8e1ceaffb00a71ec22c717b10e
minikube.k8s.io/name=first-656000
minikube.k8s.io/primary=true
minikube.k8s.io/updated_at=2023_03_31T10_34_54_0700
minikube.k8s.io/version=v1.29.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: Fri, 31 Mar 2023 17:34:51 +0000
Taints: <none>
Unschedulable: false
Lease:
HolderIdentity: first-656000
AcquireTime: <unset>
RenewTime: Fri, 31 Mar 2023 17:36:16 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Fri, 31 Mar 2023 17:35:15 +0000 Fri, 31 Mar 2023 17:34:50 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Fri, 31 Mar 2023 17:35:15 +0000 Fri, 31 Mar 2023 17:34:50 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Fri, 31 Mar 2023 17:35:15 +0000 Fri, 31 Mar 2023 17:34:50 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready True Fri, 31 Mar 2023 17:35:15 +0000 Fri, 31 Mar 2023 17:34:58 +0000 KubeletReady kubelet is posting ready status
Addresses:
InternalIP: 192.168.64.51
Hostname: first-656000
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: 4033ad69b35d4fe1b4c120f1ee149c3f
System UUID: 43f711ed-0000-0000-8922-f01898ef957c
Boot ID: 07d62232-9e5f-436e-9463-fa10e039aec4
Kernel Version: 5.10.57
OS Image: Buildroot 2021.02.12
Operating System: linux
Architecture: amd64
Container Runtime Version: docker://20.10.23
Kubelet Version: v1.26.3
Kube-Proxy Version: v1.26.3
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-787d4945fb-m9fph 100m (5%!)(MISSING) 0 (0%!)(MISSING) 70Mi (1%!)(MISSING) 170Mi (2%!)(MISSING) 76s
kube-system etcd-first-656000 100m (5%!)(MISSING) 0 (0%!)(MISSING) 100Mi (1%!)(MISSING) 0 (0%!)(MISSING) 92s
kube-system kube-apiserver-first-656000 250m (12%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 91s
kube-system kube-controller-manager-first-656000 200m (10%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 90s
kube-system kube-proxy-v8g6f 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 76s
kube-system kube-scheduler-first-656000 100m (5%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 90s
kube-system storage-provisioner 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 88s
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 74s kube-proxy
Normal Starting 90s kubelet Starting kubelet.
Normal NodeAllocatableEnforced 90s kubelet Updated Node Allocatable limit across pods
Normal NodeHasSufficientMemory 90s kubelet Node first-656000 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 90s kubelet Node first-656000 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 90s kubelet Node first-656000 status is now: NodeHasSufficientPID
Normal NodeReady 86s kubelet Node first-656000 status is now: NodeReady
Normal RegisteredNode 77s node-controller Node first-656000 event: Registered Node first-656000 in Controller
*
* ==> dmesg <==
* [ +0.000002] ACPI Warning: Could not enable fixed event - RealTimeClock (4) (20200925/evxface-618)
[ +0.009460] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ +3.069124] systemd-fstab-generator[125]: Ignoring "noauto" for root device
[ +0.037089] 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.844509] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ +0.000004] NFSD: unable to find recovery directory /var/lib/nfs/v4recovery
[ +0.000000] NFSD: Unable to initialize client recovery tracking! (-2)
[ +3.527156] systemd-fstab-generator[554]: Ignoring "noauto" for root device
[ +0.091257] systemd-fstab-generator[565]: Ignoring "noauto" for root device
[ +5.864992] systemd-fstab-generator[771]: Ignoring "noauto" for root device
[ +1.476785] kauditd_printk_skb: 16 callbacks suppressed
[ +0.235320] systemd-fstab-generator[933]: Ignoring "noauto" for root device
[ +0.228909] systemd-fstab-generator[973]: Ignoring "noauto" for root device
[ +0.094276] systemd-fstab-generator[984]: Ignoring "noauto" for root device
[ +0.106114] systemd-fstab-generator[997]: Ignoring "noauto" for root device
[ +1.409446] systemd-fstab-generator[1146]: Ignoring "noauto" for root device
[ +0.085477] systemd-fstab-generator[1157]: Ignoring "noauto" for root device
[ +0.084416] systemd-fstab-generator[1168]: Ignoring "noauto" for root device
[ +0.092594] systemd-fstab-generator[1179]: Ignoring "noauto" for root device
[ +0.101245] systemd-fstab-generator[1198]: Ignoring "noauto" for root device
[ +3.420801] systemd-fstab-generator[1471]: Ignoring "noauto" for root device
[ +0.407613] kauditd_printk_skb: 68 callbacks suppressed
[ +7.276088] systemd-fstab-generator[2405]: Ignoring "noauto" for root device
[Mar31 17:35] kauditd_printk_skb: 8 callbacks suppressed
*
* ==> etcd [f6551016a6d1] <==
* {"level":"info","ts":"2023-03-31T17:34:49.952Z","caller":"fileutil/purge.go:44","msg":"started to purge file","dir":"/var/lib/minikube/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2023-03-31T17:34:49.953Z","caller":"fileutil/purge.go:44","msg":"started to purge file","dir":"/var/lib/minikube/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2023-03-31T17:34:49.959Z","caller":"embed/etcd.go:687","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-03-31T17:34:49.961Z","caller":"embed/etcd.go:275","msg":"now serving peer/client/metrics","local-member-id":"b8434eb7a3474524","initial-advertise-peer-urls":["https://192.168.64.51:2380"],"listen-peer-urls":["https://192.168.64.51:2380"],"advertise-client-urls":["https://192.168.64.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://192.168.64.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2023-03-31T17:34:49.961Z","caller":"embed/etcd.go:586","msg":"serving peer traffic","address":"192.168.64.51:2380"}
{"level":"info","ts":"2023-03-31T17:34:49.961Z","caller":"embed/etcd.go:558","msg":"cmux::serve","address":"192.168.64.51:2380"}
{"level":"info","ts":"2023-03-31T17:34:49.961Z","caller":"embed/etcd.go:762","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 is starting a new election at term 1"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 became pre-candidate at term 1"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 received MsgPreVoteResp from b8434eb7a3474524 at term 1"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 became candidate at term 2"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 received MsgVoteResp from b8434eb7a3474524 at term 2"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 became leader at term 2"}
{"level":"info","ts":"2023-03-31T17:34:50.313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b8434eb7a3474524 elected leader b8434eb7a3474524 at term 2"}
{"level":"info","ts":"2023-03-31T17:34:50.317Z","caller":"etcdserver/server.go:2054","msg":"published local member to cluster through raft","local-member-id":"b8434eb7a3474524","local-member-attributes":"{Name:first-656000 ClientURLs:[https://192.168.64.51:2379]}","request-path":"/0/members/b8434eb7a3474524/attributes","cluster-id":"4fda3446f8920824","publish-timeout":"7s"}
{"level":"info","ts":"2023-03-31T17:34:50.317Z","caller":"embed/serve.go:100","msg":"ready to serve client requests"}
{"level":"info","ts":"2023-03-31T17:34:50.318Z","caller":"embed/serve.go:198","msg":"serving client traffic securely","address":"192.168.64.51:2379"}
{"level":"info","ts":"2023-03-31T17:34:50.322Z","caller":"etcdserver/server.go:2563","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2023-03-31T17:34:50.322Z","caller":"embed/serve.go:100","msg":"ready to serve client requests"}
{"level":"info","ts":"2023-03-31T17:34:50.324Z","caller":"embed/serve.go:198","msg":"serving client traffic securely","address":"127.0.0.1:2379"}
{"level":"info","ts":"2023-03-31T17:34:50.324Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4fda3446f8920824","local-member-id":"b8434eb7a3474524","cluster-version":"3.5"}
{"level":"info","ts":"2023-03-31T17:34:50.324Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2023-03-31T17:34:50.325Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2023-03-31T17:34:50.325Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2023-03-31T17:34:50.325Z","caller":"etcdserver/server.go:2587","msg":"cluster version is updated","cluster-version":"3.5"}
*
* ==> kernel <==
* 17:36:24 up 2 min, 0 users, load average: 0.70, 0.32, 0.12
Linux first-656000 5.10.57 #1 SMP Wed Mar 29 23:38:32 UTC 2023 x86_64 GNU/Linux
PRETTY_NAME="Buildroot 2021.02.12"
*
* ==> kube-apiserver [30064c0acbea] <==
* I0331 17:34:51.661132 1 apf_controller.go:366] Running API Priority and Fairness config worker
I0331 17:34:51.661462 1 apf_controller.go:369] Running API Priority and Fairness periodic rebalancing process
I0331 17:34:51.661612 1 cache.go:39] Caches are synced for AvailableConditionController controller
I0331 17:34:51.662087 1 shared_informer.go:280] Caches are synced for cluster_authentication_trust_controller
I0331 17:34:51.662739 1 cache.go:39] Caches are synced for autoregister controller
I0331 17:34:51.663023 1 shared_informer.go:280] Caches are synced for configmaps
I0331 17:34:51.667053 1 shared_informer.go:280] Caches are synced for crd-autoregister
I0331 17:34:51.695759 1 controller.go:615] quota admission added evaluator for: leases.coordination.k8s.io
I0331 17:34:51.699957 1 shared_informer.go:280] Caches are synced for node_authorizer
I0331 17:34:52.380699 1 controller.go:132] OpenAPI AggregationController: action for item k8s_internal_local_delegation_chain_0000000000: Nothing (removed from the queue).
I0331 17:34:52.578099 1 storage_scheduling.go:95] created PriorityClass system-node-critical with value 2000001000
I0331 17:34:52.582431 1 storage_scheduling.go:95] created PriorityClass system-cluster-critical with value 2000000000
I0331 17:34:52.582494 1 storage_scheduling.go:111] all system priority classes are created successfully or already exist.
I0331 17:34:52.930535 1 controller.go:615] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I0331 17:34:52.956345 1 controller.go:615] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
I0331 17:34:53.056166 1 alloc.go:327] "allocated clusterIPs" service="default/kubernetes" clusterIPs=map[IPv4:10.96.0.1]
W0331 17:34:53.059957 1 lease.go:251] Resetting endpoints for master service "kubernetes" to [192.168.64.51]
I0331 17:34:53.060586 1 controller.go:615] quota admission added evaluator for: endpoints
I0331 17:34:53.063049 1 controller.go:615] quota admission added evaluator for: endpointslices.discovery.k8s.io
I0331 17:34:53.612999 1 controller.go:615] quota admission added evaluator for: serviceaccounts
I0331 17:34:54.603119 1 controller.go:615] quota admission added evaluator for: deployments.apps
I0331 17:34:54.609918 1 alloc.go:327] "allocated clusterIPs" service="kube-system/kube-dns" clusterIPs=map[IPv4:10.96.0.10]
I0331 17:34:54.616898 1 controller.go:615] quota admission added evaluator for: daemonsets.apps
I0331 17:35:08.281602 1 controller.go:615] quota admission added evaluator for: replicasets.apps
I0331 17:35:08.331686 1 controller.go:615] quota admission added evaluator for: controllerrevisions.apps
*
* ==> kube-controller-manager [45f91bf085e2] <==
* I0331 17:35:07.639133 1 taint_manager.go:206] "Starting NoExecuteTaintManager"
I0331 17:35:07.639169 1 taint_manager.go:211] "Sending events to api server"
I0331 17:35:07.639417 1 event.go:294] "Event occurred" object="first-656000" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node first-656000 event: Registered Node first-656000 in Controller"
I0331 17:35:07.647913 1 shared_informer.go:280] Caches are synced for daemon sets
I0331 17:35:07.650982 1 shared_informer.go:280] Caches are synced for deployment
I0331 17:35:07.655367 1 shared_informer.go:280] Caches are synced for stateful set
I0331 17:35:07.659982 1 shared_informer.go:280] Caches are synced for ReplicationController
I0331 17:35:07.669476 1 shared_informer.go:280] Caches are synced for endpoint
I0331 17:35:07.679604 1 shared_informer.go:280] Caches are synced for ReplicaSet
I0331 17:35:07.680716 1 shared_informer.go:280] Caches are synced for job
I0331 17:35:07.680822 1 shared_informer.go:280] Caches are synced for ephemeral
I0331 17:35:07.687748 1 shared_informer.go:280] Caches are synced for resource quota
I0331 17:35:07.729038 1 shared_informer.go:280] Caches are synced for HPA
I0331 17:35:07.730366 1 shared_informer.go:280] Caches are synced for PVC protection
I0331 17:35:07.730455 1 shared_informer.go:280] Caches are synced for attach detach
I0331 17:35:07.730575 1 shared_informer.go:280] Caches are synced for persistent volume
I0331 17:35:07.730387 1 shared_informer.go:280] Caches are synced for disruption
I0331 17:35:07.730479 1 shared_informer.go:280] Caches are synced for GC
I0331 17:35:07.730467 1 shared_informer.go:280] Caches are synced for endpoint_slice
I0331 17:35:08.061551 1 shared_informer.go:280] Caches are synced for garbage collector
I0331 17:35:08.130126 1 shared_informer.go:280] Caches are synced for garbage collector
I0331 17:35:08.130235 1 garbagecollector.go:163] Garbage collector: all resource monitors have synced. Proceeding to collect garbage
I0331 17:35:08.284239 1 event.go:294] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-787d4945fb to 1"
I0331 17:35:08.337184 1 event.go:294] "Event occurred" object="kube-system/kube-proxy" fieldPath="" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-v8g6f"
I0331 17:35:08.535087 1 event.go:294] "Event occurred" object="kube-system/coredns-787d4945fb" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-787d4945fb-m9fph"
*
* ==> kube-proxy [7569d05cf58b] <==
* I0331 17:35:09.486581 1 node.go:163] Successfully retrieved node IP: 192.168.64.51
I0331 17:35:09.490122 1 server_others.go:109] "Detected node IP" address="192.168.64.51"
I0331 17:35:09.494233 1 server_others.go:535] "Using iptables proxy"
I0331 17:35:09.566571 1 server_others.go:170] "kube-proxy running in single-stack mode, this ipFamily is not supported" ipFamily=IPv6
I0331 17:35:09.566611 1 server_others.go:176] "Using iptables Proxier"
I0331 17:35:09.566638 1 proxier.go:242] "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"
I0331 17:35:09.566844 1 server.go:655] "Version info" version="v1.26.3"
I0331 17:35:09.566876 1 server.go:657] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
I0331 17:35:09.567830 1 config.go:317] "Starting service config controller"
I0331 17:35:09.567863 1 shared_informer.go:273] Waiting for caches to sync for service config
I0331 17:35:09.567879 1 config.go:226] "Starting endpoint slice config controller"
I0331 17:35:09.567882 1 shared_informer.go:273] Waiting for caches to sync for endpoint slice config
I0331 17:35:09.571542 1 config.go:444] "Starting node config controller"
I0331 17:35:09.571552 1 shared_informer.go:273] Waiting for caches to sync for node config
I0331 17:35:09.668304 1 shared_informer.go:280] Caches are synced for endpoint slice config
I0331 17:35:09.668367 1 shared_informer.go:280] Caches are synced for service config
I0331 17:35:09.671605 1 shared_informer.go:280] Caches are synced for node config
*
* ==> kube-scheduler [754a7384e5c4] <==
* W0331 17:34:51.659920 1 reflector.go:424] 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
E0331 17:34:51.660128 1 reflector.go:140] 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
W0331 17:34:51.659925 1 reflector.go:424] 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
E0331 17:34:51.660136 1 reflector.go:140] 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
W0331 17:34:51.659962 1 reflector.go:424] 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
E0331 17:34:51.660290 1 reflector.go:140] 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
W0331 17:34:52.524010 1 reflector.go:424] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIStorageCapacity: csistoragecapacities.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csistoragecapacities" in API group "storage.k8s.io" at the cluster scope
E0331 17:34:52.524102 1 reflector.go:140] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIStorageCapacity: failed to list *v1.CSIStorageCapacity: csistoragecapacities.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csistoragecapacities" in API group "storage.k8s.io" at the cluster scope
W0331 17:34:52.614717 1 reflector.go:424] 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
E0331 17:34:52.614812 1 reflector.go:140] 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
W0331 17:34:52.618805 1 reflector.go:424] 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
E0331 17:34:52.619459 1 reflector.go:140] 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
W0331 17:34:52.649636 1 reflector.go:424] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
E0331 17:34:52.649653 1 reflector.go:140] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.PersistentVolumeClaim: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
W0331 17:34:52.673226 1 reflector.go:424] 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
E0331 17:34:52.673264 1 reflector.go:140] 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
W0331 17:34:52.725039 1 reflector.go:424] 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
E0331 17:34:52.725078 1 reflector.go:140] 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
W0331 17:34:52.736170 1 reflector.go:424] pkg/server/dynamiccertificates/configmap_cafile_content.go:206: failed to list *v1.ConfigMap: configmaps "extension-apiserver-authentication" is forbidden: User "system:kube-scheduler" cannot list resource "configmaps" in API group "" in the namespace "kube-system"
E0331 17:34:52.736207 1 reflector.go:140] pkg/server/dynamiccertificates/configmap_cafile_content.go:206: Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "extension-apiserver-authentication" is forbidden: User "system:kube-scheduler" cannot list resource "configmaps" in API group "" in the namespace "kube-system"
W0331 17:34:52.758931 1 reflector.go:424] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.StorageClass: storageclasses.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "storageclasses" in API group "storage.k8s.io" at the cluster scope
E0331 17:34:52.758971 1 reflector.go:140] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.StorageClass: failed to list *v1.StorageClass: storageclasses.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "storageclasses" in API group "storage.k8s.io" at the cluster scope
W0331 17:34:52.822016 1 reflector.go:424] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Pod: pods is forbidden: User "system:kube-scheduler" cannot list resource "pods" in API group "" at the cluster scope
E0331 17:34:52.822054 1 reflector.go:140] 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
I0331 17:34:55.050752 1 shared_informer.go:280] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
*
* ==> kubelet <==
* -- Journal begins at Fri 2023-03-31 17:34:28 UTC, ends at Fri 2023-03-31 17:36:25 UTC. --
Mar 31 17:34:56 first-656000 kubelet[2419]: I0331 17:34:56.876421 2419 request.go:690] Waited for 1.087673541s due to client-side throttling, not priority and fairness, request: POST:https://control-plane.minikube.internal:8443/api/v1/namespaces/kube-system/pods
Mar 31 17:34:56 first-656000 kubelet[2419]: E0331 17:34:56.880763 2419 kubelet.go:1802] "Failed creating a mirror pod for" err="pods \"kube-scheduler-first-656000\" already exists" pod="kube-system/kube-scheduler-first-656000"
Mar 31 17:34:57 first-656000 kubelet[2419]: I0331 17:34:57.084799 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-controller-manager-first-656000" podStartSLOduration=3.084743679 pod.CreationTimestamp="2023-03-31 17:34:54 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:34:57.08443505 +0000 UTC m=+2.494460591" watchObservedRunningTime="2023-03-31 17:34:57.084743679 +0000 UTC m=+2.494769216"
Mar 31 17:34:57 first-656000 kubelet[2419]: I0331 17:34:57.481177 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-apiserver-first-656000" podStartSLOduration=4.481123031 pod.CreationTimestamp="2023-03-31 17:34:53 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:34:57.481060999 +0000 UTC m=+2.891086536" watchObservedRunningTime="2023-03-31 17:34:57.481123031 +0000 UTC m=+2.891148567"
Mar 31 17:34:58 first-656000 kubelet[2419]: I0331 17:34:58.607733 2419 kubelet_node_status.go:493] "Fast updating node status as it just became ready"
Mar 31 17:34:58 first-656000 kubelet[2419]: I0331 17:34:58.678651 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/etcd-first-656000" podStartSLOduration=6.678625569 pod.CreationTimestamp="2023-03-31 17:34:52 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:34:58.280308227 +0000 UTC m=+3.690333769" watchObservedRunningTime="2023-03-31 17:34:58.678625569 +0000 UTC m=+4.088651103"
Mar 31 17:34:59 first-656000 kubelet[2419]: I0331 17:34:59.079750 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-scheduler-first-656000" podStartSLOduration=5.079724278 pod.CreationTimestamp="2023-03-31 17:34:54 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:34:58.678950413 +0000 UTC m=+4.088975948" watchObservedRunningTime="2023-03-31 17:34:59.079724278 +0000 UTC m=+4.489749814"
Mar 31 17:35:07 first-656000 kubelet[2419]: I0331 17:35:07.648291 2419 topology_manager.go:210] "Topology Admit Handler"
Mar 31 17:35:07 first-656000 kubelet[2419]: W0331 17:35:07.654767 2419 reflector.go:424] object-"kube-system"/"kube-root-ca.crt": failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:first-656000" cannot list resource "configmaps" in API group "" in the namespace "kube-system": no relationship found between node 'first-656000' and this object
Mar 31 17:35:07 first-656000 kubelet[2419]: E0331 17:35:07.654812 2419 reflector.go:140] object-"kube-system"/"kube-root-ca.crt": Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:first-656000" cannot list resource "configmaps" in API group "" in the namespace "kube-system": no relationship found between node 'first-656000' and this object
Mar 31 17:35:07 first-656000 kubelet[2419]: I0331 17:35:07.790587 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"tmp\" (UniqueName: \"kubernetes.io/host-path/3d4b159b-5534-4fdc-9f19-b997139d04ea-tmp\") pod \"storage-provisioner\" (UID: \"3d4b159b-5534-4fdc-9f19-b997139d04ea\") " pod="kube-system/storage-provisioner"
Mar 31 17:35:07 first-656000 kubelet[2419]: I0331 17:35:07.790640 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-st562\" (UniqueName: \"kubernetes.io/projected/3d4b159b-5534-4fdc-9f19-b997139d04ea-kube-api-access-st562\") pod \"storage-provisioner\" (UID: \"3d4b159b-5534-4fdc-9f19-b997139d04ea\") " pod="kube-system/storage-provisioner"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.342126 2419 topology_manager.go:210] "Topology Admit Handler"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.496142 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/9d2b6836-7c85-4358-8703-9de43cf494f7-kube-proxy\") pod \"kube-proxy-v8g6f\" (UID: \"9d2b6836-7c85-4358-8703-9de43cf494f7\") " pod="kube-system/kube-proxy-v8g6f"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.496196 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/9d2b6836-7c85-4358-8703-9de43cf494f7-lib-modules\") pod \"kube-proxy-v8g6f\" (UID: \"9d2b6836-7c85-4358-8703-9de43cf494f7\") " pod="kube-system/kube-proxy-v8g6f"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.496222 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/9d2b6836-7c85-4358-8703-9de43cf494f7-xtables-lock\") pod \"kube-proxy-v8g6f\" (UID: \"9d2b6836-7c85-4358-8703-9de43cf494f7\") " pod="kube-system/kube-proxy-v8g6f"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.496239 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kltgz\" (UniqueName: \"kubernetes.io/projected/9d2b6836-7c85-4358-8703-9de43cf494f7-kube-api-access-kltgz\") pod \"kube-proxy-v8g6f\" (UID: \"9d2b6836-7c85-4358-8703-9de43cf494f7\") " pod="kube-system/kube-proxy-v8g6f"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.539604 2419 topology_manager.go:210] "Topology Admit Handler"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.697604 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"config-volume\" (UniqueName: \"kubernetes.io/configmap/34171faf-9d34-4fc0-871d-1c5cba86e87f-config-volume\") pod \"coredns-787d4945fb-m9fph\" (UID: \"34171faf-9d34-4fc0-871d-1c5cba86e87f\") " pod="kube-system/coredns-787d4945fb-m9fph"
Mar 31 17:35:08 first-656000 kubelet[2419]: I0331 17:35:08.697664 2419 reconciler_common.go:253] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s6bpb\" (UniqueName: \"kubernetes.io/projected/34171faf-9d34-4fc0-871d-1c5cba86e87f-kube-api-access-s6bpb\") pod \"coredns-787d4945fb-m9fph\" (UID: \"34171faf-9d34-4fc0-871d-1c5cba86e87f\") " pod="kube-system/coredns-787d4945fb-m9fph"
Mar 31 17:35:09 first-656000 kubelet[2419]: I0331 17:35:09.882382 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/coredns-787d4945fb-m9fph" podStartSLOduration=1.8823551109999999 pod.CreationTimestamp="2023-03-31 17:35:08 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:35:09.882147635 +0000 UTC m=+15.292173179" watchObservedRunningTime="2023-03-31 17:35:09.882355111 +0000 UTC m=+15.292380655"
Mar 31 17:35:10 first-656000 kubelet[2419]: I0331 17:35:10.945522 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-v8g6f" podStartSLOduration=2.944740429 pod.CreationTimestamp="2023-03-31 17:35:08 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:35:09.91030197 +0000 UTC m=+15.320327517" watchObservedRunningTime="2023-03-31 17:35:10.944740429 +0000 UTC m=+16.354765965"
Mar 31 17:35:10 first-656000 kubelet[2419]: I0331 17:35:10.962479 2419 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/storage-provisioner" podStartSLOduration=14.962403539 pod.CreationTimestamp="2023-03-31 17:34:56 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-03-31 17:35:10.946128245 +0000 UTC m=+16.356153777" watchObservedRunningTime="2023-03-31 17:35:10.962403539 +0000 UTC m=+16.372429075"
Mar 31 17:35:15 first-656000 kubelet[2419]: I0331 17:35:15.121726 2419 kuberuntime_manager.go:1114] "Updating runtime config through cri with podcidr" CIDR="10.244.0.0/24"
Mar 31 17:35:15 first-656000 kubelet[2419]: I0331 17:35:15.123314 2419 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="10.244.0.0/24"
*
* ==> storage-provisioner [b1752c0d07a4] <==
* I0331 17:35:09.950322 1 storage_provisioner.go:116] Initializing the minikube storage provisioner...
I0331 17:35:09.961776 1 storage_provisioner.go:141] Storage provisioner initialized, now starting service!
I0331 17:35:09.961834 1 leaderelection.go:243] attempting to acquire leader lease kube-system/k8s.io-minikube-hostpath...
I0331 17:35:09.973715 1 leaderelection.go:253] successfully acquired lease kube-system/k8s.io-minikube-hostpath
I0331 17:35:09.974449 1 event.go:282] Event(v1.ObjectReference{Kind:"Endpoints", Namespace:"kube-system", Name:"k8s.io-minikube-hostpath", UID:"b07318f1-7294-45f7-b56c-7bb482005e3d", APIVersion:"v1", ResourceVersion:"367", FieldPath:""}): type: 'Normal' reason: 'LeaderElection' first-656000_331e9a6d-98f1-41ef-b2dc-34fd03d9bc3f became leader
I0331 17:35:09.979518 1 controller.go:835] Starting provisioner controller k8s.io/minikube-hostpath_first-656000_331e9a6d-98f1-41ef-b2dc-34fd03d9bc3f!
I0331 17:35:10.080707 1 controller.go:884] Started provisioner controller k8s.io/minikube-hostpath_first-656000_331e9a6d-98f1-41ef-b2dc-34fd03d9bc3f!
-- /stdout --
helpers_test.go:254: (dbg) Run: out/minikube-darwin-amd64 status --format={{.APIServer}} -p first-656000 -n first-656000
helpers_test.go:261: (dbg) Run: kubectl --context first-656000 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-656000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p first-656000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p first-656000: (5.272755502s)
--- FAIL: TestMinikubeProfile (132.40s)