=== RUN TestMinikubeProfile
minikube_profile_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p first-013000 --driver=hyperkit
E0707 15:59:43.184771 29643 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/16845-29196/.minikube/profiles/functional-571000/client.crt: no such file or directory
minikube_profile_test.go:44: (dbg) Done: out/minikube-darwin-amd64 start -p first-013000 --driver=hyperkit : (39.42487921s)
minikube_profile_test.go:44: (dbg) Run: out/minikube-darwin-amd64 start -p second-015000 --driver=hyperkit
minikube_profile_test.go:44: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p second-015000 --driver=hyperkit : exit status 90 (18.275909206s)
-- stdout --
* [second-015000] minikube v1.30.1 on Darwin 13.4.1
- MINIKUBE_LOCATION=16845
- KUBECONFIG=/Users/jenkins/minikube-integration/16845-29196/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/16845-29196/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting control plane node second-015000 in cluster second-015000
* Creating hyperkit VM (CPUs=2, Memory=6000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
*
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * 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-015000 --driver=hyperkit ": exit status 90
panic.go:522: *** TestMinikubeProfile FAILED at 2023-07-07 16:00:16.377381 -0700 PDT m=+986.664689314
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p second-015000 -n second-015000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p second-015000 -n second-015000: exit status 6 (133.590181ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0707 16:00:16.500328 31361 status.go:415] kubeconfig endpoint: extract IP: "second-015000" does not appear in /Users/jenkins/minikube-integration/16845-29196/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "second-015000" 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-015000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p second-015000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p second-015000: (5.337408883s)
panic.go:522: *** TestMinikubeProfile FAILED at 2023-07-07 16:00:21.848825 -0700 PDT m=+992.136013538
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p first-013000 -n first-013000
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-013000 logs -n 25
helpers_test.go:247: (dbg) Done: out/minikube-darwin-amd64 -p first-013000 logs -n 25: (1.996264391s)
helpers_test.go:252: TestMinikubeProfile logs:
-- stdout --
*
* ==> Audit <==
* |---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
| delete | -p functional-571000 | functional-571000 | jenkins | v1.30.1 | 07 Jul 23 15:54 PDT | 07 Jul 23 15:54 PDT |
| start | -p image-371000 | image-371000 | jenkins | v1.30.1 | 07 Jul 23 15:54 PDT | 07 Jul 23 15:55 PDT |
| | --driver=hyperkit | | | | | |
| image | build -t aaa:latest | image-371000 | jenkins | v1.30.1 | 07 Jul 23 15:55 PDT | 07 Jul 23 15:55 PDT |
| | ./testdata/image-build/test-normal | | | | | |
| | -p image-371000 | | | | | |
| image | build -t aaa:latest | image-371000 | jenkins | v1.30.1 | 07 Jul 23 15:55 PDT | 07 Jul 23 15:55 PDT |
| | --build-opt=build-arg=ENV_A=test_env_str | | | | | |
| | --build-opt=no-cache | | | | | |
| | ./testdata/image-build/test-arg -p | | | | | |
| | image-371000 | | | | | |
| image | build -t aaa:latest | image-371000 | jenkins | v1.30.1 | 07 Jul 23 15:55 PDT | 07 Jul 23 15:55 PDT |
| | ./testdata/image-build/test-normal | | | | | |
| | --build-opt=no-cache -p | | | | | |
| | image-371000 | | | | | |
| image | build -t aaa:latest | image-371000 | jenkins | v1.30.1 | 07 Jul 23 15:55 PDT | 07 Jul 23 15:55 PDT |
| | -f inner/Dockerfile | | | | | |
| | ./testdata/image-build/test-f | | | | | |
| | -p image-371000 | | | | | |
| delete | -p image-371000 | image-371000 | jenkins | v1.30.1 | 07 Jul 23 15:55 PDT | 07 Jul 23 15:55 PDT |
| start | -p ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:55 PDT | 07 Jul 23 15:56 PDT |
| | --kubernetes-version=v1.18.20 | | | | | |
| | --memory=4096 --wait=true | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| | --driver=hyperkit | | | | | |
| addons | ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:56 PDT | 07 Jul 23 15:57 PDT |
| | addons enable ingress | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| addons | ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:57 PDT |
| | addons enable ingress-dns | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| ssh | ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:57 PDT |
| | ssh curl -s http://127.0.0.1/ | | | | | |
| | -H 'Host: nginx.example.com' | | | | | |
| ip | ingress-addon-legacy-298000 ip | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:57 PDT |
| addons | ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:57 PDT |
| | addons disable ingress-dns | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| addons | ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:57 PDT |
| | addons disable ingress | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| delete | -p ingress-addon-legacy-298000 | ingress-addon-legacy-298000 | jenkins | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:57 PDT |
| start | -p json-output-146000 | json-output-146000 | testUser | v1.30.1 | 07 Jul 23 15:57 PDT | 07 Jul 23 15:59 PDT |
| | --output=json --user=testUser | | | | | |
| | --memory=2200 --wait=true | | | | | |
| | --driver=hyperkit | | | | | |
| pause | -p json-output-146000 | json-output-146000 | testUser | v1.30.1 | 07 Jul 23 15:59 PDT | 07 Jul 23 15:59 PDT |
| | --output=json --user=testUser | | | | | |
| unpause | -p json-output-146000 | json-output-146000 | testUser | v1.30.1 | 07 Jul 23 15:59 PDT | 07 Jul 23 15:59 PDT |
| | --output=json --user=testUser | | | | | |
| stop | -p json-output-146000 | json-output-146000 | testUser | v1.30.1 | 07 Jul 23 15:59 PDT | 07 Jul 23 15:59 PDT |
| | --output=json --user=testUser | | | | | |
| delete | -p json-output-146000 | json-output-146000 | jenkins | v1.30.1 | 07 Jul 23 15:59 PDT | 07 Jul 23 15:59 PDT |
| start | -p json-output-error-432000 | json-output-error-432000 | jenkins | v1.30.1 | 07 Jul 23 15:59 PDT | |
| | --memory=2200 --output=json | | | | | |
| | --wait=true --driver=fail | | | | | |
| delete | -p json-output-error-432000 | json-output-error-432000 | jenkins | v1.30.1 | 07 Jul 23 15:59 PDT | 07 Jul 23 15:59 PDT |
| start | -p first-013000 | first-013000 | jenkins | v1.30.1 | 07 Jul 23 15:59 PDT | 07 Jul 23 15:59 PDT |
| | --driver=hyperkit | | | | | |
| start | -p second-015000 | second-015000 | jenkins | v1.30.1 | 07 Jul 23 15:59 PDT | |
| | --driver=hyperkit | | | | | |
| delete | -p second-015000 | second-015000 | jenkins | v1.30.1 | 07 Jul 23 16:00 PDT | 07 Jul 23 16:00 PDT |
|---------|------------------------------------------|-----------------------------|----------|---------|---------------------|---------------------|
*
* ==> Last Start <==
* Log file created at: 2023/07/07 15:59:58
Running on machine: MacOS-Agent-3
Binary: Built with gc go1.20.5 for darwin/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I0707 15:59:58.141099 31333 out.go:296] Setting OutFile to fd 1 ...
I0707 15:59:58.141261 31333 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0707 15:59:58.141265 31333 out.go:309] Setting ErrFile to fd 2...
I0707 15:59:58.141267 31333 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0707 15:59:58.141381 31333 root.go:336] Updating PATH: /Users/jenkins/minikube-integration/16845-29196/.minikube/bin
I0707 15:59:58.142864 31333 out.go:303] Setting JSON to false
I0707 15:59:58.162066 31333 start.go:127] hostinfo: {"hostname":"MacOS-Agent-3.local","uptime":10763,"bootTime":1688760035,"procs":399,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"13.4.1","kernelVersion":"22.5.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"bd1c05a8-24a6-5973-aa69-f3c7c66a87ce"}
W0707 15:59:58.162138 31333 start.go:135] gopshost.Virtualization returned error: not implemented yet
I0707 15:59:58.182545 31333 out.go:177] * [second-015000] minikube v1.30.1 on Darwin 13.4.1
I0707 15:59:58.224799 31333 notify.go:220] Checking for updates...
I0707 15:59:58.250758 31333 out.go:177] - MINIKUBE_LOCATION=16845
I0707 15:59:58.296692 31333 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/16845-29196/kubeconfig
I0707 15:59:58.337574 31333 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0707 15:59:58.398922 31333 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0707 15:59:58.419762 31333 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/16845-29196/.minikube
I0707 15:59:58.462666 31333 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0707 15:59:58.484139 31333 config.go:182] Loaded profile config "first-013000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.27.3
I0707 15:59:58.484247 31333 driver.go:373] Setting default libvirt URI to qemu:///system
I0707 15:59:58.512983 31333 out.go:177] * Using the hyperkit driver based on user configuration
I0707 15:59:58.554654 31333 start.go:297] selected driver: hyperkit
I0707 15:59:58.554668 31333 start.go:944] validating driver "hyperkit" against <nil>
I0707 15:59:58.554685 31333 start.go:955] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0707 15:59:58.554896 31333 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0707 15:59:58.555107 31333 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/16845-29196/.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
I0707 15:59:58.563394 31333 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.30.1
I0707 15:59:58.566774 31333 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0707 15:59:58.566787 31333 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0707 15:59:58.566819 31333 start_flags.go:305] no existing cluster config was found, will generate one from the flags
I0707 15:59:58.569081 31333 start_flags.go:382] Using suggested 6000MB memory alloc based on sys=32768MB, container=0MB
I0707 15:59:58.569238 31333 start_flags.go:901] Wait components to verify : map[apiserver:true system_pods:true]
I0707 15:59:58.569255 31333 cni.go:84] Creating CNI manager for ""
I0707 15:59:58.569268 31333 cni.go:152] "hyperkit" driver + "docker" runtime found, recommending bridge
I0707 15:59:58.569274 31333 start_flags.go:314] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0707 15:59:58.569282 31333 start_flags.go:319] config:
{Name:second-015000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1688681246-16834@sha256:849205234efc46da016f3a964268d7c76363fc521532c280d0e8a6bf1cc393b5 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.27.3 ClusterName:second-015000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker
CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0}
I0707 15:59:58.569419 31333 iso.go:125] acquiring lock: {Name:mkc26c030f62bdf6e3ab619c68665518d3e66b24 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0707 15:59:58.611778 31333 out.go:177] * Starting control plane node second-015000 in cluster second-015000
I0707 15:59:58.632786 31333 preload.go:132] Checking if preload exists for k8s version v1.27.3 and runtime docker
I0707 15:59:58.632858 31333 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/16845-29196/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.27.3-docker-overlay2-amd64.tar.lz4
I0707 15:59:58.632886 31333 cache.go:57] Caching tarball of preloaded images
I0707 15:59:58.633070 31333 preload.go:174] Found /Users/jenkins/minikube-integration/16845-29196/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.27.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0707 15:59:58.633086 31333 cache.go:60] Finished verifying existence of preloaded tar for v1.27.3 on docker
I0707 15:59:58.633262 31333 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16845-29196/.minikube/profiles/second-015000/config.json ...
I0707 15:59:58.633307 31333 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/16845-29196/.minikube/profiles/second-015000/config.json: {Name:mk0e380f76caf6d048e3437fec4eb46f6f3152d8 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0707 15:59:58.633928 31333 start.go:365] acquiring machines lock for second-015000: {Name:mk81f6152b3f423bf222fad0025fe3c8ddb3ea12 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0707 15:59:58.634018 31333 start.go:369] acquired machines lock for "second-015000" in 74.681µs
I0707 15:59:58.634057 31333 start.go:93] Provisioning new machine with config: &{Name:second-015000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/16765/minikube-v1.30.1-1688144767-16765-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1688681246-16834@sha256:849205234efc46da016f3a964268d7c76363fc521532c280d0e8a6bf1cc393b5 Memory:6000 CPUs:2 DiskSize:20000 VMDriver: Driver:hyperkit HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesCo
nfig:{KubernetesVersion:v1.27.3 ClusterName:second-015000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.27.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: DisableO
ptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0} &{Name: IP: Port:8443 KubernetesVersion:v1.27.3 ContainerRuntime:docker ControlPlane:true Worker:true}
I0707 15:59:58.634137 31333 start.go:125] createHost starting for "" (driver="hyperkit")
I0707 15:59:58.682634 31333 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=6000MB, Disk=20000MB) ...
I0707 15:59:58.683117 31333 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0707 15:59:58.683797 31333 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0707 15:59:58.692342 31333 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:64713
I0707 15:59:58.692688 31333 main.go:141] libmachine: () Calling .GetVersion
I0707 15:59:58.693109 31333 main.go:141] libmachine: Using API Version 1
I0707 15:59:58.693117 31333 main.go:141] libmachine: () Calling .SetConfigRaw
I0707 15:59:58.693354 31333 main.go:141] libmachine: () Calling .GetMachineName
I0707 15:59:58.693451 31333 main.go:141] libmachine: (second-015000) Calling .GetMachineName
I0707 15:59:58.693541 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 15:59:58.693629 31333 start.go:159] libmachine.API.Create for "second-015000" (driver="hyperkit")
I0707 15:59:58.693644 31333 client.go:168] LocalClient.Create starting
I0707 15:59:58.693680 31333 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca.pem
I0707 15:59:58.693719 31333 main.go:141] libmachine: Decoding PEM data...
I0707 15:59:58.693732 31333 main.go:141] libmachine: Parsing certificate...
I0707 15:59:58.693795 31333 main.go:141] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/16845-29196/.minikube/certs/cert.pem
I0707 15:59:58.693816 31333 main.go:141] libmachine: Decoding PEM data...
I0707 15:59:58.693824 31333 main.go:141] libmachine: Parsing certificate...
I0707 15:59:58.693835 31333 main.go:141] libmachine: Running pre-create checks...
I0707 15:59:58.693841 31333 main.go:141] libmachine: (second-015000) Calling .PreCreateCheck
I0707 15:59:58.693913 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 15:59:58.694080 31333 main.go:141] libmachine: (second-015000) Calling .GetConfigRaw
I0707 15:59:58.694489 31333 main.go:141] libmachine: Creating machine...
I0707 15:59:58.694494 31333 main.go:141] libmachine: (second-015000) Calling .Create
I0707 15:59:58.694568 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 15:59:58.694691 31333 main.go:141] libmachine: (second-015000) DBG | I0707 15:59:58.694566 31341 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/16845-29196/.minikube
I0707 15:59:58.694741 31333 main.go:141] libmachine: (second-015000) Downloading /Users/jenkins/minikube-integration/16845-29196/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/16845-29196/.minikube/cache/iso/amd64/minikube-v1.30.1-1688144767-16765-amd64.iso...
I0707 15:59:58.924117 31333 main.go:141] libmachine: (second-015000) DBG | I0707 15:59:58.924038 31341 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/id_rsa...
I0707 15:59:59.098720 31333 main.go:141] libmachine: (second-015000) DBG | I0707 15:59:59.098631 31341 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/second-015000.rawdisk...
I0707 15:59:59.098733 31333 main.go:141] libmachine: (second-015000) DBG | Writing magic tar header
I0707 15:59:59.098742 31333 main.go:141] libmachine: (second-015000) DBG | Writing SSH key tar header
I0707 15:59:59.099212 31333 main.go:141] libmachine: (second-015000) DBG | I0707 15:59:59.099171 31341 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000 ...
I0707 15:59:59.443312 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 15:59:59.443328 31333 main.go:141] libmachine: (second-015000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/hyperkit.pid
I0707 15:59:59.443366 31333 main.go:141] libmachine: (second-015000) DBG | Using UUID ff2155b8-1d19-11ee-83e4-149d997f80ea
I0707 15:59:59.466757 31333 main.go:141] libmachine: (second-015000) DBG | Generated MAC 5a:57:82:35:3f:0
I0707 15:59:59.466779 31333 main.go:141] libmachine: (second-015000) 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-015000
I0707 15:59:59.466808 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"ff2155b8-1d19-11ee-83e4-149d997f80ea", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000110420)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/initrd", Bootrom:"", CPUs:2, Memory:6000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0707 15:59:59.466831 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"ff2155b8-1d19-11ee-83e4-149d997f80ea", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000110420)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/bzimage", Initrd:"/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/initrd", Bootrom:"", CPUs:2, Memory:6000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I0707 15:59:59.466891 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/hyperkit.pid", "-c", "2", "-m", "6000M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "ff2155b8-1d19-11ee-83e4-149d997f80ea", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/second-015000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/tty,log=/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/bzimage,/Users/jenkins/minikube-integration/16845-29196/.minikube/
machines/second-015000/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-015000"}
I0707 15:59:59.466926 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/hyperkit.pid -c 2 -m 6000M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U ff2155b8-1d19-11ee-83e4-149d997f80ea -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/second-015000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/tty,log=/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/console-ring -f kexec,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/bzimage,/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/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-015000"
I0707 15:59:59.466935 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I0707 15:59:59.469433 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 DEBUG: hyperkit: Pid is 31342
I0707 15:59:59.469854 31333 main.go:141] libmachine: (second-015000) DBG | Attempt 0
I0707 15:59:59.469870 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 15:59:59.469903 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 15:59:59.470731 31333 main.go:141] libmachine: (second-015000) DBG | Searching for 5a:57:82:35:3f:0 in /var/db/dhcpd_leases ...
I0707 15:59:59.470816 31333 main.go:141] libmachine: (second-015000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0707 15:59:59.470838 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:2a:a0:3b:ac:43:5 ID:1,2a:a0:3b:ac:43:5 Lease:0x64a9ea51}
I0707 15:59:59.470845 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ef:40:f6:89:98 ID:1,e:ef:40:f6:89:98 Lease:0x64a9e9f8}
I0707 15:59:59.470854 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:2a:32:21:a:c9:ea ID:1,2a:32:21:a:c9:ea Lease:0x64a9e979}
I0707 15:59:59.470859 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:22:ae:6d:e6:53:22 ID:1,22:ae:6d:e6:53:22 Lease:0x64a9e93c}
I0707 15:59:59.470878 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:a:15:e7:c7:12 ID:1,d2:a:15:e7:c7:12 Lease:0x64a9e851}
I0707 15:59:59.470894 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:4d:70:3e:72:d7 ID:1,8a:4d:70:3e:72:d7 Lease:0x64a9e823}
I0707 15:59:59.470902 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:1a:8e:ab:d5:52:f4 ID:1,1a:8e:ab:d5:52:f4 Lease:0x64a9e6d4}
I0707 15:59:59.470908 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:72:19:49:64:8b:18 ID:1,72:19:49:64:8b:18 Lease:0x64a9e658}
I0707 15:59:59.470913 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:8a:f2:e1:4a:aa:55 ID:1,8a:f2:e1:4a:aa:55 Lease:0x64a9e511}
I0707 15:59:59.470919 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:71:99:1a:a1:ca ID:1,36:71:99:1a:a1:ca Lease:0x64a9e3df}
I0707 15:59:59.470923 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:26:7d:94:3e:ce:86 ID:1,26:7d:94:3e:ce:86 Lease:0x64a9e25c}
I0707 15:59:59.470933 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:be:9b:40:44:bd:5e ID:1,be:9b:40:44:bd:5e Lease:0x64a9e299}
I0707 15:59:59.470938 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:92:23:35:2f:b4:e0 ID:1,92:23:35:2f:b4:e0 Lease:0x64a9e1d4}
I0707 15:59:59.470943 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:ca:78:94:69:83:9d ID:1,ca:78:94:69:83:9d Lease:0x64a9e1a1}
I0707 15:59:59.470948 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:2e:6b:da:4d:43:63 ID:1,2e:6b:da:4d:43:63 Lease:0x64a9e17a}
I0707 15:59:59.470954 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:1a:1e:78:c2:3:5b ID:1,1a:1e:78:c2:3:5b Lease:0x64a9e14d}
I0707 15:59:59.470959 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:56:21:77:6e:3e:d7 ID:1,56:21:77:6e:3e:d7 Lease:0x64a9e11c}
I0707 15:59:59.470969 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:b6:79:6d:78:e5:91 ID:1,b6:79:6d:78:e5:91 Lease:0x64a9e0df}
I0707 15:59:59.470976 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:d:6:69:70:57 ID:1,e:d:6:69:70:57 Lease:0x64a9e0b7}
I0707 15:59:59.470982 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:12:c:fc:38:1b:7d ID:1,12:c:fc:38:1b:7d Lease:0x64a9e07e}
I0707 15:59:59.470988 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2a:be:6e:1c:25:23 ID:1,2a:be:6e:1c:25:23 Lease:0x64a9e029}
I0707 15:59:59.470994 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:f6:26:cc:59:fb:86 ID:1,f6:26:cc:59:fb:86 Lease:0x64a9e002}
I0707 15:59:59.471001 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:32:f4:fc:97:bb:c5 ID:1,32:f4:fc:97:bb:c5 Lease:0x64a9dfd9}
I0707 15:59:59.471007 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:f2:ac:14:df:2:e1 ID:1,f2:ac:14:df:2:e1 Lease:0x64a9dfbd}
I0707 15:59:59.471013 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:86:40:da:85:93:5 ID:1,86:40:da:85:93:5 Lease:0x64a9dfaf}
I0707 15:59:59.471018 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:96:a2:d0:1b:cc:48 ID:1,96:a2:d0:1b:cc:48 Lease:0x64a88e33}
I0707 15:59:59.471023 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:7e:18:98:3:79:ac ID:1,7e:18:98:3:79:ac Lease:0x64a88e11}
I0707 15:59:59.471028 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:a6:24:7b:fc:77:e8 ID:1,a6:24:7b:fc:77:e8 Lease:0x64a88dd3}
I0707 15:59:59.471047 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:da:c2:99:f7:b9:2b ID:1,da:c2:99:f7:b9:2b Lease:0x64a9def7}
I0707 15:59:59.471059 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:12:2c:d8:d3:98:3d ID:1,12:2c:d8:d3:98:3d Lease:0x64a9de95}
I0707 15:59:59.471065 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:96:63:2d:24:c0:8e ID:1,96:63:2d:24:c0:8e Lease:0x64a9deac}
I0707 15:59:59.471070 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:ce:d:af:fb:a4:29 ID:1,ce:d:af:fb:a4:29 Lease:0x64a88d00}
I0707 15:59:59.471080 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:c6:d1:4d:d1:c6:c6 ID:1,c6:d1:4d:d1:c6:c6 Lease:0x64a9ddd3}
I0707 15:59:59.471085 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:4a:0:ad:11:5a:b8 ID:1,4a:0:ad:11:5a:b8 Lease:0x64a9dd66}
I0707 15:59:59.471091 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:76:24:4a:dc:55:63 ID:1,76:24:4a:dc:55:63 Lease:0x64a9dcf9}
I0707 15:59:59.471096 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:7a:76:a9:a4:41:d6 ID:1,7a:76:a9:a4:41:d6 Lease:0x64a9dcac}
I0707 15:59:59.471104 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:9a:d4:1c:20:49:9a ID:1,9a:d4:1c:20:49:9a Lease:0x64a88ab6}
I0707 15:59:59.471112 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:5e:28:a1:fd:5:74 ID:1,5e:28:a1:fd:5:74 Lease:0x64a88a2a}
I0707 15:59:59.471121 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:f2:9f:60:b5:67 ID:1,5e:f2:9f:60:b5:67 Lease:0x64a9dbf9}
I0707 15:59:59.471128 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:ba:29:cf:65:9a:f6 ID:1,ba:29:cf:65:9a:f6 Lease:0x64a9dbc4}
I0707 15:59:59.471134 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:c2:46:a3:47:d0:6f ID:1,c2:46:a3:47:d0:6f Lease:0x64a888da}
I0707 15:59:59.471139 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:e6:f8:da:7f:b0:2 ID:1,e6:f8:da:7f:b0:2 Lease:0x64a888ac}
I0707 15:59:59.471144 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:1a:e8:95:cf:b7:2c ID:1,1a:e8:95:cf:b7:2c Lease:0x64a9d9e1}
I0707 15:59:59.471153 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:82:60:aa:b:ac:82 ID:1,82:60:aa:b:ac:82 Lease:0x64a9d9bc}
I0707 15:59:59.471160 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:b2:5e:c6:47:87:ac ID:1,b2:5e:c6:47:87:ac Lease:0x64a9d97e}
I0707 15:59:59.471167 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9a:cd:ff:b7:63:f ID:1,9a:cd:ff:b7:63:f Lease:0x64a9d8f8}
I0707 15:59:59.471173 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:d2:28:10:10:80:9f ID:1,d2:28:10:10:80:9f Lease:0x64a9d8c0}
I0707 15:59:59.471178 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:e:3f:ec:83:e4:c9 ID:1,e:3f:ec:83:e4:c9 Lease:0x64a9d7c9}
I0707 15:59:59.471192 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:92:45:cd:92:c5:57 ID:1,92:45:cd:92:c5:57 Lease:0x64a8863e}
I0707 15:59:59.471206 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ee:ec:a1:83:cb:bd ID:1,ee:ec:a1:83:cb:bd Lease:0x64a9d66a}
I0707 15:59:59.476154 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I0707 15:59:59.486369 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I0707 15:59:59.487142 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0707 15:59:59.487159 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0707 15:59:59.487166 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0707 15:59:59.487171 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 15:59:59 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0707 16:00:00.053386 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I0707 16:00:00.053400 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I0707 16:00:00.158517 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I0707 16:00:00.158530 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I0707 16:00:00.158538 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I0707 16:00:00.158547 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I0707 16:00:00.159407 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I0707 16:00:00.159415 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:00 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I0707 16:00:01.472935 31333 main.go:141] libmachine: (second-015000) DBG | Attempt 1
I0707 16:00:01.472957 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 16:00:01.473043 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 16:00:01.473812 31333 main.go:141] libmachine: (second-015000) DBG | Searching for 5a:57:82:35:3f:0 in /var/db/dhcpd_leases ...
I0707 16:00:01.473897 31333 main.go:141] libmachine: (second-015000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0707 16:00:01.473903 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:2a:a0:3b:ac:43:5 ID:1,2a:a0:3b:ac:43:5 Lease:0x64a9ea51}
I0707 16:00:01.473912 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ef:40:f6:89:98 ID:1,e:ef:40:f6:89:98 Lease:0x64a9e9f8}
I0707 16:00:01.473917 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:2a:32:21:a:c9:ea ID:1,2a:32:21:a:c9:ea Lease:0x64a9e979}
I0707 16:00:01.473926 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:22:ae:6d:e6:53:22 ID:1,22:ae:6d:e6:53:22 Lease:0x64a9e93c}
I0707 16:00:01.473934 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:a:15:e7:c7:12 ID:1,d2:a:15:e7:c7:12 Lease:0x64a9e851}
I0707 16:00:01.473941 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:4d:70:3e:72:d7 ID:1,8a:4d:70:3e:72:d7 Lease:0x64a9e823}
I0707 16:00:01.473946 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:1a:8e:ab:d5:52:f4 ID:1,1a:8e:ab:d5:52:f4 Lease:0x64a9e6d4}
I0707 16:00:01.473952 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:72:19:49:64:8b:18 ID:1,72:19:49:64:8b:18 Lease:0x64a9e658}
I0707 16:00:01.473959 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:8a:f2:e1:4a:aa:55 ID:1,8a:f2:e1:4a:aa:55 Lease:0x64a9e511}
I0707 16:00:01.473965 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:71:99:1a:a1:ca ID:1,36:71:99:1a:a1:ca Lease:0x64a9e3df}
I0707 16:00:01.473970 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:26:7d:94:3e:ce:86 ID:1,26:7d:94:3e:ce:86 Lease:0x64a9e25c}
I0707 16:00:01.473981 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:be:9b:40:44:bd:5e ID:1,be:9b:40:44:bd:5e Lease:0x64a9e299}
I0707 16:00:01.474002 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:92:23:35:2f:b4:e0 ID:1,92:23:35:2f:b4:e0 Lease:0x64a9e1d4}
I0707 16:00:01.474032 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:ca:78:94:69:83:9d ID:1,ca:78:94:69:83:9d Lease:0x64a9e1a1}
I0707 16:00:01.474040 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:2e:6b:da:4d:43:63 ID:1,2e:6b:da:4d:43:63 Lease:0x64a9e17a}
I0707 16:00:01.474046 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:1a:1e:78:c2:3:5b ID:1,1a:1e:78:c2:3:5b Lease:0x64a9e14d}
I0707 16:00:01.474051 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:56:21:77:6e:3e:d7 ID:1,56:21:77:6e:3e:d7 Lease:0x64a9e11c}
I0707 16:00:01.474060 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:b6:79:6d:78:e5:91 ID:1,b6:79:6d:78:e5:91 Lease:0x64a9e0df}
I0707 16:00:01.474065 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:d:6:69:70:57 ID:1,e:d:6:69:70:57 Lease:0x64a9e0b7}
I0707 16:00:01.474076 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:12:c:fc:38:1b:7d ID:1,12:c:fc:38:1b:7d Lease:0x64a9e07e}
I0707 16:00:01.474084 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2a:be:6e:1c:25:23 ID:1,2a:be:6e:1c:25:23 Lease:0x64a9e029}
I0707 16:00:01.474090 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:f6:26:cc:59:fb:86 ID:1,f6:26:cc:59:fb:86 Lease:0x64a9e002}
I0707 16:00:01.474098 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:32:f4:fc:97:bb:c5 ID:1,32:f4:fc:97:bb:c5 Lease:0x64a9dfd9}
I0707 16:00:01.474104 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:f2:ac:14:df:2:e1 ID:1,f2:ac:14:df:2:e1 Lease:0x64a9dfbd}
I0707 16:00:01.474109 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:86:40:da:85:93:5 ID:1,86:40:da:85:93:5 Lease:0x64a9dfaf}
I0707 16:00:01.474116 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:96:a2:d0:1b:cc:48 ID:1,96:a2:d0:1b:cc:48 Lease:0x64a88e33}
I0707 16:00:01.474122 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:7e:18:98:3:79:ac ID:1,7e:18:98:3:79:ac Lease:0x64a88e11}
I0707 16:00:01.474141 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:a6:24:7b:fc:77:e8 ID:1,a6:24:7b:fc:77:e8 Lease:0x64a88dd3}
I0707 16:00:01.474146 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:da:c2:99:f7:b9:2b ID:1,da:c2:99:f7:b9:2b Lease:0x64a9def7}
I0707 16:00:01.474175 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:12:2c:d8:d3:98:3d ID:1,12:2c:d8:d3:98:3d Lease:0x64a9de95}
I0707 16:00:01.474196 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:96:63:2d:24:c0:8e ID:1,96:63:2d:24:c0:8e Lease:0x64a9deac}
I0707 16:00:01.474201 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:ce:d:af:fb:a4:29 ID:1,ce:d:af:fb:a4:29 Lease:0x64a88d00}
I0707 16:00:01.474239 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:c6:d1:4d:d1:c6:c6 ID:1,c6:d1:4d:d1:c6:c6 Lease:0x64a9ddd3}
I0707 16:00:01.474244 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:4a:0:ad:11:5a:b8 ID:1,4a:0:ad:11:5a:b8 Lease:0x64a9dd66}
I0707 16:00:01.474264 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:76:24:4a:dc:55:63 ID:1,76:24:4a:dc:55:63 Lease:0x64a9dcf9}
I0707 16:00:01.474271 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:7a:76:a9:a4:41:d6 ID:1,7a:76:a9:a4:41:d6 Lease:0x64a9dcac}
I0707 16:00:01.474276 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:9a:d4:1c:20:49:9a ID:1,9a:d4:1c:20:49:9a Lease:0x64a88ab6}
I0707 16:00:01.474310 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:5e:28:a1:fd:5:74 ID:1,5e:28:a1:fd:5:74 Lease:0x64a88a2a}
I0707 16:00:01.474315 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:f2:9f:60:b5:67 ID:1,5e:f2:9f:60:b5:67 Lease:0x64a9dbf9}
I0707 16:00:01.474339 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:ba:29:cf:65:9a:f6 ID:1,ba:29:cf:65:9a:f6 Lease:0x64a9dbc4}
I0707 16:00:01.474346 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:c2:46:a3:47:d0:6f ID:1,c2:46:a3:47:d0:6f Lease:0x64a888da}
I0707 16:00:01.474353 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:e6:f8:da:7f:b0:2 ID:1,e6:f8:da:7f:b0:2 Lease:0x64a888ac}
I0707 16:00:01.474361 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:1a:e8:95:cf:b7:2c ID:1,1a:e8:95:cf:b7:2c Lease:0x64a9d9e1}
I0707 16:00:01.474395 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:82:60:aa:b:ac:82 ID:1,82:60:aa:b:ac:82 Lease:0x64a9d9bc}
I0707 16:00:01.474415 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:b2:5e:c6:47:87:ac ID:1,b2:5e:c6:47:87:ac Lease:0x64a9d97e}
I0707 16:00:01.474441 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9a:cd:ff:b7:63:f ID:1,9a:cd:ff:b7:63:f Lease:0x64a9d8f8}
I0707 16:00:01.474447 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:d2:28:10:10:80:9f ID:1,d2:28:10:10:80:9f Lease:0x64a9d8c0}
I0707 16:00:01.474471 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:e:3f:ec:83:e4:c9 ID:1,e:3f:ec:83:e4:c9 Lease:0x64a9d7c9}
I0707 16:00:01.474479 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:92:45:cd:92:c5:57 ID:1,92:45:cd:92:c5:57 Lease:0x64a8863e}
I0707 16:00:01.474487 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ee:ec:a1:83:cb:bd ID:1,ee:ec:a1:83:cb:bd Lease:0x64a9d66a}
I0707 16:00:03.475265 31333 main.go:141] libmachine: (second-015000) DBG | Attempt 2
I0707 16:00:03.475279 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 16:00:03.475291 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 16:00:03.476072 31333 main.go:141] libmachine: (second-015000) DBG | Searching for 5a:57:82:35:3f:0 in /var/db/dhcpd_leases ...
I0707 16:00:03.476147 31333 main.go:141] libmachine: (second-015000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0707 16:00:03.476156 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:2a:a0:3b:ac:43:5 ID:1,2a:a0:3b:ac:43:5 Lease:0x64a9ea51}
I0707 16:00:03.476164 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ef:40:f6:89:98 ID:1,e:ef:40:f6:89:98 Lease:0x64a9e9f8}
I0707 16:00:03.476174 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:2a:32:21:a:c9:ea ID:1,2a:32:21:a:c9:ea Lease:0x64a9e979}
I0707 16:00:03.476181 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:22:ae:6d:e6:53:22 ID:1,22:ae:6d:e6:53:22 Lease:0x64a9e93c}
I0707 16:00:03.476191 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:a:15:e7:c7:12 ID:1,d2:a:15:e7:c7:12 Lease:0x64a9e851}
I0707 16:00:03.476196 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:4d:70:3e:72:d7 ID:1,8a:4d:70:3e:72:d7 Lease:0x64a9e823}
I0707 16:00:03.476211 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:1a:8e:ab:d5:52:f4 ID:1,1a:8e:ab:d5:52:f4 Lease:0x64a9e6d4}
I0707 16:00:03.476227 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:72:19:49:64:8b:18 ID:1,72:19:49:64:8b:18 Lease:0x64a9e658}
I0707 16:00:03.476235 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:8a:f2:e1:4a:aa:55 ID:1,8a:f2:e1:4a:aa:55 Lease:0x64a9e511}
I0707 16:00:03.476243 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:71:99:1a:a1:ca ID:1,36:71:99:1a:a1:ca Lease:0x64a9e3df}
I0707 16:00:03.476252 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:26:7d:94:3e:ce:86 ID:1,26:7d:94:3e:ce:86 Lease:0x64a9e25c}
I0707 16:00:03.476261 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:be:9b:40:44:bd:5e ID:1,be:9b:40:44:bd:5e Lease:0x64a9e299}
I0707 16:00:03.476269 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:92:23:35:2f:b4:e0 ID:1,92:23:35:2f:b4:e0 Lease:0x64a9e1d4}
I0707 16:00:03.476276 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:ca:78:94:69:83:9d ID:1,ca:78:94:69:83:9d Lease:0x64a9e1a1}
I0707 16:00:03.476281 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:2e:6b:da:4d:43:63 ID:1,2e:6b:da:4d:43:63 Lease:0x64a9e17a}
I0707 16:00:03.476287 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:1a:1e:78:c2:3:5b ID:1,1a:1e:78:c2:3:5b Lease:0x64a9e14d}
I0707 16:00:03.476292 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:56:21:77:6e:3e:d7 ID:1,56:21:77:6e:3e:d7 Lease:0x64a9e11c}
I0707 16:00:03.476300 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:b6:79:6d:78:e5:91 ID:1,b6:79:6d:78:e5:91 Lease:0x64a9e0df}
I0707 16:00:03.476309 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:d:6:69:70:57 ID:1,e:d:6:69:70:57 Lease:0x64a9e0b7}
I0707 16:00:03.476316 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:12:c:fc:38:1b:7d ID:1,12:c:fc:38:1b:7d Lease:0x64a9e07e}
I0707 16:00:03.476321 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2a:be:6e:1c:25:23 ID:1,2a:be:6e:1c:25:23 Lease:0x64a9e029}
I0707 16:00:03.476340 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:f6:26:cc:59:fb:86 ID:1,f6:26:cc:59:fb:86 Lease:0x64a9e002}
I0707 16:00:03.476352 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:32:f4:fc:97:bb:c5 ID:1,32:f4:fc:97:bb:c5 Lease:0x64a9dfd9}
I0707 16:00:03.476361 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:f2:ac:14:df:2:e1 ID:1,f2:ac:14:df:2:e1 Lease:0x64a9dfbd}
I0707 16:00:03.476366 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:86:40:da:85:93:5 ID:1,86:40:da:85:93:5 Lease:0x64a9dfaf}
I0707 16:00:03.476375 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:96:a2:d0:1b:cc:48 ID:1,96:a2:d0:1b:cc:48 Lease:0x64a88e33}
I0707 16:00:03.476382 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:7e:18:98:3:79:ac ID:1,7e:18:98:3:79:ac Lease:0x64a88e11}
I0707 16:00:03.476387 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:a6:24:7b:fc:77:e8 ID:1,a6:24:7b:fc:77:e8 Lease:0x64a88dd3}
I0707 16:00:03.476392 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:da:c2:99:f7:b9:2b ID:1,da:c2:99:f7:b9:2b Lease:0x64a9def7}
I0707 16:00:03.476398 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:12:2c:d8:d3:98:3d ID:1,12:2c:d8:d3:98:3d Lease:0x64a9de95}
I0707 16:00:03.476404 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:96:63:2d:24:c0:8e ID:1,96:63:2d:24:c0:8e Lease:0x64a9deac}
I0707 16:00:03.476411 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:ce:d:af:fb:a4:29 ID:1,ce:d:af:fb:a4:29 Lease:0x64a88d00}
I0707 16:00:03.476416 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:c6:d1:4d:d1:c6:c6 ID:1,c6:d1:4d:d1:c6:c6 Lease:0x64a9ddd3}
I0707 16:00:03.476421 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:4a:0:ad:11:5a:b8 ID:1,4a:0:ad:11:5a:b8 Lease:0x64a9dd66}
I0707 16:00:03.476426 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:76:24:4a:dc:55:63 ID:1,76:24:4a:dc:55:63 Lease:0x64a9dcf9}
I0707 16:00:03.476432 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:7a:76:a9:a4:41:d6 ID:1,7a:76:a9:a4:41:d6 Lease:0x64a9dcac}
I0707 16:00:03.476438 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:9a:d4:1c:20:49:9a ID:1,9a:d4:1c:20:49:9a Lease:0x64a88ab6}
I0707 16:00:03.476447 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:5e:28:a1:fd:5:74 ID:1,5e:28:a1:fd:5:74 Lease:0x64a88a2a}
I0707 16:00:03.476454 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:f2:9f:60:b5:67 ID:1,5e:f2:9f:60:b5:67 Lease:0x64a9dbf9}
I0707 16:00:03.476459 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:ba:29:cf:65:9a:f6 ID:1,ba:29:cf:65:9a:f6 Lease:0x64a9dbc4}
I0707 16:00:03.476464 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:c2:46:a3:47:d0:6f ID:1,c2:46:a3:47:d0:6f Lease:0x64a888da}
I0707 16:00:03.476476 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:e6:f8:da:7f:b0:2 ID:1,e6:f8:da:7f:b0:2 Lease:0x64a888ac}
I0707 16:00:03.476485 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:1a:e8:95:cf:b7:2c ID:1,1a:e8:95:cf:b7:2c Lease:0x64a9d9e1}
I0707 16:00:03.476492 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:82:60:aa:b:ac:82 ID:1,82:60:aa:b:ac:82 Lease:0x64a9d9bc}
I0707 16:00:03.476498 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:b2:5e:c6:47:87:ac ID:1,b2:5e:c6:47:87:ac Lease:0x64a9d97e}
I0707 16:00:03.476504 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9a:cd:ff:b7:63:f ID:1,9a:cd:ff:b7:63:f Lease:0x64a9d8f8}
I0707 16:00:03.476509 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:d2:28:10:10:80:9f ID:1,d2:28:10:10:80:9f Lease:0x64a9d8c0}
I0707 16:00:03.476514 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:e:3f:ec:83:e4:c9 ID:1,e:3f:ec:83:e4:c9 Lease:0x64a9d7c9}
I0707 16:00:03.476521 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:92:45:cd:92:c5:57 ID:1,92:45:cd:92:c5:57 Lease:0x64a8863e}
I0707 16:00:03.476529 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ee:ec:a1:83:cb:bd ID:1,ee:ec:a1:83:cb:bd Lease:0x64a9d66a}
I0707 16:00:05.076136 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:05 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I0707 16:00:05.076233 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:05 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I0707 16:00:05.076242 31333 main.go:141] libmachine: (second-015000) DBG | 2023/07/07 16:00:05 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I0707 16:00:05.477607 31333 main.go:141] libmachine: (second-015000) DBG | Attempt 3
I0707 16:00:05.477616 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 16:00:05.477698 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 16:00:05.478438 31333 main.go:141] libmachine: (second-015000) DBG | Searching for 5a:57:82:35:3f:0 in /var/db/dhcpd_leases ...
I0707 16:00:05.478539 31333 main.go:141] libmachine: (second-015000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0707 16:00:05.478547 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:2a:a0:3b:ac:43:5 ID:1,2a:a0:3b:ac:43:5 Lease:0x64a9ea51}
I0707 16:00:05.478553 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ef:40:f6:89:98 ID:1,e:ef:40:f6:89:98 Lease:0x64a9e9f8}
I0707 16:00:05.478558 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:2a:32:21:a:c9:ea ID:1,2a:32:21:a:c9:ea Lease:0x64a9e979}
I0707 16:00:05.478565 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:22:ae:6d:e6:53:22 ID:1,22:ae:6d:e6:53:22 Lease:0x64a9e93c}
I0707 16:00:05.478576 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:a:15:e7:c7:12 ID:1,d2:a:15:e7:c7:12 Lease:0x64a9e851}
I0707 16:00:05.478587 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:4d:70:3e:72:d7 ID:1,8a:4d:70:3e:72:d7 Lease:0x64a9e823}
I0707 16:00:05.478598 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:1a:8e:ab:d5:52:f4 ID:1,1a:8e:ab:d5:52:f4 Lease:0x64a9e6d4}
I0707 16:00:05.478606 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:72:19:49:64:8b:18 ID:1,72:19:49:64:8b:18 Lease:0x64a9e658}
I0707 16:00:05.478611 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:8a:f2:e1:4a:aa:55 ID:1,8a:f2:e1:4a:aa:55 Lease:0x64a9e511}
I0707 16:00:05.478617 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:71:99:1a:a1:ca ID:1,36:71:99:1a:a1:ca Lease:0x64a9e3df}
I0707 16:00:05.478622 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:26:7d:94:3e:ce:86 ID:1,26:7d:94:3e:ce:86 Lease:0x64a9e25c}
I0707 16:00:05.478633 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:be:9b:40:44:bd:5e ID:1,be:9b:40:44:bd:5e Lease:0x64a9e299}
I0707 16:00:05.478638 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:92:23:35:2f:b4:e0 ID:1,92:23:35:2f:b4:e0 Lease:0x64a9e1d4}
I0707 16:00:05.478644 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:ca:78:94:69:83:9d ID:1,ca:78:94:69:83:9d Lease:0x64a9e1a1}
I0707 16:00:05.478652 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:2e:6b:da:4d:43:63 ID:1,2e:6b:da:4d:43:63 Lease:0x64a9e17a}
I0707 16:00:05.478660 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:1a:1e:78:c2:3:5b ID:1,1a:1e:78:c2:3:5b Lease:0x64a9e14d}
I0707 16:00:05.478671 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:56:21:77:6e:3e:d7 ID:1,56:21:77:6e:3e:d7 Lease:0x64a9e11c}
I0707 16:00:05.478677 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:b6:79:6d:78:e5:91 ID:1,b6:79:6d:78:e5:91 Lease:0x64a9e0df}
I0707 16:00:05.478687 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:d:6:69:70:57 ID:1,e:d:6:69:70:57 Lease:0x64a9e0b7}
I0707 16:00:05.478695 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:12:c:fc:38:1b:7d ID:1,12:c:fc:38:1b:7d Lease:0x64a9e07e}
I0707 16:00:05.478701 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2a:be:6e:1c:25:23 ID:1,2a:be:6e:1c:25:23 Lease:0x64a9e029}
I0707 16:00:05.478706 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:f6:26:cc:59:fb:86 ID:1,f6:26:cc:59:fb:86 Lease:0x64a9e002}
I0707 16:00:05.478711 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:32:f4:fc:97:bb:c5 ID:1,32:f4:fc:97:bb:c5 Lease:0x64a9dfd9}
I0707 16:00:05.478716 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:f2:ac:14:df:2:e1 ID:1,f2:ac:14:df:2:e1 Lease:0x64a9dfbd}
I0707 16:00:05.478721 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:86:40:da:85:93:5 ID:1,86:40:da:85:93:5 Lease:0x64a9dfaf}
I0707 16:00:05.478726 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:96:a2:d0:1b:cc:48 ID:1,96:a2:d0:1b:cc:48 Lease:0x64a88e33}
I0707 16:00:05.478731 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:7e:18:98:3:79:ac ID:1,7e:18:98:3:79:ac Lease:0x64a88e11}
I0707 16:00:05.478742 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:a6:24:7b:fc:77:e8 ID:1,a6:24:7b:fc:77:e8 Lease:0x64a88dd3}
I0707 16:00:05.478749 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:da:c2:99:f7:b9:2b ID:1,da:c2:99:f7:b9:2b Lease:0x64a9def7}
I0707 16:00:05.478755 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:12:2c:d8:d3:98:3d ID:1,12:2c:d8:d3:98:3d Lease:0x64a9de95}
I0707 16:00:05.478760 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:96:63:2d:24:c0:8e ID:1,96:63:2d:24:c0:8e Lease:0x64a9deac}
I0707 16:00:05.478765 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:ce:d:af:fb:a4:29 ID:1,ce:d:af:fb:a4:29 Lease:0x64a88d00}
I0707 16:00:05.478772 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:c6:d1:4d:d1:c6:c6 ID:1,c6:d1:4d:d1:c6:c6 Lease:0x64a9ddd3}
I0707 16:00:05.478779 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:4a:0:ad:11:5a:b8 ID:1,4a:0:ad:11:5a:b8 Lease:0x64a9dd66}
I0707 16:00:05.478786 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:76:24:4a:dc:55:63 ID:1,76:24:4a:dc:55:63 Lease:0x64a9dcf9}
I0707 16:00:05.478791 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:7a:76:a9:a4:41:d6 ID:1,7a:76:a9:a4:41:d6 Lease:0x64a9dcac}
I0707 16:00:05.478800 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:9a:d4:1c:20:49:9a ID:1,9a:d4:1c:20:49:9a Lease:0x64a88ab6}
I0707 16:00:05.478805 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:5e:28:a1:fd:5:74 ID:1,5e:28:a1:fd:5:74 Lease:0x64a88a2a}
I0707 16:00:05.478812 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:f2:9f:60:b5:67 ID:1,5e:f2:9f:60:b5:67 Lease:0x64a9dbf9}
I0707 16:00:05.478817 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:ba:29:cf:65:9a:f6 ID:1,ba:29:cf:65:9a:f6 Lease:0x64a9dbc4}
I0707 16:00:05.478827 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:c2:46:a3:47:d0:6f ID:1,c2:46:a3:47:d0:6f Lease:0x64a888da}
I0707 16:00:05.478833 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:e6:f8:da:7f:b0:2 ID:1,e6:f8:da:7f:b0:2 Lease:0x64a888ac}
I0707 16:00:05.478845 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:1a:e8:95:cf:b7:2c ID:1,1a:e8:95:cf:b7:2c Lease:0x64a9d9e1}
I0707 16:00:05.478852 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:82:60:aa:b:ac:82 ID:1,82:60:aa:b:ac:82 Lease:0x64a9d9bc}
I0707 16:00:05.478857 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:b2:5e:c6:47:87:ac ID:1,b2:5e:c6:47:87:ac Lease:0x64a9d97e}
I0707 16:00:05.478864 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9a:cd:ff:b7:63:f ID:1,9a:cd:ff:b7:63:f Lease:0x64a9d8f8}
I0707 16:00:05.478869 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:d2:28:10:10:80:9f ID:1,d2:28:10:10:80:9f Lease:0x64a9d8c0}
I0707 16:00:05.478875 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:e:3f:ec:83:e4:c9 ID:1,e:3f:ec:83:e4:c9 Lease:0x64a9d7c9}
I0707 16:00:05.478881 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:92:45:cd:92:c5:57 ID:1,92:45:cd:92:c5:57 Lease:0x64a8863e}
I0707 16:00:05.478886 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ee:ec:a1:83:cb:bd ID:1,ee:ec:a1:83:cb:bd Lease:0x64a9d66a}
I0707 16:00:07.478901 31333 main.go:141] libmachine: (second-015000) DBG | Attempt 4
I0707 16:00:07.478914 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 16:00:07.478979 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 16:00:07.479729 31333 main.go:141] libmachine: (second-015000) DBG | Searching for 5a:57:82:35:3f:0 in /var/db/dhcpd_leases ...
I0707 16:00:07.479828 31333 main.go:141] libmachine: (second-015000) DBG | Found 50 entries in /var/db/dhcpd_leases!
I0707 16:00:07.479844 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.51 HWAddress:2a:a0:3b:ac:43:5 ID:1,2a:a0:3b:ac:43:5 Lease:0x64a9ea51}
I0707 16:00:07.479850 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.50 HWAddress:e:ef:40:f6:89:98 ID:1,e:ef:40:f6:89:98 Lease:0x64a9e9f8}
I0707 16:00:07.479857 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.49 HWAddress:2a:32:21:a:c9:ea ID:1,2a:32:21:a:c9:ea Lease:0x64a9e979}
I0707 16:00:07.479865 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.48 HWAddress:22:ae:6d:e6:53:22 ID:1,22:ae:6d:e6:53:22 Lease:0x64a9e93c}
I0707 16:00:07.479886 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.47 HWAddress:d2:a:15:e7:c7:12 ID:1,d2:a:15:e7:c7:12 Lease:0x64a9e851}
I0707 16:00:07.479893 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.46 HWAddress:8a:4d:70:3e:72:d7 ID:1,8a:4d:70:3e:72:d7 Lease:0x64a9e823}
I0707 16:00:07.479897 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.45 HWAddress:1a:8e:ab:d5:52:f4 ID:1,1a:8e:ab:d5:52:f4 Lease:0x64a9e6d4}
I0707 16:00:07.479903 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.44 HWAddress:72:19:49:64:8b:18 ID:1,72:19:49:64:8b:18 Lease:0x64a9e658}
I0707 16:00:07.479908 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.43 HWAddress:8a:f2:e1:4a:aa:55 ID:1,8a:f2:e1:4a:aa:55 Lease:0x64a9e511}
I0707 16:00:07.479919 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.42 HWAddress:36:71:99:1a:a1:ca ID:1,36:71:99:1a:a1:ca Lease:0x64a9e3df}
I0707 16:00:07.479925 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.41 HWAddress:26:7d:94:3e:ce:86 ID:1,26:7d:94:3e:ce:86 Lease:0x64a9e25c}
I0707 16:00:07.479934 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.40 HWAddress:be:9b:40:44:bd:5e ID:1,be:9b:40:44:bd:5e Lease:0x64a9e299}
I0707 16:00:07.479939 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.39 HWAddress:92:23:35:2f:b4:e0 ID:1,92:23:35:2f:b4:e0 Lease:0x64a9e1d4}
I0707 16:00:07.479945 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.38 HWAddress:ca:78:94:69:83:9d ID:1,ca:78:94:69:83:9d Lease:0x64a9e1a1}
I0707 16:00:07.479950 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.37 HWAddress:2e:6b:da:4d:43:63 ID:1,2e:6b:da:4d:43:63 Lease:0x64a9e17a}
I0707 16:00:07.479955 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.36 HWAddress:1a:1e:78:c2:3:5b ID:1,1a:1e:78:c2:3:5b Lease:0x64a9e14d}
I0707 16:00:07.479960 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.35 HWAddress:56:21:77:6e:3e:d7 ID:1,56:21:77:6e:3e:d7 Lease:0x64a9e11c}
I0707 16:00:07.479970 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.34 HWAddress:b6:79:6d:78:e5:91 ID:1,b6:79:6d:78:e5:91 Lease:0x64a9e0df}
I0707 16:00:07.479976 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.33 HWAddress:e:d:6:69:70:57 ID:1,e:d:6:69:70:57 Lease:0x64a9e0b7}
I0707 16:00:07.479983 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.32 HWAddress:12:c:fc:38:1b:7d ID:1,12:c:fc:38:1b:7d Lease:0x64a9e07e}
I0707 16:00:07.479989 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.31 HWAddress:2a:be:6e:1c:25:23 ID:1,2a:be:6e:1c:25:23 Lease:0x64a9e029}
I0707 16:00:07.479994 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.30 HWAddress:f6:26:cc:59:fb:86 ID:1,f6:26:cc:59:fb:86 Lease:0x64a9e002}
I0707 16:00:07.480019 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.29 HWAddress:32:f4:fc:97:bb:c5 ID:1,32:f4:fc:97:bb:c5 Lease:0x64a9dfd9}
I0707 16:00:07.480035 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.28 HWAddress:f2:ac:14:df:2:e1 ID:1,f2:ac:14:df:2:e1 Lease:0x64a9dfbd}
I0707 16:00:07.480046 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.27 HWAddress:86:40:da:85:93:5 ID:1,86:40:da:85:93:5 Lease:0x64a9dfaf}
I0707 16:00:07.480054 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.26 HWAddress:96:a2:d0:1b:cc:48 ID:1,96:a2:d0:1b:cc:48 Lease:0x64a88e33}
I0707 16:00:07.480060 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.25 HWAddress:7e:18:98:3:79:ac ID:1,7e:18:98:3:79:ac Lease:0x64a88e11}
I0707 16:00:07.480067 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.24 HWAddress:a6:24:7b:fc:77:e8 ID:1,a6:24:7b:fc:77:e8 Lease:0x64a88dd3}
I0707 16:00:07.480073 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.23 HWAddress:da:c2:99:f7:b9:2b ID:1,da:c2:99:f7:b9:2b Lease:0x64a9def7}
I0707 16:00:07.480079 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.22 HWAddress:12:2c:d8:d3:98:3d ID:1,12:2c:d8:d3:98:3d Lease:0x64a9de95}
I0707 16:00:07.480085 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.21 HWAddress:96:63:2d:24:c0:8e ID:1,96:63:2d:24:c0:8e Lease:0x64a9deac}
I0707 16:00:07.480093 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.20 HWAddress:ce:d:af:fb:a4:29 ID:1,ce:d:af:fb:a4:29 Lease:0x64a88d00}
I0707 16:00:07.480099 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.19 HWAddress:c6:d1:4d:d1:c6:c6 ID:1,c6:d1:4d:d1:c6:c6 Lease:0x64a9ddd3}
I0707 16:00:07.480106 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.18 HWAddress:4a:0:ad:11:5a:b8 ID:1,4a:0:ad:11:5a:b8 Lease:0x64a9dd66}
I0707 16:00:07.480111 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.17 HWAddress:76:24:4a:dc:55:63 ID:1,76:24:4a:dc:55:63 Lease:0x64a9dcf9}
I0707 16:00:07.480116 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.16 HWAddress:7a:76:a9:a4:41:d6 ID:1,7a:76:a9:a4:41:d6 Lease:0x64a9dcac}
I0707 16:00:07.480121 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.15 HWAddress:9a:d4:1c:20:49:9a ID:1,9a:d4:1c:20:49:9a Lease:0x64a88ab6}
I0707 16:00:07.480127 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.14 HWAddress:5e:28:a1:fd:5:74 ID:1,5e:28:a1:fd:5:74 Lease:0x64a88a2a}
I0707 16:00:07.480134 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.13 HWAddress:5e:f2:9f:60:b5:67 ID:1,5e:f2:9f:60:b5:67 Lease:0x64a9dbf9}
I0707 16:00:07.480143 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.12 HWAddress:ba:29:cf:65:9a:f6 ID:1,ba:29:cf:65:9a:f6 Lease:0x64a9dbc4}
I0707 16:00:07.480150 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.11 HWAddress:c2:46:a3:47:d0:6f ID:1,c2:46:a3:47:d0:6f Lease:0x64a888da}
I0707 16:00:07.480156 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.10 HWAddress:e6:f8:da:7f:b0:2 ID:1,e6:f8:da:7f:b0:2 Lease:0x64a888ac}
I0707 16:00:07.480163 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:1a:e8:95:cf:b7:2c ID:1,1a:e8:95:cf:b7:2c Lease:0x64a9d9e1}
I0707 16:00:07.480169 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:82:60:aa:b:ac:82 ID:1,82:60:aa:b:ac:82 Lease:0x64a9d9bc}
I0707 16:00:07.480174 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:b2:5e:c6:47:87:ac ID:1,b2:5e:c6:47:87:ac Lease:0x64a9d97e}
I0707 16:00:07.480181 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9a:cd:ff:b7:63:f ID:1,9a:cd:ff:b7:63:f Lease:0x64a9d8f8}
I0707 16:00:07.480187 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:d2:28:10:10:80:9f ID:1,d2:28:10:10:80:9f Lease:0x64a9d8c0}
I0707 16:00:07.480197 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:e:3f:ec:83:e4:c9 ID:1,e:3f:ec:83:e4:c9 Lease:0x64a9d7c9}
I0707 16:00:07.480204 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:92:45:cd:92:c5:57 ID:1,92:45:cd:92:c5:57 Lease:0x64a8863e}
I0707 16:00:07.480214 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:ee:ec:a1:83:cb:bd ID:1,ee:ec:a1:83:cb:bd Lease:0x64a9d66a}
I0707 16:00:09.480317 31333 main.go:141] libmachine: (second-015000) DBG | Attempt 5
I0707 16:00:09.480333 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 16:00:09.480435 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 16:00:09.481355 31333 main.go:141] libmachine: (second-015000) DBG | Searching for 5a:57:82:35:3f:0 in /var/db/dhcpd_leases ...
I0707 16:00:09.481460 31333 main.go:141] libmachine: (second-015000) DBG | Found 51 entries in /var/db/dhcpd_leases!
I0707 16:00:09.481472 31333 main.go:141] libmachine: (second-015000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.52 HWAddress:5a:57:82:35:3f:0 ID:1,5a:57:82:35:3f:0 Lease:0x64a9ea78}
I0707 16:00:09.481481 31333 main.go:141] libmachine: (second-015000) DBG | Found match: 5a:57:82:35:3f:0
I0707 16:00:09.481486 31333 main.go:141] libmachine: (second-015000) DBG | IP: 192.168.64.52
I0707 16:00:09.481562 31333 main.go:141] libmachine: (second-015000) Calling .GetConfigRaw
I0707 16:00:09.482217 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:09.482344 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:09.482449 31333 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0707 16:00:09.482461 31333 main.go:141] libmachine: (second-015000) Calling .GetState
I0707 16:00:09.482582 31333 main.go:141] libmachine: (second-015000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0707 16:00:09.482651 31333 main.go:141] libmachine: (second-015000) DBG | hyperkit pid from json: 31342
I0707 16:00:09.483577 31333 main.go:141] libmachine: Detecting operating system of created instance...
I0707 16:00:09.483590 31333 main.go:141] libmachine: Waiting for SSH to be available...
I0707 16:00:09.483594 31333 main.go:141] libmachine: Getting to WaitForSSH function...
I0707 16:00:09.483600 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:09.483759 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:09.483894 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:09.484013 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:09.484161 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:09.484325 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:09.484711 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:09.484716 31333 main.go:141] libmachine: About to run SSH command:
exit 0
I0707 16:00:09.522097 31333 main.go:141] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I0707 16:00:12.598521 31333 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0707 16:00:12.598531 31333 main.go:141] libmachine: Detecting the provisioner...
I0707 16:00:12.598535 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:12.598678 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:12.598771 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.598865 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.598962 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:12.599086 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:12.599396 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:12.599401 31333 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0707 16:00:12.674464 31333 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-g6f2898e-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I0707 16:00:12.674529 31333 main.go:141] libmachine: found compatible host: buildroot
I0707 16:00:12.674533 31333 main.go:141] libmachine: Provisioning with buildroot...
I0707 16:00:12.674537 31333 main.go:141] libmachine: (second-015000) Calling .GetMachineName
I0707 16:00:12.674668 31333 buildroot.go:166] provisioning hostname "second-015000"
I0707 16:00:12.674676 31333 main.go:141] libmachine: (second-015000) Calling .GetMachineName
I0707 16:00:12.674785 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:12.674864 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:12.674945 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.675031 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.675104 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:12.675226 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:12.675527 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:12.675534 31333 main.go:141] libmachine: About to run SSH command:
sudo hostname second-015000 && echo "second-015000" | sudo tee /etc/hostname
I0707 16:00:12.758526 31333 main.go:141] libmachine: SSH cmd err, output: <nil>: second-015000
I0707 16:00:12.758545 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:12.758672 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:12.758780 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.758872 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.758954 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:12.759076 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:12.759377 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:12.759385 31333 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\ssecond-015000' /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-015000/g' /etc/hosts;
else
echo '127.0.1.1 second-015000' | sudo tee -a /etc/hosts;
fi
fi
I0707 16:00:12.840227 31333 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0707 16:00:12.840242 31333 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/16845-29196/.minikube CaCertPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/16845-29196/.minikube}
I0707 16:00:12.840257 31333 buildroot.go:174] setting up certificates
I0707 16:00:12.840269 31333 provision.go:83] configureAuth start
I0707 16:00:12.840274 31333 main.go:141] libmachine: (second-015000) Calling .GetMachineName
I0707 16:00:12.840412 31333 main.go:141] libmachine: (second-015000) Calling .GetIP
I0707 16:00:12.840498 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:12.840562 31333 provision.go:138] copyHostCerts
I0707 16:00:12.840646 31333 exec_runner.go:144] found /Users/jenkins/minikube-integration/16845-29196/.minikube/ca.pem, removing ...
I0707 16:00:12.840652 31333 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16845-29196/.minikube/ca.pem
I0707 16:00:12.841459 31333 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/16845-29196/.minikube/ca.pem (1082 bytes)
I0707 16:00:12.841654 31333 exec_runner.go:144] found /Users/jenkins/minikube-integration/16845-29196/.minikube/cert.pem, removing ...
I0707 16:00:12.841658 31333 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16845-29196/.minikube/cert.pem
I0707 16:00:12.841717 31333 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16845-29196/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/16845-29196/.minikube/cert.pem (1123 bytes)
I0707 16:00:12.841881 31333 exec_runner.go:144] found /Users/jenkins/minikube-integration/16845-29196/.minikube/key.pem, removing ...
I0707 16:00:12.841884 31333 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/16845-29196/.minikube/key.pem
I0707 16:00:12.841941 31333 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/16845-29196/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/16845-29196/.minikube/key.pem (1675 bytes)
I0707 16:00:12.842066 31333 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca-key.pem org=jenkins.second-015000 san=[192.168.64.52 192.168.64.52 localhost 127.0.0.1 minikube second-015000]
I0707 16:00:12.901925 31333 provision.go:172] copyRemoteCerts
I0707 16:00:12.901979 31333 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0707 16:00:12.901993 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:12.902140 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:12.902251 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.902358 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:12.902460 31333 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/id_rsa Username:docker}
I0707 16:00:12.946268 31333 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0707 16:00:12.961728 31333 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16845-29196/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0707 16:00:12.977215 31333 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16845-29196/.minikube/machines/server.pem --> /etc/docker/server.pem (1216 bytes)
I0707 16:00:12.992222 31333 provision.go:86] duration metric: configureAuth took 151.939462ms
I0707 16:00:12.992232 31333 buildroot.go:189] setting minikube options for container-runtime
I0707 16:00:12.992352 31333 config.go:182] Loaded profile config "second-015000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.27.3
I0707 16:00:12.992362 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:12.992505 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:12.992588 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:12.992687 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.992766 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:12.992839 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:12.992945 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:12.993231 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:12.993236 31333 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0707 16:00:13.070244 31333 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0707 16:00:13.070258 31333 buildroot.go:70] root file system type: tmpfs
I0707 16:00:13.070346 31333 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0707 16:00:13.070359 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:13.070488 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:13.070571 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.070647 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.070747 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:13.070871 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:13.071175 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:13.071218 31333 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
I0707 16:00:13.155090 31333 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
I0707 16:00:13.164025 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:13.164150 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:13.164228 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.164295 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.164376 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:13.164493 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:13.164795 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:13.164808 31333 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; }
I0707 16:00:13.685541 31333 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.
I0707 16:00:13.685551 31333 main.go:141] libmachine: Checking connection to Docker...
I0707 16:00:13.685556 31333 main.go:141] libmachine: (second-015000) Calling .GetURL
I0707 16:00:13.685691 31333 main.go:141] libmachine: Docker is up and running!
I0707 16:00:13.685696 31333 main.go:141] libmachine: Reticulating splines...
I0707 16:00:13.685699 31333 client.go:171] LocalClient.Create took 14.991725097s
I0707 16:00:13.685709 31333 start.go:167] duration metric: libmachine.API.Create for "second-015000" took 14.991755486s
I0707 16:00:13.685718 31333 start.go:300] post-start starting for "second-015000" (driver="hyperkit")
I0707 16:00:13.685727 31333 start.go:329] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I0707 16:00:13.685738 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:13.685872 31333 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
I0707 16:00:13.685884 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:13.685962 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:13.686050 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.686123 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:13.686199 31333 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/id_rsa Username:docker}
I0707 16:00:13.729249 31333 ssh_runner.go:195] Run: cat /etc/os-release
I0707 16:00:13.731843 31333 info.go:137] Remote host: Buildroot 2021.02.12
I0707 16:00:13.731853 31333 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16845-29196/.minikube/addons for local assets ...
I0707 16:00:13.731932 31333 filesync.go:126] Scanning /Users/jenkins/minikube-integration/16845-29196/.minikube/files for local assets ...
I0707 16:00:13.732083 31333 filesync.go:149] local asset: /Users/jenkins/minikube-integration/16845-29196/.minikube/files/etc/ssl/certs/296432.pem -> 296432.pem in /etc/ssl/certs
I0707 16:00:13.732246 31333 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0707 16:00:13.738910 31333 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/16845-29196/.minikube/files/etc/ssl/certs/296432.pem --> /etc/ssl/certs/296432.pem (1708 bytes)
I0707 16:00:13.754748 31333 start.go:303] post-start completed in 69.022995ms
I0707 16:00:13.754776 31333 main.go:141] libmachine: (second-015000) Calling .GetConfigRaw
I0707 16:00:13.755341 31333 main.go:141] libmachine: (second-015000) Calling .GetIP
I0707 16:00:13.755503 31333 profile.go:148] Saving config to /Users/jenkins/minikube-integration/16845-29196/.minikube/profiles/second-015000/config.json ...
I0707 16:00:13.755789 31333 start.go:128] duration metric: createHost completed in 15.121316241s
I0707 16:00:13.755804 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:13.755891 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:13.755967 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.756042 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.756116 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:13.756213 31333 main.go:141] libmachine: Using SSH client type: native
I0707 16:00:13.756512 31333 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x140d2c0] 0x1410360 <nil> [] 0s} 192.168.64.52 22 <nil> <nil>}
I0707 16:00:13.756517 31333 main.go:141] libmachine: About to run SSH command:
date +%!s(MISSING).%!N(MISSING)
I0707 16:00:13.830707 31333 main.go:141] libmachine: SSH cmd err, output: <nil>: 1688770813.818122272
I0707 16:00:13.830713 31333 fix.go:206] guest clock: 1688770813.818122272
I0707 16:00:13.830718 31333 fix.go:219] Guest: 2023-07-07 16:00:13.818122272 -0700 PDT Remote: 2023-07-07 16:00:13.755797 -0700 PDT m=+15.646358748 (delta=62.325272ms)
I0707 16:00:13.830734 31333 fix.go:190] guest clock delta is within tolerance: 62.325272ms
I0707 16:00:13.830737 31333 start.go:83] releasing machines lock for "second-015000", held for 15.196380812s
I0707 16:00:13.830753 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:13.830878 31333 main.go:141] libmachine: (second-015000) Calling .GetIP
I0707 16:00:13.830966 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:13.831271 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:13.831353 31333 main.go:141] libmachine: (second-015000) Calling .DriverName
I0707 16:00:13.831436 31333 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0707 16:00:13.831458 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:13.831470 31333 ssh_runner.go:195] Run: cat /version.json
I0707 16:00:13.831478 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHHostname
I0707 16:00:13.831564 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:13.831576 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHPort
I0707 16:00:13.831660 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.831675 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHKeyPath
I0707 16:00:13.831752 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:13.831770 31333 main.go:141] libmachine: (second-015000) Calling .GetSSHUsername
I0707 16:00:13.831844 31333 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/id_rsa Username:docker}
I0707 16:00:13.831876 31333 sshutil.go:53] new ssh client: &{IP:192.168.64.52 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/16845-29196/.minikube/machines/second-015000/id_rsa Username:docker}
I0707 16:00:13.870991 31333 ssh_runner.go:195] Run: systemctl --version
I0707 16:00:13.916900 31333 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0707 16:00:13.921261 31333 cni.go:215] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0707 16:00:13.921297 31333 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" ;
I0707 16:00:13.932599 31333 cni.go:268] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0707 16:00:13.932607 31333 start.go:466] detecting cgroup driver to use...
I0707 16:00:13.932706 31333 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"
I0707 16:00:13.945583 31333 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"
I0707 16:00:13.952830 31333 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"
I0707 16:00:13.959926 31333 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I0707 16:00:13.959970 31333 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0707 16:00:13.967077 31333 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"
I0707 16:00:13.974097 31333 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0707 16:00:13.981286 31333 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0707 16:00:13.988328 31333 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0707 16:00:13.995544 31333 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"
I0707 16:00:14.002706 31333 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0707 16:00:14.009050 31333 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0707 16:00:14.015458 31333 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0707 16:00:14.098583 31333 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0707 16:00:14.109604 31333 start.go:466] detecting cgroup driver to use...
I0707 16:00:14.109669 31333 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0707 16:00:14.120180 31333 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0707 16:00:14.129464 31333 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0707 16:00:14.140760 31333 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0707 16:00:14.149735 31333 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0707 16:00:14.158094 31333 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0707 16:00:14.184020 31333 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0707 16:00:14.192933 31333 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"
I0707 16:00:14.205470 31333 ssh_runner.go:195] Run: which cri-dockerd
I0707 16:00:14.207864 31333 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0707 16:00:14.213425 31333 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0707 16:00:14.224386 31333 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0707 16:00:14.326050 31333 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0707 16:00:14.414640 31333 docker.go:535] configuring docker to use "cgroupfs" as cgroup driver...
I0707 16:00:14.414673 31333 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (144 bytes)
I0707 16:00:14.426826 31333 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0707 16:00:14.513426 31333 ssh_runner.go:195] Run: sudo systemctl restart docker
I0707 16:00:15.821730 31333 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.308260718s)
I0707 16:00:15.821788 31333 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0707 16:00:15.907081 31333 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I0707 16:00:15.999062 31333 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0707 16:00:16.088307 31333 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0707 16:00:16.185837 31333 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I0707 16:00:16.226187 31333 out.go:177]
W0707 16:00:16.247301 31333 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
W0707 16:00:16.247323 31333 out.go:239] *
W0707 16:00:16.248541 31333 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0707 16:00:16.314874 31333 out.go:177]
*
* ==> Docker <==
* -- Journal begins at Fri 2023-07-07 22:59:28 UTC, ends at Fri 2023-07-07 23:00:22 UTC. --
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.875347861Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:09 first-013000 cri-dockerd[1026]: time="2023-07-07T23:00:09Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/0fb60d771fba0b27520c5412678f3d36eee64cca7bb43ead692f0dc3fc327bf8/resolv.conf as [nameserver 192.168.64.1]"
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.979710887Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.979777770Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.979794997Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.979804585Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.986423004Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.986506184Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.986522893Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jul 07 23:00:09 first-013000 dockerd[1136]: time="2023-07-07T23:00:09.986597186Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:10 first-013000 cri-dockerd[1026]: time="2023-07-07T23:00:10Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/708cfebfb35e2df07d10ebc4feec77365bf1bf7342c4f1c85698e4cb0d31d955/resolv.conf as [nameserver 192.168.64.1]"
Jul 07 23:00:10 first-013000 dockerd[1136]: time="2023-07-07T23:00:10.355935074Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jul 07 23:00:10 first-013000 dockerd[1136]: time="2023-07-07T23:00:10.355997388Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:10 first-013000 dockerd[1136]: time="2023-07-07T23:00:10.356021689Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jul 07 23:00:10 first-013000 dockerd[1136]: time="2023-07-07T23:00:10.356294257Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.503624005Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.503700459Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.503717397Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.503728630Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:11 first-013000 cri-dockerd[1026]: time="2023-07-07T23:00:11Z" level=info msg="Will attempt to re-write config file /var/lib/docker/containers/e4420f0a3b4bcc9994be7bddb89e76a3c140ea6d90173611954715e2c4ae7d7c/resolv.conf as [nameserver 192.168.64.1]"
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.860777746Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.860840389Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.860998935Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jul 07 23:00:11 first-013000 dockerd[1136]: time="2023-07-07T23:00:11.861036688Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jul 07 23:00:17 first-013000 cri-dockerd[1026]: time="2023-07-07T23:00:17Z" 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
00a9c55e3fbf9 ead0a4a53df89 11 seconds ago Running coredns 0 e4420f0a3b4bc
af2a3d92627ef 6e38f40d628db 12 seconds ago Running storage-provisioner 0 708cfebfb35e2
f81b2313a30c2 5780543258cf0 13 seconds ago Running kube-proxy 0 0fb60d771fba0
1238639ff0ed4 41697ceeb70b3 32 seconds ago Running kube-scheduler 0 22f0d7dee1ba4
09ddd12e5bd59 86b6af7dd652c 32 seconds ago Running etcd 0 44325d0a5f320
7d650a923d412 08a0c939e61b7 32 seconds ago Running kube-apiserver 0 6b7c2ec8b52f7
0193ba7b0d79d 7cffc01dba0e1 33 seconds ago Running kube-controller-manager 0 a513fcb33309d
*
* ==> coredns [00a9c55e3fbf] <==
* .:53
[INFO] plugin/reload: Running configuration SHA512 = 82b95b61957b89eeea31bdaf6987f010031330ef97d5f8469dbdaa80b119a5b0c9955b961009dd5b77ee3ada002b456836be781510516cbd9d015b1a704a24ea
CoreDNS-1.10.1
linux/amd64, go1.20, 055b2c3
[INFO] 127.0.0.1:45895 - 54286 "HINFO IN 9076689397951422660.5140076821266140522. udp 57 false 512" NXDOMAIN qr,rd,ra 132 0.00361186s
*
* ==> describe nodes <==
* Name: first-013000
Roles: control-plane
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=first-013000
kubernetes.io/os=linux
minikube.k8s.io/commit=3dd06f17c6a1b64a4b1936ddf0915ac0c80e3794
minikube.k8s.io/name=first-013000
minikube.k8s.io/primary=true
minikube.k8s.io/updated_at=2023_07_07T15_59_56_0700
minikube.k8s.io/version=v1.30.1
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, 07 Jul 2023 22:59:53 +0000
Taints: <none>
Unschedulable: false
Lease:
HolderIdentity: first-013000
AcquireTime: <unset>
RenewTime: Fri, 07 Jul 2023 23:00:17 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Fri, 07 Jul 2023 23:00:17 +0000 Fri, 07 Jul 2023 22:59:51 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Fri, 07 Jul 2023 23:00:17 +0000 Fri, 07 Jul 2023 22:59:51 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Fri, 07 Jul 2023 23:00:17 +0000 Fri, 07 Jul 2023 22:59:51 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready True Fri, 07 Jul 2023 23:00:17 +0000 Fri, 07 Jul 2023 22:59:57 +0000 KubeletReady kubelet is posting ready status
Addresses:
InternalIP: 192.168.64.51
Hostname: first-013000
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: fbb3647144bb4f379a642a10b23c3f34
System UUID: e79211ee-0000-0000-9e09-149d997f80ea
Boot ID: 012a44b2-dcff-48df-afad-c80a54a4d15c
Kernel Version: 5.10.57
OS Image: Buildroot 2021.02.12
Operating System: linux
Architecture: amd64
Container Runtime Version: docker://24.0.2
Kubelet Version: v1.27.3
Kube-Proxy Version: v1.27.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-5d78c9869d-s4ln4 100m (5%!)(MISSING) 0 (0%!)(MISSING) 70Mi (1%!)(MISSING) 170Mi (2%!)(MISSING) 14s
kube-system etcd-first-013000 100m (5%!)(MISSING) 0 (0%!)(MISSING) 100Mi (1%!)(MISSING) 0 (0%!)(MISSING) 29s
kube-system kube-apiserver-first-013000 250m (12%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 29s
kube-system kube-controller-manager-first-013000 200m (10%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 27s
kube-system kube-proxy-6zrwv 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 14s
kube-system kube-scheduler-first-013000 100m (5%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 27s
kube-system storage-provisioner 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 26s
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 13s kube-proxy
Normal Starting 35s kubelet Starting kubelet.
Normal NodeHasSufficientMemory 34s (x8 over 35s) kubelet Node first-013000 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 34s (x8 over 35s) kubelet Node first-013000 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 34s (x7 over 35s) kubelet Node first-013000 status is now: NodeHasSufficientPID
Normal NodeAllocatableEnforced 34s kubelet Updated Node Allocatable limit across pods
Normal Starting 27s kubelet Starting kubelet.
Normal NodeAllocatableEnforced 27s kubelet Updated Node Allocatable limit across pods
Normal NodeHasSufficientMemory 27s kubelet Node first-013000 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 27s kubelet Node first-013000 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 27s kubelet Node first-013000 status is now: NodeHasSufficientPID
Normal NodeReady 26s kubelet Node first-013000 status is now: NodeReady
Normal RegisteredNode 14s node-controller Node first-013000 event: Registered Node first-013000 in Controller
*
* ==> dmesg <==
* [ +0.000002] ACPI Warning: Could not enable fixed event - RealTimeClock (4) (20200925/evxface-618)
[ +0.008973] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ +3.145937] systemd-fstab-generator[125]: Ignoring "noauto" for root device
[ +0.037669] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[ +0.000002] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[ +1.903582] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ +0.000007] NFSD: unable to find recovery directory /var/lib/nfs/v4recovery
[ +0.000002] NFSD: Unable to initialize client recovery tracking! (-2)
[ +3.150084] systemd-fstab-generator[550]: Ignoring "noauto" for root device
[ +0.087374] systemd-fstab-generator[561]: Ignoring "noauto" for root device
[ +0.802036] systemd-fstab-generator[747]: Ignoring "noauto" for root device
[ +0.211936] systemd-fstab-generator[787]: Ignoring "noauto" for root device
[ +0.091131] systemd-fstab-generator[798]: Ignoring "noauto" for root device
[ +0.096959] systemd-fstab-generator[811]: Ignoring "noauto" for root device
[ +1.278729] kauditd_printk_skb: 16 callbacks suppressed
[ +0.156167] systemd-fstab-generator[971]: Ignoring "noauto" for root device
[ +0.091563] systemd-fstab-generator[982]: Ignoring "noauto" for root device
[ +0.090543] systemd-fstab-generator[993]: Ignoring "noauto" for root device
[ +0.085043] systemd-fstab-generator[1004]: Ignoring "noauto" for root device
[ +0.103638] systemd-fstab-generator[1018]: Ignoring "noauto" for root device
[ +5.412673] systemd-fstab-generator[1121]: Ignoring "noauto" for root device
[ +1.805631] kauditd_printk_skb: 29 callbacks suppressed
[ +4.156735] systemd-fstab-generator[1443]: Ignoring "noauto" for root device
[ +7.795613] systemd-fstab-generator[2369]: Ignoring "noauto" for root device
[Jul 7 23:00] kauditd_printk_skb: 39 callbacks suppressed
*
* ==> etcd [09ddd12e5bd5] <==
* {"level":"info","ts":"2023-07-07T22:59:51.240Z","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-07-07T22:59:51.240Z","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-07-07T22:59:51.240Z","caller":"embed/etcd.go:762","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2023-07-07T22:59:51.240Z","caller":"embed/etcd.go:586","msg":"serving peer traffic","address":"192.168.64.51:2380"}
{"level":"info","ts":"2023-07-07T22:59:51.240Z","caller":"embed/etcd.go:558","msg":"cmux::serve","address":"192.168.64.51:2380"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 is starting a new election at term 1"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 became pre-candidate at term 1"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 received MsgPreVoteResp from b8434eb7a3474524 at term 1"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 became candidate at term 2"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 received MsgVoteResp from b8434eb7a3474524 at term 2"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b8434eb7a3474524 became leader at term 2"}
{"level":"info","ts":"2023-07-07T22:59:51.590Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b8434eb7a3474524 elected leader b8434eb7a3474524 at term 2"}
{"level":"info","ts":"2023-07-07T22:59:51.599Z","caller":"etcdserver/server.go:2062","msg":"published local member to cluster through raft","local-member-id":"b8434eb7a3474524","local-member-attributes":"{Name:first-013000 ClientURLs:[https://192.168.64.51:2379]}","request-path":"/0/members/b8434eb7a3474524/attributes","cluster-id":"4fda3446f8920824","publish-timeout":"7s"}
{"level":"info","ts":"2023-07-07T22:59:51.599Z","caller":"embed/serve.go:100","msg":"ready to serve client requests"}
{"level":"info","ts":"2023-07-07T22:59:51.602Z","caller":"embed/serve.go:198","msg":"serving client traffic securely","address":"192.168.64.51:2379"}
{"level":"info","ts":"2023-07-07T22:59:51.602Z","caller":"etcdserver/server.go:2571","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2023-07-07T22:59:51.604Z","caller":"embed/serve.go:100","msg":"ready to serve client requests"}
{"level":"info","ts":"2023-07-07T22:59:51.623Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2023-07-07T22:59:51.623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2023-07-07T22:59:51.627Z","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-07-07T22:59:51.627Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2023-07-07T22:59:51.627Z","caller":"etcdserver/server.go:2595","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2023-07-07T22:59:51.628Z","caller":"embed/serve.go:198","msg":"serving client traffic securely","address":"127.0.0.1:2379"}
{"level":"info","ts":"2023-07-07T22:59:58.481Z","caller":"traceutil/trace.go:171","msg":"trace[1302482345] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"100.702696ms","start":"2023-07-07T22:59:58.380Z","end":"2023-07-07T22:59:58.481Z","steps":["trace[1302482345] 'process raft request' (duration: 100.648496ms)"],"step_count":1}
{"level":"info","ts":"2023-07-07T22:59:58.787Z","caller":"traceutil/trace.go:171","msg":"trace[1006946902] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"110.27163ms","start":"2023-07-07T22:59:58.677Z","end":"2023-07-07T22:59:58.787Z","steps":["trace[1006946902] 'process raft request' (duration: 72.831398ms)","trace[1006946902] 'compare' (duration: 37.29625ms)"],"step_count":2}
*
* ==> kernel <==
* 23:00:23 up 1 min, 0 users, load average: 0.72, 0.26, 0.09
Linux first-013000 5.10.57 #1 SMP Fri Jun 30 21:41:53 UTC 2023 x86_64 GNU/Linux
PRETTY_NAME="Buildroot 2021.02.12"
*
* ==> kube-apiserver [7d650a923d41] <==
* I0707 22:59:53.270482 1 shared_informer.go:318] Caches are synced for crd-autoregister
I0707 22:59:53.270761 1 aggregator.go:152] initial CRD sync complete...
I0707 22:59:53.270922 1 autoregister_controller.go:141] Starting autoregister controller
I0707 22:59:53.271034 1 cache.go:32] Waiting for caches to sync for autoregister controller
I0707 22:59:53.271082 1 cache.go:39] Caches are synced for autoregister controller
I0707 22:59:53.321643 1 shared_informer.go:318] Caches are synced for node_authorizer
E0707 22:59:53.335715 1 controller.go:146] "Failed to ensure lease exists, will retry" err="namespaces \"kube-system\" not found" interval="200ms"
E0707 22:59:53.335760 1 controller.go:150] while syncing ConfigMap "kube-system/kube-apiserver-legacy-service-account-token-tracking", err: namespaces "kube-system" not found
I0707 22:59:53.405091 1 controller.go:624] quota admission added evaluator for: leases.coordination.k8s.io
I0707 22:59:53.927920 1 controller.go:132] OpenAPI AggregationController: action for item k8s_internal_local_delegation_chain_0000000000: Nothing (removed from the queue).
I0707 22:59:54.197953 1 storage_scheduling.go:95] created PriorityClass system-node-critical with value 2000001000
I0707 22:59:54.200958 1 storage_scheduling.go:95] created PriorityClass system-cluster-critical with value 2000000000
I0707 22:59:54.200988 1 storage_scheduling.go:111] all system priority classes are created successfully or already exist.
I0707 22:59:54.503680 1 controller.go:624] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I0707 22:59:54.541853 1 controller.go:624] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
I0707 22:59:54.653378 1 alloc.go:330] "allocated clusterIPs" service="default/kubernetes" clusterIPs=map[IPv4:10.96.0.1]
W0707 22:59:54.657540 1 lease.go:251] Resetting endpoints for master service "kubernetes" to [192.168.64.51]
I0707 22:59:54.658261 1 controller.go:624] quota admission added evaluator for: endpoints
I0707 22:59:54.661554 1 controller.go:624] quota admission added evaluator for: endpointslices.discovery.k8s.io
I0707 22:59:55.232095 1 controller.go:624] quota admission added evaluator for: serviceaccounts
I0707 22:59:56.296307 1 controller.go:624] quota admission added evaluator for: deployments.apps
I0707 22:59:56.303923 1 alloc.go:330] "allocated clusterIPs" service="kube-system/kube-dns" clusterIPs=map[IPv4:10.96.0.10]
I0707 22:59:56.311580 1 controller.go:624] quota admission added evaluator for: daemonsets.apps
I0707 23:00:09.502702 1 controller.go:624] quota admission added evaluator for: controllerrevisions.apps
I0707 23:00:09.507208 1 controller.go:624] quota admission added evaluator for: replicasets.apps
*
* ==> kube-controller-manager [0193ba7b0d79] <==
* I0707 23:00:09.490305 1 range_allocator.go:174] "Sending events to api server"
I0707 23:00:09.490366 1 range_allocator.go:178] "Starting range CIDR allocator"
I0707 23:00:09.490395 1 shared_informer.go:311] Waiting for caches to sync for cidrallocator
I0707 23:00:09.490402 1 shared_informer.go:318] Caches are synced for cidrallocator
I0707 23:00:09.490554 1 shared_informer.go:318] Caches are synced for expand
I0707 23:00:09.493020 1 shared_informer.go:318] Caches are synced for ReplicationController
I0707 23:00:09.496061 1 shared_informer.go:318] Caches are synced for crt configmap
I0707 23:00:09.504651 1 shared_informer.go:318] Caches are synced for attach detach
I0707 23:00:09.507402 1 shared_informer.go:318] Caches are synced for PV protection
I0707 23:00:09.508591 1 range_allocator.go:380] "Set node PodCIDR" node="first-013000" podCIDRs=[10.244.0.0/24]
I0707 23:00:09.515140 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-5d78c9869d to 1"
I0707 23:00:09.532197 1 event.go:307] "Event occurred" object="kube-system/kube-proxy" fieldPath="" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-6zrwv"
I0707 23:00:09.564588 1 event.go:307] "Event occurred" object="kube-system/coredns-5d78c9869d" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-5d78c9869d-s4ln4"
I0707 23:00:09.580938 1 shared_informer.go:318] Caches are synced for taint
I0707 23:00:09.581028 1 node_lifecycle_controller.go:1223] "Initializing eviction metric for zone" zone=""
I0707 23:00:09.581119 1 node_lifecycle_controller.go:875] "Missing timestamp for Node. Assuming now as a timestamp" node="first-013000"
I0707 23:00:09.581168 1 node_lifecycle_controller.go:1069] "Controller detected that zone is now in new state" zone="" newState=Normal
I0707 23:00:09.581180 1 taint_manager.go:206] "Starting NoExecuteTaintManager"
I0707 23:00:09.581204 1 taint_manager.go:211] "Sending events to api server"
I0707 23:00:09.581694 1 event.go:307] "Event occurred" object="first-013000" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node first-013000 event: Registered Node first-013000 in Controller"
I0707 23:00:09.686653 1 shared_informer.go:318] Caches are synced for resource quota
I0707 23:00:09.693727 1 shared_informer.go:318] Caches are synced for resource quota
I0707 23:00:10.025363 1 shared_informer.go:318] Caches are synced for garbage collector
I0707 23:00:10.031098 1 shared_informer.go:318] Caches are synced for garbage collector
I0707 23:00:10.031288 1 garbagecollector.go:166] "All resource monitors have synced. Proceeding to collect garbage"
*
* ==> kube-proxy [f81b2313a30c] <==
* I0707 23:00:10.098491 1 node.go:141] Successfully retrieved node IP: 192.168.64.51
I0707 23:00:10.098588 1 server_others.go:110] "Detected node IP" address="192.168.64.51"
I0707 23:00:10.098611 1 server_others.go:554] "Using iptables proxy"
I0707 23:00:10.134008 1 server_others.go:178] "kube-proxy running in single-stack mode: secondary ipFamily is not supported" ipFamily=IPv6
I0707 23:00:10.134152 1 server_others.go:192] "Using iptables Proxier"
I0707 23:00:10.134212 1 proxier.go:253] "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"
I0707 23:00:10.134573 1 server.go:658] "Version info" version="v1.27.3"
I0707 23:00:10.134664 1 server.go:660] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
I0707 23:00:10.135159 1 config.go:188] "Starting service config controller"
I0707 23:00:10.135258 1 shared_informer.go:311] Waiting for caches to sync for service config
I0707 23:00:10.135312 1 config.go:97] "Starting endpoint slice config controller"
I0707 23:00:10.135326 1 shared_informer.go:311] Waiting for caches to sync for endpoint slice config
I0707 23:00:10.135794 1 config.go:315] "Starting node config controller"
I0707 23:00:10.135821 1 shared_informer.go:311] Waiting for caches to sync for node config
I0707 23:00:10.236072 1 shared_informer.go:318] Caches are synced for node config
I0707 23:00:10.236136 1 shared_informer.go:318] Caches are synced for endpoint slice config
I0707 23:00:10.236079 1 shared_informer.go:318] Caches are synced for service config
*
* ==> kube-scheduler [1238639ff0ed] <==
* W0707 22:59:53.262143 1 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E0707 22:59:53.262195 1 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
W0707 22:59:53.262373 1 reflector.go:533] 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
E0707 22:59:53.262403 1 reflector.go:148] 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
W0707 22:59:53.262450 1 reflector.go:533] 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
E0707 22:59:53.262533 1 reflector.go:148] 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
W0707 22:59:53.262641 1 reflector.go:533] 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
E0707 22:59:53.262674 1 reflector.go:148] 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
W0707 22:59:53.262695 1 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
E0707 22:59:53.262700 1 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Namespace: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
W0707 22:59:54.133947 1 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E0707 22:59:54.133999 1 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
W0707 22:59:54.134455 1 reflector.go:533] 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
E0707 22:59:54.134471 1 reflector.go:148] 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
W0707 22:59:54.156280 1 reflector.go:533] 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
E0707 22:59:54.156330 1 reflector.go:148] 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
W0707 22:59:54.157234 1 reflector.go:533] 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
E0707 22:59:54.157471 1 reflector.go:148] 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
W0707 22:59:54.214218 1 reflector.go:533] 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
E0707 22:59:54.214236 1 reflector.go:148] 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
W0707 22:59:54.376641 1 reflector.go:533] 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
E0707 22:59:54.376678 1 reflector.go:148] 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
W0707 22:59:54.440019 1 reflector.go:533] 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"
E0707 22:59:54.440219 1 reflector.go:148] 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"
I0707 22:59:57.249142 1 shared_informer.go:318] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
*
* ==> kubelet <==
* -- Journal begins at Fri 2023-07-07 22:59:28 UTC, ends at Fri 2023-07-07 23:00:23 UTC. --
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.275769 2388 kubelet_node_status.go:493] "Fast updating node status as it just became ready"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.386325 2388 apiserver.go:52] "Watching apiserver"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.408911 2388 desired_state_of_world_populator.go:153] "Finished populating initial desired state of world"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.428922 2388 reconciler.go:41] "Reconciler: start to sync state"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.503229 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-apiserver-first-013000" podStartSLOduration=3.502263127 podCreationTimestamp="2023-07-07 22:59:54 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 22:59:57.502234039 +0000 UTC m=+1.228562929" watchObservedRunningTime="2023-07-07 22:59:57.502263127 +0000 UTC m=+1.228592015"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.509665 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-controller-manager-first-013000" podStartSLOduration=1.509645084 podCreationTimestamp="2023-07-07 22:59:56 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 22:59:57.509184246 +0000 UTC m=+1.235513136" watchObservedRunningTime="2023-07-07 22:59:57.509645084 +0000 UTC m=+1.235973980"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.516420 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-scheduler-first-013000" podStartSLOduration=1.516326243 podCreationTimestamp="2023-07-07 22:59:56 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 22:59:57.515715046 +0000 UTC m=+1.242043936" watchObservedRunningTime="2023-07-07 22:59:57.516326243 +0000 UTC m=+1.242655132"
Jul 07 22:59:57 first-013000 kubelet[2388]: I0707 22:59:57.532403 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/etcd-first-013000" podStartSLOduration=3.5323802669999997 podCreationTimestamp="2023-07-07 22:59:54 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 22:59:57.522496304 +0000 UTC m=+1.248825194" watchObservedRunningTime="2023-07-07 22:59:57.532380267 +0000 UTC m=+1.258709157"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.533077 2388 topology_manager.go:212] "Topology Admit Handler"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.607366 2388 topology_manager.go:212] "Topology Admit Handler"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.620265 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b103bd6c-f82a-4d20-a0f3-d587cfe7b842-kube-proxy\") pod \"kube-proxy-6zrwv\" (UID: \"b103bd6c-f82a-4d20-a0f3-d587cfe7b842\") " pod="kube-system/kube-proxy-6zrwv"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.620336 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sjkjm\" (UniqueName: \"kubernetes.io/projected/b103bd6c-f82a-4d20-a0f3-d587cfe7b842-kube-api-access-sjkjm\") pod \"kube-proxy-6zrwv\" (UID: \"b103bd6c-f82a-4d20-a0f3-d587cfe7b842\") " pod="kube-system/kube-proxy-6zrwv"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.620358 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b103bd6c-f82a-4d20-a0f3-d587cfe7b842-xtables-lock\") pod \"kube-proxy-6zrwv\" (UID: \"b103bd6c-f82a-4d20-a0f3-d587cfe7b842\") " pod="kube-system/kube-proxy-6zrwv"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.620373 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b103bd6c-f82a-4d20-a0f3-d587cfe7b842-lib-modules\") pod \"kube-proxy-6zrwv\" (UID: \"b103bd6c-f82a-4d20-a0f3-d587cfe7b842\") " pod="kube-system/kube-proxy-6zrwv"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.620388 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"tmp\" (UniqueName: \"kubernetes.io/host-path/a294981e-35ca-4a70-be07-6b3a20560195-tmp\") pod \"storage-provisioner\" (UID: \"a294981e-35ca-4a70-be07-6b3a20560195\") " pod="kube-system/storage-provisioner"
Jul 07 23:00:09 first-013000 kubelet[2388]: I0707 23:00:09.620403 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h47q4\" (UniqueName: \"kubernetes.io/projected/a294981e-35ca-4a70-be07-6b3a20560195-kube-api-access-h47q4\") pod \"storage-provisioner\" (UID: \"a294981e-35ca-4a70-be07-6b3a20560195\") " pod="kube-system/storage-provisioner"
Jul 07 23:00:10 first-013000 kubelet[2388]: I0707 23:00:10.538823 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-6zrwv" podStartSLOduration=1.538797242 podCreationTimestamp="2023-07-07 23:00:09 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 23:00:10.537906545 +0000 UTC m=+14.264235435" watchObservedRunningTime="2023-07-07 23:00:10.538797242 +0000 UTC m=+14.265126138"
Jul 07 23:00:11 first-013000 kubelet[2388]: I0707 23:00:11.161429 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/storage-provisioner" podStartSLOduration=14.16140142 podCreationTimestamp="2023-07-07 22:59:57 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 23:00:10.55229603 +0000 UTC m=+14.278624919" watchObservedRunningTime="2023-07-07 23:00:11.16140142 +0000 UTC m=+14.887730315"
Jul 07 23:00:11 first-013000 kubelet[2388]: I0707 23:00:11.161604 2388 topology_manager.go:212] "Topology Admit Handler"
Jul 07 23:00:11 first-013000 kubelet[2388]: I0707 23:00:11.232308 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jpb8p\" (UniqueName: \"kubernetes.io/projected/6453464f-5ed5-4793-aafe-fa6f0ba686ab-kube-api-access-jpb8p\") pod \"coredns-5d78c9869d-s4ln4\" (UID: \"6453464f-5ed5-4793-aafe-fa6f0ba686ab\") " pod="kube-system/coredns-5d78c9869d-s4ln4"
Jul 07 23:00:11 first-013000 kubelet[2388]: I0707 23:00:11.232412 2388 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"config-volume\" (UniqueName: \"kubernetes.io/configmap/6453464f-5ed5-4793-aafe-fa6f0ba686ab-config-volume\") pod \"coredns-5d78c9869d-s4ln4\" (UID: \"6453464f-5ed5-4793-aafe-fa6f0ba686ab\") " pod="kube-system/coredns-5d78c9869d-s4ln4"
Jul 07 23:00:11 first-013000 kubelet[2388]: I0707 23:00:11.791050 2388 pod_container_deletor.go:80] "Container not found in pod's containers" containerID="e4420f0a3b4bcc9994be7bddb89e76a3c140ea6d90173611954715e2c4ae7d7c"
Jul 07 23:00:12 first-013000 kubelet[2388]: I0707 23:00:12.808361 2388 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/coredns-5d78c9869d-s4ln4" podStartSLOduration=3.808340067 podCreationTimestamp="2023-07-07 23:00:09 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2023-07-07 23:00:12.808122041 +0000 UTC m=+16.534450932" watchObservedRunningTime="2023-07-07 23:00:12.808340067 +0000 UTC m=+16.534668957"
Jul 07 23:00:17 first-013000 kubelet[2388]: I0707 23:00:17.187259 2388 kuberuntime_manager.go:1460] "Updating runtime config through cri with podcidr" CIDR="10.244.0.0/24"
Jul 07 23:00:17 first-013000 kubelet[2388]: I0707 23:00:17.188683 2388 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="10.244.0.0/24"
*
* ==> storage-provisioner [af2a3d92627e] <==
* I0707 23:00:10.401025 1 storage_provisioner.go:116] Initializing the minikube storage provisioner...
I0707 23:00:10.408750 1 storage_provisioner.go:141] Storage provisioner initialized, now starting service!
I0707 23:00:10.408807 1 leaderelection.go:243] attempting to acquire leader lease kube-system/k8s.io-minikube-hostpath...
I0707 23:00:10.413785 1 leaderelection.go:253] successfully acquired lease kube-system/k8s.io-minikube-hostpath
I0707 23:00:10.414299 1 event.go:282] Event(v1.ObjectReference{Kind:"Endpoints", Namespace:"kube-system", Name:"k8s.io-minikube-hostpath", UID:"bd18016b-4535-49cf-ba00-26c498cb49d1", APIVersion:"v1", ResourceVersion:"394", FieldPath:""}): type: 'Normal' reason: 'LeaderElection' first-013000_2e824ee5-1485-4c22-856c-c9973aba7269 became leader
I0707 23:00:10.414383 1 controller.go:835] Starting provisioner controller k8s.io/minikube-hostpath_first-013000_2e824ee5-1485-4c22-856c-c9973aba7269!
I0707 23:00:10.514970 1 controller.go:884] Started provisioner controller k8s.io/minikube-hostpath_first-013000_2e824ee5-1485-4c22-856c-c9973aba7269!
-- /stdout --
helpers_test.go:254: (dbg) Run: out/minikube-darwin-amd64 status --format={{.APIServer}} -p first-013000 -n first-013000
helpers_test.go:261: (dbg) Run: kubectl --context first-013000 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-013000" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-darwin-amd64 delete -p first-013000
helpers_test.go:178: (dbg) Done: out/minikube-darwin-amd64 delete -p first-013000: (5.251654474s)
--- FAIL: TestMinikubeProfile (70.81s)