=== RUN TestFunctional/serial/SoftStart
functional_test.go:655: (dbg) Run: out/minikube-darwin-amd64 start -p functional-196000 --alsologtostderr -v=8
functional_test.go:655: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p functional-196000 --alsologtostderr -v=8: exit status 60 (5.795195334s)
-- stdout --
* [functional-196000] minikube v1.33.0-beta.0 on Darwin 14.4.1
- MINIKUBE_LOCATION=18641
- KUBECONFIG=/Users/jenkins/minikube-integration/18641-1206/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/18641-1206/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on existing profile
* Starting "functional-196000" primary control-plane node in "functional-196000" cluster
* Restarting existing hyperkit VM for "functional-196000" ...
* Restarting existing hyperkit VM for "functional-196000" ...
-- /stdout --
** stderr **
I0415 03:29:45.502259 2347 out.go:291] Setting OutFile to fd 1 ...
I0415 03:29:45.502430 2347 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0415 03:29:45.502436 2347 out.go:304] Setting ErrFile to fd 2...
I0415 03:29:45.502440 2347 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0415 03:29:45.503269 2347 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/18641-1206/.minikube/bin
I0415 03:29:45.504972 2347 out.go:298] Setting JSON to false
I0415 03:29:45.526960 2347 start.go:129] hostinfo: {"hostname":"MacOS-Agent-4.local","uptime":1755,"bootTime":1713175230,"procs":442,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.4.1","kernelVersion":"23.4.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"f2f27e25-cfda-5ffd-9706-e98286194e62"}
W0415 03:29:45.527059 2347 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0415 03:29:45.548514 2347 out.go:177] * [functional-196000] minikube v1.33.0-beta.0 on Darwin 14.4.1
I0415 03:29:45.591164 2347 out.go:177] - MINIKUBE_LOCATION=18641
I0415 03:29:45.591254 2347 notify.go:220] Checking for updates...
I0415 03:29:45.613203 2347 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/18641-1206/kubeconfig
I0415 03:29:45.634470 2347 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0415 03:29:45.656209 2347 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0415 03:29:45.677299 2347 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/18641-1206/.minikube
I0415 03:29:45.698348 2347 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0415 03:29:45.720309 2347 config.go:182] Loaded profile config "functional-196000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.29.3
I0415 03:29:45.720486 2347 driver.go:392] Setting default libvirt URI to qemu:///system
I0415 03:29:45.721194 2347 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0415 03:29:45.721265 2347 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0415 03:29:45.730374 2347 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:49855
I0415 03:29:45.730747 2347 main.go:141] libmachine: () Calling .GetVersion
I0415 03:29:45.731176 2347 main.go:141] libmachine: Using API Version 1
I0415 03:29:45.731189 2347 main.go:141] libmachine: () Calling .SetConfigRaw
I0415 03:29:45.731436 2347 main.go:141] libmachine: () Calling .GetMachineName
I0415 03:29:45.731556 2347 main.go:141] libmachine: (functional-196000) Calling .DriverName
I0415 03:29:45.788208 2347 out.go:177] * Using the hyperkit driver based on existing profile
I0415 03:29:45.830110 2347 start.go:297] selected driver: hyperkit
I0415 03:29:45.830141 2347 start.go:901] validating driver "hyperkit" against &{Name:functional-196000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18621/minikube-v1.33.0-1712854267-18621-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.43-1712854342-18621@sha256:ed83a14d1540ae575c52399493a92b74b64f457445525b45c4b55f3ec4ca873f Memory:4000 CPUs:2 DiskSize:20000 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:8441 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfi
g:{KubernetesVersion:v1.29.3 ClusterName:functional-196000 Namespace:default APIServerHAVIP: 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:} Nodes:[{Name: IP: Port:8441 KubernetesVersion:v1.29.3 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] Moun
tPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0415 03:29:45.830370 2347 start.go:912] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0415 03:29:45.830584 2347 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0415 03:29:45.830799 2347 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/18641-1206/.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
I0415 03:29:45.839898 2347 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.33.0-beta.0
I0415 03:29:45.843628 2347 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0415 03:29:45.843653 2347 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0415 03:29:45.846265 2347 cni.go:84] Creating CNI manager for ""
I0415 03:29:45.846290 2347 cni.go:158] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0415 03:29:45.846375 2347 start.go:340] cluster config:
{Name:functional-196000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18621/minikube-v1.33.0-1712854267-18621-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.43-1712854342-18621@sha256:ed83a14d1540ae575c52399493a92b74b64f457445525b45c4b55f3ec4ca873f Memory:4000 CPUs:2 DiskSize:20000 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:8441 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.29.3 ClusterName:functional-196000 Namespace:default APISe
rverHAVIP: 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:} Nodes:[{Name: IP: Port:8441 KubernetesVersion:v1.29.3 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false Disa
bleMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0415 03:29:45.846478 2347 iso.go:125] acquiring lock: {Name:mkc51bceea1bacd04dd8d0a0bb2cc95f4d9d7b0c Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0415 03:29:45.892875 2347 out.go:177] * Starting "functional-196000" primary control-plane node in "functional-196000" cluster
I0415 03:29:45.913923 2347 preload.go:132] Checking if preload exists for k8s version v1.29.3 and runtime docker
I0415 03:29:45.913996 2347 preload.go:147] Found local preload: /Users/jenkins/minikube-integration/18641-1206/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4
I0415 03:29:45.914024 2347 cache.go:56] Caching tarball of preloaded images
I0415 03:29:45.914222 2347 preload.go:173] Found /Users/jenkins/minikube-integration/18641-1206/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0415 03:29:45.914240 2347 cache.go:59] Finished verifying existence of preloaded tar for v1.29.3 on docker
I0415 03:29:45.914376 2347 profile.go:143] Saving config to /Users/jenkins/minikube-integration/18641-1206/.minikube/profiles/functional-196000/config.json ...
I0415 03:29:45.915224 2347 start.go:360] acquireMachinesLock for functional-196000: {Name:mk4519de2f1a4bf5ac296dd5cdddacf75580a4fb Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0415 03:29:45.915323 2347 start.go:364] duration metric: took 79.786µs to acquireMachinesLock for "functional-196000"
I0415 03:29:45.915350 2347 start.go:96] Skipping create...Using existing machine configuration
I0415 03:29:45.915366 2347 fix.go:54] fixHost starting:
I0415 03:29:45.915661 2347 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0415 03:29:45.915687 2347 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0415 03:29:45.924535 2347 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:49857
I0415 03:29:45.924871 2347 main.go:141] libmachine: () Calling .GetVersion
I0415 03:29:45.925218 2347 main.go:141] libmachine: Using API Version 1
I0415 03:29:45.925228 2347 main.go:141] libmachine: () Calling .SetConfigRaw
I0415 03:29:45.925444 2347 main.go:141] libmachine: () Calling .GetMachineName
I0415 03:29:45.925560 2347 main.go:141] libmachine: (functional-196000) Calling .DriverName
I0415 03:29:45.925691 2347 main.go:141] libmachine: (functional-196000) Calling .GetState
I0415 03:29:45.925801 2347 main.go:141] libmachine: (functional-196000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0415 03:29:45.925832 2347 main.go:141] libmachine: (functional-196000) DBG | hyperkit pid from json: 0
I0415 03:29:45.925848 2347 main.go:141] libmachine: (functional-196000) Error reading pid file: open /Users/jenkins/minikube-integration/18641-1206/.minikube/machines/functional-196000/hyperkit.json: no such file or directory
I0415 03:29:45.925861 2347 fix.go:112] recreateIfNeeded on functional-196000: state=Stopped err=<nil>
I0415 03:29:45.925891 2347 main.go:141] libmachine: (functional-196000) Calling .DriverName
W0415 03:29:45.925982 2347 fix.go:138] unexpected machine state, will restart: <nil>
I0415 03:29:45.946809 2347 out.go:177] * Restarting existing hyperkit VM for "functional-196000" ...
I0415 03:29:45.988986 2347 main.go:141] libmachine: (functional-196000) Calling .Start
I0415 03:29:45.989262 2347 main.go:141] libmachine: (functional-196000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0415 03:29:45.989303 2347 main.go:141] libmachine: (functional-196000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/18641-1206/.minikube/machines/functional-196000/hyperkit.pid
I0415 03:29:45.989385 2347 main.go:141] libmachine: (functional-196000) DBG | Using UUID cd6ebe4d-4732-4dc7-8067-7553f8213a54
I0415 03:29:45.991768 2347 main.go:141] libmachine: (functional-196000) DBG | virtio_net: Could not create vmnet interface, permission denied or no entitlement?
I0415 03:29:45.991911 2347 fix.go:56] duration metric: took 76.546939ms for fixHost
I0415 03:29:45.991922 2347 start.go:83] releasing machines lock for "functional-196000", held for 76.58951ms
W0415 03:29:45.991941 2347 start.go:713] error starting host: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
W0415 03:29:45.991999 2347 out.go:239] ! StartHost failed, but will try again: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
! StartHost failed, but will try again: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
I0415 03:29:45.992011 2347 start.go:728] Will try again in 5 seconds ...
I0415 03:29:50.993007 2347 start.go:360] acquireMachinesLock for functional-196000: {Name:mk4519de2f1a4bf5ac296dd5cdddacf75580a4fb Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0415 03:29:50.993195 2347 start.go:364] duration metric: took 152.367µs to acquireMachinesLock for "functional-196000"
I0415 03:29:50.993230 2347 start.go:96] Skipping create...Using existing machine configuration
I0415 03:29:50.993240 2347 fix.go:54] fixHost starting:
I0415 03:29:50.993693 2347 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0415 03:29:50.993755 2347 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0415 03:29:51.003380 2347 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:49859
I0415 03:29:51.003897 2347 main.go:141] libmachine: () Calling .GetVersion
I0415 03:29:51.004390 2347 main.go:141] libmachine: Using API Version 1
I0415 03:29:51.004416 2347 main.go:141] libmachine: () Calling .SetConfigRaw
I0415 03:29:51.004729 2347 main.go:141] libmachine: () Calling .GetMachineName
I0415 03:29:51.004853 2347 main.go:141] libmachine: (functional-196000) Calling .DriverName
I0415 03:29:51.004952 2347 main.go:141] libmachine: (functional-196000) Calling .GetState
I0415 03:29:51.005033 2347 main.go:141] libmachine: (functional-196000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0415 03:29:51.005051 2347 main.go:141] libmachine: (functional-196000) Error reading pid file: open /Users/jenkins/minikube-integration/18641-1206/.minikube/machines/functional-196000/hyperkit.json: no such file or directory
I0415 03:29:51.005058 2347 main.go:141] libmachine: (functional-196000) DBG | hyperkit pid from json: 0
I0415 03:29:51.005093 2347 fix.go:112] recreateIfNeeded on functional-196000: state=Stopped err=<nil>
I0415 03:29:51.005104 2347 main.go:141] libmachine: (functional-196000) Calling .DriverName
W0415 03:29:51.005186 2347 fix.go:138] unexpected machine state, will restart: <nil>
I0415 03:29:51.026829 2347 out.go:177] * Restarting existing hyperkit VM for "functional-196000" ...
I0415 03:29:51.068539 2347 main.go:141] libmachine: (functional-196000) Calling .Start
I0415 03:29:51.068856 2347 main.go:141] libmachine: (functional-196000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0415 03:29:51.068904 2347 main.go:141] libmachine: (functional-196000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/18641-1206/.minikube/machines/functional-196000/hyperkit.pid
I0415 03:29:51.068928 2347 main.go:141] libmachine: (functional-196000) DBG | Using UUID cd6ebe4d-4732-4dc7-8067-7553f8213a54
I0415 03:29:51.071198 2347 main.go:141] libmachine: (functional-196000) DBG | virtio_net: Could not create vmnet interface, permission denied or no entitlement?
I0415 03:29:51.071267 2347 fix.go:56] duration metric: took 78.027183ms for fixHost
I0415 03:29:51.071276 2347 start.go:83] releasing machines lock for "functional-196000", held for 78.068302ms
W0415 03:29:51.071375 2347 out.go:239] * Failed to start hyperkit VM. Running "minikube delete -p functional-196000" may fix it: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
* Failed to start hyperkit VM. Running "minikube delete -p functional-196000" may fix it: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
I0415 03:29:51.113510 2347 out.go:177]
W0415 03:29:51.134453 2347 out.go:239] X Exiting due to PR_HYPERKIT_VMNET_FRAMEWORK: Failed to start host: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
X Exiting due to PR_HYPERKIT_VMNET_FRAMEWORK: Failed to start host: driver start: getting MAC address from UUID: vmnet: error from vmnet.framework: -1
W0415 03:29:51.134522 2347 out.go:239] * Suggestion:
Hyperkit networking is broken. Try disabling Internet Sharing: System Preference > Sharing > Internet Sharing.
Alternatively, you can try upgrading to the latest hyperkit version, or using an alternate driver.
* Suggestion:
Hyperkit networking is broken. Try disabling Internet Sharing: System Preference > Sharing > Internet Sharing.
Alternatively, you can try upgrading to the latest hyperkit version, or using an alternate driver.
W0415 03:29:51.134545 2347 out.go:239] * Related issues:
* Related issues:
W0415 03:29:51.134584 2347 out.go:239] - https://github.com/kubernetes/minikube/issues/6028
- https://github.com/kubernetes/minikube/issues/6028
W0415 03:29:51.134605 2347 out.go:239] - https://github.com/kubernetes/minikube/issues/5594
- https://github.com/kubernetes/minikube/issues/5594
I0415 03:29:51.176310 2347 out.go:177]
** /stderr **
functional_test.go:657: failed to soft start minikube. args "out/minikube-darwin-amd64 start -p functional-196000 --alsologtostderr -v=8": exit status 60
functional_test.go:659: soft start took 5.79674816s for "functional-196000" cluster.
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-196000 -n functional-196000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-196000 -n functional-196000: exit status 7 (73.022687ms)
-- stdout --
Stopped
-- /stdout --
helpers_test.go:239: status error: exit status 7 (may be ok)
helpers_test.go:241: "functional-196000" host is not running, skipping log retrieval (state="Stopped")
--- FAIL: TestFunctional/serial/SoftStart (5.87s)