=== RUN TestAddons/Setup
addons_test.go:89: (dbg) Run: out/minikube-darwin-amd64 start -p addons-076000 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --driver=hyperkit --addons=ingress --addons=ingress-dns --addons=helm-tiller
addons_test.go:89: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p addons-076000 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --driver=hyperkit --addons=ingress --addons=ingress-dns --addons=helm-tiller: exit status 90 (15.170443388s)
-- stdout --
* [addons-076000] minikube v1.31.2 on Darwin 14.0
- MINIKUBE_LOCATION=17345
- KUBECONFIG=/Users/jenkins/minikube-integration/17345-936/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/17345-936/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on user configuration
* Starting control plane node addons-076000 in cluster addons-076000
* Creating hyperkit VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I1003 17:03:41.002457 1517 out.go:296] Setting OutFile to fd 1 ...
I1003 17:03:41.002714 1517 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1003 17:03:41.002721 1517 out.go:309] Setting ErrFile to fd 2...
I1003 17:03:41.002725 1517 out.go:343] TERM=,COLORTERM=, which probably does not support color
I1003 17:03:41.002897 1517 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/17345-936/.minikube/bin
I1003 17:03:41.004360 1517 out.go:303] Setting JSON to false
I1003 17:03:41.026268 1517 start.go:128] hostinfo: {"hostname":"MacOS-Agent-1.local","uptime":189,"bootTime":1696377632,"procs":416,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.0","kernelVersion":"23.0.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"b7610dcb-1435-5842-8d5a-b2388403fea3"}
W1003 17:03:41.026373 1517 start.go:136] gopshost.Virtualization returned error: not implemented yet
I1003 17:03:41.048548 1517 out.go:177] * [addons-076000] minikube v1.31.2 on Darwin 14.0
I1003 17:03:41.092328 1517 out.go:177] - MINIKUBE_LOCATION=17345
I1003 17:03:41.092475 1517 notify.go:220] Checking for updates...
I1003 17:03:41.115305 1517 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/17345-936/kubeconfig
I1003 17:03:41.137456 1517 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1003 17:03:41.159042 1517 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I1003 17:03:41.180326 1517 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/17345-936/.minikube
I1003 17:03:41.201277 1517 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I1003 17:03:41.223548 1517 driver.go:373] Setting default libvirt URI to qemu:///system
I1003 17:03:41.253451 1517 out.go:177] * Using the hyperkit driver based on user configuration
I1003 17:03:41.296284 1517 start.go:298] selected driver: hyperkit
I1003 17:03:41.296341 1517 start.go:902] validating driver "hyperkit" against <nil>
I1003 17:03:41.296360 1517 start.go:913] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I1003 17:03:41.300831 1517 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1003 17:03:41.300930 1517 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/17345-936/.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
I1003 17:03:41.308432 1517 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.31.2
I1003 17:03:41.312076 1517 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1003 17:03:41.312097 1517 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I1003 17:03:41.312123 1517 start_flags.go:307] no existing cluster config was found, will generate one from the flags
I1003 17:03:41.312321 1517 start_flags.go:923] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I1003 17:03:41.312349 1517 cni.go:84] Creating CNI manager for ""
I1003 17:03:41.312362 1517 cni.go:158] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I1003 17:03:41.312369 1517 start_flags.go:316] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I1003 17:03:41.312377 1517 start_flags.go:321] config:
{Name:addons-076000 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.40-1696360059-17345@sha256:76d99edd1576614d5c20a839dd16ae1d7c810f3b909a01797063d483159ea3ae 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.2 ClusterName:addons-076000 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 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 DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 AutoPauseInterval:1m0s}
I1003 17:03:41.312504 1517 iso.go:125] acquiring lock: {Name:mk09a17bc6b7923952debc9d2a1712efcb7ec756 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1003 17:03:41.354254 1517 out.go:177] * Starting control plane node addons-076000 in cluster addons-076000
I1003 17:03:41.376200 1517 preload.go:132] Checking if preload exists for k8s version v1.28.2 and runtime docker
I1003 17:03:41.376292 1517 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/17345-936/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.28.2-docker-overlay2-amd64.tar.lz4
I1003 17:03:41.376321 1517 cache.go:57] Caching tarball of preloaded images
I1003 17:03:41.376509 1517 preload.go:174] Found /Users/jenkins/minikube-integration/17345-936/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.28.2-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1003 17:03:41.376530 1517 cache.go:60] Finished verifying existence of preloaded tar for v1.28.2 on docker
I1003 17:03:41.377019 1517 profile.go:148] Saving config to /Users/jenkins/minikube-integration/17345-936/.minikube/profiles/addons-076000/config.json ...
I1003 17:03:41.377057 1517 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/17345-936/.minikube/profiles/addons-076000/config.json: {Name:mk2259f69c5af3ed0b147fef6258b3a0c1de9f85 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1003 17:03:41.377857 1517 start.go:365] acquiring machines lock for addons-076000: {Name:mk2907765d495f362bb545719659eca626cfc4d0 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1003 17:03:41.378047 1517 start.go:369] acquired machines lock for "addons-076000" in 169.32µs
I1003 17:03:41.378099 1517 start.go:93] Provisioning new machine with config: &{Name:addons-076000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/17240/minikube-v1.31.0-1695060926-17240-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.40-1696360059-17345@sha256:76d99edd1576614d5c20a839dd16ae1d7c810f3b909a01797063d483159ea3ae 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 KubernetesCo
nfig:{KubernetesVersion:v1.28.2 ClusterName:addons-076000 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.28.2 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 Mount
Options:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.28.2 ContainerRuntime:docker ControlPlane:true Worker:true}
I1003 17:03:41.378176 1517 start.go:125] createHost starting for "" (driver="hyperkit")
I1003 17:03:41.400516 1517 out.go:204] * Creating hyperkit VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
I1003 17:03:41.400915 1517 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1003 17:03:41.400992 1517 main.go:141] libmachine: Launching plugin server for driver hyperkit
I1003 17:03:41.409945 1517 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:49346
I1003 17:03:41.410329 1517 main.go:141] libmachine: () Calling .GetVersion
I1003 17:03:41.410760 1517 main.go:141] libmachine: Using API Version 1
I1003 17:03:41.410770 1517 main.go:141] libmachine: () Calling .SetConfigRaw
I1003 17:03:41.410965 1517 main.go:141] libmachine: () Calling .GetMachineName
I1003 17:03:41.411072 1517 main.go:141] libmachine: (addons-076000) Calling .GetMachineName
I1003 17:03:41.411168 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:41.411269 1517 start.go:159] libmachine.API.Create for "addons-076000" (driver="hyperkit")
I1003 17:03:41.411299 1517 client.go:168] LocalClient.Create starting
I1003 17:03:41.411334 1517 main.go:141] libmachine: Creating CA: /Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca.pem
I1003 17:03:41.609055 1517 main.go:141] libmachine: Creating client certificate: /Users/jenkins/minikube-integration/17345-936/.minikube/certs/cert.pem
I1003 17:03:41.777781 1517 main.go:141] libmachine: Running pre-create checks...
I1003 17:03:41.777793 1517 main.go:141] libmachine: (addons-076000) Calling .PreCreateCheck
I1003 17:03:41.778065 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:41.778250 1517 main.go:141] libmachine: (addons-076000) Calling .GetConfigRaw
I1003 17:03:41.778741 1517 main.go:141] libmachine: Creating machine...
I1003 17:03:41.778750 1517 main.go:141] libmachine: (addons-076000) Calling .Create
I1003 17:03:41.778881 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:41.779121 1517 main.go:141] libmachine: (addons-076000) DBG | I1003 17:03:41.778882 1525 common.go:145] Making disk image using store path: /Users/jenkins/minikube-integration/17345-936/.minikube
I1003 17:03:41.779215 1517 main.go:141] libmachine: (addons-076000) Downloading /Users/jenkins/minikube-integration/17345-936/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/17345-936/.minikube/cache/iso/amd64/minikube-v1.31.0-1695060926-17240-amd64.iso...
I1003 17:03:41.995627 1517 main.go:141] libmachine: (addons-076000) DBG | I1003 17:03:41.995533 1525 common.go:152] Creating ssh key: /Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/id_rsa...
I1003 17:03:42.139006 1517 main.go:141] libmachine: (addons-076000) DBG | I1003 17:03:42.138893 1525 common.go:158] Creating raw disk image: /Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/addons-076000.rawdisk...
I1003 17:03:42.139020 1517 main.go:141] libmachine: (addons-076000) DBG | Writing magic tar header
I1003 17:03:42.139030 1517 main.go:141] libmachine: (addons-076000) DBG | Writing SSH key tar header
I1003 17:03:42.139950 1517 main.go:141] libmachine: (addons-076000) DBG | I1003 17:03:42.139823 1525 common.go:172] Fixing permissions on /Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000 ...
I1003 17:03:42.495370 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:42.495387 1517 main.go:141] libmachine: (addons-076000) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/hyperkit.pid
I1003 17:03:42.495432 1517 main.go:141] libmachine: (addons-076000) DBG | Using UUID 79ffcae8-6249-11ee-8f5c-149d997cd0f1
I1003 17:03:42.561187 1517 main.go:141] libmachine: (addons-076000) DBG | Generated MAC 12:d6:1e:c9:6f:b1
I1003 17:03:42.561226 1517 main.go:141] libmachine: (addons-076000) 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=addons-076000
I1003 17:03:42.561292 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"79ffcae8-6249-11ee-8f5c-149d997cd0f1", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc00011b1d0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/bzimage", Initrd:"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/initrd", Bootrom:"", CPUs:2, Memory:4000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1003 17:03:42.561349 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"79ffcae8-6249-11ee-8f5c-149d997cd0f1", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc00011b1d0)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/bzimage", Initrd:"/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/initrd", Bootrom:"", CPUs:2, Memory:4000, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1003 17:03:42.561405 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/hyperkit.pid", "-c", "2", "-m", "4000M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "79ffcae8-6249-11ee-8f5c-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/addons-076000.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/tty,log=/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/bzimage,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addon
s-076000/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=addons-076000"}
I1003 17:03:42.561479 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/hyperkit.pid -c 2 -m 4000M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U 79ffcae8-6249-11ee-8f5c-149d997cd0f1 -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/addons-076000.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/tty,log=/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/console-ring -f kexec,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/bzimage,/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/initrd,earlyprintk=serial loglevel=3 console=ttyS0 co
nsole=tty0 noembed nomodeset norestore waitusb=10 systemd.legacy_systemd_cgroup_controller=yes random.trust_cpu=on hw_rng_model=virtio base host=addons-076000"
I1003 17:03:42.561498 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I1003 17:03:42.564602 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 DEBUG: hyperkit: Pid is 1530
I1003 17:03:42.565175 1517 main.go:141] libmachine: (addons-076000) DBG | Attempt 0
I1003 17:03:42.565195 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:42.565312 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:42.566611 1517 main.go:141] libmachine: (addons-076000) DBG | Searching for 12:d6:1e:c9:6f:b1 in /var/db/dhcpd_leases ...
I1003 17:03:42.566654 1517 main.go:141] libmachine: (addons-076000) DBG | Found 1 entries in /var/db/dhcpd_leases!
I1003 17:03:42.566667 1517 main.go:141] libmachine: (addons-076000) DBG | dhcp entry: {Name: IPAddress:192.168.86.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x651dfcb3}
I1003 17:03:42.586277 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I1003 17:03:42.617719 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I1003 17:03:42.618421 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I1003 17:03:42.618438 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1003 17:03:42.618446 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1003 17:03:42.618454 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:42 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1003 17:03:43.128000 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 0
I1003 17:03:43.128017 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I1003 17:03:43.232996 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
I1003 17:03:43.233025 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1003 17:03:43.233038 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1003 17:03:43.233067 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1003 17:03:43.233911 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: rdmsr to register 0x3a on vcpu 1
I1003 17:03:43.233929 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:43 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I1003 17:03:44.566832 1517 main.go:141] libmachine: (addons-076000) DBG | Attempt 1
I1003 17:03:44.566849 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:44.566924 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:44.567721 1517 main.go:141] libmachine: (addons-076000) DBG | Searching for 12:d6:1e:c9:6f:b1 in /var/db/dhcpd_leases ...
I1003 17:03:44.567753 1517 main.go:141] libmachine: (addons-076000) DBG | Found 1 entries in /var/db/dhcpd_leases!
I1003 17:03:44.567767 1517 main.go:141] libmachine: (addons-076000) DBG | dhcp entry: {Name: IPAddress:192.168.86.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x651dfcb3}
I1003 17:03:46.569153 1517 main.go:141] libmachine: (addons-076000) DBG | Attempt 2
I1003 17:03:46.569188 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:46.569289 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:46.570126 1517 main.go:141] libmachine: (addons-076000) DBG | Searching for 12:d6:1e:c9:6f:b1 in /var/db/dhcpd_leases ...
I1003 17:03:46.570148 1517 main.go:141] libmachine: (addons-076000) DBG | Found 1 entries in /var/db/dhcpd_leases!
I1003 17:03:46.570163 1517 main.go:141] libmachine: (addons-076000) DBG | dhcp entry: {Name: IPAddress:192.168.86.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x651dfcb3}
I1003 17:03:48.135569 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:48 INFO : hyperkit: stderr: rdmsr to register 0x64d on vcpu 1
I1003 17:03:48.135636 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:48 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I1003 17:03:48.135646 1517 main.go:141] libmachine: (addons-076000) DBG | 2023/10/03 17:03:48 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I1003 17:03:48.570859 1517 main.go:141] libmachine: (addons-076000) DBG | Attempt 3
I1003 17:03:48.570874 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:48.570931 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:48.571673 1517 main.go:141] libmachine: (addons-076000) DBG | Searching for 12:d6:1e:c9:6f:b1 in /var/db/dhcpd_leases ...
I1003 17:03:48.571712 1517 main.go:141] libmachine: (addons-076000) DBG | Found 1 entries in /var/db/dhcpd_leases!
I1003 17:03:48.571723 1517 main.go:141] libmachine: (addons-076000) DBG | dhcp entry: {Name: IPAddress:192.168.86.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x651dfcb3}
I1003 17:03:50.572722 1517 main.go:141] libmachine: (addons-076000) DBG | Attempt 4
I1003 17:03:50.572737 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:50.572836 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:50.573638 1517 main.go:141] libmachine: (addons-076000) DBG | Searching for 12:d6:1e:c9:6f:b1 in /var/db/dhcpd_leases ...
I1003 17:03:50.573666 1517 main.go:141] libmachine: (addons-076000) DBG | Found 1 entries in /var/db/dhcpd_leases!
I1003 17:03:50.573680 1517 main.go:141] libmachine: (addons-076000) DBG | dhcp entry: {Name: IPAddress:192.168.86.2 HWAddress:f2:21:c3:3b:c7:2c ID:1,f2:21:c3:3b:c7:2c Lease:0x651dfcb3}
I1003 17:03:52.575274 1517 main.go:141] libmachine: (addons-076000) DBG | Attempt 5
I1003 17:03:52.575305 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:52.575415 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:52.576768 1517 main.go:141] libmachine: (addons-076000) DBG | Searching for 12:d6:1e:c9:6f:b1 in /var/db/dhcpd_leases ...
I1003 17:03:52.576817 1517 main.go:141] libmachine: (addons-076000) DBG | Found 2 entries in /var/db/dhcpd_leases!
I1003 17:03:52.576869 1517 main.go:141] libmachine: (addons-076000) DBG | dhcp entry: {Name:minikube IPAddress:192.168.87.2 HWAddress:12:d6:1e:c9:6f:b1 ID:1,12:d6:1e:c9:6f:b1 Lease:0x651dfd66}
I1003 17:03:52.576898 1517 main.go:141] libmachine: (addons-076000) DBG | Found match: 12:d6:1e:c9:6f:b1
I1003 17:03:52.576915 1517 main.go:141] libmachine: (addons-076000) DBG | IP: 192.168.87.2
I1003 17:03:52.576971 1517 main.go:141] libmachine: (addons-076000) Calling .GetConfigRaw
I1003 17:03:52.577765 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:52.577914 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:52.578053 1517 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I1003 17:03:52.578070 1517 main.go:141] libmachine: (addons-076000) Calling .GetState
I1003 17:03:52.578180 1517 main.go:141] libmachine: (addons-076000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1003 17:03:52.578248 1517 main.go:141] libmachine: (addons-076000) DBG | hyperkit pid from json: 1530
I1003 17:03:52.579219 1517 main.go:141] libmachine: Detecting operating system of created instance...
I1003 17:03:52.579238 1517 main.go:141] libmachine: Waiting for SSH to be available...
I1003 17:03:52.579249 1517 main.go:141] libmachine: Getting to WaitForSSH function...
I1003 17:03:52.579257 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.579387 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:52.579505 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.579593 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.579683 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:52.580428 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:52.580717 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:52.580725 1517 main.go:141] libmachine: About to run SSH command:
exit 0
I1003 17:03:52.639989 1517 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1003 17:03:52.640004 1517 main.go:141] libmachine: Detecting the provisioner...
I1003 17:03:52.640014 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.640146 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:52.640230 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.640318 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.640395 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:52.640518 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:52.640769 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:52.640777 1517 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I1003 17:03:52.697354 1517 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-gb090841-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I1003 17:03:52.697404 1517 main.go:141] libmachine: found compatible host: buildroot
I1003 17:03:52.697411 1517 main.go:141] libmachine: Provisioning with buildroot...
I1003 17:03:52.697417 1517 main.go:141] libmachine: (addons-076000) Calling .GetMachineName
I1003 17:03:52.697551 1517 buildroot.go:166] provisioning hostname "addons-076000"
I1003 17:03:52.697562 1517 main.go:141] libmachine: (addons-076000) Calling .GetMachineName
I1003 17:03:52.697658 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.697758 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:52.697847 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.697947 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.698080 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:52.698218 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:52.698471 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:52.698480 1517 main.go:141] libmachine: About to run SSH command:
sudo hostname addons-076000 && echo "addons-076000" | sudo tee /etc/hostname
I1003 17:03:52.764134 1517 main.go:141] libmachine: SSH cmd err, output: <nil>: addons-076000
I1003 17:03:52.764159 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.764304 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:52.764402 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.764482 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.764573 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:52.764701 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:52.764944 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:52.764958 1517 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\saddons-076000' /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 addons-076000/g' /etc/hosts;
else
echo '127.0.1.1 addons-076000' | sudo tee -a /etc/hosts;
fi
fi
I1003 17:03:52.828255 1517 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1003 17:03:52.828276 1517 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/17345-936/.minikube CaCertPath:/Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/17345-936/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/17345-936/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/17345-936/.minikube}
I1003 17:03:52.828291 1517 buildroot.go:174] setting up certificates
I1003 17:03:52.828301 1517 provision.go:83] configureAuth start
I1003 17:03:52.828308 1517 main.go:141] libmachine: (addons-076000) Calling .GetMachineName
I1003 17:03:52.828443 1517 main.go:141] libmachine: (addons-076000) Calling .GetIP
I1003 17:03:52.828551 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.828645 1517 provision.go:138] copyHostCerts
I1003 17:03:52.828719 1517 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17345-936/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/17345-936/.minikube/cert.pem (1123 bytes)
I1003 17:03:52.829039 1517 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17345-936/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/17345-936/.minikube/key.pem (1675 bytes)
I1003 17:03:52.829244 1517 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/17345-936/.minikube/ca.pem (1078 bytes)
I1003 17:03:52.829378 1517 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/17345-936/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca-key.pem org=jenkins.addons-076000 san=[192.168.87.2 192.168.87.2 localhost 127.0.0.1 minikube addons-076000]
I1003 17:03:52.904151 1517 provision.go:172] copyRemoteCerts
I1003 17:03:52.904210 1517 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I1003 17:03:52.904226 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.904351 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:52.904441 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.904521 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:52.904618 1517 sshutil.go:53] new ssh client: &{IP:192.168.87.2 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/id_rsa Username:docker}
I1003 17:03:52.939596 1517 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17345-936/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I1003 17:03:52.955886 1517 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17345-936/.minikube/machines/server.pem --> /etc/docker/server.pem (1216 bytes)
I1003 17:03:52.971631 1517 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/17345-936/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I1003 17:03:52.987347 1517 provision.go:86] duration metric: configureAuth took 159.026941ms
I1003 17:03:52.987360 1517 buildroot.go:189] setting minikube options for container-runtime
I1003 17:03:52.987493 1517 config.go:182] Loaded profile config "addons-076000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.28.2
I1003 17:03:52.987507 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:52.987640 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:52.987721 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:52.987814 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.987895 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:52.987978 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:52.988095 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:52.988334 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:52.988343 1517 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I1003 17:03:53.045005 1517 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I1003 17:03:53.045018 1517 buildroot.go:70] root file system type: tmpfs
I1003 17:03:53.045099 1517 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I1003 17:03:53.045112 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:53.045241 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:53.045338 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.045421 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.045505 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:53.045637 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:53.045907 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:53.045955 1517 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
I1003 17:03:53.109831 1517 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
I1003 17:03:53.109855 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:53.109992 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:53.110082 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.110179 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.110288 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:53.110428 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:53.110695 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:53.110708 1517 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; }
I1003 17:03:53.607386 1517 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.
I1003 17:03:53.607409 1517 main.go:141] libmachine: Checking connection to Docker...
I1003 17:03:53.607416 1517 main.go:141] libmachine: (addons-076000) Calling .GetURL
I1003 17:03:53.607547 1517 main.go:141] libmachine: Docker is up and running!
I1003 17:03:53.607554 1517 main.go:141] libmachine: Reticulating splines...
I1003 17:03:53.607558 1517 client.go:171] LocalClient.Create took 12.195552837s
I1003 17:03:53.607570 1517 start.go:167] duration metric: libmachine.API.Create for "addons-076000" took 12.195600242s
I1003 17:03:53.607580 1517 start.go:300] post-start starting for "addons-076000" (driver="hyperkit")
I1003 17:03:53.607589 1517 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]
I1003 17:03:53.607599 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:53.607744 1517 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
I1003 17:03:53.607758 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:53.607839 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:53.607923 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.607997 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:53.608084 1517 sshutil.go:53] new ssh client: &{IP:192.168.87.2 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/id_rsa Username:docker}
I1003 17:03:53.640747 1517 ssh_runner.go:195] Run: cat /etc/os-release
I1003 17:03:53.643456 1517 info.go:137] Remote host: Buildroot 2021.02.12
I1003 17:03:53.643469 1517 filesync.go:126] Scanning /Users/jenkins/minikube-integration/17345-936/.minikube/addons for local assets ...
I1003 17:03:53.643557 1517 filesync.go:126] Scanning /Users/jenkins/minikube-integration/17345-936/.minikube/files for local assets ...
I1003 17:03:53.643604 1517 start.go:303] post-start completed in 36.017016ms
I1003 17:03:53.643621 1517 main.go:141] libmachine: (addons-076000) Calling .GetConfigRaw
I1003 17:03:53.644169 1517 main.go:141] libmachine: (addons-076000) Calling .GetIP
I1003 17:03:53.644303 1517 profile.go:148] Saving config to /Users/jenkins/minikube-integration/17345-936/.minikube/profiles/addons-076000/config.json ...
I1003 17:03:53.644640 1517 start.go:128] duration metric: createHost completed in 12.265746775s
I1003 17:03:53.644656 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:53.644747 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:53.644838 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.644926 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.645007 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:53.645112 1517 main.go:141] libmachine: Using SSH client type: native
I1003 17:03:53.645355 1517 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x13f3fc0] 0x13f6ca0 <nil> [] 0s} 192.168.87.2 22 <nil> <nil>}
I1003 17:03:53.645363 1517 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I1003 17:03:53.702129 1517 main.go:141] libmachine: SSH cmd err, output: <nil>: 1696377833.591583409
I1003 17:03:53.702145 1517 fix.go:206] guest clock: 1696377833.591583409
I1003 17:03:53.702153 1517 fix.go:219] Guest: 2023-10-03 17:03:53.591583409 -0700 PDT Remote: 2023-10-03 17:03:53.644648 -0700 PDT m=+12.672681521 (delta=-53.064591ms)
I1003 17:03:53.702170 1517 fix.go:190] guest clock delta is within tolerance: -53.064591ms
I1003 17:03:53.702173 1517 start.go:83] releasing machines lock for "addons-076000", held for 12.323407042s
I1003 17:03:53.702191 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:53.702322 1517 main.go:141] libmachine: (addons-076000) Calling .GetIP
I1003 17:03:53.702417 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:53.702703 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:53.702805 1517 main.go:141] libmachine: (addons-076000) Calling .DriverName
I1003 17:03:53.702870 1517 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I1003 17:03:53.702902 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:53.702943 1517 ssh_runner.go:195] Run: cat /version.json
I1003 17:03:53.702955 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHHostname
I1003 17:03:53.702996 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:53.703048 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHPort
I1003 17:03:53.703098 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.703134 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHKeyPath
I1003 17:03:53.703222 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:53.703246 1517 main.go:141] libmachine: (addons-076000) Calling .GetSSHUsername
I1003 17:03:53.703302 1517 sshutil.go:53] new ssh client: &{IP:192.168.87.2 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/id_rsa Username:docker}
I1003 17:03:53.703332 1517 sshutil.go:53] new ssh client: &{IP:192.168.87.2 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/17345-936/.minikube/machines/addons-076000/id_rsa Username:docker}
I1003 17:03:53.735066 1517 ssh_runner.go:195] Run: systemctl --version
I1003 17:03:53.738885 1517 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W1003 17:03:53.785904 1517 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I1003 17:03:53.785983 1517 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" ;
I1003 17:03:53.797328 1517 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I1003 17:03:53.797342 1517 start.go:469] detecting cgroup driver to use...
I1003 17:03:53.797445 1517 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"
I1003 17:03:53.809597 1517 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"
I1003 17:03:53.816148 1517 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"
I1003 17:03:53.822652 1517 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I1003 17:03:53.822689 1517 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I1003 17:03:53.829262 1517 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"
I1003 17:03:53.835849 1517 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I1003 17:03:53.842411 1517 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I1003 17:03:53.848924 1517 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I1003 17:03:53.855725 1517 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"
I1003 17:03:53.862318 1517 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I1003 17:03:53.868167 1517 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I1003 17:03:53.873982 1517 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1003 17:03:53.955911 1517 ssh_runner.go:195] Run: sudo systemctl restart containerd
I1003 17:03:53.968573 1517 start.go:469] detecting cgroup driver to use...
I1003 17:03:53.968643 1517 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I1003 17:03:53.982119 1517 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1003 17:03:53.994721 1517 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I1003 17:03:54.011251 1517 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1003 17:03:54.020089 1517 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1003 17:03:54.028816 1517 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I1003 17:03:54.049618 1517 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1003 17:03:54.058839 1517 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"
I1003 17:03:54.070965 1517 ssh_runner.go:195] Run: which cri-dockerd
I1003 17:03:54.073298 1517 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I1003 17:03:54.078781 1517 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I1003 17:03:54.089951 1517 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I1003 17:03:54.172860 1517 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I1003 17:03:54.265760 1517 docker.go:554] configuring docker to use "cgroupfs" as cgroup driver...
I1003 17:03:54.265844 1517 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I1003 17:03:54.277393 1517 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1003 17:03:54.361585 1517 ssh_runner.go:195] Run: sudo systemctl restart docker
I1003 17:03:55.642861 1517 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.281174494s)
I1003 17:03:55.642919 1517 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1003 17:03:55.722842 1517 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I1003 17:03:55.806759 1517 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I1003 17:03:55.895428 1517 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1003 17:03:55.979036 1517 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I1003 17:03:56.018062 1517 out.go:177]
W1003 17:03:56.042176 1517 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.
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.
W1003 17:03:56.042199 1517 out.go:239] *
*
W1003 17:03:56.043372 1517 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I1003 17:03:56.087878 1517 out.go:177]
** /stderr **
addons_test.go:91: out/minikube-darwin-amd64 start -p addons-076000 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --driver=hyperkit --addons=ingress --addons=ingress-dns --addons=helm-tiller failed: exit status 90
--- FAIL: TestAddons/Setup (15.18s)