=== RUN TestFunctional/serial/SoftStart
functional_test.go:600: (dbg) Run: out/minikube-darwin-amd64 start -p functional-20211117115319-2067 --alsologtostderr -v=8
functional_test.go:600: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p functional-20211117115319-2067 --alsologtostderr -v=8: exit status 80 (1m8.547410219s)
-- stdout --
* [functional-20211117115319-2067] minikube v1.24.0 on Darwin 11.1
- MINIKUBE_LOCATION=12739
- KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube
* Using the docker driver based on existing profile
* Starting control plane node functional-20211117115319-2067 in cluster functional-20211117115319-2067
* Pulling base image ...
* docker "functional-20211117115319-2067" container is missing, will recreate.
* Creating docker container (CPUs=2, Memory=4000MB) ...
* docker "functional-20211117115319-2067" container is missing, will recreate.
* Creating docker container (CPUs=2, Memory=4000MB) ...
-- /stdout --
** stderr **
I1117 11:54:05.564855 3296 out.go:297] Setting OutFile to fd 1 ...
I1117 11:54:05.565007 3296 out.go:344] TERM=,COLORTERM=, which probably does not support color
I1117 11:54:05.565012 3296 out.go:310] Setting ErrFile to fd 2...
I1117 11:54:05.565015 3296 out.go:344] TERM=,COLORTERM=, which probably does not support color
I1117 11:54:05.565108 3296 root.go:315] Updating PATH: /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/bin
I1117 11:54:05.565382 3296 out.go:304] Setting JSON to false
I1117 11:54:05.590297 3296 start.go:112] hostinfo: {"hostname":"administrators-Mac-mini.local","uptime":1420,"bootTime":1637177425,"procs":316,"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"}
W1117 11:54:05.590482 3296 start.go:120] gopshost.Virtualization returned error: not implemented yet
I1117 11:54:05.626714 3296 out.go:176] * [functional-20211117115319-2067] minikube v1.24.0 on Darwin 11.1
I1117 11:54:05.626908 3296 notify.go:174] Checking for updates...
I1117 11:54:05.704287 3296 out.go:176] - MINIKUBE_LOCATION=12739
I1117 11:54:05.730512 3296 out.go:176] - KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/kubeconfig
I1117 11:54:05.756409 3296 out.go:176] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1117 11:54:05.782252 3296 out.go:176] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube
I1117 11:54:05.782594 3296 config.go:176] Loaded profile config "functional-20211117115319-2067": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.22.3
I1117 11:54:05.782631 3296 driver.go:343] Setting default libvirt URI to qemu:///system
I1117 11:54:05.871099 3296 docker.go:132] docker version: linux-20.10.5
I1117 11:54:05.871222 3296 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I1117 11:54:06.019577 3296 info.go:263] docker info: {ID:O4L5:FEGT:JIID:EORR:XXSY:TL4H:Z4QO:B57Z:YUBU:SYLY:CFE3:7ISX Containers:0 ContainersRunning:0 ContainersPaused:0 ContainersStopped:0 Images:21 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true]] SystemStatus:<nil> Plugins:{Volume:[local] Network:[bridge host ipvlan macvlan null overlay] Authorization:<nil> Log:[awslogs fluentd gcplogs gelf journald json-file local logentries splunk syslog]} MemoryLimit:true SwapLimit:true KernelMemory:true KernelMemoryTCP:true CPUCfsPeriod:true CPUCfsQuota:true CPUShares:true CPUSet:true PidsLimit:true IPv4Forwarding:true BridgeNfIptables:true BridgeNfIP6Tables:true Debug:false NFd:44 OomKillDisable:true NGoroutines:46 SystemTime:2021-11-17 19:54:05.978272033 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:3 KernelVersion:5.10.25-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServerAddress:https://in
dex.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:6 MemTotal:6234726400 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy:http.docker.internal:3128 HTTPSProxy:http.docker.internal:3128 NoProxy: Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.5 ClusterStore: ClusterAdvertise: Runtimes:{Runc:{Path:runc}} DefaultRuntime:runc Swarm:{NodeID: NodeAddr: LocalNodeState:inactive ControlAvailable:false Error: RemoteManagers:<nil>} LiveRestoreEnabled:false Isolation: InitBinary:docker-init ContainerdCommit:{ID:05f951a3781f4f2c1911b05e61c160e9c30eaa8e Expected:05f951a3781f4f2c1911b05e61c160e9c30eaa8e} RuncCommit:{ID:12644e614e25b05da6fd08a38ffa0cfe1903fdec Expected:12644e614e25b05da6fd08a38ffa0cfe1903fdec} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,profile=defau
lt] ProductLicense: Warnings:<nil> ServerErrors:[] ClientInfo:{Debug:false Plugins:[map[Experimental:true Name:app Path:/usr/local/lib/docker/cli-plugins/docker-app SchemaVersion:0.1.0 ShortDescription:Docker App Vendor:Docker Inc. Version:v0.9.1-beta3] map[Name:buildx Path:/usr/local/lib/docker/cli-plugins/docker-buildx SchemaVersion:0.1.0 ShortDescription:Build with BuildKit Vendor:Docker Inc. Version:v0.5.1-docker] map[Name:scan Path:/usr/local/lib/docker/cli-plugins/docker-scan SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.6.0]] Warnings:<nil>}}
I1117 11:54:06.046331 3296 out.go:176] * Using the docker driver based on existing profile
I1117 11:54:06.046357 3296 start.go:280] selected driver: docker
I1117 11:54:06.046363 3296 start.go:775] validating driver "docker" against &{Name:functional-20211117115319-2067 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c Memory:4000 CPUs:2 DiskSize:20000 VMDriver: Driver:docker 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.3 ClusterName:functional-20211117115319-2067 Namespace:default APIServerName:minikubeCA APIServer
Names:[] 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:8441 NodeName:} Nodes:[{Name: IP: Port:8441 KubernetesVersion:v1.22.3 ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host}
I1117 11:54:06.046426 3296 start.go:786] status for docker: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc:}
I1117 11:54:06.046635 3296 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I1117 11:54:06.194368 3296 info.go:263] docker info: {ID:O4L5:FEGT:JIID:EORR:XXSY:TL4H:Z4QO:B57Z:YUBU:SYLY:CFE3:7ISX Containers:0 ContainersRunning:0 ContainersPaused:0 ContainersStopped:0 Images:21 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true]] SystemStatus:<nil> Plugins:{Volume:[local] Network:[bridge host ipvlan macvlan null overlay] Authorization:<nil> Log:[awslogs fluentd gcplogs gelf journald json-file local logentries splunk syslog]} MemoryLimit:true SwapLimit:true KernelMemory:true KernelMemoryTCP:true CPUCfsPeriod:true CPUCfsQuota:true CPUShares:true CPUSet:true PidsLimit:true IPv4Forwarding:true BridgeNfIptables:true BridgeNfIP6Tables:true Debug:false NFd:44 OomKillDisable:true NGoroutines:46 SystemTime:2021-11-17 19:54:06.152973452 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:3 KernelVersion:5.10.25-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServerAddress:https://in
dex.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:6 MemTotal:6234726400 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy:http.docker.internal:3128 HTTPSProxy:http.docker.internal:3128 NoProxy: Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.5 ClusterStore: ClusterAdvertise: Runtimes:{Runc:{Path:runc}} DefaultRuntime:runc Swarm:{NodeID: NodeAddr: LocalNodeState:inactive ControlAvailable:false Error: RemoteManagers:<nil>} LiveRestoreEnabled:false Isolation: InitBinary:docker-init ContainerdCommit:{ID:05f951a3781f4f2c1911b05e61c160e9c30eaa8e Expected:05f951a3781f4f2c1911b05e61c160e9c30eaa8e} RuncCommit:{ID:12644e614e25b05da6fd08a38ffa0cfe1903fdec Expected:12644e614e25b05da6fd08a38ffa0cfe1903fdec} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,profile=defau
lt] ProductLicense: Warnings:<nil> ServerErrors:[] ClientInfo:{Debug:false Plugins:[map[Experimental:true Name:app Path:/usr/local/lib/docker/cli-plugins/docker-app SchemaVersion:0.1.0 ShortDescription:Docker App Vendor:Docker Inc. Version:v0.9.1-beta3] map[Name:buildx Path:/usr/local/lib/docker/cli-plugins/docker-buildx SchemaVersion:0.1.0 ShortDescription:Build with BuildKit Vendor:Docker Inc. Version:v0.5.1-docker] map[Name:scan Path:/usr/local/lib/docker/cli-plugins/docker-scan SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.6.0]] Warnings:<nil>}}
I1117 11:54:06.196352 3296 cni.go:93] Creating CNI manager for ""
I1117 11:54:06.196372 3296 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I1117 11:54:06.196383 3296 start_flags.go:282] config:
{Name:functional-20211117115319-2067 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c Memory:4000 CPUs:2 DiskSize:20000 VMDriver: Driver:docker 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.3 ClusterName:functional-20211117115319-2067 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISock
et: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8441 NodeName:} Nodes:[{Name: IP: Port:8441 KubernetesVersion:v1.22.3 ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host}
I1117 11:54:06.223316 3296 out.go:176] * Starting control plane node functional-20211117115319-2067 in cluster functional-20211117115319-2067
I1117 11:54:06.223342 3296 cache.go:118] Beginning downloading kic base image for docker with docker
I1117 11:54:06.270083 3296 out.go:176] * Pulling base image ...
I1117 11:54:06.270165 3296 preload.go:132] Checking if preload exists for k8s version v1.22.3 and runtime docker
I1117 11:54:06.270239 3296 image.go:75] Checking for gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c in local docker daemon
I1117 11:54:06.270244 3296 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4
I1117 11:54:06.270270 3296 cache.go:57] Caching tarball of preloaded images
I1117 11:54:06.271095 3296 preload.go:174] Found /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1117 11:54:06.271368 3296 cache.go:60] Finished verifying existence of preloaded tar for v1.22.3 on docker
I1117 11:54:06.271777 3296 profile.go:147] Saving config to /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/profiles/functional-20211117115319-2067/config.json ...
I1117 11:54:06.385691 3296 image.go:79] Found gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c in local docker daemon, skipping pull
I1117 11:54:06.385703 3296 cache.go:140] gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c exists in daemon, skipping load
I1117 11:54:06.385716 3296 cache.go:206] Successfully downloaded all kic artifacts
I1117 11:54:06.385778 3296 start.go:313] acquiring machines lock for functional-20211117115319-2067: {Name:mk4569454e13da3fe88fd1d74c9c9e521ae0a801 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1117 11:54:06.385870 3296 start.go:317] acquired machines lock for "functional-20211117115319-2067" in 71.87µs
I1117 11:54:06.385892 3296 start.go:93] Skipping create...Using existing machine configuration
I1117 11:54:06.385903 3296 fix.go:55] fixHost starting:
I1117 11:54:06.386165 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:06.484727 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:06.484781 3296 fix.go:108] recreateIfNeeded on functional-20211117115319-2067: state= err=unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:06.484804 3296 fix.go:113] machineExists: false. err=machine does not exist
I1117 11:54:06.511656 3296 out.go:176] * docker "functional-20211117115319-2067" container is missing, will recreate.
I1117 11:54:06.511699 3296 delete.go:124] DEMOLISHING functional-20211117115319-2067 ...
I1117 11:54:06.512005 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:06.611410 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
W1117 11:54:06.611455 3296 stop.go:75] unable to get state: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:06.611469 3296 delete.go:129] stophost failed (probably ok): ssh power off: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:06.611861 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:06.710406 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:06.710458 3296 delete.go:82] Unable to get host status for functional-20211117115319-2067, assuming it has already been deleted: state: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:06.710566 3296 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117115319-2067
W1117 11:54:06.808356 3296 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117115319-2067 returned with exit code 1
I1117 11:54:06.808382 3296 kic.go:360] could not find the container functional-20211117115319-2067 to remove it. will try anyways
I1117 11:54:06.808461 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:06.906653 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
W1117 11:54:06.906701 3296 oci.go:83] error getting container status, will try to delete anyways: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:06.906791 3296 cli_runner.go:115] Run: docker exec --privileged -t functional-20211117115319-2067 /bin/bash -c "sudo init 0"
W1117 11:54:07.003495 3296 cli_runner.go:162] docker exec --privileged -t functional-20211117115319-2067 /bin/bash -c "sudo init 0" returned with exit code 1
I1117 11:54:07.003521 3296 oci.go:656] error shutdown functional-20211117115319-2067: docker exec --privileged -t functional-20211117115319-2067 /bin/bash -c "sudo init 0": exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:08.011057 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:08.110491 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:08.110532 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:08.110540 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:08.110570 3296 retry.go:31] will retry after 552.330144ms: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:08.669601 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:08.771352 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:08.771390 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:08.771400 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:08.771419 3296 retry.go:31] will retry after 1.080381816s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:09.853704 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:09.953268 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:09.953314 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:09.953323 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:09.953344 3296 retry.go:31] will retry after 1.31013006s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:11.272507 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:11.374456 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:11.374499 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:11.374507 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:11.374532 3296 retry.go:31] will retry after 1.582392691s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:12.963480 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:13.070966 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:13.071011 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:13.071033 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:13.071059 3296 retry.go:31] will retry after 2.340488664s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:15.418626 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:15.515930 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:15.515974 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:15.515983 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:15.516007 3296 retry.go:31] will retry after 4.506218855s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:20.026267 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:20.125516 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:20.125554 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:20.125563 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:20.125585 3296 retry.go:31] will retry after 3.221479586s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:23.353043 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:23.453031 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:23.453068 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:23.453076 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:23.453105 3296 oci.go:87] couldn't shut down functional-20211117115319-2067 (might be okay): verify shutdown: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:23.453186 3296 cli_runner.go:115] Run: docker rm -f -v functional-20211117115319-2067
I1117 11:54:23.549488 3296 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117115319-2067
W1117 11:54:23.644145 3296 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117115319-2067 returned with exit code 1
I1117 11:54:23.644272 3296 cli_runner.go:115] Run: docker network inspect functional-20211117115319-2067 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
I1117 11:54:23.740429 3296 cli_runner.go:115] Run: docker network rm functional-20211117115319-2067
I1117 11:54:26.417982 3296 cli_runner.go:168] Completed: docker network rm functional-20211117115319-2067: (2.677523249s)
W1117 11:54:26.418260 3296 delete.go:139] delete failed (probably ok) <nil>
I1117 11:54:26.418267 3296 fix.go:120] Sleeping 1 second for extra luck!
I1117 11:54:27.419340 3296 start.go:126] createHost starting for "" (driver="docker")
I1117 11:54:27.446610 3296 out.go:203] * Creating docker container (CPUs=2, Memory=4000MB) ...
I1117 11:54:27.446798 3296 start.go:160] libmachine.API.Create for "functional-20211117115319-2067" (driver="docker")
I1117 11:54:27.446839 3296 client.go:168] LocalClient.Create starting
I1117 11:54:27.447003 3296 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/certs/ca.pem
I1117 11:54:27.447100 3296 main.go:130] libmachine: Decoding PEM data...
I1117 11:54:27.447132 3296 main.go:130] libmachine: Parsing certificate...
I1117 11:54:27.447285 3296 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/certs/cert.pem
I1117 11:54:27.468276 3296 main.go:130] libmachine: Decoding PEM data...
I1117 11:54:27.468372 3296 main.go:130] libmachine: Parsing certificate...
I1117 11:54:27.469495 3296 cli_runner.go:115] Run: docker network inspect functional-20211117115319-2067 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
W1117 11:54:27.570482 3296 cli_runner.go:162] docker network inspect functional-20211117115319-2067 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}" returned with exit code 1
I1117 11:54:27.570589 3296 network_create.go:254] running [docker network inspect functional-20211117115319-2067] to gather additional debugging logs...
I1117 11:54:27.570608 3296 cli_runner.go:115] Run: docker network inspect functional-20211117115319-2067
W1117 11:54:27.666045 3296 cli_runner.go:162] docker network inspect functional-20211117115319-2067 returned with exit code 1
I1117 11:54:27.666069 3296 network_create.go:257] error running [docker network inspect functional-20211117115319-2067]: docker network inspect functional-20211117115319-2067: exit status 1
stdout:
[]
stderr:
Error: No such network: functional-20211117115319-2067
I1117 11:54:27.666079 3296 network_create.go:259] output of [docker network inspect functional-20211117115319-2067]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: functional-20211117115319-2067
** /stderr **
I1117 11:54:27.666173 3296 cli_runner.go:115] Run: docker network inspect bridge --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
I1117 11:54:27.763749 3296 network.go:288] reserving subnet 192.168.49.0 for 1m0s: &{mu:{state:0 sema:0} read:{v:{m:map[] amended:true}} dirty:map[192.168.49.0:0xc000112ab0] misses:0}
I1117 11:54:27.763793 3296 network.go:235] using free private subnet 192.168.49.0/24: &{IP:192.168.49.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.49.0/24 Gateway:192.168.49.1 ClientMin:192.168.49.2 ClientMax:192.168.49.254 Broadcast:192.168.49.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I1117 11:54:27.763814 3296 network_create.go:106] attempt to create docker network functional-20211117115319-2067 192.168.49.0/24 with gateway 192.168.49.1 and MTU of 1500 ...
I1117 11:54:27.763905 3296 cli_runner.go:115] Run: docker network create --driver=bridge --subnet=192.168.49.0/24 --gateway=192.168.49.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true functional-20211117115319-2067
I1117 11:54:31.689596 3296 cli_runner.go:168] Completed: docker network create --driver=bridge --subnet=192.168.49.0/24 --gateway=192.168.49.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true functional-20211117115319-2067: (3.925678755s)
I1117 11:54:31.689619 3296 network_create.go:90] docker network functional-20211117115319-2067 192.168.49.0/24 created
I1117 11:54:31.689634 3296 kic.go:106] calculated static IP "192.168.49.2" for the "functional-20211117115319-2067" container
I1117 11:54:31.689746 3296 cli_runner.go:115] Run: docker ps -a --format {{.Names}}
I1117 11:54:31.784307 3296 cli_runner.go:115] Run: docker volume create functional-20211117115319-2067 --label name.minikube.sigs.k8s.io=functional-20211117115319-2067 --label created_by.minikube.sigs.k8s.io=true
I1117 11:54:31.880617 3296 oci.go:102] Successfully created a docker volume functional-20211117115319-2067
I1117 11:54:31.880742 3296 cli_runner.go:115] Run: docker run --rm --name functional-20211117115319-2067-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=functional-20211117115319-2067 --entrypoint /usr/bin/test -v functional-20211117115319-2067:/var gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -d /var/lib
I1117 11:54:32.293686 3296 oci.go:106] Successfully prepared a docker volume functional-20211117115319-2067
E1117 11:54:32.293742 3296 oci.go:173] error getting kernel modules path: Unable to locate kernel modules
I1117 11:54:32.293752 3296 preload.go:132] Checking if preload exists for k8s version v1.22.3 and runtime docker
I1117 11:54:32.293759 3296 client.go:171] LocalClient.Create took 4.846949723s
I1117 11:54:32.293771 3296 kic.go:179] Starting extracting preloaded images to volume ...
I1117 11:54:32.293875 3296 cli_runner.go:115] Run: docker run --rm --entrypoint /usr/bin/tar -v /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v functional-20211117115319-2067:/extractDir gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -I lz4 -xf /preloaded.tar -C /extractDir
I1117 11:54:34.298565 3296 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 11:54:34.298649 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:34.439995 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:54:34.440143 3296 retry.go:31] will retry after 149.242379ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:34.599248 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:34.715647 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:54:34.715737 3296 retry.go:31] will retry after 300.341948ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:35.020169 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:35.130108 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:54:35.130197 3296 retry.go:31] will retry after 571.057104ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:35.710225 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:35.824393 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
W1117 11:54:35.824507 3296 start.go:257] error running df -h /var: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
W1117 11:54:35.824527 3296 start.go:239] error getting percentage of /var that is free: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:35.824540 3296 start.go:129] duration metric: createHost completed in 8.405219001s
I1117 11:54:35.824628 3296 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 11:54:35.824700 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:35.940359 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:54:35.940477 3296 retry.go:31] will retry after 178.565968ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:36.119485 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:36.248102 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:54:36.248253 3296 retry.go:31] will retry after 330.246446ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:36.579063 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:36.695698 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:54:36.695784 3296 retry.go:31] will retry after 460.157723ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:37.163981 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:54:37.279863 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
W1117 11:54:37.279958 3296 start.go:257] error running df -h /var: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
W1117 11:54:37.279971 3296 start.go:239] error getting percentage of /var that is free: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:37.279985 3296 fix.go:57] fixHost completed within 30.894316489s
I1117 11:54:37.279993 3296 start.go:80] releasing machines lock for "functional-20211117115319-2067", held for 30.894346806s
W1117 11:54:37.280010 3296 start.go:532] error starting host: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
W1117 11:54:37.280151 3296 out.go:241] ! StartHost failed, but will try again: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
! StartHost failed, but will try again: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
I1117 11:54:37.280159 3296 start.go:547] Will try again in 5 seconds ...
I1117 11:54:38.230675 3296 cli_runner.go:168] Completed: docker run --rm --entrypoint /usr/bin/tar -v /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v functional-20211117115319-2067:/extractDir gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -I lz4 -xf /preloaded.tar -C /extractDir: (5.936824512s)
I1117 11:54:38.230692 3296 kic.go:188] duration metric: took 5.936966 seconds to extract preloaded images to volume
I1117 11:54:42.283467 3296 start.go:313] acquiring machines lock for functional-20211117115319-2067: {Name:mk4569454e13da3fe88fd1d74c9c9e521ae0a801 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1117 11:54:42.283625 3296 start.go:317] acquired machines lock for "functional-20211117115319-2067" in 126.044µs
I1117 11:54:42.283682 3296 start.go:93] Skipping create...Using existing machine configuration
I1117 11:54:42.283694 3296 fix.go:55] fixHost starting:
I1117 11:54:42.284185 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:42.382332 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:42.382371 3296 fix.go:108] recreateIfNeeded on functional-20211117115319-2067: state= err=unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:42.382380 3296 fix.go:113] machineExists: false. err=machine does not exist
I1117 11:54:42.409154 3296 out.go:176] * docker "functional-20211117115319-2067" container is missing, will recreate.
I1117 11:54:42.409188 3296 delete.go:124] DEMOLISHING functional-20211117115319-2067 ...
I1117 11:54:42.409412 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:42.506942 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
W1117 11:54:42.506984 3296 stop.go:75] unable to get state: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:42.507002 3296 delete.go:129] stophost failed (probably ok): ssh power off: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:42.507424 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:42.605469 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:42.605512 3296 delete.go:82] Unable to get host status for functional-20211117115319-2067, assuming it has already been deleted: state: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:42.605619 3296 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117115319-2067
W1117 11:54:42.703653 3296 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117115319-2067 returned with exit code 1
I1117 11:54:42.703679 3296 kic.go:360] could not find the container functional-20211117115319-2067 to remove it. will try anyways
I1117 11:54:42.703771 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:42.801536 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
W1117 11:54:42.801580 3296 oci.go:83] error getting container status, will try to delete anyways: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:42.801669 3296 cli_runner.go:115] Run: docker exec --privileged -t functional-20211117115319-2067 /bin/bash -c "sudo init 0"
W1117 11:54:42.900286 3296 cli_runner.go:162] docker exec --privileged -t functional-20211117115319-2067 /bin/bash -c "sudo init 0" returned with exit code 1
I1117 11:54:42.900317 3296 oci.go:656] error shutdown functional-20211117115319-2067: docker exec --privileged -t functional-20211117115319-2067 /bin/bash -c "sudo init 0": exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:43.900582 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:43.998932 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:43.998975 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:43.998987 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:43.999006 3296 retry.go:31] will retry after 391.517075ms: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:44.398654 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:44.496940 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:44.496979 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:44.496987 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:44.497007 3296 retry.go:31] will retry after 594.826393ms: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:45.098577 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:45.198453 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:45.198494 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:45.198503 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:45.198524 3296 retry.go:31] will retry after 1.326470261s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:46.526040 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:46.626225 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:46.626272 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:46.626281 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:46.626299 3296 retry.go:31] will retry after 1.212558276s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:47.848618 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:47.949811 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:47.949855 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:47.949873 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:47.949891 3296 retry.go:31] will retry after 1.779941781s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:49.733334 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:49.832934 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:49.832983 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:49.832992 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:49.833013 3296 retry.go:31] will retry after 3.268621161s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:53.107145 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:53.204311 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:53.204352 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:53.204371 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:53.204391 3296 retry.go:31] will retry after 6.097818893s: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:59.303260 3296 cli_runner.go:115] Run: docker container inspect functional-20211117115319-2067 --format={{.State.Status}}
W1117 11:54:59.401878 3296 cli_runner.go:162] docker container inspect functional-20211117115319-2067 --format={{.State.Status}} returned with exit code 1
I1117 11:54:59.401918 3296 oci.go:668] temporary error verifying shutdown: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:59.401926 3296 oci.go:670] temporary error: container functional-20211117115319-2067 status is but expect it to be exited
I1117 11:54:59.401950 3296 oci.go:87] couldn't shut down functional-20211117115319-2067 (might be okay): verify shutdown: couldn't verify container is exited. %v: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:54:59.402036 3296 cli_runner.go:115] Run: docker rm -f -v functional-20211117115319-2067
I1117 11:54:59.498788 3296 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117115319-2067
W1117 11:54:59.593884 3296 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117115319-2067 returned with exit code 1
I1117 11:54:59.593994 3296 cli_runner.go:115] Run: docker network inspect functional-20211117115319-2067 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
I1117 11:54:59.689360 3296 cli_runner.go:115] Run: docker network rm functional-20211117115319-2067
I1117 11:55:02.563412 3296 cli_runner.go:168] Completed: docker network rm functional-20211117115319-2067: (2.874019756s)
W1117 11:55:02.563682 3296 delete.go:139] delete failed (probably ok) <nil>
I1117 11:55:02.563689 3296 fix.go:120] Sleeping 1 second for extra luck!
I1117 11:55:03.573815 3296 start.go:126] createHost starting for "" (driver="docker")
I1117 11:55:03.601190 3296 out.go:203] * Creating docker container (CPUs=2, Memory=4000MB) ...
I1117 11:55:03.601394 3296 start.go:160] libmachine.API.Create for "functional-20211117115319-2067" (driver="docker")
I1117 11:55:03.601429 3296 client.go:168] LocalClient.Create starting
I1117 11:55:03.601645 3296 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/certs/ca.pem
I1117 11:55:03.601736 3296 main.go:130] libmachine: Decoding PEM data...
I1117 11:55:03.601769 3296 main.go:130] libmachine: Parsing certificate...
I1117 11:55:03.601864 3296 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/certs/cert.pem
I1117 11:55:03.601919 3296 main.go:130] libmachine: Decoding PEM data...
I1117 11:55:03.601939 3296 main.go:130] libmachine: Parsing certificate...
I1117 11:55:03.602919 3296 cli_runner.go:115] Run: docker network inspect functional-20211117115319-2067 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
W1117 11:55:03.699665 3296 cli_runner.go:162] docker network inspect functional-20211117115319-2067 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}" returned with exit code 1
I1117 11:55:03.699774 3296 network_create.go:254] running [docker network inspect functional-20211117115319-2067] to gather additional debugging logs...
I1117 11:55:03.699792 3296 cli_runner.go:115] Run: docker network inspect functional-20211117115319-2067
W1117 11:55:03.796520 3296 cli_runner.go:162] docker network inspect functional-20211117115319-2067 returned with exit code 1
I1117 11:55:03.796544 3296 network_create.go:257] error running [docker network inspect functional-20211117115319-2067]: docker network inspect functional-20211117115319-2067: exit status 1
stdout:
[]
stderr:
Error: No such network: functional-20211117115319-2067
I1117 11:55:03.796557 3296 network_create.go:259] output of [docker network inspect functional-20211117115319-2067]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: functional-20211117115319-2067
** /stderr **
I1117 11:55:03.796655 3296 cli_runner.go:115] Run: docker network inspect bridge --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
I1117 11:55:03.892690 3296 network.go:279] skipping subnet 192.168.49.0 that has unexpired reservation: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc000112ab0] amended:false}} dirty:map[] misses:0}
I1117 11:55:03.892723 3296 network.go:238] skipping subnet 192.168.49.0/24 that is reserved: &{IP:192.168.49.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.49.0/24 Gateway:192.168.49.1 ClientMin:192.168.49.2 ClientMax:192.168.49.254 Broadcast:192.168.49.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I1117 11:55:03.892917 3296 network.go:288] reserving subnet 192.168.58.0 for 1m0s: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc000112ab0] amended:true}} dirty:map[192.168.49.0:0xc000112ab0 192.168.58.0:0xc000112570] misses:0}
I1117 11:55:03.892929 3296 network.go:235] using free private subnet 192.168.58.0/24: &{IP:192.168.58.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.58.0/24 Gateway:192.168.58.1 ClientMin:192.168.58.2 ClientMax:192.168.58.254 Broadcast:192.168.58.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I1117 11:55:03.892936 3296 network_create.go:106] attempt to create docker network functional-20211117115319-2067 192.168.58.0/24 with gateway 192.168.58.1 and MTU of 1500 ...
I1117 11:55:03.893021 3296 cli_runner.go:115] Run: docker network create --driver=bridge --subnet=192.168.58.0/24 --gateway=192.168.58.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true functional-20211117115319-2067
I1117 11:55:07.767397 3296 cli_runner.go:168] Completed: docker network create --driver=bridge --subnet=192.168.58.0/24 --gateway=192.168.58.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true functional-20211117115319-2067: (3.874361334s)
I1117 11:55:07.767417 3296 network_create.go:90] docker network functional-20211117115319-2067 192.168.58.0/24 created
I1117 11:55:07.767428 3296 kic.go:106] calculated static IP "192.168.58.2" for the "functional-20211117115319-2067" container
I1117 11:55:07.767546 3296 cli_runner.go:115] Run: docker ps -a --format {{.Names}}
I1117 11:55:07.862489 3296 cli_runner.go:115] Run: docker volume create functional-20211117115319-2067 --label name.minikube.sigs.k8s.io=functional-20211117115319-2067 --label created_by.minikube.sigs.k8s.io=true
I1117 11:55:07.958351 3296 oci.go:102] Successfully created a docker volume functional-20211117115319-2067
I1117 11:55:07.958472 3296 cli_runner.go:115] Run: docker run --rm --name functional-20211117115319-2067-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=functional-20211117115319-2067 --entrypoint /usr/bin/test -v functional-20211117115319-2067:/var gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -d /var/lib
I1117 11:55:08.351069 3296 oci.go:106] Successfully prepared a docker volume functional-20211117115319-2067
E1117 11:55:08.351113 3296 oci.go:173] error getting kernel modules path: Unable to locate kernel modules
I1117 11:55:08.351124 3296 client.go:171] LocalClient.Create took 4.749724983s
I1117 11:55:08.351130 3296 preload.go:132] Checking if preload exists for k8s version v1.22.3 and runtime docker
I1117 11:55:08.351148 3296 kic.go:179] Starting extracting preloaded images to volume ...
I1117 11:55:08.351254 3296 cli_runner.go:115] Run: docker run --rm --entrypoint /usr/bin/tar -v /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-896-41d04d1976fcad0b0b824d850ee7b8db3632a01b/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v functional-20211117115319-2067:/extractDir gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -I lz4 -xf /preloaded.tar -C /extractDir
I1117 11:55:10.359566 3296 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 11:55:10.359676 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:10.496133 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:55:10.496285 3296 retry.go:31] will retry after 198.278561ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:10.697898 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:10.810561 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:55:10.810643 3296 retry.go:31] will retry after 298.905961ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:11.110324 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:11.224721 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:55:11.224802 3296 retry.go:31] will retry after 704.572333ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:11.929591 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:12.047007 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
W1117 11:55:12.047091 3296 start.go:257] error running df -h /var: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
W1117 11:55:12.047106 3296 start.go:239] error getting percentage of /var that is free: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:12.047120 3296 start.go:129] duration metric: createHost completed in 8.473319427s
I1117 11:55:12.047184 3296 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 11:55:12.047262 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:12.164348 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:55:12.164433 3296 retry.go:31] will retry after 341.333754ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:12.511842 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:12.631331 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:55:12.631420 3296 retry.go:31] will retry after 448.769687ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:13.080554 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:13.198293 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
I1117 11:55:13.198397 3296 retry.go:31] will retry after 575.898922ms: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:13.774601 3296 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067
W1117 11:55:13.873601 3296 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067 returned with exit code 1
W1117 11:55:13.873685 3296 start.go:257] error running df -h /var: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
W1117 11:55:13.873715 3296 start.go:239] error getting percentage of /var that is free: NewSession: new client: new client: Error creating new ssh host from driver: Error getting ssh port for driver: get ssh host-port: get port 22 for "functional-20211117115319-2067": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117115319-2067: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
I1117 11:55:13.873725 3296 fix.go:57] fixHost completed within 31.59027043s
I1117 11:55:13.873733 3296 start.go:80] releasing machines lock for "functional-20211117115319-2067", held for 31.590332295s
W1117 11:55:13.873876 3296 out.go:241] * Failed to start docker container. Running "minikube delete -p functional-20211117115319-2067" may fix it: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
* Failed to start docker container. Running "minikube delete -p functional-20211117115319-2067" may fix it: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
I1117 11:55:13.960096 3296 out.go:176]
W1117 11:55:13.960265 3296 out.go:241] X Exiting due to GUEST_PROVISION: Failed to start host: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
X Exiting due to GUEST_PROVISION: Failed to start host: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
W1117 11:55:13.960280 3296 out.go:241] *
*
W1117 11:55:13.961302 3296 out.go:241] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I1117 11:55:14.034908 3296 out.go:176]
** /stderr **
functional_test.go:602: failed to soft start minikube. args "out/minikube-darwin-amd64 start -p functional-20211117115319-2067 --alsologtostderr -v=8": exit status 80
functional_test.go:604: soft start took 1m8.559400504s for "functional-20211117115319-2067" cluster.
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:230: ======> post-mortem[TestFunctional/serial/SoftStart]: docker inspect <======
helpers_test.go:231: (dbg) Run: docker inspect functional-20211117115319-2067
helpers_test.go:235: (dbg) docker inspect functional-20211117115319-2067:
-- stdout --
[
{
"Name": "functional-20211117115319-2067",
"Id": "38a1a6b1e4bc0c0f952a8695bb84f5a5222a49ca89d7669161ddd79a932f168d",
"Created": "2021-11-17T19:55:03.996686881Z",
"Scope": "local",
"Driver": "bridge",
"EnableIPv6": false,
"IPAM": {
"Driver": "default",
"Options": {},
"Config": [
{
"Subnet": "192.168.58.0/24",
"Gateway": "192.168.58.1"
}
]
},
"Internal": false,
"Attachable": false,
"Ingress": false,
"ConfigFrom": {
"Network": ""
},
"ConfigOnly": false,
"Containers": {},
"Options": {
"--icc": "",
"--ip-masq": "",
"com.docker.network.driver.mtu": "1500"
},
"Labels": {
"created_by.minikube.sigs.k8s.io": "true"
}
}
]
-- /stdout --
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-20211117115319-2067 -n functional-20211117115319-2067
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-20211117115319-2067 -n functional-20211117115319-2067: exit status 7 (171.844925ms)
-- stdout --
Nonexistent
-- /stdout --
** stderr **
E1117 11:55:14.375423 3604 status.go:247] status error: host: state: unknown state "functional-20211117115319-2067": docker container inspect functional-20211117115319-2067 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117115319-2067
** /stderr **
helpers_test.go:239: status error: exit status 7 (may be ok)
helpers_test.go:241: "functional-20211117115319-2067" host is not running, skipping log retrieval (state="Nonexistent")
--- FAIL: TestFunctional/serial/SoftStart (68.86s)