=== RUN TestFunctional/serial/SoftStart
functional_test.go:655: (dbg) Run: out/minikube-darwin-amd64 start -p functional-303000 --alsologtostderr -v=8
functional_test.go:655: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p functional-303000 --alsologtostderr -v=8: exit status 90 (3.981288886s)
-- stdout --
* [functional-303000] minikube v1.32.0 on Darwin 14.2
- MINIKUBE_LOCATION=17734
- KUBECONFIG=/Users/jenkins/minikube-integration/17734-1975/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/17734-1975/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on existing profile
* Starting control plane node functional-303000 in cluster functional-303000
* Updating the running hyperkit "functional-303000" VM ...
-- /stdout --
** stderr **
I1212 12:03:30.300506 4267 out.go:296] Setting OutFile to fd 1 ...
I1212 12:03:30.300804 4267 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1212 12:03:30.300809 4267 out.go:309] Setting ErrFile to fd 2...
I1212 12:03:30.300813 4267 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1212 12:03:30.301000 4267 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/17734-1975/.minikube/bin
I1212 12:03:30.302436 4267 out.go:303] Setting JSON to false
I1212 12:03:30.325797 4267 start.go:128] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":1981,"bootTime":1702409429,"procs":461,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.2","kernelVersion":"23.2.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"2965c349-98a5-5970-aaa9-9eedd3ae5959"}
W1212 12:03:30.325905 4267 start.go:136] gopshost.Virtualization returned error: not implemented yet
I1212 12:03:30.349891 4267 out.go:177] * [functional-303000] minikube v1.32.0 on Darwin 14.2
I1212 12:03:30.413719 4267 out.go:177] - MINIKUBE_LOCATION=17734
I1212 12:03:30.391766 4267 notify.go:220] Checking for updates...
I1212 12:03:30.456626 4267 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/17734-1975/kubeconfig
I1212 12:03:30.498654 4267 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1212 12:03:30.519766 4267 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I1212 12:03:30.540557 4267 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/17734-1975/.minikube
I1212 12:03:30.561752 4267 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I1212 12:03:30.583574 4267 config.go:182] Loaded profile config "functional-303000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I1212 12:03:30.583761 4267 driver.go:392] Setting default libvirt URI to qemu:///system
I1212 12:03:30.584542 4267 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1212 12:03:30.584618 4267 main.go:141] libmachine: Launching plugin server for driver hyperkit
I1212 12:03:30.593571 4267 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:49876
I1212 12:03:30.593960 4267 main.go:141] libmachine: () Calling .GetVersion
I1212 12:03:30.594377 4267 main.go:141] libmachine: Using API Version 1
I1212 12:03:30.594389 4267 main.go:141] libmachine: () Calling .SetConfigRaw
I1212 12:03:30.594610 4267 main.go:141] libmachine: () Calling .GetMachineName
I1212 12:03:30.594735 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:30.622428 4267 out.go:177] * Using the hyperkit driver based on existing profile
I1212 12:03:30.643662 4267 start.go:298] selected driver: hyperkit
I1212 12:03:30.643679 4267 start.go:902] validating driver "hyperkit" against &{Name:functional-303000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/17738/minikube-v1.32.1-1701996673-17738-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1702334074-17764@sha256:242468f3f874ac6982f8a024f9c4a97f957667e2ee92ef27b2ae70cc267db401 Memory:4000 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 Kubernet
esConfig:{KubernetesVersion:v1.28.4 ClusterName:functional-303000 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:8441 NodeName:} Nodes:[{Name: IP:192.169.0.5 Port:8441 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: Moun
tMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 AutoPauseInterval:1m0s GPUs:}
I1212 12:03:30.643811 4267 start.go:913] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I1212 12:03:30.643955 4267 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1212 12:03:30.644069 4267 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/17734-1975/.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
I1212 12:03:30.652742 4267 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.32.0
I1212 12:03:30.656776 4267 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1212 12:03:30.656803 4267 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I1212 12:03:30.659627 4267 cni.go:84] Creating CNI manager for ""
I1212 12:03:30.659650 4267 cni.go:158] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I1212 12:03:30.659664 4267 start_flags.go:323] config:
{Name:functional-303000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/17738/minikube-v1.32.1-1701996673-17738-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1702334074-17764@sha256:242468f3f874ac6982f8a024f9c4a97f957667e2ee92ef27b2ae70cc267db401 Memory:4000 CPUs:2 DiskSize:20000 VMDriver: Driver:hyperkit HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.28.4 ClusterName:functional-303000 Namespace:defaul
t 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:8441 NodeName:} Nodes:[{Name: IP:192.169.0.5 Port:8441 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirro
r: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 AutoPauseInterval:1m0s GPUs:}
I1212 12:03:30.659857 4267 iso.go:125] acquiring lock: {Name:mkd640d41cda61c79a7d2c2e38355d745b556a2e Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1212 12:03:30.689438 4267 out.go:177] * Starting control plane node functional-303000 in cluster functional-303000
I1212 12:03:30.765739 4267 preload.go:132] Checking if preload exists for k8s version v1.28.4 and runtime docker
I1212 12:03:30.765819 4267 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/17734-1975/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4
I1212 12:03:30.765849 4267 cache.go:56] Caching tarball of preloaded images
I1212 12:03:30.766071 4267 preload.go:174] Found /Users/jenkins/minikube-integration/17734-1975/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1212 12:03:30.766107 4267 cache.go:59] Finished verifying existence of preloaded tar for v1.28.4 on docker
I1212 12:03:30.766326 4267 profile.go:148] Saving config to /Users/jenkins/minikube-integration/17734-1975/.minikube/profiles/functional-303000/config.json ...
I1212 12:03:30.767176 4267 start.go:365] acquiring machines lock for functional-303000: {Name:mkcfb9a2794178bbcff953e64f7f6a3e3b1e9997 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1212 12:03:30.767274 4267 start.go:369] acquired machines lock for "functional-303000" in 73.48µs
I1212 12:03:30.767309 4267 start.go:96] Skipping create...Using existing machine configuration
I1212 12:03:30.767324 4267 fix.go:54] fixHost starting:
I1212 12:03:30.767733 4267 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1212 12:03:30.767764 4267 main.go:141] libmachine: Launching plugin server for driver hyperkit
I1212 12:03:30.776338 4267 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:49878
I1212 12:03:30.776691 4267 main.go:141] libmachine: () Calling .GetVersion
I1212 12:03:30.777073 4267 main.go:141] libmachine: Using API Version 1
I1212 12:03:30.777089 4267 main.go:141] libmachine: () Calling .SetConfigRaw
I1212 12:03:30.777364 4267 main.go:141] libmachine: () Calling .GetMachineName
I1212 12:03:30.777496 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:30.777593 4267 main.go:141] libmachine: (functional-303000) Calling .GetState
I1212 12:03:30.777679 4267 main.go:141] libmachine: (functional-303000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1212 12:03:30.777743 4267 main.go:141] libmachine: (functional-303000) DBG | hyperkit pid from json: 4245
I1212 12:03:30.778757 4267 fix.go:102] recreateIfNeeded on functional-303000: state=Running err=<nil>
W1212 12:03:30.778770 4267 fix.go:128] unexpected machine state, will restart: <nil>
I1212 12:03:30.801714 4267 out.go:177] * Updating the running hyperkit "functional-303000" VM ...
I1212 12:03:30.842665 4267 machine.go:88] provisioning docker machine ...
I1212 12:03:30.842704 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:30.843024 4267 main.go:141] libmachine: (functional-303000) Calling .GetMachineName
I1212 12:03:30.843262 4267 buildroot.go:166] provisioning hostname "functional-303000"
I1212 12:03:30.843287 4267 main.go:141] libmachine: (functional-303000) Calling .GetMachineName
I1212 12:03:30.843523 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:30.843744 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:30.843956 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:30.844156 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:30.844347 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:30.844589 4267 main.go:141] libmachine: Using SSH client type: native
I1212 12:03:30.845121 4267 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.5 22 <nil> <nil>}
I1212 12:03:30.845141 4267 main.go:141] libmachine: About to run SSH command:
sudo hostname functional-303000 && echo "functional-303000" | sudo tee /etc/hostname
I1212 12:03:30.922958 4267 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-303000
I1212 12:03:30.922990 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:30.923153 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:30.923232 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:30.923337 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:30.923440 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:30.923583 4267 main.go:141] libmachine: Using SSH client type: native
I1212 12:03:30.923851 4267 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.5 22 <nil> <nil>}
I1212 12:03:30.923865 4267 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfunctional-303000' /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 functional-303000/g' /etc/hosts;
else
echo '127.0.1.1 functional-303000' | sudo tee -a /etc/hosts;
fi
fi
I1212 12:03:30.993512 4267 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1212 12:03:30.993534 4267 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/17734-1975/.minikube CaCertPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/17734-1975/.minikube}
I1212 12:03:30.993558 4267 buildroot.go:174] setting up certificates
I1212 12:03:30.993572 4267 provision.go:83] configureAuth start
I1212 12:03:30.993581 4267 main.go:141] libmachine: (functional-303000) Calling .GetMachineName
I1212 12:03:30.993756 4267 main.go:141] libmachine: (functional-303000) Calling .GetIP
I1212 12:03:30.993861 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:30.993943 4267 provision.go:138] copyHostCerts
I1212 12:03:30.993974 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/cert.pem -> /Users/jenkins/minikube-integration/17734-1975/.minikube/cert.pem
I1212 12:03:30.994023 4267 exec_runner.go:144] found /Users/jenkins/minikube-integration/17734-1975/.minikube/cert.pem, removing ...
I1212 12:03:30.994029 4267 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/17734-1975/.minikube/cert.pem
I1212 12:03:30.994154 4267 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/17734-1975/.minikube/cert.pem (1123 bytes)
I1212 12:03:30.994390 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/key.pem -> /Users/jenkins/minikube-integration/17734-1975/.minikube/key.pem
I1212 12:03:30.994418 4267 exec_runner.go:144] found /Users/jenkins/minikube-integration/17734-1975/.minikube/key.pem, removing ...
I1212 12:03:30.994422 4267 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/17734-1975/.minikube/key.pem
I1212 12:03:30.994499 4267 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/17734-1975/.minikube/key.pem (1675 bytes)
I1212 12:03:30.994652 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca.pem -> /Users/jenkins/minikube-integration/17734-1975/.minikube/ca.pem
I1212 12:03:30.994678 4267 exec_runner.go:144] found /Users/jenkins/minikube-integration/17734-1975/.minikube/ca.pem, removing ...
I1212 12:03:30.994683 4267 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/17734-1975/.minikube/ca.pem
I1212 12:03:30.994751 4267 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/17734-1975/.minikube/ca.pem (1078 bytes)
I1212 12:03:30.994924 4267 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca-key.pem org=jenkins.functional-303000 san=[192.169.0.5 192.169.0.5 localhost 127.0.0.1 minikube functional-303000]
I1212 12:03:31.185132 4267 provision.go:172] copyRemoteCerts
I1212 12:03:31.185190 4267 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I1212 12:03:31.185208 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.185436 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.185651 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.185800 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.185979 4267 sshutil.go:53] new ssh client: &{IP:192.169.0.5 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/machines/functional-303000/id_rsa Username:docker}
I1212 12:03:31.226710 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca.pem -> /etc/docker/ca.pem
I1212 12:03:31.226770 4267 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17734-1975/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I1212 12:03:31.242079 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server.pem -> /etc/docker/server.pem
I1212 12:03:31.242132 4267 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server.pem --> /etc/docker/server.pem (1229 bytes)
I1212 12:03:31.259092 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server-key.pem -> /etc/docker/server-key.pem
I1212 12:03:31.259141 4267 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17734-1975/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I1212 12:03:31.274782 4267 provision.go:86] duration metric: configureAuth took 281.208895ms
I1212 12:03:31.274800 4267 buildroot.go:189] setting minikube options for container-runtime
I1212 12:03:31.274920 4267 config.go:182] Loaded profile config "functional-303000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I1212 12:03:31.274936 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:31.275074 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.275161 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.275253 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.275327 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.275409 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.275511 4267 main.go:141] libmachine: Using SSH client type: native
I1212 12:03:31.275754 4267 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.5 22 <nil> <nil>}
I1212 12:03:31.275762 4267 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I1212 12:03:31.346176 4267 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I1212 12:03:31.346188 4267 buildroot.go:70] root file system type: tmpfs
I1212 12:03:31.346270 4267 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I1212 12:03:31.346286 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.346431 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.346523 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.346605 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.346731 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.346951 4267 main.go:141] libmachine: Using SSH client type: native
I1212 12:03:31.347290 4267 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.5 22 <nil> <nil>}
I1212 12:03:31.347343 4267 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %s "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I1212 12:03:31.426705 4267 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
I1212 12:03:31.426729 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.426863 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.426977 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.427062 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.427144 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.427258 4267 main.go:141] libmachine: Using SSH client type: native
I1212 12:03:31.427508 4267 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.5 22 <nil> <nil>}
I1212 12:03:31.427521 4267 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; }
I1212 12:03:31.502917 4267 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1212 12:03:31.502935 4267 machine.go:91] provisioned docker machine in 660.268159ms
I1212 12:03:31.502959 4267 start.go:300] post-start starting for "functional-303000" (driver="hyperkit")
I1212 12:03:31.502998 4267 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]
I1212 12:03:31.503009 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:31.503381 4267 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
I1212 12:03:31.503394 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.503495 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.503682 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.503886 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.503997 4267 sshutil.go:53] new ssh client: &{IP:192.169.0.5 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/machines/functional-303000/id_rsa Username:docker}
I1212 12:03:31.545612 4267 ssh_runner.go:195] Run: cat /etc/os-release
I1212 12:03:31.548194 4267 command_runner.go:130] > NAME=Buildroot
I1212 12:03:31.548204 4267 command_runner.go:130] > VERSION=2021.02.12-1-g0ec83c8-dirty
I1212 12:03:31.548208 4267 command_runner.go:130] > ID=buildroot
I1212 12:03:31.548214 4267 command_runner.go:130] > VERSION_ID=2021.02.12
I1212 12:03:31.548226 4267 command_runner.go:130] > PRETTY_NAME="Buildroot 2021.02.12"
I1212 12:03:31.548374 4267 info.go:137] Remote host: Buildroot 2021.02.12
I1212 12:03:31.548384 4267 filesync.go:126] Scanning /Users/jenkins/minikube-integration/17734-1975/.minikube/addons for local assets ...
I1212 12:03:31.548457 4267 filesync.go:126] Scanning /Users/jenkins/minikube-integration/17734-1975/.minikube/files for local assets ...
I1212 12:03:31.548593 4267 filesync.go:149] local asset: /Users/jenkins/minikube-integration/17734-1975/.minikube/files/etc/ssl/certs/31982.pem -> 31982.pem in /etc/ssl/certs
I1212 12:03:31.548599 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/files/etc/ssl/certs/31982.pem -> /etc/ssl/certs/31982.pem
I1212 12:03:31.548750 4267 filesync.go:149] local asset: /Users/jenkins/minikube-integration/17734-1975/.minikube/files/etc/test/nested/copy/3198/hosts -> hosts in /etc/test/nested/copy/3198
I1212 12:03:31.548755 4267 vm_assets.go:163] NewFileAsset: /Users/jenkins/minikube-integration/17734-1975/.minikube/files/etc/test/nested/copy/3198/hosts -> /etc/test/nested/copy/3198/hosts
I1212 12:03:31.548798 4267 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs /etc/test/nested/copy/3198
I1212 12:03:31.555293 4267 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17734-1975/.minikube/files/etc/ssl/certs/31982.pem --> /etc/ssl/certs/31982.pem (1708 bytes)
I1212 12:03:31.570919 4267 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17734-1975/.minikube/files/etc/test/nested/copy/3198/hosts --> /etc/test/nested/copy/3198/hosts (40 bytes)
I1212 12:03:31.586451 4267 start.go:303] post-start completed in 83.48666ms
I1212 12:03:31.586467 4267 fix.go:56] fixHost completed within 819.174885ms
I1212 12:03:31.586481 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.586605 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.586715 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.586814 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.586904 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.587015 4267 main.go:141] libmachine: Using SSH client type: native
I1212 12:03:31.587260 4267 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1406180] 0x1408e60 <nil> [] 0s} 192.169.0.5 22 <nil> <nil>}
I1212 12:03:31.587268 4267 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I1212 12:03:31.656401 4267 main.go:141] libmachine: SSH cmd err, output: <nil>: 1702411411.765762644
I1212 12:03:31.656415 4267 fix.go:206] guest clock: 1702411411.765762644
I1212 12:03:31.656425 4267 fix.go:219] Guest: 2023-12-12 12:03:31.765762644 -0800 PST Remote: 2023-12-12 12:03:31.586469 -0800 PST m=+1.331220374 (delta=179.293644ms)
I1212 12:03:31.656444 4267 fix.go:190] guest clock delta is within tolerance: 179.293644ms
I1212 12:03:31.656447 4267 start.go:83] releasing machines lock for "functional-303000", held for 889.193649ms
I1212 12:03:31.656463 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:31.656598 4267 main.go:141] libmachine: (functional-303000) Calling .GetIP
I1212 12:03:31.656683 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:31.657009 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:31.657137 4267 main.go:141] libmachine: (functional-303000) Calling .DriverName
I1212 12:03:31.657222 4267 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I1212 12:03:31.657252 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.657296 4267 ssh_runner.go:195] Run: cat /version.json
I1212 12:03:31.657308 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHHostname
I1212 12:03:31.657343 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.657419 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.657471 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHPort
I1212 12:03:31.657514 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.657563 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHKeyPath
I1212 12:03:31.657598 4267 sshutil.go:53] new ssh client: &{IP:192.169.0.5 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/machines/functional-303000/id_rsa Username:docker}
I1212 12:03:31.657652 4267 main.go:141] libmachine: (functional-303000) Calling .GetSSHUsername
I1212 12:03:31.657739 4267 sshutil.go:53] new ssh client: &{IP:192.169.0.5 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17734-1975/.minikube/machines/functional-303000/id_rsa Username:docker}
I1212 12:03:31.696282 4267 command_runner.go:130] > {"iso_version": "v1.32.1-1701996673-17738", "kicbase_version": "v0.0.42-1701974066-17719", "minikube_version": "v1.32.0", "commit": "2518fadffa02a308edcd7fa670f350a21819c5e4"}
I1212 12:03:31.696606 4267 ssh_runner.go:195] Run: systemctl --version
I1212 12:03:31.756783 4267 command_runner.go:130] > <a href="https://github.com/kubernetes/registry.k8s.io">Temporary Redirect</a>.
I1212 12:03:31.757622 4267 command_runner.go:130] > systemd 247 (247)
I1212 12:03:31.757653 4267 command_runner.go:130] > -PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK -SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ +LZ4 -ZSTD +SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid
I1212 12:03:31.757805 4267 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I1212 12:03:31.763353 4267 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W1212 12:03:31.763560 4267 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I1212 12:03:31.763625 4267 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, " -exec sh -c "sudo mv {} {}.mk_disabled" ;
I1212 12:03:31.770056 4267 cni.go:259] no active bridge cni configs found in "/etc/cni/net.d" - nothing to disable
I1212 12:03:31.770071 4267 start.go:475] detecting cgroup driver to use...
I1212 12:03:31.770177 4267 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///run/containerd/containerd.sock
" | sudo tee /etc/crictl.yaml"
I1212 12:03:31.781727 4267 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I1212 12:03:31.782062 4267 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"
I1212 12:03:31.789241 4267 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"
I1212 12:03:31.796522 4267 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I1212 12:03:31.796578 4267 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I1212 12:03:31.804023 4267 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"
I1212 12:03:31.811300 4267 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I1212 12:03:31.819339 4267 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I1212 12:03:31.826543 4267 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I1212 12:03:31.833736 4267 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"
I1212 12:03:31.840760 4267 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I1212 12:03:31.847116 4267 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I1212 12:03:31.847364 4267 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I1212 12:03:31.854665 4267 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1212 12:03:31.936731 4267 ssh_runner.go:195] Run: sudo systemctl restart containerd
I1212 12:03:31.948696 4267 start.go:475] detecting cgroup driver to use...
I1212 12:03:31.948766 4267 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I1212 12:03:31.958446 4267 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I1212 12:03:31.959113 4267 command_runner.go:130] > [Unit]
I1212 12:03:31.959145 4267 command_runner.go:130] > Description=Docker Application Container Engine
I1212 12:03:31.959152 4267 command_runner.go:130] > Documentation=https://docs.docker.com
I1212 12:03:31.959161 4267 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I1212 12:03:31.959166 4267 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I1212 12:03:31.959171 4267 command_runner.go:130] > StartLimitBurst=3
I1212 12:03:31.959175 4267 command_runner.go:130] > StartLimitIntervalSec=60
I1212 12:03:31.959178 4267 command_runner.go:130] > [Service]
I1212 12:03:31.959182 4267 command_runner.go:130] > Type=notify
I1212 12:03:31.959185 4267 command_runner.go:130] > Restart=on-failure
I1212 12:03:31.959193 4267 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I1212 12:03:31.959207 4267 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I1212 12:03:31.959213 4267 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I1212 12:03:31.959219 4267 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I1212 12:03:31.959225 4267 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I1212 12:03:31.959231 4267 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I1212 12:03:31.959241 4267 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I1212 12:03:31.959254 4267 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I1212 12:03:31.959260 4267 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I1212 12:03:31.959266 4267 command_runner.go:130] > ExecStart=
I1212 12:03:31.959279 4267 command_runner.go:130] > 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
I1212 12:03:31.959286 4267 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I1212 12:03:31.959293 4267 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I1212 12:03:31.959300 4267 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I1212 12:03:31.959306 4267 command_runner.go:130] > LimitNOFILE=infinity
I1212 12:03:31.959311 4267 command_runner.go:130] > LimitNPROC=infinity
I1212 12:03:31.959317 4267 command_runner.go:130] > LimitCORE=infinity
I1212 12:03:31.959323 4267 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I1212 12:03:31.959342 4267 command_runner.go:130] > # Only systemd 226 and above support this version.
I1212 12:03:31.959369 4267 command_runner.go:130] > TasksMax=infinity
I1212 12:03:31.959373 4267 command_runner.go:130] > TimeoutStartSec=0
I1212 12:03:31.959379 4267 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I1212 12:03:31.959383 4267 command_runner.go:130] > Delegate=yes
I1212 12:03:31.959388 4267 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I1212 12:03:31.959392 4267 command_runner.go:130] > KillMode=process
I1212 12:03:31.959395 4267 command_runner.go:130] > [Install]
I1212 12:03:31.959404 4267 command_runner.go:130] > WantedBy=multi-user.target
I1212 12:03:31.959584 4267 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1212 12:03:31.969697 4267 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I1212 12:03:31.983852 4267 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1212 12:03:31.993009 4267 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1212 12:03:32.002015 4267 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I1212 12:03:32.014881 4267 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I1212 12:03:32.014949 4267 ssh_runner.go:195] Run: which cri-dockerd
I1212 12:03:32.017080 4267 command_runner.go:130] > /usr/bin/cri-dockerd
I1212 12:03:32.017317 4267 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I1212 12:03:32.023456 4267 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I1212 12:03:32.034411 4267 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I1212 12:03:32.129675 4267 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I1212 12:03:32.224960 4267 docker.go:560] configuring docker to use "cgroupfs" as cgroup driver...
I1212 12:03:32.225042 4267 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I1212 12:03:32.236849 4267 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1212 12:03:32.333908 4267 ssh_runner.go:195] Run: sudo systemctl restart docker
I1212 12:03:33.682630 4267 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.348750347s)
I1212 12:03:33.682698 4267 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1212 12:03:33.769790 4267 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I1212 12:03:33.864520 4267 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1212 12:03:33.960817 4267 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1212 12:03:34.050442 4267 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I1212 12:03:34.060185 4267 command_runner.go:130] ! Job failed. See "journalctl -xe" for details.
I1212 12:03:34.060247 4267 ssh_runner.go:195] Run: sudo journalctl --no-pager -u cri-docker.socket
I1212 12:03:34.068030 4267 command_runner.go:130] > -- Journal begins at Tue 2023-12-12 20:03:23 UTC, ends at Tue 2023-12-12 20:03:34 UTC. --
I1212 12:03:34.068043 4267 command_runner.go:130] > Dec 12 20:03:24 minikube systemd[1]: Starting CRI Docker Socket for the API.
I1212 12:03:34.068048 4267 command_runner.go:130] > Dec 12 20:03:24 minikube systemd[1]: Listening on CRI Docker Socket for the API.
I1212 12:03:34.068056 4267 command_runner.go:130] > Dec 12 20:03:27 functional-303000 systemd[1]: cri-docker.socket: Succeeded.
I1212 12:03:34.068062 4267 command_runner.go:130] > Dec 12 20:03:27 functional-303000 systemd[1]: Closed CRI Docker Socket for the API.
I1212 12:03:34.068068 4267 command_runner.go:130] > Dec 12 20:03:27 functional-303000 systemd[1]: Stopping CRI Docker Socket for the API.
I1212 12:03:34.068076 4267 command_runner.go:130] > Dec 12 20:03:27 functional-303000 systemd[1]: Starting CRI Docker Socket for the API.
I1212 12:03:34.068082 4267 command_runner.go:130] > Dec 12 20:03:27 functional-303000 systemd[1]: Listening on CRI Docker Socket for the API.
I1212 12:03:34.068087 4267 command_runner.go:130] > Dec 12 20:03:30 functional-303000 systemd[1]: cri-docker.socket: Succeeded.
I1212 12:03:34.068094 4267 command_runner.go:130] > Dec 12 20:03:30 functional-303000 systemd[1]: Closed CRI Docker Socket for the API.
I1212 12:03:34.068099 4267 command_runner.go:130] > Dec 12 20:03:30 functional-303000 systemd[1]: Stopping CRI Docker Socket for the API.
I1212 12:03:34.068108 4267 command_runner.go:130] > Dec 12 20:03:30 functional-303000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
I1212 12:03:34.068114 4267 command_runner.go:130] > Dec 12 20:03:30 functional-303000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
I1212 12:03:34.068120 4267 command_runner.go:130] > Dec 12 20:03:34 functional-303000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
I1212 12:03:34.068127 4267 command_runner.go:130] > Dec 12 20:03:34 functional-303000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
I1212 12:03:34.092238 4267 out.go:177]
W1212 12:03:34.113808 4267 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
sudo journalctl --no-pager -u cri-docker.socket:
-- stdout --
-- Journal begins at Tue 2023-12-12 20:03:23 UTC, ends at Tue 2023-12-12 20:03:34 UTC. --
Dec 12 20:03:24 minikube systemd[1]: Starting CRI Docker Socket for the API.
Dec 12 20:03:24 minikube systemd[1]: Listening on CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: cri-docker.socket: Succeeded.
Dec 12 20:03:27 functional-303000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: Starting CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: Listening on CRI Docker Socket for the API.
Dec 12 20:03:30 functional-303000 systemd[1]: cri-docker.socket: Succeeded.
Dec 12 20:03:30 functional-303000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 12 20:03:30 functional-303000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 12 20:03:30 functional-303000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
Dec 12 20:03:30 functional-303000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
Dec 12 20:03:34 functional-303000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
Dec 12 20:03:34 functional-303000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
-- /stdout --
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
sudo journalctl --no-pager -u cri-docker.socket:
-- stdout --
-- Journal begins at Tue 2023-12-12 20:03:23 UTC, ends at Tue 2023-12-12 20:03:34 UTC. --
Dec 12 20:03:24 minikube systemd[1]: Starting CRI Docker Socket for the API.
Dec 12 20:03:24 minikube systemd[1]: Listening on CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: cri-docker.socket: Succeeded.
Dec 12 20:03:27 functional-303000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: Starting CRI Docker Socket for the API.
Dec 12 20:03:27 functional-303000 systemd[1]: Listening on CRI Docker Socket for the API.
Dec 12 20:03:30 functional-303000 systemd[1]: cri-docker.socket: Succeeded.
Dec 12 20:03:30 functional-303000 systemd[1]: Closed CRI Docker Socket for the API.
Dec 12 20:03:30 functional-303000 systemd[1]: Stopping CRI Docker Socket for the API.
Dec 12 20:03:30 functional-303000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
Dec 12 20:03:30 functional-303000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
Dec 12 20:03:34 functional-303000 systemd[1]: cri-docker.socket: Socket service cri-docker.service already active, refusing.
Dec 12 20:03:34 functional-303000 systemd[1]: Failed to listen on CRI Docker Socket for the API.
-- /stdout --
W1212 12:03:34.113838 4267 out.go:239] *
*
W1212 12:03:34.115069 4267 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I1212 12:03:34.182479 4267 out.go:177]
** /stderr **
functional_test.go:657: failed to soft start minikube. args "out/minikube-darwin-amd64 start -p functional-303000 --alsologtostderr -v=8": exit status 90
functional_test.go:659: soft start took 3.986894785s for "functional-303000" cluster.
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-303000 -n functional-303000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-303000 -n functional-303000: exit status 6 (150.79898ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E1212 12:03:34.373713 4282 status.go:415] kubeconfig endpoint: extract IP: "functional-303000" does not appear in /Users/jenkins/minikube-integration/17734-1975/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "functional-303000" 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`")
--- FAIL: TestFunctional/serial/SoftStart (4.14s)