=== RUN TestFunctional/serial/SoftStart
functional_test.go:600: (dbg) Run: out/minikube-darwin-amd64 start -p functional-20211117142648-2140 --alsologtostderr -v=8
functional_test.go:600: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p functional-20211117142648-2140 --alsologtostderr -v=8: exit status 80 (1m9.334501093s)
-- stdout --
* [functional-20211117142648-2140] minikube v1.24.0 on Darwin 11.2.3
- MINIKUBE_LOCATION=12739
- KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube
* Using the docker driver based on existing profile
* Starting control plane node functional-20211117142648-2140 in cluster functional-20211117142648-2140
* Pulling base image ...
* docker "functional-20211117142648-2140" container is missing, will recreate.
* Creating docker container (CPUs=2, Memory=4000MB) ...
* docker "functional-20211117142648-2140" container is missing, will recreate.
* Creating docker container (CPUs=2, Memory=4000MB) ...
-- /stdout --
** stderr **
I1117 14:27:35.346990 3381 out.go:297] Setting OutFile to fd 1 ...
I1117 14:27:35.347117 3381 out.go:344] TERM=,COLORTERM=, which probably does not support color
I1117 14:27:35.347124 3381 out.go:310] Setting ErrFile to fd 2...
I1117 14:27:35.347127 3381 out.go:344] TERM=,COLORTERM=, which probably does not support color
I1117 14:27:35.347203 3381 root.go:315] Updating PATH: /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/bin
I1117 14:27:35.347462 3381 out.go:304] Setting JSON to false
I1117 14:27:35.372217 3381 start.go:112] hostinfo: {"hostname":"37310.local","uptime":1630,"bootTime":1637186425,"procs":344,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"11.2.3","kernelVersion":"20.3.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"bd1c05a8-24a6-5973-aa69-f3c7c66a87ce"}
W1117 14:27:35.372312 3381 start.go:120] gopshost.Virtualization returned error: not implemented yet
I1117 14:27:35.416416 3381 out.go:176] * [functional-20211117142648-2140] minikube v1.24.0 on Darwin 11.2.3
I1117 14:27:35.416548 3381 notify.go:174] Checking for updates...
I1117 14:27:35.500669 3381 out.go:176] - MINIKUBE_LOCATION=12739
I1117 14:27:35.591326 3381 out.go:176] - KUBECONFIG=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/kubeconfig
I1117 14:27:35.679624 3381 out.go:176] - MINIKUBE_BIN=out/minikube-darwin-amd64
I1117 14:27:35.720090 3381 out.go:176] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube
I1117 14:27:35.720769 3381 config.go:176] Loaded profile config "functional-20211117142648-2140": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.22.3
I1117 14:27:35.720829 3381 driver.go:343] Setting default libvirt URI to qemu:///system
I1117 14:27:35.813102 3381 docker.go:132] docker version: linux-20.10.6
I1117 14:27:35.813243 3381 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I1117 14:27:35.992979 3381 info.go:263] docker info: {ID:SCH3:KUZR:QX4B:2EMZ:OZBW:JXN5:F6JV:DY72:V44T:W7ID:75XC:JMO5 Containers:0 ContainersRunning:0 ContainersPaused:0 ContainersStopped:0 Images:26 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true] [userxattr false]] 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 22:27:35.942167467 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:3 KernelVersion:5.10.25-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServer
Address:https://index.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.6 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=sec
comp,profile=default] 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:compose Path:/usr/local/lib/docker/cli-plugins/docker-compose SchemaVersion:0.1.0 ShortDescription:Docker Compose Vendor:Docker Inc. Version:2.0.0-beta.1] map[Name:scan Path:/usr/local/lib/docker/cli-plugins/docker-scan SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.8.0]] Warnings:<nil>}}
I1117 14:27:36.039310 3381 out.go:176] * Using the docker driver based on existing profile
I1117 14:27:36.039334 3381 start.go:280] selected driver: docker
I1117 14:27:36.039341 3381 start.go:775] validating driver "docker" against &{Name:functional-20211117142648-2140 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-20211117142648-2140 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 14:27:36.039395 3381 start.go:786] status for docker: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc:}
I1117 14:27:36.039604 3381 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I1117 14:27:36.216108 3381 info.go:263] docker info: {ID:SCH3:KUZR:QX4B:2EMZ:OZBW:JXN5:F6JV:DY72:V44T:W7ID:75XC:JMO5 Containers:0 ContainersRunning:0 ContainersPaused:0 ContainersStopped:0 Images:26 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true] [userxattr false]] 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 22:27:36.144565339 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:3 KernelVersion:5.10.25-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServer
Address:https://index.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.6 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=sec
comp,profile=default] 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:compose Path:/usr/local/lib/docker/cli-plugins/docker-compose SchemaVersion:0.1.0 ShortDescription:Docker Compose Vendor:Docker Inc. Version:2.0.0-beta.1] map[Name:scan Path:/usr/local/lib/docker/cli-plugins/docker-scan SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.8.0]] Warnings:<nil>}}
I1117 14:27:36.218102 3381 cni.go:93] Creating CNI manager for ""
I1117 14:27:36.218123 3381 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I1117 14:27:36.218137 3381 start_flags.go:282] config:
{Name:functional-20211117142648-2140 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-20211117142648-2140 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 14:27:36.244988 3381 out.go:176] * Starting control plane node functional-20211117142648-2140 in cluster functional-20211117142648-2140
I1117 14:27:36.245025 3381 cache.go:118] Beginning downloading kic base image for docker with docker
I1117 14:27:36.270851 3381 out.go:176] * Pulling base image ...
I1117 14:27:36.270909 3381 preload.go:132] Checking if preload exists for k8s version v1.22.3 and runtime docker
I1117 14:27:36.271002 3381 preload.go:148] Found local preload: /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4
I1117 14:27:36.271007 3381 image.go:75] Checking for gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c in local docker daemon
I1117 14:27:36.271033 3381 cache.go:57] Caching tarball of preloaded images
I1117 14:27:36.271262 3381 preload.go:174] Found /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1117 14:27:36.271294 3381 cache.go:60] Finished verifying existence of preloaded tar for v1.22.3 on docker
I1117 14:27:36.272165 3381 profile.go:147] Saving config to /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/profiles/functional-20211117142648-2140/config.json ...
I1117 14:27:36.389123 3381 image.go:79] Found gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c in local docker daemon, skipping pull
I1117 14:27:36.389139 3381 cache.go:140] gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c exists in daemon, skipping load
I1117 14:27:36.389149 3381 cache.go:206] Successfully downloaded all kic artifacts
I1117 14:27:36.389194 3381 start.go:313] acquiring machines lock for functional-20211117142648-2140: {Name:mk0ffa36ccb8092a6f2338223436899c154ee29e Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1117 14:27:36.389273 3381 start.go:317] acquired machines lock for "functional-20211117142648-2140" in 60.628µs
I1117 14:27:36.389294 3381 start.go:93] Skipping create...Using existing machine configuration
I1117 14:27:36.389299 3381 fix.go:55] fixHost starting:
I1117 14:27:36.389550 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:36.496885 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:36.496939 3381 fix.go:108] recreateIfNeeded on functional-20211117142648-2140: state= err=unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:36.496956 3381 fix.go:113] machineExists: false. err=machine does not exist
I1117 14:27:36.523760 3381 out.go:176] * docker "functional-20211117142648-2140" container is missing, will recreate.
I1117 14:27:36.523817 3381 delete.go:124] DEMOLISHING functional-20211117142648-2140 ...
I1117 14:27:36.524041 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:36.633142 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
W1117 14:27:36.633179 3381 stop.go:75] unable to get state: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:36.633193 3381 delete.go:129] stophost failed (probably ok): ssh power off: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:36.633608 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:36.739073 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:36.739117 3381 delete.go:82] Unable to get host status for functional-20211117142648-2140, assuming it has already been deleted: state: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:36.739213 3381 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117142648-2140
W1117 14:27:36.845970 3381 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117142648-2140 returned with exit code 1
I1117 14:27:36.845997 3381 kic.go:360] could not find the container functional-20211117142648-2140 to remove it. will try anyways
I1117 14:27:36.846081 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:36.969429 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
W1117 14:27:36.969469 3381 oci.go:83] error getting container status, will try to delete anyways: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:36.969563 3381 cli_runner.go:115] Run: docker exec --privileged -t functional-20211117142648-2140 /bin/bash -c "sudo init 0"
W1117 14:27:37.076863 3381 cli_runner.go:162] docker exec --privileged -t functional-20211117142648-2140 /bin/bash -c "sudo init 0" returned with exit code 1
I1117 14:27:37.076889 3381 oci.go:658] error shutdown functional-20211117142648-2140: docker exec --privileged -t functional-20211117142648-2140 /bin/bash -c "sudo init 0": exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:38.080292 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:38.190544 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:38.190602 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:38.190621 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:38.190652 3381 retry.go:31] will retry after 552.330144ms: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:38.745441 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:38.856896 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:38.856933 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:38.856941 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:38.856961 3381 retry.go:31] will retry after 1.080381816s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:39.945611 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:40.055529 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:40.055570 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:40.055580 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:40.055599 3381 retry.go:31] will retry after 1.31013006s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:41.369061 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:41.478760 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:41.478803 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:41.478817 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:41.478838 3381 retry.go:31] will retry after 1.582392691s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:43.069477 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:43.182845 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:43.182883 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:43.182892 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:43.182921 3381 retry.go:31] will retry after 2.340488664s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:45.533931 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:45.647004 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:45.647044 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:45.647052 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:45.647072 3381 retry.go:31] will retry after 4.506218855s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:50.156193 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:50.267447 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:50.267488 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:50.267499 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:50.267519 3381 retry.go:31] will retry after 3.221479586s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:53.492015 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:27:53.605415 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:27:53.605454 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:53.605463 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:27:53.605488 3381 oci.go:87] couldn't shut down functional-20211117142648-2140 (might be okay): verify shutdown: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:27:53.605566 3381 cli_runner.go:115] Run: docker rm -f -v functional-20211117142648-2140
I1117 14:27:53.712580 3381 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117142648-2140
W1117 14:27:53.821582 3381 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117142648-2140 returned with exit code 1
I1117 14:27:53.821691 3381 cli_runner.go:115] Run: docker network inspect functional-20211117142648-2140 --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 14:27:53.928366 3381 cli_runner.go:115] Run: docker network rm functional-20211117142648-2140
I1117 14:27:56.577075 3381 cli_runner.go:168] Completed: docker network rm functional-20211117142648-2140: (2.648616844s)
W1117 14:27:56.577352 3381 delete.go:139] delete failed (probably ok) <nil>
I1117 14:27:56.577370 3381 fix.go:120] Sleeping 1 second for extra luck!
I1117 14:27:57.583326 3381 start.go:126] createHost starting for "" (driver="docker")
I1117 14:27:57.632055 3381 out.go:203] * Creating docker container (CPUs=2, Memory=4000MB) ...
I1117 14:27:57.632215 3381 start.go:160] libmachine.API.Create for "functional-20211117142648-2140" (driver="docker")
I1117 14:27:57.632253 3381 client.go:168] LocalClient.Create starting
I1117 14:27:57.632469 3381 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/certs/ca.pem
I1117 14:27:57.632549 3381 main.go:130] libmachine: Decoding PEM data...
I1117 14:27:57.632584 3381 main.go:130] libmachine: Parsing certificate...
I1117 14:27:57.632722 3381 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/certs/cert.pem
I1117 14:27:57.632776 3381 main.go:130] libmachine: Decoding PEM data...
I1117 14:27:57.632801 3381 main.go:130] libmachine: Parsing certificate...
I1117 14:27:57.633819 3381 cli_runner.go:115] Run: docker network inspect functional-20211117142648-2140 --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 14:27:57.743829 3381 cli_runner.go:162] docker network inspect functional-20211117142648-2140 --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 14:27:57.743926 3381 network_create.go:254] running [docker network inspect functional-20211117142648-2140] to gather additional debugging logs...
I1117 14:27:57.743942 3381 cli_runner.go:115] Run: docker network inspect functional-20211117142648-2140
W1117 14:27:57.851583 3381 cli_runner.go:162] docker network inspect functional-20211117142648-2140 returned with exit code 1
I1117 14:27:57.851607 3381 network_create.go:257] error running [docker network inspect functional-20211117142648-2140]: docker network inspect functional-20211117142648-2140: exit status 1
stdout:
[]
stderr:
Error: No such network: functional-20211117142648-2140
I1117 14:27:57.851618 3381 network_create.go:259] output of [docker network inspect functional-20211117142648-2140]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: functional-20211117142648-2140
** /stderr **
I1117 14:27:57.851711 3381 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 14:27:57.961702 3381 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:0xc00061ca50] misses:0}
I1117 14:27:57.961737 3381 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 14:27:57.961753 3381 network_create.go:106] attempt to create docker network functional-20211117142648-2140 192.168.49.0/24 with gateway 192.168.49.1 and MTU of 1500 ...
I1117 14:27:57.961839 3381 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-20211117142648-2140
I1117 14:28:01.867249 3381 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-20211117142648-2140: (3.905280824s)
I1117 14:28:01.867271 3381 network_create.go:90] docker network functional-20211117142648-2140 192.168.49.0/24 created
I1117 14:28:01.867286 3381 kic.go:106] calculated static IP "192.168.49.2" for the "functional-20211117142648-2140" container
I1117 14:28:01.867404 3381 cli_runner.go:115] Run: docker ps -a --format {{.Names}}
I1117 14:28:01.993993 3381 cli_runner.go:115] Run: docker volume create functional-20211117142648-2140 --label name.minikube.sigs.k8s.io=functional-20211117142648-2140 --label created_by.minikube.sigs.k8s.io=true
I1117 14:28:02.099154 3381 oci.go:102] Successfully created a docker volume functional-20211117142648-2140
I1117 14:28:02.099276 3381 cli_runner.go:115] Run: docker run --rm --name functional-20211117142648-2140-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=functional-20211117142648-2140 --entrypoint /usr/bin/test -v functional-20211117142648-2140:/var gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -d /var/lib
I1117 14:28:02.519137 3381 oci.go:106] Successfully prepared a docker volume functional-20211117142648-2140
E1117 14:28:02.519187 3381 oci.go:197] error getting kernel modules path: Unable to locate kernel modules
I1117 14:28:02.519190 3381 preload.go:132] Checking if preload exists for k8s version v1.22.3 and runtime docker
I1117 14:28:02.519210 3381 client.go:171] LocalClient.Create took 4.886834595s
I1117 14:28:02.519221 3381 kic.go:179] Starting extracting preloaded images to volume ...
I1117 14:28:02.519322 3381 cli_runner.go:115] Run: docker run --rm --entrypoint /usr/bin/tar -v /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v functional-20211117142648-2140:/extractDir gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -I lz4 -xf /preloaded.tar -C /extractDir
I1117 14:28:04.524681 3381 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 14:28:04.524778 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:04.660686 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:04.660770 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:04.810214 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:04.923331 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:04.923468 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:05.224114 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:05.352810 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:05.352909 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:05.926437 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:06.047767 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
W1117 14:28:06.047853 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
W1117 14:28:06.047866 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:06.047874 3381 start.go:129] duration metric: createHost completed in 8.464307442s
I1117 14:28:06.047946 3381 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 14:28:06.048007 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:06.171237 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:06.171322 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:06.350676 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:06.472708 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:06.472794 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:06.809021 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:06.930542 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:06.930654 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:07.392322 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:07.511762 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
W1117 14:28:07.511840 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
W1117 14:28:07.511854 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:07.511861 3381 fix.go:57] fixHost completed within 31.1218436s
I1117 14:28:07.511874 3381 start.go:80] releasing machines lock for "functional-20211117142648-2140", held for 31.121875782s
W1117 14:28:07.511890 3381 start.go:532] error starting host: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
W1117 14:28:07.512012 3381 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 14:28:07.512018 3381 start.go:547] Will try again in 5 seconds ...
I1117 14:28:08.585600 3381 cli_runner.go:168] Completed: docker run --rm --entrypoint /usr/bin/tar -v /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v functional-20211117142648-2140:/extractDir gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -I lz4 -xf /preloaded.tar -C /extractDir: (6.066108479s)
I1117 14:28:08.585625 3381 kic.go:188] duration metric: took 6.066265 seconds to extract preloaded images to volume
I1117 14:28:12.520044 3381 start.go:313] acquiring machines lock for functional-20211117142648-2140: {Name:mk0ffa36ccb8092a6f2338223436899c154ee29e Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1117 14:28:12.520215 3381 start.go:317] acquired machines lock for "functional-20211117142648-2140" in 137.255µs
I1117 14:28:12.520257 3381 start.go:93] Skipping create...Using existing machine configuration
I1117 14:28:12.520270 3381 fix.go:55] fixHost starting:
I1117 14:28:12.520715 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:12.631417 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:12.631462 3381 fix.go:108] recreateIfNeeded on functional-20211117142648-2140: state= err=unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:12.631474 3381 fix.go:113] machineExists: false. err=machine does not exist
I1117 14:28:12.658510 3381 out.go:176] * docker "functional-20211117142648-2140" container is missing, will recreate.
I1117 14:28:12.658556 3381 delete.go:124] DEMOLISHING functional-20211117142648-2140 ...
I1117 14:28:12.658792 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:12.770735 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
W1117 14:28:12.770772 3381 stop.go:75] unable to get state: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:12.771237 3381 delete.go:129] stophost failed (probably ok): ssh power off: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:12.772084 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:12.880256 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:12.880304 3381 delete.go:82] Unable to get host status for functional-20211117142648-2140, assuming it has already been deleted: state: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:12.880407 3381 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117142648-2140
W1117 14:28:12.987720 3381 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117142648-2140 returned with exit code 1
I1117 14:28:12.987755 3381 kic.go:360] could not find the container functional-20211117142648-2140 to remove it. will try anyways
I1117 14:28:12.987856 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:13.095026 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
W1117 14:28:13.095069 3381 oci.go:83] error getting container status, will try to delete anyways: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:13.095174 3381 cli_runner.go:115] Run: docker exec --privileged -t functional-20211117142648-2140 /bin/bash -c "sudo init 0"
W1117 14:28:13.202198 3381 cli_runner.go:162] docker exec --privileged -t functional-20211117142648-2140 /bin/bash -c "sudo init 0" returned with exit code 1
I1117 14:28:13.202225 3381 oci.go:658] error shutdown functional-20211117142648-2140: docker exec --privileged -t functional-20211117142648-2140 /bin/bash -c "sudo init 0": exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:14.210060 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:14.322315 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:14.322356 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:14.322365 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:14.322387 3381 retry.go:31] will retry after 391.517075ms: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:14.714203 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:14.830859 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:14.830902 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:14.830923 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:14.830945 3381 retry.go:31] will retry after 594.826393ms: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:15.426894 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:15.537814 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:15.537857 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:15.537866 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:15.537886 3381 retry.go:31] will retry after 1.326470261s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:16.870205 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:16.999980 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:17.000019 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:17.000030 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:17.000049 3381 retry.go:31] will retry after 1.212558276s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:18.223043 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:18.334966 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:18.335002 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:18.335012 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:18.335033 3381 retry.go:31] will retry after 1.779941781s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:20.124110 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:20.237891 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:20.237935 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:20.237951 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:20.237974 3381 retry.go:31] will retry after 3.268621161s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:23.513533 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:23.621379 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:23.621420 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:23.621430 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:23.621450 3381 retry.go:31] will retry after 6.097818893s: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:29.729844 3381 cli_runner.go:115] Run: docker container inspect functional-20211117142648-2140 --format={{.State.Status}}
W1117 14:28:29.842429 3381 cli_runner.go:162] docker container inspect functional-20211117142648-2140 --format={{.State.Status}} returned with exit code 1
I1117 14:28:29.842468 3381 oci.go:670] temporary error verifying shutdown: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:29.842478 3381 oci.go:672] temporary error: container functional-20211117142648-2140 status is but expect it to be exited
I1117 14:28:29.842502 3381 oci.go:87] couldn't shut down functional-20211117142648-2140 (might be okay): verify shutdown: couldn't verify container is exited. %v: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:29.842586 3381 cli_runner.go:115] Run: docker rm -f -v functional-20211117142648-2140
I1117 14:28:29.948911 3381 cli_runner.go:115] Run: docker container inspect -f {{.Id}} functional-20211117142648-2140
W1117 14:28:30.056301 3381 cli_runner.go:162] docker container inspect -f {{.Id}} functional-20211117142648-2140 returned with exit code 1
I1117 14:28:30.056414 3381 cli_runner.go:115] Run: docker network inspect functional-20211117142648-2140 --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 14:28:30.167995 3381 cli_runner.go:115] Run: docker network rm functional-20211117142648-2140
I1117 14:28:32.986894 3381 cli_runner.go:168] Completed: docker network rm functional-20211117142648-2140: (2.818778546s)
W1117 14:28:32.987179 3381 delete.go:139] delete failed (probably ok) <nil>
I1117 14:28:32.987186 3381 fix.go:120] Sleeping 1 second for extra luck!
I1117 14:28:33.995275 3381 start.go:126] createHost starting for "" (driver="docker")
I1117 14:28:34.022644 3381 out.go:203] * Creating docker container (CPUs=2, Memory=4000MB) ...
I1117 14:28:34.022779 3381 start.go:160] libmachine.API.Create for "functional-20211117142648-2140" (driver="docker")
I1117 14:28:34.022810 3381 client.go:168] LocalClient.Create starting
I1117 14:28:34.023003 3381 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/certs/ca.pem
I1117 14:28:34.023089 3381 main.go:130] libmachine: Decoding PEM data...
I1117 14:28:34.023113 3381 main.go:130] libmachine: Parsing certificate...
I1117 14:28:34.023218 3381 main.go:130] libmachine: Reading certificate data from /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/certs/cert.pem
I1117 14:28:34.023272 3381 main.go:130] libmachine: Decoding PEM data...
I1117 14:28:34.023292 3381 main.go:130] libmachine: Parsing certificate...
I1117 14:28:34.024302 3381 cli_runner.go:115] Run: docker network inspect functional-20211117142648-2140 --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 14:28:34.133005 3381 cli_runner.go:162] docker network inspect functional-20211117142648-2140 --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 14:28:34.133117 3381 network_create.go:254] running [docker network inspect functional-20211117142648-2140] to gather additional debugging logs...
I1117 14:28:34.133133 3381 cli_runner.go:115] Run: docker network inspect functional-20211117142648-2140
W1117 14:28:34.240154 3381 cli_runner.go:162] docker network inspect functional-20211117142648-2140 returned with exit code 1
I1117 14:28:34.240182 3381 network_create.go:257] error running [docker network inspect functional-20211117142648-2140]: docker network inspect functional-20211117142648-2140: exit status 1
stdout:
[]
stderr:
Error: No such network: functional-20211117142648-2140
I1117 14:28:34.240199 3381 network_create.go:259] output of [docker network inspect functional-20211117142648-2140]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: functional-20211117142648-2140
** /stderr **
I1117 14:28:34.240332 3381 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 14:28:34.350669 3381 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:0xc00061ca50] amended:false}} dirty:map[] misses:0}
I1117 14:28:34.350711 3381 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 14:28:34.350934 3381 network.go:288] reserving subnet 192.168.58.0 for 1m0s: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc00061ca50] amended:true}} dirty:map[192.168.49.0:0xc00061ca50 192.168.58.0:0xc0004b60d8] misses:0}
I1117 14:28:34.350948 3381 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 14:28:34.350955 3381 network_create.go:106] attempt to create docker network functional-20211117142648-2140 192.168.58.0/24 with gateway 192.168.58.1 and MTU of 1500 ...
I1117 14:28:34.351053 3381 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-20211117142648-2140
I1117 14:28:38.272285 3381 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-20211117142648-2140: (3.921101288s)
I1117 14:28:38.272309 3381 network_create.go:90] docker network functional-20211117142648-2140 192.168.58.0/24 created
I1117 14:28:38.272328 3381 kic.go:106] calculated static IP "192.168.58.2" for the "functional-20211117142648-2140" container
I1117 14:28:38.272434 3381 cli_runner.go:115] Run: docker ps -a --format {{.Names}}
I1117 14:28:38.378431 3381 cli_runner.go:115] Run: docker volume create functional-20211117142648-2140 --label name.minikube.sigs.k8s.io=functional-20211117142648-2140 --label created_by.minikube.sigs.k8s.io=true
I1117 14:28:38.486859 3381 oci.go:102] Successfully created a docker volume functional-20211117142648-2140
I1117 14:28:38.486992 3381 cli_runner.go:115] Run: docker run --rm --name functional-20211117142648-2140-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=functional-20211117142648-2140 --entrypoint /usr/bin/test -v functional-20211117142648-2140:/var gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -d /var/lib
I1117 14:28:38.901896 3381 oci.go:106] Successfully prepared a docker volume functional-20211117142648-2140
E1117 14:28:38.901943 3381 oci.go:197] error getting kernel modules path: Unable to locate kernel modules
I1117 14:28:38.901946 3381 preload.go:132] Checking if preload exists for k8s version v1.22.3 and runtime docker
I1117 14:28:38.901955 3381 client.go:171] LocalClient.Create took 4.879025812s
I1117 14:28:38.901977 3381 kic.go:179] Starting extracting preloaded images to volume ...
I1117 14:28:38.902078 3381 cli_runner.go:115] Run: docker run --rm --entrypoint /usr/bin/tar -v /Users/jenkins/minikube-integration/darwin-amd64-docker--12739-993-80e07762e28b592b48b4aeaf3aab89efbbe303e1/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v14-v1.22.3-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v functional-20211117142648-2140:/extractDir gcr.io/k8s-minikube/kicbase:v0.0.28@sha256:4780f1897569d2bf77aafb3d133a08d42b4fe61127f06fcfc90c2c5d902d893c -I lz4 -xf /preloaded.tar -C /extractDir
I1117 14:28:40.902817 3381 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 14:28:40.902907 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:41.033155 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:41.033246 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:41.232301 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:41.346968 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:41.347053 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:41.646351 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:41.761763 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:41.761848 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:42.468146 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:42.594331 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
W1117 14:28:42.594416 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
W1117 14:28:42.594444 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:42.594455 3381 start.go:129] duration metric: createHost completed in 8.598905755s
I1117 14:28:42.594518 3381 ssh_runner.go:152] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"
I1117 14:28:42.594586 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:42.724568 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:42.724719 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:43.074485 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:43.195242 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:43.195321 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:43.644692 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:43.768426 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
I1117 14:28:43.768514 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:44.346914 3381 cli_runner.go:115] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140
W1117 14:28:44.454538 3381 cli_runner.go:162] docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140 returned with exit code 1
W1117 14:28:44.454620 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
W1117 14:28:44.454632 3381 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-20211117142648-2140": docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" functional-20211117142648-2140: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
I1117 14:28:44.454648 3381 fix.go:57] fixHost completed within 31.933644402s
I1117 14:28:44.454656 3381 start.go:80] releasing machines lock for "functional-20211117142648-2140", held for 31.933691008s
W1117 14:28:44.454842 3381 out.go:241] * Failed to start docker container. Running "minikube delete -p functional-20211117142648-2140" 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-20211117142648-2140" may fix it: recreate: creating host: create: creating: create kic node: kernel modules: Unable to locate kernel modules
I1117 14:28:44.511927 3381 out.go:176]
W1117 14:28:44.512130 3381 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 14:28:44.512145 3381 out.go:241] *
*
W1117 14:28:44.513201 3381 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 14:28:44.606950 3381 out.go:176]
** /stderr **
functional_test.go:602: failed to soft start minikube. args "out/minikube-darwin-amd64 start -p functional-20211117142648-2140 --alsologtostderr -v=8": exit status 80
functional_test.go:604: soft start took 1m9.347235496s for "functional-20211117142648-2140" 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-20211117142648-2140
helpers_test.go:235: (dbg) docker inspect functional-20211117142648-2140:
-- stdout --
[
{
"Name": "functional-20211117142648-2140",
"Id": "b159ef8ccb57d8af565b0c37d4dacfc0ccdf25bbe3bc274f30c2fda8f6557c59",
"Created": "2021-11-17T22:28:34.475622498Z",
"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-20211117142648-2140 -n functional-20211117142648-2140
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-20211117142648-2140 -n functional-20211117142648-2140: exit status 7 (147.895227ms)
-- stdout --
Nonexistent
-- /stdout --
** stderr **
E1117 14:28:44.925436 3694 status.go:247] status error: host: state: unknown state "functional-20211117142648-2140": docker container inspect functional-20211117142648-2140 --format={{.State.Status}}: exit status 1
stdout:
stderr:
Error: No such container: functional-20211117142648-2140
** /stderr **
helpers_test.go:239: status error: exit status 7 (may be ok)
helpers_test.go:241: "functional-20211117142648-2140" host is not running, skipping log retrieval (state="Nonexistent")
--- FAIL: TestFunctional/serial/SoftStart (69.62s)