=== RUN TestMountStart/serial/VerifyMountPostDelete
mount_start_test.go:103: (dbg) Run: out/minikube-darwin-amd64 -p mount-start-2-20211214191843-2012 ssh -- ls /minikube-host
mount_start_test.go:103: (dbg) Non-zero exit: out/minikube-darwin-amd64 -p mount-start-2-20211214191843-2012 ssh -- ls /minikube-host: exit status 1 (167.055972ms)
-- stdout --
ls: cannot access '/minikube-host': Input/output error
-- /stdout --
** stderr **
ssh: Process exited with status 2
** /stderr **
mount_start_test.go:105: mount failed: "out/minikube-darwin-amd64 -p mount-start-2-20211214191843-2012 ssh -- ls /minikube-host" : exit status 1
helpers_test.go:223: -----------------------post-mortem--------------------------------
helpers_test.go:240: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p mount-start-2-20211214191843-2012 -n mount-start-2-20211214191843-2012
helpers_test.go:245: <<< TestMountStart/serial/VerifyMountPostDelete FAILED: start of post-mortem logs <<<
helpers_test.go:246: ======> post-mortem[TestMountStart/serial/VerifyMountPostDelete]: minikube logs <======
helpers_test.go:248: (dbg) Run: out/minikube-darwin-amd64 -p mount-start-2-20211214191843-2012 logs -n 25
helpers_test.go:248: (dbg) Done: out/minikube-darwin-amd64 -p mount-start-2-20211214191843-2012 logs -n 25: (1.618967141s)
helpers_test.go:253: TestMountStart/serial/VerifyMountPostDelete logs:
-- stdout --
*
* ==> Audit <==
* |---------|---------------------------------------------------|------------------------------------------|----------|---------|-------------------------------|-------------------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|---------------------------------------------------|------------------------------------------|----------|---------|-------------------------------|-------------------------------|
| -p | functional-20211214191215-2012 image build -t | functional-20211214191215-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:14:59 PST | Tue, 14 Dec 2021 19:15:02 PST |
| | localhost/my-image:functional-20211214191215-2012 | | | | | |
| | testdata/build | | | | | |
| -p | functional-20211214191215-2012 | functional-20211214191215-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:15:02 PST | Tue, 14 Dec 2021 19:15:02 PST |
| | image ls | | | | | |
| delete | -p | functional-20211214191215-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:15:08 PST | Tue, 14 Dec 2021 19:15:13 PST |
| | functional-20211214191215-2012 | | | | | |
| start | -p | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:15:13 PST | Tue, 14 Dec 2021 19:16:24 PST |
| | ingress-addon-legacy-20211214191513-2012 | | | | | |
| | --kubernetes-version=v1.18.20 | | | | | |
| | --memory=4096 --wait=true | | | | | |
| | --alsologtostderr -v=5 --driver=hyperkit | | | | | |
| | | | | | | |
| -p | ingress-addon-legacy-20211214191513-2012 | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:16:24 PST | Tue, 14 Dec 2021 19:16:40 PST |
| | addons enable ingress --alsologtostderr | | | | | |
| | -v=5 | | | | | |
| -p | ingress-addon-legacy-20211214191513-2012 | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:16:40 PST | Tue, 14 Dec 2021 19:16:40 PST |
| | addons enable ingress-dns | | | | | |
| | --alsologtostderr -v=5 | | | | | |
| -p | ingress-addon-legacy-20211214191513-2012 | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:17:07 PST | Tue, 14 Dec 2021 19:17:07 PST |
| | ssh curl -s http://127.0.0.1/ -H 'Host: | | | | | |
| | nginx.example.com' | | | | | |
| -p | ingress-addon-legacy-20211214191513-2012 | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:17:07 PST | Tue, 14 Dec 2021 19:17:07 PST |
| | ip | | | | | |
| -p | ingress-addon-legacy-20211214191513-2012 | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:17:08 PST | Tue, 14 Dec 2021 19:17:09 PST |
| | addons disable ingress-dns | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| -p | ingress-addon-legacy-20211214191513-2012 | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:17:09 PST | Tue, 14 Dec 2021 19:17:38 PST |
| | addons disable ingress --alsologtostderr | | | | | |
| | -v=1 | | | | | |
| delete | -p | ingress-addon-legacy-20211214191513-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:17:38 PST | Tue, 14 Dec 2021 19:17:43 PST |
| | ingress-addon-legacy-20211214191513-2012 | | | | | |
| start | -p | json-output-20211214191743-2012 | testUser | v1.24.0 | Tue, 14 Dec 2021 19:17:43 PST | Tue, 14 Dec 2021 19:18:33 PST |
| | json-output-20211214191743-2012 | | | | | |
| | --output=json --user=testUser | | | | | |
| | --memory=2200 --wait=true | | | | | |
| | --driver=hyperkit | | | | | |
| pause | -p | json-output-20211214191743-2012 | testUser | v1.24.0 | Tue, 14 Dec 2021 19:18:33 PST | Tue, 14 Dec 2021 19:18:33 PST |
| | json-output-20211214191743-2012 | | | | | |
| | --output=json --user=testUser | | | | | |
| unpause | -p | json-output-20211214191743-2012 | testUser | v1.24.0 | Tue, 14 Dec 2021 19:18:34 PST | Tue, 14 Dec 2021 19:18:34 PST |
| | json-output-20211214191743-2012 | | | | | |
| | --output=json --user=testUser | | | | | |
| stop | -p | json-output-20211214191743-2012 | testUser | v1.24.0 | Tue, 14 Dec 2021 19:18:34 PST | Tue, 14 Dec 2021 19:18:42 PST |
| | json-output-20211214191743-2012 | | | | | |
| | --output=json --user=testUser | | | | | |
| delete | -p | json-output-20211214191743-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:18:42 PST | Tue, 14 Dec 2021 19:18:43 PST |
| | json-output-20211214191743-2012 | | | | | |
| delete | -p | json-output-error-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:18:43 PST | Tue, 14 Dec 2021 19:18:43 PST |
| | json-output-error-20211214191843-2012 | | | | | |
| start | -p | mount-start-1-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:18:43 PST | Tue, 14 Dec 2021 19:19:20 PST |
| | mount-start-1-20211214191843-2012 | | | | | |
| | --memory=2048 --mount | | | | | |
| | --mount-gid 0 --mount-msize 6543 | | | | | |
| | --mount-port 46464 --mount-uid 0 | | | | | |
| | --driver=hyperkit | | | | | |
| mount | /Users:/minikube-host --profile | mount-start-2-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:26:01 PST | Tue, 14 Dec 2021 19:26:01 PST |
| | mount-start-2-20211214191843-2012 | | | | | |
| | --v 0 --9p-version 9p2000.L --gid | | | | | |
| | 0 --ip --msize 6543 --port 46464 | | | | | |
| | --type 9p --uid 0 | | | | | |
| start | -p | mount-start-2-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:19:20 PST | Tue, 14 Dec 2021 19:26:01 PST |
| | mount-start-2-20211214191843-2012 | | | | | |
| | --memory=2048 --mount | | | | | |
| | --mount-gid 0 --mount-msize 6543 | | | | | |
| | --mount-port 46464 --mount-uid 0 | | | | | |
| | --driver=hyperkit | | | | | |
| -p | mount-start-1-20211214191843-2012 | mount-start-1-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:26:01 PST | Tue, 14 Dec 2021 19:26:02 PST |
| | ssh -- ls /minikube-host | | | | | |
| -p | mount-start-1-20211214191843-2012 | mount-start-1-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:26:02 PST | Tue, 14 Dec 2021 19:26:02 PST |
| | ssh -- mount | grep 9p | | | | | |
| -p | mount-start-2-20211214191843-2012 | mount-start-2-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:26:02 PST | Tue, 14 Dec 2021 19:26:02 PST |
| | ssh -- ls /minikube-host | | | | | |
| -p | mount-start-2-20211214191843-2012 | mount-start-2-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:26:02 PST | Tue, 14 Dec 2021 19:26:02 PST |
| | ssh -- mount | grep 9p | | | | | |
| delete | -p | mount-start-1-20211214191843-2012 | jenkins | v1.24.0 | Tue, 14 Dec 2021 19:26:02 PST | Tue, 14 Dec 2021 19:26:07 PST |
| | mount-start-1-20211214191843-2012 | | | | | |
| | --alsologtostderr -v=5 | | | | | |
|---------|---------------------------------------------------|------------------------------------------|----------|---------|-------------------------------|-------------------------------|
*
* ==> Last Start <==
* Log file created at: 2021/12/14 19:19:20
Running on machine: administrators-Mac-mini
Binary: Built with gc go1.17.5 for darwin/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I1214 19:19:20.602385 3092 out.go:297] Setting OutFile to fd 1 ...
I1214 19:19:20.602511 3092 out.go:344] TERM=,COLORTERM=, which probably does not support color
I1214 19:19:20.602513 3092 out.go:310] Setting ErrFile to fd 2...
I1214 19:19:20.602516 3092 out.go:344] TERM=,COLORTERM=, which probably does not support color
I1214 19:19:20.602589 3092 root.go:315] Updating PATH: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/bin
I1214 19:19:20.602932 3092 out.go:304] Setting JSON to false
I1214 19:19:20.627236 3092 start.go:112] hostinfo: {"hostname":"administrators-Mac-mini.local","uptime":1135,"bootTime":1639537225,"procs":329,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"11.1","kernelVersion":"20.2.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"b7610dcb-1435-5842-8d5a-b2388403fea3"}
W1214 19:19:20.627330 3092 start.go:120] gopshost.Virtualization returned error: not implemented yet
I1214 19:19:20.653881 3092 out.go:176] * [mount-start-2-20211214191843-2012] minikube v1.24.0 on Darwin 11.1
I1214 19:19:20.654179 3092 notify.go:174] Checking for updates...
I1214 19:19:20.701311 3092 out.go:176] - MINIKUBE_LOCATION=13173
I1214 19:19:20.727427 3092 out.go:176] - KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig
I1214 19:19:20.753357 3092 out.go:176] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1214 19:19:20.779298 3092 out.go:176] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube
I1214 19:19:20.780074 3092 config.go:176] Loaded profile config "mount-start-1-20211214191843-2012": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.22.4
I1214 19:19:20.780163 3092 driver.go:344] Setting default libvirt URI to qemu:///system
I1214 19:19:20.849335 3092 out.go:176] * Using the hyperkit driver based on user configuration
I1214 19:19:20.849388 3092 start.go:280] selected driver: hyperkit
I1214 19:19:20.849394 3092 start.go:795] validating driver "hyperkit" against <nil>
I1214 19:19:20.849520 3092 start.go:806] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc:}
I1214 19:19:20.853607 3092 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1214 19:19:20.853754 3092 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.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
I1214 19:19:20.861611 3092 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.24.0
I1214 19:19:20.864457 3092 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:19:20.864469 3092 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I1214 19:19:20.864499 3092 start_flags.go:284] no existing cluster config was found, will generate one from the flags
I1214 19:19:20.864627 3092 start_flags.go:792] Wait components to verify : map[apiserver:true system_pods:true]
I1214 19:19:20.864640 3092 cni.go:93] Creating CNI manager for ""
I1214 19:19:20.864646 3092 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I1214 19:19:20.864654 3092 start_flags.go:298] config:
{Name:mount-start-2-20211214191843-2012 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.28-1638824847-13104@sha256:a90edc66cae8cca35685dce007b915405a2ba91d903f99f7d8f79cd9d1faabab Memory:2048 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 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.22.4 ClusterName:mount-start-2-20211214191843-2012 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local
ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:true MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:0 MountIP: MountMSize:6543 MountOptions:[] MountPort:46464 MountType:9p MountUID:0}
I1214 19:19:20.864734 3092 iso.go:123] acquiring lock: {Name:mk943310bf6b2008ea713e288d58cea34c6a1f06 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1214 19:19:20.911288 3092 out.go:176] * Starting control plane node mount-start-2-20211214191843-2012 in cluster mount-start-2-20211214191843-2012
I1214 19:19:20.911322 3092 preload.go:132] Checking if preload exists for k8s version v1.22.4 and runtime docker
I1214 19:19:20.911359 3092 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v16-v1.22.4-docker-overlay2-amd64.tar.lz4
I1214 19:19:20.911370 3092 cache.go:57] Caching tarball of preloaded images
I1214 19:19:20.911486 3092 preload.go:174] Found /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v16-v1.22.4-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1214 19:19:20.911495 3092 cache.go:60] Finished verifying existence of preloaded tar for v1.22.4 on docker
I1214 19:19:20.911563 3092 profile.go:147] Saving config to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/config.json ...
I1214 19:19:20.911583 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/config.json: {Name:mkd1e5c91898b82b8a836197507b682f11372ac6 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:19:20.911908 3092 cache.go:206] Successfully downloaded all kic artifacts
I1214 19:19:20.911930 3092 start.go:313] acquiring machines lock for mount-start-2-20211214191843-2012: {Name:mka3a8a65b290e50a4d0aa5ff0dc4859952f8909 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1214 19:19:20.911968 3092 start.go:317] acquired machines lock for "mount-start-2-20211214191843-2012" in 32.525µs
I1214 19:19:20.911988 3092 start.go:89] Provisioning new machine with config: &{Name:mount-start-2-20211214191843-2012 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/13105/minikube-v1.24.0-1638835912-13105.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.28-1638824847-13104@sha256:a90edc66cae8cca35685dce007b915405a2ba91d903f99f7d8f79cd9d1faabab Memory:2048 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 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConf
ig:{KubernetesVersion:v1.22.4 ClusterName:mount-start-2-20211214191843-2012 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.22.4 ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:true MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:0 MountIP: MountMSize:6543 MountOptions:[] MountPort:46464 MountType:9p MountUID:0} &{Name: IP: Port:8443 KubernetesVersion:v1.22.4 ControlPlane:tru
e Worker:true}
I1214 19:19:20.912032 3092 start.go:126] createHost starting for "" (driver="hyperkit")
I1214 19:19:20.958486 3092 out.go:203] * Creating hyperkit VM (CPUs=2, Memory=2048MB, Disk=20000MB) ...
I1214 19:19:20.958879 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:19:20.958937 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:19:20.967337 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51156
I1214 19:19:20.967742 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:19:20.968223 3092 main.go:130] libmachine: Using API Version 1
I1214 19:19:20.968232 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:19:20.968521 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:19:20.968637 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetMachineName
I1214 19:19:20.968735 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:19:20.968860 3092 start.go:160] libmachine.API.Create for "mount-start-2-20211214191843-2012" (driver="hyperkit")
I1214 19:19:20.968881 3092 client.go:168] LocalClient.Create starting
I1214 19:19:20.968935 3092 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem
I1214 19:19:20.968980 3092 main.go:130] libmachine: Decoding PEM data...
I1214 19:19:20.968996 3092 main.go:130] libmachine: Parsing certificate...
I1214 19:19:20.969065 3092 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/cert.pem
I1214 19:19:20.969094 3092 main.go:130] libmachine: Decoding PEM data...
I1214 19:19:20.969105 3092 main.go:130] libmachine: Parsing certificate...
I1214 19:19:20.969118 3092 main.go:130] libmachine: Running pre-create checks...
I1214 19:19:20.969127 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .PreCreateCheck
I1214 19:19:20.969219 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:20.969384 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetConfigRaw
I1214 19:19:20.969931 3092 main.go:130] libmachine: Creating machine...
I1214 19:19:20.969936 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Create
I1214 19:19:20.970001 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:20.970112 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:19:20.969991 3100 common.go:107] Making disk image using store path: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube
I1214 19:19:20.970163 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Downloading /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/iso/minikube-v1.24.0-1638835912-13105.iso...
I1214 19:19:21.130856 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:19:21.130742 3100 common.go:114] Creating ssh key: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa...
I1214 19:19:21.174654 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:19:21.174584 3100 common.go:120] Creating raw disk image: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk...
I1214 19:19:21.174666 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Writing magic tar header
I1214 19:19:21.174673 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Writing SSH key tar header
I1214 19:19:21.174872 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:19:21.174820 3100 common.go:134] Fixing permissions on /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012 ...
I1214 19:19:21.330667 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:21.330701 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid
I1214 19:19:21.330737 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Using UUID cb82ade0-5d55-11ec-8df5-149d997cd0f1
I1214 19:19:21.350255 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Generated MAC 56:16:2:90:66:c5
I1214 19:19:21.350275 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) 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=mount-start-2-20211214191843-2012
I1214 19:19:21.350331 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"cb82ade0-5d55-11ec-8df5-149d997cd0f1", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0000e0e70)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191
843-2012/bzimage", Initrd:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/initrd", Bootrom:"", CPUs:2, Memory:2048, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1214 19:19:21.350397 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"cb82ade0-5d55-11ec-8df5-149d997cd0f1", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc0000e0e70)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191
843-2012/bzimage", Initrd:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/initrd", Bootrom:"", CPUs:2, Memory:2048, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1214 19:19:21.350482 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid", "-c", "2", "-m", "2048M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "cb82ade0-5d55-11ec-8df5-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--
13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"}
I1214 19:19:21.350554 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid -c 2 -m 2048M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U cb82ade0-5d55-11ec-8df5-149d997cd0f1 -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.mini
kube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring -f kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"
I1214 19:19:21.350571 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I1214 19:19:21.350886 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: Starting &exec.Cmd{Path:"/usr/local/bin/hyperkit", Args:[]string{"/usr/local/bin/hyperkit", "-A", "-u", "-F", "/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid", "-c", "2", "-m", "2048M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "cb82ade0-5d55-11ec-8df5-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "
com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"}, Env:[]string{"LOGS_FOLDER=13173/3408", "JAVA_MAIN_CLASS_629=hudson.remoting.Laun
cher", "BUILD_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/", "access_token=b18b71449baa3b85b6ff20782db5ebeeb163c232", "jenkins_user=jenkins", "SHELL=/bin/zsh", "HUDSON_SERVER_COOKIE=fd8a7b33dde6712f", "TMPDIR=/var/folders/52/zh_qmlrn1f36yr6lgs7nxtym0000gp/T/", "MINIKUBE_LOCATION=13173", "SSH_CLIENT=108.59.86.165 60420 22", "BUILD_TAG=jenkins-Hyperkit_macOS_integration-3408", "ROOT_BUILD_CAUSE=GHPRBCAUSE", "JOB_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/", "WORKSPACE=/Users/jenkins/workspace", "RUN_CHANGES_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=changes", "USER=jenkins", "BUILD_CAUSE_UPSTREAMTRIGGER=true", "ROOT_JOB_ID=21803", "KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig", "jenkins_pass=A.rM9iH3", "RUN_ARTIFACTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/r
edirect?page=artifacts", "__CF_USER_TEXT_ENCODING=0x0:0:0", "JENKINS_HOME=/mnt/disks/sdb/jenkins", "PATH=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.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", "RUN_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect", "PWD=/Users/jenkins/workspace", "HUDSON_URL=http://mkproxy.cbf.corp.google.com/", "MINIKUBE_BIN=out/minikube-darwin-amd64", "JOB_NAME=Hyperkit_macOS", "BUILD_DISPLAY_NAME=#3408 - PR #13173 @ 21803", "E2E_BIN=out/e2e-darwin-amd64", "JENKINS_URL=http://mkproxy.cbf.corp.google.com/", "ROOT_BUILD_CAUSE_GHPRBCAUSE=true", "BUILD_ID=3408", "BUILD_CAUSE=UPSTREAMTRIGGER", "JOB_BASE_NAME=Hyperkit_macOS_integration", "RUN_TESTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=tests",
"SHLVL=2", "HOME=/Users/jenkins", "COMMIT=7fa4ce093861046ed4d109975b74ec5f157758ca", "CI=true", "JENKINS_SERVER_COOKIE=fd8a7b33dde6712f", "WORKSPACE_TMP=/Users/jenkins/workspace@tmp", "EXECUTOR_NUMBER=0", "NODE_LABELS=MacOS Agent 1 hyperkit macos-docker", "LOGNAME=jenkins", "HUDSON_HOME=/mnt/disks/sdb/jenkins", "SSH_CONNECTION=108.59.86.165 60420 207.254.39.213 22", "NODE_NAME=MacOS Agent 1", "GOPATH=/Users/jenkins/go", "JOB_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/display/redirect", "BUILD_NUMBER=3408", "HUDSON_COOKIE=c1c0f554-5b40-4641-b08d-2c5680461367", "SHORT_COMMIT=7fa4ce0", "MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube", "BUILD_REF=PR #13173", "_=/Users/jenkins/go/bin/gotestsum", "GOARCH=amd64", "GOCACHE=/Users/jenkins/Library/Caches/go-build", "GOENV=/Users/jenkins/Library/Application Support/go/env", "GOHOSTARCH=amd64", "GOHOSTOS=darwin", "GOMODCACHE=/Users/jenkins/go/pkg/mod
", "GOOS=darwin", "GOPROXY=https://proxy.golang.org,direct", "GOROOT=/usr/local/go", "GOSUMDB=sum.golang.org", "GOTOOLDIR=/usr/local/go/pkg/tool/darwin_amd64", "GOVERSION=go1.17.2", "GCCGO=gccgo", "AR=ar", "CC=clang", "CXX=clang++", "CGO_ENABLED=1", "LIBVIRT_DEFAULT_URI=qemu:///system", "MACHINE_PLUGIN_TOKEN=42", "MACHINE_PLUGIN_DRIVER_NAME=hyperkit", "MACHINE_DEBUG=1"}, Dir:"", Stdin:io.Reader(nil), Stdout:(*os.File)(0xc0000106a0), Stderr:(*os.File)(0xc0000106b0), ExtraFiles:[]*os.File(nil), SysProcAttr:(*syscall.SysProcAttr)(nil), Process:(*os.Process)(nil), ProcessState:(*os.ProcessState)(nil), ctx:context.Context(nil), lookPathErr:error(nil), finished:false, childFiles:[]*os.File(nil), closeAfterStart:[]io.Closer{(*os.File)(0xc0000106a0), (*os.File)(0xc0000106b0)}, closeAfterWait:[]io.Closer{(*os.File)(0xc000010698), (*os.File)(0xc0000106a8)}, goroutine:[]func() error(nil), errch:(chan error)(nil), waitDone:(chan struct {})(nil)}
I1214 19:19:21.351743 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: Pid is 3101
I1214 19:19:21.352016 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 0
I1214 19:19:21.352027 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:21.352096 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:19:21.352409 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 DEBUG: hyperkit: Waiting for &exec.Cmd{Path:"/usr/local/bin/hyperkit", Args:[]string{"/usr/local/bin/hyperkit", "-A", "-u", "-F", "/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid", "-c", "2", "-m", "2048M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "cb82ade0-5d55-11ec-8df5-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso", "-s", "4,virtio-rnd", "-l"
, "com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"}, Env:[]string{"LOGS_FOLDER=13173/3408", "JAVA_MAIN_CLASS_629=hudson.remoting.L
auncher", "BUILD_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/", "access_token=b18b71449baa3b85b6ff20782db5ebeeb163c232", "jenkins_user=jenkins", "SHELL=/bin/zsh", "HUDSON_SERVER_COOKIE=fd8a7b33dde6712f", "TMPDIR=/var/folders/52/zh_qmlrn1f36yr6lgs7nxtym0000gp/T/", "MINIKUBE_LOCATION=13173", "SSH_CLIENT=108.59.86.165 60420 22", "BUILD_TAG=jenkins-Hyperkit_macOS_integration-3408", "ROOT_BUILD_CAUSE=GHPRBCAUSE", "JOB_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/", "WORKSPACE=/Users/jenkins/workspace", "RUN_CHANGES_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=changes", "USER=jenkins", "BUILD_CAUSE_UPSTREAMTRIGGER=true", "ROOT_JOB_ID=21803", "KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig", "jenkins_pass=A.rM9iH3", "RUN_ARTIFACTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/displa
y/redirect?page=artifacts", "__CF_USER_TEXT_ENCODING=0x0:0:0", "JENKINS_HOME=/mnt/disks/sdb/jenkins", "PATH=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.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", "RUN_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect", "PWD=/Users/jenkins/workspace", "HUDSON_URL=http://mkproxy.cbf.corp.google.com/", "MINIKUBE_BIN=out/minikube-darwin-amd64", "JOB_NAME=Hyperkit_macOS", "BUILD_DISPLAY_NAME=#3408 - PR #13173 @ 21803", "E2E_BIN=out/e2e-darwin-amd64", "JENKINS_URL=http://mkproxy.cbf.corp.google.com/", "ROOT_BUILD_CAUSE_GHPRBCAUSE=true", "BUILD_ID=3408", "BUILD_CAUSE=UPSTREAMTRIGGER", "JOB_BASE_NAME=Hyperkit_macOS_integration", "RUN_TESTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=test
s", "SHLVL=2", "HOME=/Users/jenkins", "COMMIT=7fa4ce093861046ed4d109975b74ec5f157758ca", "CI=true", "JENKINS_SERVER_COOKIE=fd8a7b33dde6712f", "WORKSPACE_TMP=/Users/jenkins/workspace@tmp", "EXECUTOR_NUMBER=0", "NODE_LABELS=MacOS Agent 1 hyperkit macos-docker", "LOGNAME=jenkins", "HUDSON_HOME=/mnt/disks/sdb/jenkins", "SSH_CONNECTION=108.59.86.165 60420 207.254.39.213 22", "NODE_NAME=MacOS Agent 1", "GOPATH=/Users/jenkins/go", "JOB_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/display/redirect", "BUILD_NUMBER=3408", "HUDSON_COOKIE=c1c0f554-5b40-4641-b08d-2c5680461367", "SHORT_COMMIT=7fa4ce0", "MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube", "BUILD_REF=PR #13173", "_=/Users/jenkins/go/bin/gotestsum", "GOARCH=amd64", "GOCACHE=/Users/jenkins/Library/Caches/go-build", "GOENV=/Users/jenkins/Library/Application Support/go/env", "GOHOSTARCH=amd64", "GOHOSTOS=darwin", "GOMODCACHE=/Users/jenkins/go/pkg/
mod", "GOOS=darwin", "GOPROXY=https://proxy.golang.org,direct", "GOROOT=/usr/local/go", "GOSUMDB=sum.golang.org", "GOTOOLDIR=/usr/local/go/pkg/tool/darwin_amd64", "GOVERSION=go1.17.2", "GCCGO=gccgo", "AR=ar", "CC=clang", "CXX=clang++", "CGO_ENABLED=1", "LIBVIRT_DEFAULT_URI=qemu:///system", "MACHINE_PLUGIN_TOKEN=42", "MACHINE_PLUGIN_DRIVER_NAME=hyperkit", "MACHINE_DEBUG=1"}, Dir:"", Stdin:io.Reader(nil), Stdout:(*os.File)(0xc0000106a0), Stderr:(*os.File)(0xc0000106b0), ExtraFiles:[]*os.File(nil), SysProcAttr:(*syscall.SysProcAttr)(nil), Process:(*os.Process)(0xc000027860), ProcessState:(*os.ProcessState)(nil), ctx:context.Context(nil), lookPathErr:error(nil), finished:false, childFiles:[]*os.File{(*os.File)(0xc0000106b8), (*os.File)(0xc0000106a0), (*os.File)(0xc0000106b0)}, closeAfterStart:[]io.Closer{(*os.File)(0xc0000106a0), (*os.File)(0xc0000106b0), (*os.File)(0xc0000106b8)}, closeAfterWait:[]io.Closer{(*os.File)(0xc000010698), (*os.File)(0xc0000106a8)}, goroutine:[]func() error(nil), errch:(chan error)(nil
), waitDone:(chan struct {})(nil)}
I1214 19:19:21.353072 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 56:16:2:90:66:c5 in /var/db/dhcpd_leases ...
I1214 19:19:21.353096 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 6 entries in /var/db/dhcpd_leases!
I1214 19:19:21.353111 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:19:21.353143 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:19:21.353158 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:19:21.353169 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:19:21.353181 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:19:21.353199 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:19:21.359052 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I1214 19:19:21.367743 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I1214 19:19:21.368390 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1214 19:19:21.368401 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1214 19:19:21.368411 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1214 19:19:21.756529 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I1214 19:19:21.866462 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1214 19:19:21.866477 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1214 19:19:21.866491 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1214 19:19:21.867318 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:21 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I1214 19:19:23.355259 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 1
I1214 19:19:23.355277 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:23.355429 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:19:23.356874 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 56:16:2:90:66:c5 in /var/db/dhcpd_leases ...
I1214 19:19:23.357300 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 6 entries in /var/db/dhcpd_leases!
I1214 19:19:23.357311 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:19:23.357323 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:19:23.357329 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:19:23.357335 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:19:23.357340 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:19:23.357346 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:19:25.358490 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 2
I1214 19:19:25.358525 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:25.358682 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:19:25.359331 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 56:16:2:90:66:c5 in /var/db/dhcpd_leases ...
I1214 19:19:25.359387 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 6 entries in /var/db/dhcpd_leases!
I1214 19:19:25.359409 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:19:25.359438 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:19:25.359444 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:19:25.359450 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:19:25.359455 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:19:25.359495 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:19:25.747186 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:25 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I1214 19:19:25.747197 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:19:25 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I1214 19:19:27.360411 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 3
I1214 19:19:27.360419 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:27.360506 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:19:27.361125 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 56:16:2:90:66:c5 in /var/db/dhcpd_leases ...
I1214 19:19:27.361170 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 6 entries in /var/db/dhcpd_leases!
I1214 19:19:27.361186 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:19:27.361200 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:19:27.361205 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:19:27.361210 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:19:27.361215 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:19:27.361234 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:19:29.365546 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 4
I1214 19:19:29.365562 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:29.365709 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:19:29.366761 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 56:16:2:90:66:c5 in /var/db/dhcpd_leases ...
I1214 19:19:29.366843 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 7 entries in /var/db/dhcpd_leases!
I1214 19:19:29.366856 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:56:16:2:90:66:c5 ID:1,56:16:2:90:66:c5 Lease:0x61bab040}
I1214 19:19:29.366868 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found match: 56:16:2:90:66:c5
I1214 19:19:29.366878 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | IP: 192.168.64.8
I1214 19:19:29.366974 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetConfigRaw
I1214 19:19:29.367867 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:19:29.368011 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:19:29.368149 3092 main.go:130] libmachine: Waiting for machine to be running, this may take a few minutes...
I1214 19:19:29.368158 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetState
I1214 19:19:29.368273 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:19:29.368380 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:19:29.369463 3092 main.go:130] libmachine: Detecting operating system of created instance...
I1214 19:19:29.369469 3092 main.go:130] libmachine: Waiting for SSH to be available...
I1214 19:19:29.369473 3092 main.go:130] libmachine: Getting to WaitForSSH function...
I1214 19:19:29.369478 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:19:29.369615 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:19:29.369733 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:19:29.369862 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:19:29.369941 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:19:29.370047 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:19:29.370185 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.8 22 <nil> <nil>}
I1214 19:19:29.370189 3092 main.go:130] libmachine: About to run SSH command:
exit 0
I1214 19:19:30.372998 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:34.382500 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:38.389081 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:42.398877 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:46.409441 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:50.413469 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:54.420275 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:19:58.421874 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:02.423613 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:06.428793 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:10.434310 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:14.438939 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:18.440782 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:22.443524 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:26.445765 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:30.453055 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:34.455470 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:38.456918 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:42.459014 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:46.466081 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:50.475944 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:54.477640 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:20:58.480333 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:02.482081 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:06.485101 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:10.488805 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:14.491152 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:18.493674 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:22.495932 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:26.499316 3092 main.go:130] libmachine: Error dialing TCP: dial tcp 192.168.64.8:22: connect: connection refused
I1214 19:21:29.533254 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:32.565116 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:35.591370 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:38.628246 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:41.664061 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:44.695946 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:47.724652 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:50.756112 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:53.789075 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:56.823113 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:21:59.851346 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:02.877434 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:05.909291 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:08.935662 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:11.961687 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:14.987621 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:18.019096 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:21.048605 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:24.082180 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:27.114348 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:30.142194 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:33.177241 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:36.216216 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:39.243605 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:42.272989 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:45.302585 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:48.331657 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:51.368615 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:54.396134 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:22:57.425014 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:00.429583 3092 main.go:130] libmachine: SSH cmd err, output: <nil>:
I1214 19:23:00.429595 3092 main.go:130] libmachine: Detecting the provisioner...
I1214 19:23:00.429619 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:23:00.429921 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:23:00.430125 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:23:00.430325 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:23:00.430531 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:23:00.430815 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:23:00.431017 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.8 22 <nil> <nil>}
I1214 19:23:00.431028 3092 main.go:130] libmachine: About to run SSH command:
cat /etc/os-release
I1214 19:23:00.458191 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:03.490234 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:06.519079 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:09.548674 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:12.575228 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:15.600750 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:18.629016 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:21.663869 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:24.691712 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:27.718278 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:30.745200 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:33.774217 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:36.805122 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:39.839756 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:42.871569 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:45.909257 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:48.945851 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:51.978333 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:55.004834 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:23:58.035853 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:01.066424 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:04.095082 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:07.124250 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:10.157418 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:13.191727 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:16.229188 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:19.264363 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:22.291656 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:25.317306 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:28.343950 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:31.370094 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:34.396666 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:37.423369 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:40.449105 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:43.476821 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:46.514492 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:49.547617 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:52.577624 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:55.603886 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:24:58.632035 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:01.659724 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:04.690694 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:07.721792 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:10.750026 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:13.779648 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:16.810876 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:19.837093 3092 main.go:130] libmachine: Error dialing TCP: ssh: handshake failed: ssh: unable to authenticate, attempted methods [none publickey], no supported methods remain
I1214 19:25:20.971421 3092 start.go:129] duration metric: createHost completed in 6m0.058072977s
I1214 19:25:20.971431 3092 start.go:80] releasing machines lock for "mount-start-2-20211214191843-2012", held for 6m0.058155173s
W1214 19:25:20.971461 3092 start.go:567] error starting host: creating host: create host timed out in 360.000000 seconds
I1214 19:25:20.972054 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:25:20.972091 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:25:20.981227 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51265
I1214 19:25:20.981742 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:25:20.982137 3092 main.go:130] libmachine: Using API Version 1
I1214 19:25:20.982150 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:25:20.982404 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:25:20.982781 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:25:20.982800 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:25:20.989833 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51267
I1214 19:25:20.990214 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:25:20.990526 3092 main.go:130] libmachine: Using API Version 1
I1214 19:25:20.990536 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:25:20.990736 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:25:20.990838 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetState
I1214 19:25:20.990923 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:20.991008 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:25:20.991693 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:21.040376 3092 out.go:176] * Deleting "mount-start-2-20211214191843-2012" in hyperkit ...
I1214 19:25:21.040436 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Remove
I1214 19:25:21.040744 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:21.040761 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:21.040975 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:25:21.042220 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:21.042353 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | waiting for graceful shutdown
I1214 19:25:21.139857 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:21 INFO : hyperkit: stdout: linkname /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty
I1214 19:25:21.140500 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:21 INFO : hyperkit: stdout: COM1 connected to /dev/ttys002
I1214 19:25:21.144221 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:21 WARN : hyperkit: failed to read stdout: EOF
I1214 19:25:21.144241 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:21 WARN : hyperkit: failed to read stderr: EOF
I1214 19:25:22.045687 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:22.045840 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3101
I1214 19:25:22.047095 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid 3101 missing from process table
W1214 19:25:22.058301 3092 out.go:241] ! StartHost failed, but will try again: creating host: create host timed out in 360.000000 seconds
I1214 19:25:22.058312 3092 start.go:582] Will try again in 5 seconds ...
I1214 19:25:27.060361 3092 start.go:313] acquiring machines lock for mount-start-2-20211214191843-2012: {Name:mka3a8a65b290e50a4d0aa5ff0dc4859952f8909 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1214 19:25:27.060516 3092 start.go:317] acquired machines lock for "mount-start-2-20211214191843-2012" in 133.808µs
I1214 19:25:27.060549 3092 start.go:89] Provisioning new machine with config: &{Name:mount-start-2-20211214191843-2012 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/13105/minikube-v1.24.0-1638835912-13105.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.28-1638824847-13104@sha256:a90edc66cae8cca35685dce007b915405a2ba91d903f99f7d8f79cd9d1faabab Memory:2048 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 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConf
ig:{KubernetesVersion:v1.22.4 ClusterName:mount-start-2-20211214191843-2012 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.22.4 ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:true MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:0 MountIP: MountMSize:6543 MountOptions:[] MountPort:46464 MountType:9p MountUID:0} &{Name: IP: Port:8443 KubernetesVersion:v1.22.4 ControlPlane:tru
e Worker:true}
I1214 19:25:27.060628 3092 start.go:126] createHost starting for "" (driver="hyperkit")
I1214 19:25:27.088804 3092 out.go:203] * Creating hyperkit VM (CPUs=2, Memory=2048MB, Disk=20000MB) ...
I1214 19:25:27.088996 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:25:27.089035 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:25:27.117238 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51270
I1214 19:25:27.117694 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:25:27.118260 3092 main.go:130] libmachine: Using API Version 1
I1214 19:25:27.118275 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:25:27.118514 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:25:27.118637 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetMachineName
I1214 19:25:27.118724 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:27.118817 3092 start.go:160] libmachine.API.Create for "mount-start-2-20211214191843-2012" (driver="hyperkit")
I1214 19:25:27.118834 3092 client.go:168] LocalClient.Create starting
I1214 19:25:27.118879 3092 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem
I1214 19:25:27.118917 3092 main.go:130] libmachine: Decoding PEM data...
I1214 19:25:27.118929 3092 main.go:130] libmachine: Parsing certificate...
I1214 19:25:27.118975 3092 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/cert.pem
I1214 19:25:27.119000 3092 main.go:130] libmachine: Decoding PEM data...
I1214 19:25:27.119008 3092 main.go:130] libmachine: Parsing certificate...
I1214 19:25:27.119020 3092 main.go:130] libmachine: Running pre-create checks...
I1214 19:25:27.119023 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .PreCreateCheck
I1214 19:25:27.119107 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:27.119125 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetConfigRaw
I1214 19:25:27.119777 3092 main.go:130] libmachine: Creating machine...
I1214 19:25:27.119783 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Create
I1214 19:25:27.119848 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:27.119955 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:25:27.119838 3116 common.go:107] Making disk image using store path: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube
I1214 19:25:27.119998 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Downloading /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/boot2docker.iso from file:///Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/iso/minikube-v1.24.0-1638835912-13105.iso...
I1214 19:25:27.272863 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:25:27.272782 3116 common.go:114] Creating ssh key: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa...
I1214 19:25:27.591668 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:25:27.591615 3116 common.go:120] Creating raw disk image: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk...
I1214 19:25:27.591683 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Writing magic tar header
I1214 19:25:27.591690 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Writing SSH key tar header
I1214 19:25:27.591921 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | I1214 19:25:27.591854 3116 common.go:134] Fixing permissions on /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012 ...
I1214 19:25:27.749682 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:27.749695 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | clean start, hyperkit pid file doesn't exist: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid
I1214 19:25:27.749706 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Using UUID a5bdbc20-5d56-11ec-8df5-149d997cd0f1
I1214 19:25:27.766330 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Generated MAC 2e:9d:78:58:c1:b8
I1214 19:25:27.766364 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) 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=mount-start-2-20211214191843-2012
I1214 19:25:27.766415 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: Start &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"a5bdbc20-5d56-11ec-8df5-149d997cd0f1", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000110360)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191
843-2012/bzimage", Initrd:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/initrd", Bootrom:"", CPUs:2, Memory:2048, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1214 19:25:27.766453 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: check &hyperkit.HyperKit{HyperKit:"/usr/local/bin/hyperkit", Argv0:"", StateDir:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012", VPNKitSock:"", VPNKitUUID:"", VPNKitPreferredIPv4:"", UUID:"a5bdbc20-5d56-11ec-8df5-149d997cd0f1", Disks:[]hyperkit.Disk{(*hyperkit.RawDisk)(0xc000110360)}, ISOImages:[]string{"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso"}, VSock:false, VSockDir:"", VSockPorts:[]int(nil), VSockGuestCID:3, VMNet:true, Sockets9P:[]hyperkit.Socket9P(nil), Kernel:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191
843-2012/bzimage", Initrd:"/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/initrd", Bootrom:"", CPUs:2, Memory:2048, Console:1, Serials:[]hyperkit.Serial(nil), Pid:0, Arguments:[]string(nil), CmdLine:"", process:(*os.Process)(nil)}
I1214 19:25:27.766506 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: Arguments: []string{"-A", "-u", "-F", "/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid", "-c", "2", "-m", "2048M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "a5bdbc20-5d56-11ec-8df5-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--
13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"}
I1214 19:25:27.766563 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: CmdLine: "/usr/local/bin/hyperkit -A -u -F /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid -c 2 -m 2048M -s 0:0,hostbridge -s 31,lpc -s 1:0,virtio-net -U a5bdbc20-5d56-11ec-8df5-149d997cd0f1 -s 2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk -s 3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso -s 4,virtio-rnd -l com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.mini
kube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring -f kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"
I1214 19:25:27.766585 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: Redirecting stdout/stderr to logger
I1214 19:25:27.766744 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: Starting &exec.Cmd{Path:"/usr/local/bin/hyperkit", Args:[]string{"/usr/local/bin/hyperkit", "-A", "-u", "-F", "/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid", "-c", "2", "-m", "2048M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "a5bdbc20-5d56-11ec-8df5-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso", "-s", "4,virtio-rnd", "-l", "
com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"}, Env:[]string{"LOGS_FOLDER=13173/3408", "JAVA_MAIN_CLASS_629=hudson.remoting.Laun
cher", "BUILD_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/", "access_token=b18b71449baa3b85b6ff20782db5ebeeb163c232", "jenkins_user=jenkins", "SHELL=/bin/zsh", "HUDSON_SERVER_COOKIE=fd8a7b33dde6712f", "TMPDIR=/var/folders/52/zh_qmlrn1f36yr6lgs7nxtym0000gp/T/", "MINIKUBE_LOCATION=13173", "SSH_CLIENT=108.59.86.165 60420 22", "BUILD_TAG=jenkins-Hyperkit_macOS_integration-3408", "ROOT_BUILD_CAUSE=GHPRBCAUSE", "JOB_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/", "WORKSPACE=/Users/jenkins/workspace", "RUN_CHANGES_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=changes", "USER=jenkins", "BUILD_CAUSE_UPSTREAMTRIGGER=true", "ROOT_JOB_ID=21803", "KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig", "jenkins_pass=A.rM9iH3", "RUN_ARTIFACTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/r
edirect?page=artifacts", "__CF_USER_TEXT_ENCODING=0x0:0:0", "JENKINS_HOME=/mnt/disks/sdb/jenkins", "PATH=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.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", "RUN_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect", "PWD=/Users/jenkins/workspace", "HUDSON_URL=http://mkproxy.cbf.corp.google.com/", "MINIKUBE_BIN=out/minikube-darwin-amd64", "JOB_NAME=Hyperkit_macOS", "BUILD_DISPLAY_NAME=#3408 - PR #13173 @ 21803", "E2E_BIN=out/e2e-darwin-amd64", "JENKINS_URL=http://mkproxy.cbf.corp.google.com/", "ROOT_BUILD_CAUSE_GHPRBCAUSE=true", "BUILD_ID=3408", "BUILD_CAUSE=UPSTREAMTRIGGER", "JOB_BASE_NAME=Hyperkit_macOS_integration", "RUN_TESTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=tests",
"SHLVL=2", "HOME=/Users/jenkins", "COMMIT=7fa4ce093861046ed4d109975b74ec5f157758ca", "CI=true", "JENKINS_SERVER_COOKIE=fd8a7b33dde6712f", "WORKSPACE_TMP=/Users/jenkins/workspace@tmp", "EXECUTOR_NUMBER=0", "NODE_LABELS=MacOS Agent 1 hyperkit macos-docker", "LOGNAME=jenkins", "HUDSON_HOME=/mnt/disks/sdb/jenkins", "SSH_CONNECTION=108.59.86.165 60420 207.254.39.213 22", "NODE_NAME=MacOS Agent 1", "GOPATH=/Users/jenkins/go", "JOB_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/display/redirect", "BUILD_NUMBER=3408", "HUDSON_COOKIE=c1c0f554-5b40-4641-b08d-2c5680461367", "SHORT_COMMIT=7fa4ce0", "MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube", "BUILD_REF=PR #13173", "_=/Users/jenkins/go/bin/gotestsum", "GOARCH=amd64", "GOCACHE=/Users/jenkins/Library/Caches/go-build", "GOENV=/Users/jenkins/Library/Application Support/go/env", "GOHOSTARCH=amd64", "GOHOSTOS=darwin", "GOMODCACHE=/Users/jenkins/go/pkg/mod
", "GOOS=darwin", "GOPROXY=https://proxy.golang.org,direct", "GOROOT=/usr/local/go", "GOSUMDB=sum.golang.org", "GOTOOLDIR=/usr/local/go/pkg/tool/darwin_amd64", "GOVERSION=go1.17.2", "GCCGO=gccgo", "AR=ar", "CC=clang", "CXX=clang++", "CGO_ENABLED=1", "LIBVIRT_DEFAULT_URI=qemu:///system", "MACHINE_PLUGIN_TOKEN=42", "MACHINE_PLUGIN_DRIVER_NAME=hyperkit", "MACHINE_DEBUG=1"}, Dir:"", Stdin:io.Reader(nil), Stdout:(*os.File)(0xc000618020), Stderr:(*os.File)(0xc000618030), ExtraFiles:[]*os.File(nil), SysProcAttr:(*syscall.SysProcAttr)(nil), Process:(*os.Process)(nil), ProcessState:(*os.ProcessState)(nil), ctx:context.Context(nil), lookPathErr:error(nil), finished:false, childFiles:[]*os.File(nil), closeAfterStart:[]io.Closer{(*os.File)(0xc000618020), (*os.File)(0xc000618030)}, closeAfterWait:[]io.Closer{(*os.File)(0xc000618018), (*os.File)(0xc000618028)}, goroutine:[]func() error(nil), errch:(chan error)(nil), waitDone:(chan struct {})(nil)}
I1214 19:25:27.767726 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: Pid is 3117
I1214 19:25:27.768149 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 0
I1214 19:25:27.768165 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:27.768245 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:25:27.768514 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 DEBUG: hyperkit: Waiting for &exec.Cmd{Path:"/usr/local/bin/hyperkit", Args:[]string{"/usr/local/bin/hyperkit", "-A", "-u", "-F", "/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/hyperkit.pid", "-c", "2", "-m", "2048M", "-s", "0:0,hostbridge", "-s", "31,lpc", "-s", "1:0,virtio-net", "-U", "a5bdbc20-5d56-11ec-8df5-149d997cd0f1", "-s", "2:0,virtio-blk,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/mount-start-2-20211214191843-2012.rawdisk", "-s", "3,ahci-cd,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso", "-s", "4,virtio-rnd", "-l"
, "com1,autopty=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/tty,log=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/console-ring", "-f", "kexec,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/bzimage,/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/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=mount-start-2-20211214191843-2012"}, Env:[]string{"LOGS_FOLDER=13173/3408", "JAVA_MAIN_CLASS_629=hudson.remoting.L
auncher", "BUILD_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/", "access_token=b18b71449baa3b85b6ff20782db5ebeeb163c232", "jenkins_user=jenkins", "SHELL=/bin/zsh", "HUDSON_SERVER_COOKIE=fd8a7b33dde6712f", "TMPDIR=/var/folders/52/zh_qmlrn1f36yr6lgs7nxtym0000gp/T/", "MINIKUBE_LOCATION=13173", "SSH_CLIENT=108.59.86.165 60420 22", "BUILD_TAG=jenkins-Hyperkit_macOS_integration-3408", "ROOT_BUILD_CAUSE=GHPRBCAUSE", "JOB_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/", "WORKSPACE=/Users/jenkins/workspace", "RUN_CHANGES_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=changes", "USER=jenkins", "BUILD_CAUSE_UPSTREAMTRIGGER=true", "ROOT_JOB_ID=21803", "KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig", "jenkins_pass=A.rM9iH3", "RUN_ARTIFACTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/displa
y/redirect?page=artifacts", "__CF_USER_TEXT_ENCODING=0x0:0:0", "JENKINS_HOME=/mnt/disks/sdb/jenkins", "PATH=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.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", "RUN_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect", "PWD=/Users/jenkins/workspace", "HUDSON_URL=http://mkproxy.cbf.corp.google.com/", "MINIKUBE_BIN=out/minikube-darwin-amd64", "JOB_NAME=Hyperkit_macOS", "BUILD_DISPLAY_NAME=#3408 - PR #13173 @ 21803", "E2E_BIN=out/e2e-darwin-amd64", "JENKINS_URL=http://mkproxy.cbf.corp.google.com/", "ROOT_BUILD_CAUSE_GHPRBCAUSE=true", "BUILD_ID=3408", "BUILD_CAUSE=UPSTREAMTRIGGER", "JOB_BASE_NAME=Hyperkit_macOS_integration", "RUN_TESTS_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/3408/display/redirect?page=test
s", "SHLVL=2", "HOME=/Users/jenkins", "COMMIT=7fa4ce093861046ed4d109975b74ec5f157758ca", "CI=true", "JENKINS_SERVER_COOKIE=fd8a7b33dde6712f", "WORKSPACE_TMP=/Users/jenkins/workspace@tmp", "EXECUTOR_NUMBER=0", "NODE_LABELS=MacOS Agent 1 hyperkit macos-docker", "LOGNAME=jenkins", "HUDSON_HOME=/mnt/disks/sdb/jenkins", "SSH_CONNECTION=108.59.86.165 60420 207.254.39.213 22", "NODE_NAME=MacOS Agent 1", "GOPATH=/Users/jenkins/go", "JOB_DISPLAY_URL=http://mkproxy.cbf.corp.google.com/job/Hyperkit_macOS_integration/display/redirect", "BUILD_NUMBER=3408", "HUDSON_COOKIE=c1c0f554-5b40-4641-b08d-2c5680461367", "SHORT_COMMIT=7fa4ce0", "MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube", "BUILD_REF=PR #13173", "_=/Users/jenkins/go/bin/gotestsum", "GOARCH=amd64", "GOCACHE=/Users/jenkins/Library/Caches/go-build", "GOENV=/Users/jenkins/Library/Application Support/go/env", "GOHOSTARCH=amd64", "GOHOSTOS=darwin", "GOMODCACHE=/Users/jenkins/go/pkg/
mod", "GOOS=darwin", "GOPROXY=https://proxy.golang.org,direct", "GOROOT=/usr/local/go", "GOSUMDB=sum.golang.org", "GOTOOLDIR=/usr/local/go/pkg/tool/darwin_amd64", "GOVERSION=go1.17.2", "GCCGO=gccgo", "AR=ar", "CC=clang", "CXX=clang++", "CGO_ENABLED=1", "LIBVIRT_DEFAULT_URI=qemu:///system", "MACHINE_PLUGIN_TOKEN=42", "MACHINE_PLUGIN_DRIVER_NAME=hyperkit", "MACHINE_DEBUG=1"}, Dir:"", Stdin:io.Reader(nil), Stdout:(*os.File)(0xc000618020), Stderr:(*os.File)(0xc000618030), ExtraFiles:[]*os.File(nil), SysProcAttr:(*syscall.SysProcAttr)(nil), Process:(*os.Process)(0xc000112510), ProcessState:(*os.ProcessState)(nil), ctx:context.Context(nil), lookPathErr:error(nil), finished:false, childFiles:[]*os.File{(*os.File)(0xc000618038), (*os.File)(0xc000618020), (*os.File)(0xc000618030)}, closeAfterStart:[]io.Closer{(*os.File)(0xc000618020), (*os.File)(0xc000618030), (*os.File)(0xc000618038)}, closeAfterWait:[]io.Closer{(*os.File)(0xc000618018), (*os.File)(0xc000618028)}, goroutine:[]func() error(nil), errch:(chan error)(nil
), waitDone:(chan struct {})(nil)}
I1214 19:25:27.769061 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 2e:9d:78:58:c1:b8 in /var/db/dhcpd_leases ...
I1214 19:25:27.769112 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 7 entries in /var/db/dhcpd_leases!
I1214 19:25:27.769122 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:56:16:2:90:66:c5 ID:1,56:16:2:90:66:c5 Lease:0x61b96021}
I1214 19:25:27.769130 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:25:27.769137 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:25:27.769143 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:25:27.769147 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:25:27.769157 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:25:27.769170 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:25:27.773163 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 INFO : hyperkit: stderr: Using fd 5 for I/O notifications
I1214 19:25:27.781497 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 INFO : hyperkit: stderr: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/boot2docker.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
I1214 19:25:27.782350 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1214 19:25:27.782367 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1214 19:25:27.782381 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:27 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1214 19:25:28.205386 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:28 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 0
I1214 19:25:28.310410 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
I1214 19:25:28.310420 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
I1214 19:25:28.310455 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:28 INFO : hyperkit: stderr: vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
I1214 19:25:28.311266 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:28 INFO : hyperkit: stderr: rdmsr to register 0x140 on vcpu 1
I1214 19:25:29.770837 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 1
I1214 19:25:29.770846 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:29.770946 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:25:29.771536 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 2e:9d:78:58:c1:b8 in /var/db/dhcpd_leases ...
I1214 19:25:29.771605 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 7 entries in /var/db/dhcpd_leases!
I1214 19:25:29.771618 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:56:16:2:90:66:c5 ID:1,56:16:2:90:66:c5 Lease:0x61b96021}
I1214 19:25:29.771628 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:25:29.771635 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:25:29.771641 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:25:29.771645 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:25:29.771660 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:25:29.771669 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:25:31.773861 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 2
I1214 19:25:31.773874 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:31.773969 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:25:31.775081 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 2e:9d:78:58:c1:b8 in /var/db/dhcpd_leases ...
I1214 19:25:31.775134 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 7 entries in /var/db/dhcpd_leases!
I1214 19:25:31.775141 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:56:16:2:90:66:c5 ID:1,56:16:2:90:66:c5 Lease:0x61b96021}
I1214 19:25:31.775153 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:25:31.775161 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:25:31.775167 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:25:31.775174 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:25:31.775188 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:25:31.775198 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:25:32.162873 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:32 INFO : hyperkit: stderr: rdmsr to register 0x64e on vcpu 1
I1214 19:25:32.162882 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | 2021/12/14 19:25:32 INFO : hyperkit: stderr: rdmsr to register 0x34 on vcpu 1
I1214 19:25:33.776702 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 3
I1214 19:25:33.776713 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:33.776809 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:25:33.777429 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 2e:9d:78:58:c1:b8 in /var/db/dhcpd_leases ...
I1214 19:25:33.777460 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 7 entries in /var/db/dhcpd_leases!
I1214 19:25:33.777469 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.8 HWAddress:56:16:2:90:66:c5 ID:1,56:16:2:90:66:c5 Lease:0x61b96021}
I1214 19:25:33.777476 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.7 HWAddress:ee:bb:4a:62:3:f9 ID:1,ee:bb:4a:62:3:f9 Lease:0x61bab01b}
I1214 19:25:33.777485 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.6 HWAddress:9e:29:3c:c6:22:84 ID:1,9e:29:3c:c6:22:84 Lease:0x61baafdf}
I1214 19:25:33.777495 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.5 HWAddress:52:8d:86:d3:c:be ID:1,52:8d:86:d3:c:be Lease:0x61baaf4d}
I1214 19:25:33.777505 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.4 HWAddress:b2:45:d4:e7:3d:34 ID:1,b2:45:d4:e7:3d:34 Lease:0x61baae98}
I1214 19:25:33.777511 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.3 HWAddress:7e:49:54:5f:c1:20 ID:1,7e:49:54:5f:c1:20 Lease:0x61baae67}
I1214 19:25:33.777519 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.2 HWAddress:6e:1f:f3:6:43:9 ID:1,6e:1f:f3:6:43:9 Lease:0x61baad1f}
I1214 19:25:35.785666 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Attempt 4
I1214 19:25:35.785690 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:35.785857 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:25:35.786755 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Searching for 2e:9d:78:58:c1:b8 in /var/db/dhcpd_leases ...
I1214 19:25:35.786841 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found 8 entries in /var/db/dhcpd_leases!
I1214 19:25:35.786855 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | dhcp entry: {Name:minikube IPAddress:192.168.64.9 HWAddress:2e:9d:78:58:c1:b8 ID:1,2e:9d:78:58:c1:b8 Lease:0x61bab1ae}
I1214 19:25:35.786867 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Found match: 2e:9d:78:58:c1:b8
I1214 19:25:35.786883 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | IP: 192.168.64.9
I1214 19:25:35.786986 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetConfigRaw
I1214 19:25:35.787991 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:35.788136 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:35.788262 3092 main.go:130] libmachine: Waiting for machine to be running, this may take a few minutes...
I1214 19:25:35.788270 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetState
I1214 19:25:35.788358 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:25:35.788467 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:25:35.789169 3092 main.go:130] libmachine: Detecting operating system of created instance...
I1214 19:25:35.789175 3092 main.go:130] libmachine: Waiting for SSH to be available...
I1214 19:25:35.789179 3092 main.go:130] libmachine: Getting to WaitForSSH function...
I1214 19:25:35.789183 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:35.789297 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:35.789410 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:35.789542 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:35.789653 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:35.789810 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:35.789983 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:35.789987 3092 main.go:130] libmachine: About to run SSH command:
exit 0
I1214 19:25:35.863089 3092 main.go:130] libmachine: SSH cmd err, output: <nil>:
I1214 19:25:35.863096 3092 main.go:130] libmachine: Detecting the provisioner...
I1214 19:25:35.863102 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:35.863231 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:35.863313 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:35.863403 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:35.863490 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:35.863630 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:35.863763 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:35.863767 3092 main.go:130] libmachine: About to run SSH command:
cat /etc/os-release
I1214 19:25:35.938579 3092 main.go:130] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.4-dirty
ID=buildroot
VERSION_ID=2021.02.4
PRETTY_NAME="Buildroot 2021.02.4"
I1214 19:25:35.938633 3092 main.go:130] libmachine: found compatible host: buildroot
I1214 19:25:35.938636 3092 main.go:130] libmachine: Provisioning with buildroot...
I1214 19:25:35.938640 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetMachineName
I1214 19:25:35.938791 3092 buildroot.go:166] provisioning hostname "mount-start-2-20211214191843-2012"
I1214 19:25:35.938799 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetMachineName
I1214 19:25:35.938883 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:35.938970 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:35.939057 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:35.939135 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:35.939194 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:35.939309 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:35.939422 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:35.939431 3092 main.go:130] libmachine: About to run SSH command:
sudo hostname mount-start-2-20211214191843-2012 && echo "mount-start-2-20211214191843-2012" | sudo tee /etc/hostname
I1214 19:25:36.019844 3092 main.go:130] libmachine: SSH cmd err, output: <nil>: mount-start-2-20211214191843-2012
I1214 19:25:36.019857 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:36.020005 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:36.020085 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.020160 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.020251 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:36.020373 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:36.020477 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:36.020486 3092 main.go:130] libmachine: About to run SSH command:
if ! grep -xq '.*\smount-start-2-20211214191843-2012' /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 mount-start-2-20211214191843-2012/g' /etc/hosts;
else
echo '127.0.1.1 mount-start-2-20211214191843-2012' | sudo tee -a /etc/hosts;
fi
fi
I1214 19:25:36.099662 3092 main.go:130] libmachine: SSH cmd err, output: <nil>:
I1214 19:25:36.099675 3092 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube CaCertPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/key.pem ServerCertRemotePath:/etc/
docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube}
I1214 19:25:36.099693 3092 buildroot.go:174] setting up certificates
I1214 19:25:36.099701 3092 provision.go:83] configureAuth start
I1214 19:25:36.099706 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetMachineName
I1214 19:25:36.099846 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetIP
I1214 19:25:36.099944 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:36.100026 3092 provision.go:138] copyHostCerts
I1214 19:25:36.100113 3092 exec_runner.go:144] found /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.pem, removing ...
I1214 19:25:36.100120 3092 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.pem
I1214 19:25:36.100244 3092 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.pem (1078 bytes)
I1214 19:25:36.100440 3092 exec_runner.go:144] found /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cert.pem, removing ...
I1214 19:25:36.100444 3092 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cert.pem
I1214 19:25:36.100505 3092 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cert.pem (1123 bytes)
I1214 19:25:36.100643 3092 exec_runner.go:144] found /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/key.pem, removing ...
I1214 19:25:36.100646 3092 exec_runner.go:207] rm: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/key.pem
I1214 19:25:36.100709 3092 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/key.pem (1679 bytes)
I1214 19:25:36.100823 3092 provision.go:112] generating server cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca-key.pem org=jenkins.mount-start-2-20211214191843-2012 san=[192.168.64.9 192.168.64.9 localhost 127.0.0.1 minikube mount-start-2-20211214191843-2012]
I1214 19:25:36.262974 3092 provision.go:172] copyRemoteCerts
I1214 19:25:36.263034 3092 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I1214 19:25:36.263049 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:36.263297 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:36.263476 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.263644 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:36.263785 3092 sshutil.go:53] new ssh client: &{IP:192.168.64.9 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa Username:docker}
I1214 19:25:36.307985 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I1214 19:25:36.322799 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/server.pem --> /etc/docker/server.pem (1269 bytes)
I1214 19:25:36.337539 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I1214 19:25:36.352103 3092 provision.go:86] duration metric: configureAuth took 252.396051ms
I1214 19:25:36.352109 3092 buildroot.go:189] setting minikube options for container-runtime
I1214 19:25:36.352229 3092 config.go:176] Loaded profile config "mount-start-2-20211214191843-2012": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.22.4
I1214 19:25:36.352239 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:36.352360 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:36.352458 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:36.352539 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.352616 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.352684 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:36.352787 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:36.352882 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:36.352886 3092 main.go:130] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I1214 19:25:36.427012 3092 main.go:130] libmachine: SSH cmd err, output: <nil>: tmpfs
I1214 19:25:36.427018 3092 buildroot.go:70] root file system type: tmpfs
I1214 19:25:36.427161 3092 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I1214 19:25:36.427172 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:36.427305 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:36.427399 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.427482 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.427563 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:36.427700 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:36.427807 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:36.427851 3092 main.go:130] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %!s(MISSING) "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperkit --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I1214 19:25:36.508516 3092 main.go:130] 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
I1214 19:25:36.508535 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:36.508668 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:36.508739 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.508823 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:36.508899 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:36.509020 3092 main.go:130] libmachine: Using SSH client type: native
I1214 19:25:36.509147 3092 main.go:130] libmachine: &{{{<nil> 0 [] [] []} docker [0x1397120] 0x139a200 <nil> [] 0s} 192.168.64.9 22 <nil> <nil>}
I1214 19:25:36.509156 3092 main.go:130] 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; }
I1214 19:25:37.022943 3092 main.go:130] 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.
I1214 19:25:37.022952 3092 main.go:130] libmachine: Checking connection to Docker...
I1214 19:25:37.022957 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetURL
I1214 19:25:37.023105 3092 main.go:130] libmachine: Docker is up and running!
I1214 19:25:37.023108 3092 main.go:130] libmachine: Reticulating splines...
I1214 19:25:37.023111 3092 client.go:171] LocalClient.Create took 9.904238863s
I1214 19:25:37.023117 3092 start.go:168] duration metric: libmachine.API.Create for "mount-start-2-20211214191843-2012" took 9.904265603s
I1214 19:25:37.023125 3092 start.go:267] post-start starting for "mount-start-2-20211214191843-2012" (driver="hyperkit")
I1214 19:25:37.023131 3092 start.go:277] 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]
I1214 19:25:37.023145 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:37.023296 3092 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
I1214 19:25:37.023306 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:37.023413 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:37.023496 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:37.023565 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:37.023637 3092 sshutil.go:53] new ssh client: &{IP:192.168.64.9 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa Username:docker}
I1214 19:25:37.070546 3092 ssh_runner.go:195] Run: cat /etc/os-release
I1214 19:25:37.073024 3092 info.go:137] Remote host: Buildroot 2021.02.4
I1214 19:25:37.073032 3092 filesync.go:126] Scanning /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/addons for local assets ...
I1214 19:25:37.073124 3092 filesync.go:126] Scanning /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/files for local assets ...
I1214 19:25:37.073273 3092 filesync.go:149] local asset: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/files/etc/ssl/certs/20122.pem -> 20122.pem in /etc/ssl/certs
I1214 19:25:37.073433 3092 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I1214 19:25:37.079117 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/files/etc/ssl/certs/20122.pem --> /etc/ssl/certs/20122.pem (1708 bytes)
I1214 19:25:37.094704 3092 start.go:270] post-start completed in 71.573456ms
I1214 19:25:37.094727 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetConfigRaw
I1214 19:25:37.095408 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetIP
I1214 19:25:37.095547 3092 profile.go:147] Saving config to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/config.json ...
I1214 19:25:37.095824 3092 start.go:129] duration metric: createHost completed in 10.035151794s
I1214 19:25:37.095830 3092 start.go:80] releasing machines lock for "mount-start-2-20211214191843-2012", held for 10.035270248s
I1214 19:25:37.095849 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:37.095932 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetIP
I1214 19:25:37.096019 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:37.096105 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:37.096376 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:25:37.096542 3092 ssh_runner.go:195] Run: systemctl --version
I1214 19:25:37.096543 3092 ssh_runner.go:195] Run: curl -sS -m 2 https://k8s.gcr.io/
I1214 19:25:37.096551 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:37.096573 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:25:37.096641 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:37.096661 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:25:37.096717 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:37.096741 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:25:37.096816 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:37.096837 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:25:37.096917 3092 sshutil.go:53] new ssh client: &{IP:192.168.64.9 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa Username:docker}
I1214 19:25:37.096928 3092 sshutil.go:53] new ssh client: &{IP:192.168.64.9 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa Username:docker}
I1214 19:25:37.136467 3092 preload.go:132] Checking if preload exists for k8s version v1.22.4 and runtime docker
I1214 19:25:37.136581 3092 ssh_runner.go:195] Run: docker images --format {{.Repository}}:{{.Tag}}
I1214 19:25:37.605715 3092 docker.go:558] Got preloaded images:
I1214 19:25:37.605730 3092 docker.go:564] k8s.gcr.io/kube-apiserver:v1.22.4 wasn't preloaded
I1214 19:25:37.605826 3092 ssh_runner.go:195] Run: sudo cat /var/lib/docker/image/overlay2/repositories.json
I1214 19:25:37.612795 3092 ssh_runner.go:195] Run: which lz4
I1214 19:25:37.616021 3092 ssh_runner.go:195] Run: stat -c "%!s(MISSING) %!y(MISSING)" /preloaded.tar.lz4
I1214 19:25:37.618477 3092 ssh_runner.go:352] existence check for /preloaded.tar.lz4: stat -c "%!s(MISSING) %!y(MISSING)" /preloaded.tar.lz4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/preloaded.tar.lz4': No such file or directory
I1214 19:25:37.618504 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v16-v1.22.4-docker-overlay2-amd64.tar.lz4 --> /preloaded.tar.lz4 (526185943 bytes)
I1214 19:25:39.762225 3092 docker.go:523] Took 2.146236 seconds to copy over tarball
I1214 19:25:39.762286 3092 ssh_runner.go:195] Run: sudo tar -I lz4 -C /var -xf /preloaded.tar.lz4
I1214 19:25:43.983825 3092 ssh_runner.go:235] Completed: sudo tar -I lz4 -C /var -xf /preloaded.tar.lz4: (4.221512359s)
I1214 19:25:43.983860 3092 ssh_runner.go:146] rm: /preloaded.tar.lz4
I1214 19:25:44.011474 3092 ssh_runner.go:195] Run: sudo cat /var/lib/docker/image/overlay2/repositories.json
I1214 19:25:44.017915 3092 ssh_runner.go:362] scp memory --> /var/lib/docker/image/overlay2/repositories.json (3149 bytes)
I1214 19:25:44.028818 3092 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1214 19:25:44.113388 3092 ssh_runner.go:195] Run: sudo systemctl restart docker
I1214 19:25:46.106109 3092 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.992687906s)
I1214 19:25:46.106192 3092 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1214 19:25:46.115146 3092 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I1214 19:25:46.128560 3092 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1214 19:25:46.138302 3092 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1214 19:25:46.146630 3092 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I1214 19:25:46.173137 3092 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1214 19:25:46.182268 3092 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %!s(MISSING) "runtime-endpoint: unix:///var/run/dockershim.sock
image-endpoint: unix:///var/run/dockershim.sock
" | sudo tee /etc/crictl.yaml"
I1214 19:25:46.194049 3092 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I1214 19:25:46.283641 3092 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I1214 19:25:46.371030 3092 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1214 19:25:46.465185 3092 ssh_runner.go:195] Run: sudo systemctl start docker
I1214 19:25:46.475504 3092 ssh_runner.go:195] Run: docker version --format {{.Server.Version}}
I1214 19:25:46.505558 3092 ssh_runner.go:195] Run: docker version --format {{.Server.Version}}
I1214 19:25:46.575903 3092 out.go:203] * Preparing Kubernetes v1.22.4 on Docker 20.10.8 ...
I1214 19:25:46.575993 3092 ssh_runner.go:195] Run: grep 192.168.64.1 host.minikube.internal$ /etc/hosts
I1214 19:25:46.578656 3092 ssh_runner.go:195] Run: /bin/bash -c "{ grep -v $'\thost.minikube.internal$' "/etc/hosts"; echo "192.168.64.1 host.minikube.internal"; } > /tmp/h.$$; sudo cp /tmp/h.$$ "/etc/hosts""
I1214 19:25:46.587173 3092 preload.go:132] Checking if preload exists for k8s version v1.22.4 and runtime docker
I1214 19:25:46.587241 3092 ssh_runner.go:195] Run: docker images --format {{.Repository}}:{{.Tag}}
I1214 19:25:46.609072 3092 docker.go:558] Got preloaded images: -- stdout --
k8s.gcr.io/kube-apiserver:v1.22.4
k8s.gcr.io/kube-controller-manager:v1.22.4
k8s.gcr.io/kube-scheduler:v1.22.4
k8s.gcr.io/kube-proxy:v1.22.4
kubernetesui/dashboard:v2.3.1
k8s.gcr.io/etcd:3.5.0-0
kubernetesui/metrics-scraper:v1.0.7
k8s.gcr.io/coredns/coredns:v1.8.4
gcr.io/k8s-minikube/storage-provisioner:v5
k8s.gcr.io/pause:3.5
-- /stdout --
I1214 19:25:46.609079 3092 docker.go:489] Images already preloaded, skipping extraction
I1214 19:25:46.609172 3092 ssh_runner.go:195] Run: docker images --format {{.Repository}}:{{.Tag}}
I1214 19:25:46.631007 3092 docker.go:558] Got preloaded images: -- stdout --
k8s.gcr.io/kube-apiserver:v1.22.4
k8s.gcr.io/kube-controller-manager:v1.22.4
k8s.gcr.io/kube-scheduler:v1.22.4
k8s.gcr.io/kube-proxy:v1.22.4
kubernetesui/dashboard:v2.3.1
k8s.gcr.io/etcd:3.5.0-0
kubernetesui/metrics-scraper:v1.0.7
k8s.gcr.io/coredns/coredns:v1.8.4
gcr.io/k8s-minikube/storage-provisioner:v5
k8s.gcr.io/pause:3.5
-- /stdout --
I1214 19:25:46.631043 3092 cache_images.go:79] Images are preloaded, skipping loading
I1214 19:25:46.631125 3092 ssh_runner.go:195] Run: docker info --format {{.CgroupDriver}}
I1214 19:25:46.657253 3092 cni.go:93] Creating CNI manager for ""
I1214 19:25:46.657261 3092 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I1214 19:25:46.657272 3092 kubeadm.go:87] Using pod CIDR: 10.244.0.0/16
I1214 19:25:46.657279 3092 kubeadm.go:153] kubeadm options: {CertDir:/var/lib/minikube/certs ServiceCIDR:10.96.0.0/12 PodSubnet:10.244.0.0/16 AdvertiseAddress:192.168.64.9 APIServerPort:8443 KubernetesVersion:v1.22.4 EtcdDataDir:/var/lib/minikube/etcd EtcdExtraArgs:map[] ClusterName:mount-start-2-20211214191843-2012 NodeName:mount-start-2-20211214191843-2012 DNSDomain:cluster.local CRISocket:/var/run/dockershim.sock ImageRepository: ComponentOptions:[{Component:apiServer ExtraArgs:map[enable-admission-plugins:NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota] Pairs:map[certSANs:["127.0.0.1", "localhost", "192.168.64.9"]]} {Component:controllerManager ExtraArgs:map[allocate-node-cidrs:true leader-elect:false] Pairs:map[]} {Component:scheduler ExtraArgs:map[leader-elect:false] Pairs:map[]}] FeatureArgs:map[] NoTaintMaster:true NodeIP:192.168.64.9 CgroupDriver:systemd ClientCAFil
e:/var/lib/minikube/certs/ca.crt StaticPodPath:/etc/kubernetes/manifests ControlPlaneAddress:control-plane.minikube.internal KubeProxyOptions:map[]}
I1214 19:25:46.657364 3092 kubeadm.go:157] kubeadm config:
apiVersion: kubeadm.k8s.io/v1beta2
kind: InitConfiguration
localAPIEndpoint:
advertiseAddress: 192.168.64.9
bindPort: 8443
bootstrapTokens:
- groups:
- system:bootstrappers:kubeadm:default-node-token
ttl: 24h0m0s
usages:
- signing
- authentication
nodeRegistration:
criSocket: /var/run/dockershim.sock
name: "mount-start-2-20211214191843-2012"
kubeletExtraArgs:
node-ip: 192.168.64.9
taints: []
---
apiVersion: kubeadm.k8s.io/v1beta2
kind: ClusterConfiguration
apiServer:
certSANs: ["127.0.0.1", "localhost", "192.168.64.9"]
extraArgs:
enable-admission-plugins: "NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota"
controllerManager:
extraArgs:
allocate-node-cidrs: "true"
leader-elect: "false"
scheduler:
extraArgs:
leader-elect: "false"
certificatesDir: /var/lib/minikube/certs
clusterName: mk
controlPlaneEndpoint: control-plane.minikube.internal:8443
dns:
type: CoreDNS
etcd:
local:
dataDir: /var/lib/minikube/etcd
extraArgs:
proxy-refresh-interval: "70000"
kubernetesVersion: v1.22.4
networking:
dnsDomain: cluster.local
podSubnet: "10.244.0.0/16"
serviceSubnet: 10.96.0.0/12
---
apiVersion: kubelet.config.k8s.io/v1beta1
kind: KubeletConfiguration
authentication:
x509:
clientCAFile: /var/lib/minikube/certs/ca.crt
cgroupDriver: systemd
clusterDomain: "cluster.local"
# disable disk resource management by default
imageGCHighThresholdPercent: 100
evictionHard:
nodefs.available: "0%!"(MISSING)
nodefs.inodesFree: "0%!"(MISSING)
imagefs.available: "0%!"(MISSING)
failSwapOn: false
staticPodPath: /etc/kubernetes/manifests
---
apiVersion: kubeproxy.config.k8s.io/v1alpha1
kind: KubeProxyConfiguration
clusterCIDR: "10.244.0.0/16"
metricsBindAddress: 0.0.0.0:10249
conntrack:
maxPerCore: 0
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_established"
tcpEstablishedTimeout: 0s
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_close"
tcpCloseWaitTimeout: 0s
I1214 19:25:46.657922 3092 kubeadm.go:927] kubelet [Unit]
Wants=docker.socket
[Service]
ExecStart=
ExecStart=/var/lib/minikube/binaries/v1.22.4/kubelet --bootstrap-kubeconfig=/etc/kubernetes/bootstrap-kubelet.conf --config=/var/lib/kubelet/config.yaml --container-runtime=docker --hostname-override=mount-start-2-20211214191843-2012 --kubeconfig=/etc/kubernetes/kubelet.conf --node-ip=192.168.64.9
[Install]
config:
{KubernetesVersion:v1.22.4 ClusterName:mount-start-2-20211214191843-2012 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:}
I1214 19:25:46.658011 3092 ssh_runner.go:195] Run: sudo ls /var/lib/minikube/binaries/v1.22.4
I1214 19:25:46.664991 3092 binaries.go:44] Found k8s binaries, skipping transfer
I1214 19:25:46.665087 3092 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/kubelet.service.d /lib/systemd/system /var/tmp/minikube
I1214 19:25:46.671605 3092 ssh_runner.go:362] scp memory --> /etc/systemd/system/kubelet.service.d/10-kubeadm.conf (359 bytes)
I1214 19:25:46.682609 3092 ssh_runner.go:362] scp memory --> /lib/systemd/system/kubelet.service (352 bytes)
I1214 19:25:46.693710 3092 ssh_runner.go:362] scp memory --> /var/tmp/minikube/kubeadm.yaml.new (2075 bytes)
I1214 19:25:46.704848 3092 ssh_runner.go:195] Run: grep 192.168.64.9 control-plane.minikube.internal$ /etc/hosts
I1214 19:25:46.707044 3092 ssh_runner.go:195] Run: /bin/bash -c "{ grep -v $'\tcontrol-plane.minikube.internal$' "/etc/hosts"; echo "192.168.64.9 control-plane.minikube.internal"; } > /tmp/h.$$; sudo cp /tmp/h.$$ "/etc/hosts""
I1214 19:25:46.714605 3092 certs.go:54] Setting up /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012 for IP: 192.168.64.9
I1214 19:25:46.714709 3092 certs.go:182] skipping minikubeCA CA generation: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.key
I1214 19:25:46.714758 3092 certs.go:182] skipping proxyClientCA CA generation: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/proxy-client-ca.key
I1214 19:25:46.714805 3092 certs.go:302] generating minikube-user signed cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/client.key
I1214 19:25:46.714818 3092 crypto.go:68] Generating cert /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/client.crt with IP's: []
I1214 19:25:46.847195 3092 crypto.go:156] Writing cert to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/client.crt ...
I1214 19:25:46.847204 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/client.crt: {Name:mk2d2455b88e78df4b7337e513fc71e2096be814 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:25:46.847508 3092 crypto.go:164] Writing key to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/client.key ...
I1214 19:25:46.847513 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/client.key: {Name:mke7af7a2a1424b6dade6df840f7e21589c4cae3 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:25:46.847694 3092 certs.go:302] generating minikube signed cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.key.81962d82
I1214 19:25:46.847708 3092 crypto.go:68] Generating cert /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.crt.81962d82 with IP's: [192.168.64.9 10.96.0.1 127.0.0.1 10.0.0.1]
I1214 19:25:47.065198 3092 crypto.go:156] Writing cert to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.crt.81962d82 ...
I1214 19:25:47.065208 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.crt.81962d82: {Name:mk3f6ce6053eaa6b876774fdc8739a4383c3cdf6 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:25:47.065479 3092 crypto.go:164] Writing key to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.key.81962d82 ...
I1214 19:25:47.065485 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.key.81962d82: {Name:mkcf2e6a4fad19938def8b6dc42c77abf2a1c44b Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:25:47.065652 3092 certs.go:320] copying /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.crt.81962d82 -> /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.crt
I1214 19:25:47.065819 3092 certs.go:324] copying /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.key.81962d82 -> /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.key
I1214 19:25:47.065977 3092 certs.go:302] generating aggregator signed cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.key
I1214 19:25:47.065991 3092 crypto.go:68] Generating cert /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.crt with IP's: []
I1214 19:25:47.270679 3092 crypto.go:156] Writing cert to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.crt ...
I1214 19:25:47.270691 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.crt: {Name:mka0071c2ee3f15b8e97a8dc62b2dc0ad3c08ceb Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:25:47.270963 3092 crypto.go:164] Writing key to /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.key ...
I1214 19:25:47.270969 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.key: {Name:mkc1c97b8b9f2dbe95bce21428028ac256639af5 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:25:47.271345 3092 certs.go:388] found cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/2012.pem (1338 bytes)
W1214 19:25:47.271387 3092 certs.go:384] ignoring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/2012_empty.pem, impossibly tiny 0 bytes
I1214 19:25:47.271395 3092 certs.go:388] found cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca-key.pem (1679 bytes)
I1214 19:25:47.271428 3092 certs.go:388] found cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/ca.pem (1078 bytes)
I1214 19:25:47.271458 3092 certs.go:388] found cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/cert.pem (1123 bytes)
I1214 19:25:47.271489 3092 certs.go:388] found cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/key.pem (1679 bytes)
I1214 19:25:47.271555 3092 certs.go:388] found cert: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/files/etc/ssl/certs/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/files/etc/ssl/certs/20122.pem (1708 bytes)
I1214 19:25:47.272265 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.crt --> /var/lib/minikube/certs/apiserver.crt (1399 bytes)
I1214 19:25:47.288566 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/apiserver.key --> /var/lib/minikube/certs/apiserver.key (1679 bytes)
I1214 19:25:47.304117 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.crt --> /var/lib/minikube/certs/proxy-client.crt (1147 bytes)
I1214 19:25:47.320799 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/proxy-client.key --> /var/lib/minikube/certs/proxy-client.key (1679 bytes)
I1214 19:25:47.336229 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.crt --> /var/lib/minikube/certs/ca.crt (1111 bytes)
I1214 19:25:47.351340 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.key --> /var/lib/minikube/certs/ca.key (1679 bytes)
I1214 19:25:47.366608 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/proxy-client-ca.crt --> /var/lib/minikube/certs/proxy-client-ca.crt (1119 bytes)
I1214 19:25:47.382406 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/proxy-client-ca.key --> /var/lib/minikube/certs/proxy-client-ca.key (1679 bytes)
I1214 19:25:47.398234 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/files/etc/ssl/certs/20122.pem --> /usr/share/ca-certificates/20122.pem (1708 bytes)
I1214 19:25:47.413513 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/ca.crt --> /usr/share/ca-certificates/minikubeCA.pem (1111 bytes)
I1214 19:25:47.429742 3092 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/certs/2012.pem --> /usr/share/ca-certificates/2012.pem (1338 bytes)
I1214 19:25:47.444789 3092 ssh_runner.go:362] scp memory --> /var/lib/minikube/kubeconfig (738 bytes)
I1214 19:25:47.455905 3092 ssh_runner.go:195] Run: openssl version
I1214 19:25:47.459437 3092 ssh_runner.go:195] Run: sudo /bin/bash -c "test -s /usr/share/ca-certificates/2012.pem && ln -fs /usr/share/ca-certificates/2012.pem /etc/ssl/certs/2012.pem"
I1214 19:25:47.466044 3092 ssh_runner.go:195] Run: ls -la /usr/share/ca-certificates/2012.pem
I1214 19:25:47.468935 3092 certs.go:431] hashing: -rw-r--r-- 1 root root 1338 Dec 15 03:12 /usr/share/ca-certificates/2012.pem
I1214 19:25:47.468968 3092 ssh_runner.go:195] Run: openssl x509 -hash -noout -in /usr/share/ca-certificates/2012.pem
I1214 19:25:47.472673 3092 ssh_runner.go:195] Run: sudo /bin/bash -c "test -L /etc/ssl/certs/51391683.0 || ln -fs /etc/ssl/certs/2012.pem /etc/ssl/certs/51391683.0"
I1214 19:25:47.479992 3092 ssh_runner.go:195] Run: sudo /bin/bash -c "test -s /usr/share/ca-certificates/20122.pem && ln -fs /usr/share/ca-certificates/20122.pem /etc/ssl/certs/20122.pem"
I1214 19:25:47.486773 3092 ssh_runner.go:195] Run: ls -la /usr/share/ca-certificates/20122.pem
I1214 19:25:47.489634 3092 certs.go:431] hashing: -rw-r--r-- 1 root root 1708 Dec 15 03:12 /usr/share/ca-certificates/20122.pem
I1214 19:25:47.489673 3092 ssh_runner.go:195] Run: openssl x509 -hash -noout -in /usr/share/ca-certificates/20122.pem
I1214 19:25:47.493118 3092 ssh_runner.go:195] Run: sudo /bin/bash -c "test -L /etc/ssl/certs/3ec20f2e.0 || ln -fs /etc/ssl/certs/20122.pem /etc/ssl/certs/3ec20f2e.0"
I1214 19:25:47.499732 3092 ssh_runner.go:195] Run: sudo /bin/bash -c "test -s /usr/share/ca-certificates/minikubeCA.pem && ln -fs /usr/share/ca-certificates/minikubeCA.pem /etc/ssl/certs/minikubeCA.pem"
I1214 19:25:47.506311 3092 ssh_runner.go:195] Run: ls -la /usr/share/ca-certificates/minikubeCA.pem
I1214 19:25:47.509158 3092 certs.go:431] hashing: -rw-r--r-- 1 root root 1111 Dec 15 03:06 /usr/share/ca-certificates/minikubeCA.pem
I1214 19:25:47.509194 3092 ssh_runner.go:195] Run: openssl x509 -hash -noout -in /usr/share/ca-certificates/minikubeCA.pem
I1214 19:25:47.512527 3092 ssh_runner.go:195] Run: sudo /bin/bash -c "test -L /etc/ssl/certs/b5213941.0 || ln -fs /etc/ssl/certs/minikubeCA.pem /etc/ssl/certs/b5213941.0"
I1214 19:25:47.519211 3092 kubeadm.go:390] StartCluster: {Name:mount-start-2-20211214191843-2012 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/13105/minikube-v1.24.0-1638835912-13105.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.28-1638824847-13104@sha256:a90edc66cae8cca35685dce007b915405a2ba91d903f99f7d8f79cd9d1faabab Memory:2048 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 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.22.4 ClusterName:mount-start-2-20211214191843-2012 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP:192.168.64.9 Port:8443 KubernetesVersion:v1.22.4 ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:true MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:0 MountIP: MountMSize:6543 MountOptions:[] MountPort:46464 MountType:9p MountUID:0}
I1214 19:25:47.519308 3092 ssh_runner.go:195] Run: docker ps --filter status=paused --filter=name=k8s_.*_(kube-system)_ --format={{.ID}}
I1214 19:25:47.540009 3092 ssh_runner.go:195] Run: sudo ls /var/lib/kubelet/kubeadm-flags.env /var/lib/kubelet/config.yaml /var/lib/minikube/etcd
I1214 19:25:47.548063 3092 ssh_runner.go:195] Run: sudo cp /var/tmp/minikube/kubeadm.yaml.new /var/tmp/minikube/kubeadm.yaml
I1214 19:25:47.554294 3092 ssh_runner.go:195] Run: sudo ls -la /etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf
I1214 19:25:47.561081 3092 kubeadm.go:151] config check failed, skipping stale config cleanup: sudo ls -la /etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf: Process exited with status 2
stdout:
stderr:
ls: cannot access '/etc/kubernetes/admin.conf': No such file or directory
ls: cannot access '/etc/kubernetes/kubelet.conf': No such file or directory
ls: cannot access '/etc/kubernetes/controller-manager.conf': No such file or directory
ls: cannot access '/etc/kubernetes/scheduler.conf': No such file or directory
I1214 19:25:47.561093 3092 ssh_runner.go:286] Start: /bin/bash -c "sudo env PATH="/var/lib/minikube/binaries/v1.22.4:$PATH" kubeadm init --config /var/tmp/minikube/kubeadm.yaml --ignore-preflight-errors=DirAvailable--etc-kubernetes-manifests,DirAvailable--var-lib-minikube,DirAvailable--var-lib-minikube-etcd,FileAvailable--etc-kubernetes-manifests-kube-scheduler.yaml,FileAvailable--etc-kubernetes-manifests-kube-apiserver.yaml,FileAvailable--etc-kubernetes-manifests-kube-controller-manager.yaml,FileAvailable--etc-kubernetes-manifests-etcd.yaml,Port-10250,Swap,Mem"
I1214 19:25:47.985464 3092 out.go:203] - Generating certificates and keys ...
I1214 19:25:51.255653 3092 out.go:203] - Booting up control plane ...
I1214 19:25:59.799382 3092 out.go:203] - Configuring RBAC rules ...
I1214 19:26:00.181396 3092 cni.go:93] Creating CNI manager for ""
I1214 19:26:00.181416 3092 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I1214 19:26:00.181454 3092 ssh_runner.go:195] Run: /bin/bash -c "cat /proc/$(pgrep kube-apiserver)/oom_adj"
I1214 19:26:00.181534 3092 ssh_runner.go:195] Run: sudo /var/lib/minikube/binaries/v1.22.4/kubectl create clusterrolebinding minikube-rbac --clusterrole=cluster-admin --serviceaccount=kube-system:default --kubeconfig=/var/lib/minikube/kubeconfig
I1214 19:26:00.181537 3092 ssh_runner.go:195] Run: sudo /var/lib/minikube/binaries/v1.22.4/kubectl label nodes minikube.k8s.io/version=v1.24.0 minikube.k8s.io/commit=1bfd93799ca1a0aa711376fa94919427c19ad092 minikube.k8s.io/name=mount-start-2-20211214191843-2012 minikube.k8s.io/updated_at=2021_12_14T19_26_00_0700 --all --overwrite --kubeconfig=/var/lib/minikube/kubeconfig
I1214 19:26:00.194451 3092 ops.go:34] apiserver oom_adj: -16
I1214 19:26:00.360844 3092 kubeadm.go:1003] duration metric: took 179.373004ms to wait for elevateKubeSystemPrivileges.
I1214 19:26:00.395029 3092 kubeadm.go:392] StartCluster complete in 12.875774398s
I1214 19:26:00.395050 3092 settings.go:142] acquiring lock: {Name:mk550e0011db21c270ff93888bdac36ee088273c Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:26:00.395134 3092 settings.go:150] Updating kubeconfig: /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig
I1214 19:26:00.395927 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/kubeconfig: {Name:mk52303b645614728a3a01ebf1ea5dc2c2511b10 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:26:00.911323 3092 kapi.go:244] deployment "coredns" in namespace "kube-system" and context "mount-start-2-20211214191843-2012" rescaled to 1
I1214 19:26:00.911351 3092 start.go:207] Will wait 6m0s for node &{Name: IP:192.168.64.9 Port:8443 KubernetesVersion:v1.22.4 ControlPlane:true Worker:true}
I1214 19:26:00.911381 3092 ssh_runner.go:195] Run: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.22.4/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml"
I1214 19:26:00.911397 3092 addons.go:415] enableAddons start: toEnable=map[], additional=[]
I1214 19:26:00.936868 3092 out.go:176] * Verifying Kubernetes components...
I1214 19:26:00.988763 3092 out.go:176] * Creating mount /Users:/minikube-host ...
I1214 19:26:00.911552 3092 config.go:176] Loaded profile config "mount-start-2-20211214191843-2012": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.22.4
I1214 19:26:00.937017 3092 addons.go:65] Setting storage-provisioner=true in profile "mount-start-2-20211214191843-2012"
I1214 19:26:00.937032 3092 addons.go:65] Setting default-storageclass=true in profile "mount-start-2-20211214191843-2012"
I1214 19:26:00.988828 3092 addons.go:153] Setting addon storage-provisioner=true in "mount-start-2-20211214191843-2012"
W1214 19:26:00.988834 3092 addons.go:165] addon storage-provisioner should already be in state true
I1214 19:26:00.988835 3092 addons_storage_classes.go:33] enableOrDisableStorageClasses default-storageclass=true on "mount-start-2-20211214191843-2012"
I1214 19:26:00.937077 3092 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service kubelet
I1214 19:26:00.986593 3092 ssh_runner.go:195] Run: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.22.4/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml | sed '/^ forward . \/etc\/resolv.conf.*/i \ hosts {\n 192.168.64.1 host.minikube.internal\n fallthrough\n }' | sudo /var/lib/minikube/binaries/v1.22.4/kubectl --kubeconfig=/var/lib/minikube/kubeconfig replace -f -"
I1214 19:26:00.988867 3092 host.go:66] Checking if "mount-start-2-20211214191843-2012" exists ...
I1214 19:26:00.991340 3092 lock.go:35] WriteFile acquiring /Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/profiles/mount-start-2-20211214191843-2012/.mount-process: {Name:mkd6dd5e1c7fc7da332aa4a2d10964e8c2c56d38 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I1214 19:26:01.009747 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:26:01.009771 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:26:01.009794 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:26:01.009822 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:26:01.017769 3092 api_server.go:51] waiting for apiserver process to appear ...
I1214 19:26:01.017827 3092 ssh_runner.go:195] Run: sudo pgrep -xnf kube-apiserver.*minikube.*
I1214 19:26:01.020714 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51292
I1214 19:26:01.021334 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:26:01.021665 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51294
I1214 19:26:01.021761 3092 main.go:130] libmachine: Using API Version 1
I1214 19:26:01.021776 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:26:01.022077 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:26:01.022103 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:26:01.022552 3092 main.go:130] libmachine: Using API Version 1
I1214 19:26:01.022559 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:26:01.022749 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:26:01.022779 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:26:01.022847 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:26:01.023789 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetState
I1214 19:26:01.024154 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:26:01.024274 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:26:01.034075 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51296
I1214 19:26:01.034634 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:26:01.035201 3092 addons.go:153] Setting addon default-storageclass=true in "mount-start-2-20211214191843-2012"
W1214 19:26:01.035206 3092 addons.go:165] addon default-storageclass should already be in state true
I1214 19:26:01.035238 3092 host.go:66] Checking if "mount-start-2-20211214191843-2012" exists ...
I1214 19:26:01.035397 3092 main.go:130] libmachine: Using API Version 1
I1214 19:26:01.035423 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:26:01.035725 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:26:01.035749 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:26:01.035757 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:26:01.035973 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetState
I1214 19:26:01.036598 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:26:01.036654 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:26:01.038292 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:26:01.064884 3092 out.go:176] - Using image gcr.io/k8s-minikube/storage-provisioner:v5
I1214 19:26:01.045715 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51298
I1214 19:26:01.065024 3092 addons.go:348] installing /etc/kubernetes/addons/storage-provisioner.yaml
I1214 19:26:01.065029 3092 ssh_runner.go:362] scp memory --> /etc/kubernetes/addons/storage-provisioner.yaml (2676 bytes)
I1214 19:26:01.065041 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:26:01.065224 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:26:01.065330 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:26:01.065422 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:26:01.065431 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:26:01.065521 3092 sshutil.go:53] new ssh client: &{IP:192.168.64.9 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa Username:docker}
I1214 19:26:01.065799 3092 main.go:130] libmachine: Using API Version 1
I1214 19:26:01.065806 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:26:01.066052 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:26:01.066509 3092 main.go:130] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I1214 19:26:01.066523 3092 main.go:130] libmachine: Launching plugin server for driver hyperkit
I1214 19:26:01.073920 3092 main.go:130] libmachine: Plugin server listening at address 127.0.0.1:51302
I1214 19:26:01.074380 3092 main.go:130] libmachine: () Calling .GetVersion
I1214 19:26:01.074890 3092 main.go:130] libmachine: Using API Version 1
I1214 19:26:01.074904 3092 main.go:130] libmachine: () Calling .SetConfigRaw
I1214 19:26:01.075286 3092 main.go:130] libmachine: () Calling .GetMachineName
I1214 19:26:01.075415 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetState
I1214 19:26:01.075543 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I1214 19:26:01.075698 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | hyperkit pid from json: 3117
I1214 19:26:01.077166 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .DriverName
I1214 19:26:01.077380 3092 addons.go:348] installing /etc/kubernetes/addons/storageclass.yaml
I1214 19:26:01.077385 3092 ssh_runner.go:362] scp memory --> /etc/kubernetes/addons/storageclass.yaml (271 bytes)
I1214 19:26:01.077407 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHHostname
I1214 19:26:01.077587 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHPort
I1214 19:26:01.077817 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHKeyPath
I1214 19:26:01.077998 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .GetSSHUsername
I1214 19:26:01.078083 3092 sshutil.go:53] new ssh client: &{IP:192.168.64.9 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/darwin-amd64-hyperkit--13173-832-7fa4ce093861046ed4d109975b74ec5f157758ca/.minikube/machines/mount-start-2-20211214191843-2012/id_rsa Username:docker}
I1214 19:26:01.154040 3092 ssh_runner.go:195] Run: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.22.4/kubectl apply -f /etc/kubernetes/addons/storageclass.yaml
I1214 19:26:01.164368 3092 ssh_runner.go:195] Run: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.22.4/kubectl apply -f /etc/kubernetes/addons/storage-provisioner.yaml
I1214 19:26:01.652400 3092 start.go:774] {"host.minikube.internal": 192.168.64.1} host record injected into CoreDNS
I1214 19:26:01.652459 3092 api_server.go:71] duration metric: took 741.090092ms to wait for apiserver process to appear ...
I1214 19:26:01.652469 3092 api_server.go:87] waiting for apiserver healthz status ...
I1214 19:26:01.652476 3092 api_server.go:240] Checking apiserver healthz at https://192.168.64.9:8443/healthz ...
I1214 19:26:01.657002 3092 api_server.go:266] https://192.168.64.9:8443/healthz returned 200:
ok
I1214 19:26:01.657906 3092 api_server.go:140] control plane version: v1.22.4
I1214 19:26:01.657912 3092 api_server.go:130] duration metric: took 5.440977ms to wait for apiserver health ...
I1214 19:26:01.657919 3092 system_pods.go:43] waiting for kube-system pods to appear ...
I1214 19:26:01.664032 3092 system_pods.go:59] 4 kube-system pods found
I1214 19:26:01.664042 3092 system_pods.go:61] "etcd-mount-start-2-20211214191843-2012" [7f93f4ea-ef32-4b1d-a20f-8a7d61732f59] Pending
I1214 19:26:01.664045 3092 system_pods.go:61] "kube-apiserver-mount-start-2-20211214191843-2012" [9a3f5818-25bd-47fc-9c50-70459038363e] Pending
I1214 19:26:01.664053 3092 system_pods.go:61] "kube-controller-manager-mount-start-2-20211214191843-2012" [bc96af36-7579-4012-88bb-99b36eb1cde9] Pending
I1214 19:26:01.664055 3092 system_pods.go:61] "kube-scheduler-mount-start-2-20211214191843-2012" [9296d345-1811-477a-a648-7c3c251c2d6b] Pending
I1214 19:26:01.664057 3092 system_pods.go:74] duration metric: took 6.135582ms to wait for pod list to return data ...
I1214 19:26:01.664063 3092 kubeadm.go:547] duration metric: took 752.694636ms to wait for : map[apiserver:true system_pods:true] ...
I1214 19:26:01.664069 3092 node_conditions.go:102] verifying NodePressure condition ...
I1214 19:26:01.666359 3092 node_conditions.go:122] node storage ephemeral capacity is 17784752Ki
I1214 19:26:01.666370 3092 node_conditions.go:123] node cpu capacity is 2
I1214 19:26:01.666376 3092 node_conditions.go:105] duration metric: took 2.304532ms to run NodePressure ...
I1214 19:26:01.666384 3092 start.go:212] waiting for startup goroutines ...
I1214 19:26:01.695512 3092 main.go:130] libmachine: Making call to close driver server
I1214 19:26:01.695522 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Close
I1214 19:26:01.695697 3092 main.go:130] libmachine: Successfully made call to close driver server
I1214 19:26:01.695697 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Closing plugin on server side
I1214 19:26:01.695704 3092 main.go:130] libmachine: Making call to close connection to plugin binary
I1214 19:26:01.695710 3092 main.go:130] libmachine: Making call to close driver server
I1214 19:26:01.695713 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Close
I1214 19:26:01.695958 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Closing plugin on server side
I1214 19:26:01.695976 3092 main.go:130] libmachine: Successfully made call to close driver server
I1214 19:26:01.695980 3092 main.go:130] libmachine: Making call to close connection to plugin binary
I1214 19:26:01.695990 3092 main.go:130] libmachine: Making call to close driver server
I1214 19:26:01.695994 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Close
I1214 19:26:01.696197 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) DBG | Closing plugin on server side
I1214 19:26:01.696210 3092 main.go:130] libmachine: Successfully made call to close driver server
I1214 19:26:01.696215 3092 main.go:130] libmachine: Making call to close connection to plugin binary
I1214 19:26:01.765453 3092 main.go:130] libmachine: Making call to close driver server
I1214 19:26:01.765460 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Close
I1214 19:26:01.765760 3092 main.go:130] libmachine: Successfully made call to close driver server
I1214 19:26:01.765785 3092 main.go:130] libmachine: Making call to close connection to plugin binary
I1214 19:26:01.765792 3092 main.go:130] libmachine: Making call to close driver server
I1214 19:26:01.765797 3092 main.go:130] libmachine: (mount-start-2-20211214191843-2012) Calling .Close
I1214 19:26:01.765933 3092 main.go:130] libmachine: Successfully made call to close driver server
I1214 19:26:01.765953 3092 main.go:130] libmachine: Making call to close connection to plugin binary
I1214 19:26:01.796247 3092 out.go:176] * Enabled addons: default-storageclass, storage-provisioner
I1214 19:26:01.796275 3092 addons.go:417] enableAddons completed in 884.883007ms
I1214 19:26:01.854770 3092 start.go:493] kubectl: 1.19.7, cluster: 1.22.4 (minor skew: 3)
I1214 19:26:01.880708 3092 out.go:176]
W1214 19:26:01.880894 3092 out.go:241] ! /usr/local/bin/kubectl is version 1.19.7, which may have incompatibilites with Kubernetes 1.22.4.
I1214 19:26:01.906817 3092 out.go:176] - Want kubectl v1.22.4? Try 'minikube kubectl -- get pods -A'
I1214 19:26:01.932738 3092 out.go:176] * Done! kubectl is now configured to use "mount-start-2-20211214191843-2012" cluster and "default" namespace by default
*
* ==> Docker <==
* -- Journal begins at Wed 2021-12-15 03:25:34 UTC, ends at Wed 2021-12-15 03:26:08 UTC. --
Dec 15 03:25:45 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:45.208520243Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Dec 15 03:25:45 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:45.208535713Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Dec 15 03:25:45 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:45.208542468Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006551888Z" level=warning msg="Your kernel does not support cgroup blkio weight"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006665193Z" level=warning msg="Your kernel does not support cgroup blkio weight_device"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006710985Z" level=warning msg="Your kernel does not support cgroup blkio throttle.read_bps_device"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006755522Z" level=warning msg="Your kernel does not support cgroup blkio throttle.write_bps_device"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006797142Z" level=warning msg="Your kernel does not support cgroup blkio throttle.read_iops_device"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006837518Z" level=warning msg="Your kernel does not support cgroup blkio throttle.write_iops_device"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.006983275Z" level=info msg="Loading containers: start."
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.079269816Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.110994799Z" level=info msg="Loading containers: done."
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.124050388Z" level=info msg="Docker daemon" commit=75249d8 graphdriver(s)=overlay2 version=20.10.8
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.124105742Z" level=info msg="Daemon has completed initialization"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 systemd[1]: Started Docker Application Container Engine.
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.145773507Z" level=info msg="API listen on [::]:2376"
Dec 15 03:25:46 mount-start-2-20211214191843-2012 dockerd[2189]: time="2021-12-15T03:25:46.159774192Z" level=info msg="API listen on /var/run/docker.sock"
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.141734185Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/efcac41a5d0c58613ab7fa040f9eee1bcaa999437488d29b58f1d507e2eddb68 pid=3057
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.147874548Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/0df4ffc7316d541f3700d5ba544b156598eaf10d128e4a9b4c23600fd0a2d81e pid=3088
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.149748538Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/fe73ebd8df75354ddc007c17802153305b9db87c91d95b8dc7b6ad6b4a43dffe pid=3084
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.155357495Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/0fbe8a69a6beddde347ab39f7dd7d1b8105d81cba50dc1b4b6c0b802da6922d6 pid=3100
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.728798171Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/7e4c4fecc324ee44ce354a666eb218c09dab28591f0ef2a22eae20920666bffb pid=3269
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.733011927Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/563d62e5ff4df54b92cdc6bff10f88b7f36d4407b6b70d09289a7c171124079c pid=3270
Dec 15 03:25:53 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:53.852258893Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/9a846ed6180a5ff6dab88e26a9cff16503b68315297dedcc1483dbb712fd89e0 pid=3332
Dec 15 03:25:54 mount-start-2-20211214191843-2012 dockerd[2195]: time="2021-12-15T03:25:54.014950655Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/963d3b0e0883ac78771e5dcc00f514938798c4e4aabeba8483c89f3ef8a91f27 pid=3365
*
* ==> container status <==
* CONTAINER IMAGE CREATED STATE NAME ATTEMPT POD ID
963d3b0e0883a 721ba97f54a65 15 seconds ago Running kube-scheduler 0 fe73ebd8df753
9a846ed6180a5 0ce02f92d3e43 15 seconds ago Running kube-controller-manager 0 0df4ffc7316d5
563d62e5ff4df 0048118155842 15 seconds ago Running etcd 0 efcac41a5d0c5
7e4c4fecc324e 8a5cc299272d9 15 seconds ago Running kube-apiserver 0 0fbe8a69a6bed
*
* ==> describe nodes <==
* Name: mount-start-2-20211214191843-2012
Roles: control-plane,master
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=mount-start-2-20211214191843-2012
kubernetes.io/os=linux
minikube.k8s.io/commit=1bfd93799ca1a0aa711376fa94919427c19ad092
minikube.k8s.io/name=mount-start-2-20211214191843-2012
minikube.k8s.io/updated_at=2021_12_14T19_26_00_0700
minikube.k8s.io/version=v1.24.0
node-role.kubernetes.io/control-plane=
node-role.kubernetes.io/master=
node.kubernetes.io/exclude-from-external-load-balancers=
Annotations: kubeadm.alpha.kubernetes.io/cri-socket: /var/run/dockershim.sock
volumes.kubernetes.io/controller-managed-attach-detach: true
CreationTimestamp: Wed, 15 Dec 2021 03:25:57 +0000
Taints: node.kubernetes.io/not-ready:NoSchedule
Unschedulable: false
Lease:
HolderIdentity: mount-start-2-20211214191843-2012
AcquireTime: <unset>
RenewTime: Wed, 15 Dec 2021 03:26:00 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Wed, 15 Dec 2021 03:26:00 +0000 Wed, 15 Dec 2021 03:25:55 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Wed, 15 Dec 2021 03:26:00 +0000 Wed, 15 Dec 2021 03:25:55 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Wed, 15 Dec 2021 03:26:00 +0000 Wed, 15 Dec 2021 03:25:55 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready False Wed, 15 Dec 2021 03:26:00 +0000 Wed, 15 Dec 2021 03:25:55 +0000 KubeletNotReady container runtime status check may not have completed yet
Addresses:
InternalIP: 192.168.64.9
Hostname: mount-start-2-20211214191843-2012
Capacity:
cpu: 2
ephemeral-storage: 17784752Ki
hugepages-2Mi: 0
memory: 2034912Ki
pods: 110
Allocatable:
cpu: 2
ephemeral-storage: 17784752Ki
hugepages-2Mi: 0
memory: 2034912Ki
pods: 110
System Info:
Machine ID: 18a5ccf27b61490bac3f850955da7a6a
System UUID: a5bd11ec-0000-0000-8df5-149d997cd0f1
Boot ID: afd9cd06-8c64-448c-a638-da471e2e4b52
Kernel Version: 4.19.202
OS Image: Buildroot 2021.02.4
Operating System: linux
Architecture: amd64
Container Runtime Version: docker://20.10.8
Kubelet Version: v1.22.4
Kube-Proxy Version: v1.22.4
Non-terminated Pods: (4 in total)
Namespace Name CPU Requests CPU Limits Memory Requests Memory Limits Age
--------- ---- ------------ ---------- --------------- ------------- ---
kube-system etcd-mount-start-2-20211214191843-2012 100m (5%!)(MISSING) 0 (0%!)(MISSING) 100Mi (5%!)(MISSING) 0 (0%!)(MISSING) 8s
kube-system kube-apiserver-mount-start-2-20211214191843-2012 250m (12%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 10s
kube-system kube-controller-manager-mount-start-2-20211214191843-2012 200m (10%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 8s
kube-system kube-scheduler-mount-start-2-20211214191843-2012 100m (5%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 8s
Allocated resources:
(Total limits may be over 100 percent, i.e., overcommitted.)
Resource Requests Limits
-------- -------- ------
cpu 650m (32%!)(MISSING) 0 (0%!)(MISSING)
memory 100Mi (5%!)(MISSING) 0 (0%!)(MISSING)
ephemeral-storage 0 (0%!)(MISSING) 0 (0%!)(MISSING)
hugepages-2Mi 0 (0%!)(MISSING) 0 (0%!)(MISSING)
Events:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal NodeHasSufficientMemory 16s (x5 over 16s) kubelet Node mount-start-2-20211214191843-2012 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 16s (x5 over 16s) kubelet Node mount-start-2-20211214191843-2012 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 16s (x3 over 16s) kubelet Node mount-start-2-20211214191843-2012 status is now: NodeHasSufficientPID
Normal Starting 8s kubelet Starting kubelet.
Normal NodeHasSufficientMemory 8s kubelet Node mount-start-2-20211214191843-2012 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 8s kubelet Node mount-start-2-20211214191843-2012 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 8s kubelet Node mount-start-2-20211214191843-2012 status is now: NodeHasSufficientPID
Normal NodeAllocatableEnforced 8s kubelet Updated Node Allocatable limit across pods
*
* ==> dmesg <==
* [Dec15 03:25] ERROR: earlyprintk= earlyser already used
[ +0.000000] You have booted with nomodeset. This means your GPU drivers are DISABLED
[ +0.000000] Any video related functionality will be severely degraded, and you may not even be able to suspend the system properly
[ +0.000000] Unless you actually understand what nomodeset does, you should reboot without enabling it
[ +0.018723] ACPI BIOS Warning (bug): Incorrect checksum in table [DSDT] - 0xBE, should be 0x1B (20180810/tbprint-173)
[ +4.011286] ACPI Error: Could not enable RealTimeClock event (20180810/evxfevnt-182)
[ +0.000002] ACPI Warning: Could not enable fixed event - RealTimeClock (4) (20180810/evxface-618)
[ +0.006175] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ +1.909099] systemd-fstab-generator[1115]: Ignoring "noauto" for root device
[ +0.023581] systemd[1]: system-getty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[ +0.000002] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[ +0.598884] SELinux: unrecognized netlink message: protocol=0 nlmsg_type=106 sclass=netlink_route_socket pid=1640 comm=systemd-network
[ +0.315334] NFSD: the nfsdcld client tracking upcall will be removed in 3.10. Please transition to using nfsdcltrack.
[ +0.421652] vboxguest: loading out-of-tree module taints kernel.
[ +0.002604] vboxguest: PCI device not found, probably running on physical hardware.
[ +1.122151] systemd-fstab-generator[1991]: Ignoring "noauto" for root device
[ +0.102131] systemd-fstab-generator[2002]: Ignoring "noauto" for root device
[ +7.327805] systemd-fstab-generator[2179]: Ignoring "noauto" for root device
[ +1.934527] kauditd_printk_skb: 68 callbacks suppressed
[ +0.237473] systemd-fstab-generator[2344]: Ignoring "noauto" for root device
[ +0.086154] systemd-fstab-generator[2355]: Ignoring "noauto" for root device
[ +0.086589] systemd-fstab-generator[2366]: Ignoring "noauto" for root device
[ +4.739611] systemd-fstab-generator[2604]: Ignoring "noauto" for root device
[ +1.229053] kauditd_printk_skb: 107 callbacks suppressed
[ +7.497352] systemd-fstab-generator[3725]: Ignoring "noauto" for root device
*
* ==> etcd [563d62e5ff4d] <==
* {"level":"info","ts":"2021-12-15T03:25:54.536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b switched to configuration voters=(850216049952756587)"}
{"level":"info","ts":"2021-12-15T03:25:54.537Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"5ab2023f7fe478e6","local-member-id":"bcc92fe27c5136b","added-peer-id":"bcc92fe27c5136b","added-peer-peer-urls":["https://192.168.64.9:2380"]}
{"level":"info","ts":"2021-12-15T03:25:54.580Z","caller":"embed/etcd.go:687","msg":"starting with client TLS","tls-info":"cert = /var/lib/minikube/certs/etcd/server.crt, key = /var/lib/minikube/certs/etcd/server.key, client-cert=, client-key=, trusted-ca = /var/lib/minikube/certs/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2021-12-15T03:25:54.583Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"192.168.64.9:2380"}
{"level":"info","ts":"2021-12-15T03:25:54.583Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"192.168.64.9:2380"}
{"level":"info","ts":"2021-12-15T03:25:54.583Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"bcc92fe27c5136b","initial-advertise-peer-urls":["https://192.168.64.9:2380"],"listen-peer-urls":["https://192.168.64.9:2380"],"advertise-client-urls":["https://192.168.64.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://192.168.64.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2021-12-15T03:25:54.583Z","caller":"embed/etcd.go:762","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b is starting a new election at term 1"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b became pre-candidate at term 1"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b received MsgPreVoteResp from bcc92fe27c5136b at term 1"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b became candidate at term 2"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b received MsgVoteResp from bcc92fe27c5136b at term 2"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcc92fe27c5136b became leader at term 2"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bcc92fe27c5136b elected leader bcc92fe27c5136b at term 2"}
{"level":"info","ts":"2021-12-15T03:25:54.996Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2021-12-15T03:25:55.003Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"5ab2023f7fe478e6","local-member-id":"bcc92fe27c5136b","cluster-version":"3.5"}
{"level":"info","ts":"2021-12-15T03:25:55.003Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2021-12-15T03:25:55.004Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2021-12-15T03:25:55.004Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"bcc92fe27c5136b","local-member-attributes":"{Name:mount-start-2-20211214191843-2012 ClientURLs:[https://192.168.64.9:2379]}","request-path":"/0/members/bcc92fe27c5136b/attributes","cluster-id":"5ab2023f7fe478e6","publish-timeout":"7s"}
{"level":"info","ts":"2021-12-15T03:25:55.004Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"}
{"level":"info","ts":"2021-12-15T03:25:55.004Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"}
{"level":"info","ts":"2021-12-15T03:25:55.005Z","caller":"embed/serve.go:188","msg":"serving client traffic securely","address":"127.0.0.1:2379"}
{"level":"info","ts":"2021-12-15T03:25:55.004Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"}
{"level":"info","ts":"2021-12-15T03:25:55.006Z","caller":"embed/serve.go:188","msg":"serving client traffic securely","address":"192.168.64.9:2379"}
{"level":"info","ts":"2021-12-15T03:25:55.046Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"}
*
* ==> kernel <==
* 03:26:09 up 0 min, 0 users, load average: 1.28, 0.32, 0.11
Linux mount-start-2-20211214191843-2012 4.19.202 #1 SMP Tue Dec 7 01:00:44 UTC 2021 x86_64 GNU/Linux
PRETTY_NAME="Buildroot 2021.02.4"
*
* ==> kube-apiserver [7e4c4fecc324] <==
* I1215 03:25:57.014803 1 controller.go:83] Starting OpenAPI AggregationController
I1215 03:25:57.014817 1 available_controller.go:491] Starting AvailableConditionController
I1215 03:25:57.014820 1 cache.go:32] Waiting for caches to sync for AvailableConditionController controller
I1215 03:25:57.077268 1 controller.go:611] quota admission added evaluator for: namespaces
I1215 03:25:57.098207 1 shared_informer.go:247] Caches are synced for node_authorizer
I1215 03:25:57.108450 1 shared_informer.go:247] Caches are synced for cluster_authentication_trust_controller
I1215 03:25:57.108799 1 cache.go:39] Caches are synced for autoregister controller
I1215 03:25:57.108862 1 apf_controller.go:317] Running API Priority and Fairness config worker
I1215 03:25:57.109992 1 shared_informer.go:247] Caches are synced for crd-autoregister
I1215 03:25:57.118113 1 cache.go:39] Caches are synced for AvailableConditionController controller
I1215 03:25:57.119653 1 cache.go:39] Caches are synced for APIServiceRegistrationController controller
I1215 03:25:58.006216 1 controller.go:132] OpenAPI AggregationController: action for item : Nothing (removed from the queue).
I1215 03:25:58.006442 1 controller.go:132] OpenAPI AggregationController: action for item k8s_internal_local_delegation_chain_0000000000: Nothing (removed from the queue).
I1215 03:25:58.012162 1 storage_scheduling.go:132] created PriorityClass system-node-critical with value 2000001000
I1215 03:25:58.014950 1 storage_scheduling.go:132] created PriorityClass system-cluster-critical with value 2000000000
I1215 03:25:58.014975 1 storage_scheduling.go:148] all system priority classes are created successfully or already exist.
I1215 03:25:58.326542 1 controller.go:611] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I1215 03:25:58.354080 1 controller.go:611] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
W1215 03:25:58.487029 1 lease.go:233] Resetting endpoints for master service "kubernetes" to [192.168.64.9]
I1215 03:25:58.487808 1 controller.go:611] quota admission added evaluator for: endpoints
I1215 03:25:58.490130 1 controller.go:611] quota admission added evaluator for: endpointslices.discovery.k8s.io
I1215 03:25:59.083515 1 controller.go:611] quota admission added evaluator for: serviceaccounts
I1215 03:26:00.111114 1 controller.go:611] quota admission added evaluator for: deployments.apps
I1215 03:26:00.134140 1 controller.go:611] quota admission added evaluator for: daemonsets.apps
I1215 03:26:00.346955 1 controller.go:611] quota admission added evaluator for: leases.coordination.k8s.io
*
* ==> kube-controller-manager [9a846ed6180a] <==
* I1215 03:26:09.152567 1 ttlafterfinished_controller.go:109] Starting TTL after finished controller
I1215 03:26:09.152573 1 shared_informer.go:240] Waiting for caches to sync for TTL after finished
I1215 03:26:09.203443 1 controllermanager.go:577] Started "persistentvolume-binder"
I1215 03:26:09.203709 1 pv_controller_base.go:308] Starting persistent volume controller
I1215 03:26:09.203736 1 shared_informer.go:240] Waiting for caches to sync for persistent volume
I1215 03:26:09.233417 1 controllermanager.go:577] Started "clusterrole-aggregation"
I1215 03:26:09.233570 1 clusterroleaggregation_controller.go:194] Starting ClusterRoleAggregator
I1215 03:26:09.233700 1 shared_informer.go:240] Waiting for caches to sync for ClusterRoleAggregator
I1215 03:26:09.284361 1 controllermanager.go:577] Started "pv-protection"
I1215 03:26:09.284475 1 pv_protection_controller.go:83] Starting PV protection controller
I1215 03:26:09.284576 1 shared_informer.go:240] Waiting for caches to sync for PV protection
I1215 03:26:09.328348 1 controllermanager.go:577] Started "serviceaccount"
I1215 03:26:09.328664 1 serviceaccounts_controller.go:117] Starting service account controller
I1215 03:26:09.328701 1 shared_informer.go:240] Waiting for caches to sync for service account
I1215 03:26:09.380794 1 controllermanager.go:577] Started "horizontalpodautoscaling"
I1215 03:26:09.381019 1 horizontal.go:169] Starting HPA controller
I1215 03:26:09.381088 1 shared_informer.go:240] Waiting for caches to sync for HPA
I1215 03:26:09.421679 1 controllermanager.go:577] Started "disruption"
I1215 03:26:09.423618 1 disruption.go:363] Starting disruption controller
I1215 03:26:09.424413 1 shared_informer.go:240] Waiting for caches to sync for disruption
I1215 03:26:09.435339 1 controllermanager.go:577] Started "cronjob"
I1215 03:26:09.435490 1 cronjob_controllerv2.go:125] "Starting cronjob controller v2"
I1215 03:26:09.435515 1 shared_informer.go:240] Waiting for caches to sync for cronjob
I1215 03:26:09.450589 1 controllermanager.go:577] Started "bootstrapsigner"
I1215 03:26:09.450606 1 shared_informer.go:240] Waiting for caches to sync for bootstrap_signer
*
* ==> kube-scheduler [963d3b0e0883] <==
* E1215 03:25:57.089945 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.StorageClass: failed to list *v1.StorageClass: storageclasses.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "storageclasses" in API group "storage.k8s.io" at the cluster scope
E1215 03:25:57.090080 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
E1215 03:25:57.090193 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
E1215 03:25:57.090391 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E1215 03:25:57.090614 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.ReplicationController: failed to list *v1.ReplicationController: replicationcontrollers is forbidden: User "system:kube-scheduler" cannot list resource "replicationcontrollers" in API group "" at the cluster scope
E1215 03:25:57.090986 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolume: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
E1215 03:25:57.091084 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolumeClaim: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
E1215 03:25:57.091095 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PodDisruptionBudget: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E1215 03:25:57.091139 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Namespace: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
E1215 03:25:57.092258 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
E1215 03:25:57.958195 1 reflector.go:138] k8s.io/apiserver/pkg/server/dynamiccertificates/configmap_cafile_content.go:205: Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "extension-apiserver-authentication" is forbidden: User "system:kube-scheduler" cannot list resource "configmaps" in API group "" in the namespace "kube-system"
E1215 03:25:58.000394 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.ReplicaSet: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User "system:kube-scheduler" cannot list resource "replicasets" in API group "apps" at the cluster scope
E1215 03:25:58.052923 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E1215 03:25:58.098089 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Namespace: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
E1215 03:25:58.099080 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
E1215 03:25:58.104837 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
E1215 03:25:58.120879 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PodDisruptionBudget: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E1215 03:25:58.152228 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolume: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
E1215 03:25:58.181082 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
E1215 03:25:58.209281 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolumeClaim: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
E1215 03:25:58.448834 1 plugin.go:138] "getting namespace, assuming empty set of namespace labels" err="namespace \"kube-system\" not found" namespace="kube-system"
E1215 03:26:00.711131 1 plugin.go:138] "getting namespace, assuming empty set of namespace labels" err="namespace \"kube-system\" not found" namespace="kube-system"
E1215 03:26:00.711373 1 plugin.go:138] "getting namespace, assuming empty set of namespace labels" err="namespace \"kube-system\" not found" namespace="kube-system"
I1215 03:26:00.784490 1 shared_informer.go:247] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
E1215 03:26:00.890859 1 plugin.go:138] "getting namespace, assuming empty set of namespace labels" err="namespace \"kube-system\" not found" namespace="kube-system"
*
* ==> kubelet <==
* -- Journal begins at Wed 2021-12-15 03:25:34 UTC, ends at Wed 2021-12-15 03:26:09 UTC. --
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.579488 3732 manager.go:607] "Failed to retrieve checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.580310 3732 plugin_manager.go:114] "Starting Kubelet Plugin Manager"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.702583 3732 topology_manager.go:200] "Topology Admit Handler"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.702929 3732 topology_manager.go:200] "Topology Admit Handler"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.703562 3732 topology_manager.go:200] "Topology Admit Handler"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.703668 3732 topology_manager.go:200] "Topology Admit Handler"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: E1215 03:26:00.710091 3732 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-apiserver-mount-start-2-20211214191843-2012\" already exists" pod="kube-system/kube-apiserver-mount-start-2-20211214191843-2012"
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.749809 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/d410b3f47891560941d6fea44d241d82-kubeconfig\") pod \"kube-scheduler-mount-start-2-20211214191843-2012\" (UID: \"d410b3f47891560941d6fea44d241d82\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.750037 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/069956d1dd57aa9bbc67e34e0f60f050-ca-certs\") pod \"kube-apiserver-mount-start-2-20211214191843-2012\" (UID: \"069956d1dd57aa9bbc67e34e0f60f050\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.750289 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/9098695d5dac45d6e92a23d9edd36c00-ca-certs\") pod \"kube-controller-manager-mount-start-2-20211214191843-2012\" (UID: \"9098695d5dac45d6e92a23d9edd36c00\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.750533 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/9098695d5dac45d6e92a23d9edd36c00-k8s-certs\") pod \"kube-controller-manager-mount-start-2-20211214191843-2012\" (UID: \"9098695d5dac45d6e92a23d9edd36c00\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.750885 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/9098695d5dac45d6e92a23d9edd36c00-kubeconfig\") pod \"kube-controller-manager-mount-start-2-20211214191843-2012\" (UID: \"9098695d5dac45d6e92a23d9edd36c00\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.751222 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/9098695d5dac45d6e92a23d9edd36c00-usr-share-ca-certificates\") pod \"kube-controller-manager-mount-start-2-20211214191843-2012\" (UID: \"9098695d5dac45d6e92a23d9edd36c00\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.751486 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"etcd-certs\" (UniqueName: \"kubernetes.io/host-path/a5ea629d49bbec5c65ec36e92e50eeb5-etcd-certs\") pod \"etcd-mount-start-2-20211214191843-2012\" (UID: \"a5ea629d49bbec5c65ec36e92e50eeb5\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.751655 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"etcd-data\" (UniqueName: \"kubernetes.io/host-path/a5ea629d49bbec5c65ec36e92e50eeb5-etcd-data\") pod \"etcd-mount-start-2-20211214191843-2012\" (UID: \"a5ea629d49bbec5c65ec36e92e50eeb5\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.751823 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/069956d1dd57aa9bbc67e34e0f60f050-k8s-certs\") pod \"kube-apiserver-mount-start-2-20211214191843-2012\" (UID: \"069956d1dd57aa9bbc67e34e0f60f050\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.751979 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/069956d1dd57aa9bbc67e34e0f60f050-usr-share-ca-certificates\") pod \"kube-apiserver-mount-start-2-20211214191843-2012\" (UID: \"069956d1dd57aa9bbc67e34e0f60f050\") "
Dec 15 03:26:00 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:00.752163 3732 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flexvolume-dir\" (UniqueName: \"kubernetes.io/host-path/9098695d5dac45d6e92a23d9edd36c00-flexvolume-dir\") pod \"kube-controller-manager-mount-start-2-20211214191843-2012\" (UID: \"9098695d5dac45d6e92a23d9edd36c00\") "
Dec 15 03:26:01 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:01.295152 3732 apiserver.go:52] "Watching apiserver"
Dec 15 03:26:01 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:01.760411 3732 reconciler.go:157] "Reconciler: start to sync state"
Dec 15 03:26:01 mount-start-2-20211214191843-2012 kubelet[3732]: E1215 03:26:01.908298 3732 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-apiserver-mount-start-2-20211214191843-2012\" already exists" pod="kube-system/kube-apiserver-mount-start-2-20211214191843-2012"
Dec 15 03:26:02 mount-start-2-20211214191843-2012 kubelet[3732]: E1215 03:26:02.092371 3732 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-scheduler-mount-start-2-20211214191843-2012\" already exists" pod="kube-system/kube-scheduler-mount-start-2-20211214191843-2012"
Dec 15 03:26:02 mount-start-2-20211214191843-2012 kubelet[3732]: E1215 03:26:02.294721 3732 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-controller-manager-mount-start-2-20211214191843-2012\" already exists" pod="kube-system/kube-controller-manager-mount-start-2-20211214191843-2012"
Dec 15 03:26:02 mount-start-2-20211214191843-2012 kubelet[3732]: I1215 03:26:02.487510 3732 request.go:665] Waited for 1.026223174s due to client-side throttling, not priority and fairness, request: POST:https://control-plane.minikube.internal:8443/api/v1/namespaces/kube-system/pods
Dec 15 03:26:02 mount-start-2-20211214191843-2012 kubelet[3732]: E1215 03:26:02.491297 3732 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"etcd-mount-start-2-20211214191843-2012\" already exists" pod="kube-system/etcd-mount-start-2-20211214191843-2012"
-- /stdout --
helpers_test.go:255: (dbg) Run: out/minikube-darwin-amd64 status --format={{.APIServer}} -p mount-start-2-20211214191843-2012 -n mount-start-2-20211214191843-2012
helpers_test.go:262: (dbg) Run: kubectl --context mount-start-2-20211214191843-2012 get po -o=jsonpath={.items[*].metadata.name} -A --field-selector=status.phase!=Running
helpers_test.go:262: (dbg) Done: kubectl --context mount-start-2-20211214191843-2012 get po -o=jsonpath={.items[*].metadata.name} -A --field-selector=status.phase!=Running: (1.791291565s)
helpers_test.go:271: non-running pods: storage-provisioner
helpers_test.go:273: ======> post-mortem[TestMountStart/serial/VerifyMountPostDelete]: describe non-running pods <======
helpers_test.go:276: (dbg) Run: kubectl --context mount-start-2-20211214191843-2012 describe pod storage-provisioner
helpers_test.go:276: (dbg) Non-zero exit: kubectl --context mount-start-2-20211214191843-2012 describe pod storage-provisioner: exit status 1 (54.986181ms)
** stderr **
Error from server (NotFound): pods "storage-provisioner" not found
** /stderr **
helpers_test.go:278: kubectl --context mount-start-2-20211214191843-2012 describe pod storage-provisioner: exit status 1
--- FAIL: TestMountStart/serial/VerifyMountPostDelete (3.97s)