=== RUN TestPreload
preload_test.go:44: (dbg) Run: out/minikube-linux-amd64 start -p test-preload-898644 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=kvm2 --kubernetes-version=v1.24.4
E0530 20:22:25.633318 146455 cert_rotation.go:168] key failed with : open /home/jenkins/minikube-integration/16597-138860/.minikube/profiles/functional-886578/client.crt: no such file or directory
preload_test.go:44: (dbg) Non-zero exit: out/minikube-linux-amd64 start -p test-preload-898644 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=kvm2 --kubernetes-version=v1.24.4: exit status 90 (25.689701538s)
-- stdout --
* [test-preload-898644] minikube v1.30.1 on Ubuntu 20.04 (kvm/amd64)
- MINIKUBE_LOCATION=16597
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- KUBECONFIG=/home/jenkins/minikube-integration/16597-138860/kubeconfig
- MINIKUBE_HOME=/home/jenkins/minikube-integration/16597-138860/.minikube
- MINIKUBE_BIN=out/minikube-linux-amd64
- MINIKUBE_FORCE_SYSTEMD=
* Using the kvm2 driver based on user configuration
* Starting control plane node test-preload-898644 in cluster test-preload-898644
* Creating kvm2 VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I0530 20:22:07.821502 165817 out.go:296] Setting OutFile to fd 1 ...
I0530 20:22:07.821602 165817 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0530 20:22:07.821610 165817 out.go:309] Setting ErrFile to fd 2...
I0530 20:22:07.821614 165817 out.go:343] TERM=,COLORTERM=, which probably does not support color
I0530 20:22:07.821728 165817 root.go:336] Updating PATH: /home/jenkins/minikube-integration/16597-138860/.minikube/bin
I0530 20:22:07.822657 165817 out.go:303] Setting JSON to false
I0530 20:22:07.823812 165817 start.go:125] hostinfo: {"hostname":"ubuntu-20-agent-10","uptime":7477,"bootTime":1685470651,"procs":202,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1034-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}
I0530 20:22:07.823901 165817 start.go:135] virtualization: kvm guest
I0530 20:22:07.826409 165817 out.go:177] * [test-preload-898644] minikube v1.30.1 on Ubuntu 20.04 (kvm/amd64)
I0530 20:22:07.827846 165817 notify.go:220] Checking for updates...
I0530 20:22:07.827848 165817 out.go:177] - MINIKUBE_LOCATION=16597
I0530 20:22:07.829269 165817 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0530 20:22:07.830812 165817 out.go:177] - KUBECONFIG=/home/jenkins/minikube-integration/16597-138860/kubeconfig
I0530 20:22:07.832195 165817 out.go:177] - MINIKUBE_HOME=/home/jenkins/minikube-integration/16597-138860/.minikube
I0530 20:22:07.833400 165817 out.go:177] - MINIKUBE_BIN=out/minikube-linux-amd64
I0530 20:22:07.834740 165817 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0530 20:22:07.835990 165817 driver.go:375] Setting default libvirt URI to qemu:///system
I0530 20:22:07.870024 165817 out.go:177] * Using the kvm2 driver based on user configuration
I0530 20:22:07.871501 165817 start.go:295] selected driver: kvm2
I0530 20:22:07.871517 165817 start.go:870] validating driver "kvm2" against <nil>
I0530 20:22:07.871529 165817 start.go:881] status for kvm2: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0530 20:22:07.872121 165817 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.872192 165817 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/minikube-integration/16597-138860/.minikube/bin:/home/jenkins/workspace/KVM_Linux_integration/out/:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games:/usr/local/go/bin:/home/jenkins/go/bin:/usr/local/bin/:/usr/local/go/bin/:/home/jenkins/go/bin
I0530 20:22:07.886270 165817 install.go:137] /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2 version is 1.30.1
I0530 20:22:07.886316 165817 start_flags.go:305] no existing cluster config was found, will generate one from the flags
I0530 20:22:07.886549 165817 start_flags.go:915] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0530 20:22:07.886581 165817 cni.go:84] Creating CNI manager for ""
I0530 20:22:07.886594 165817 cni.go:157] "kvm2" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0530 20:22:07.886603 165817 start_flags.go:314] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0530 20:22:07.886610 165817 start_flags.go:319] config:
{Name:test-preload-898644 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1685034446-16582@sha256:aa728b22374c829d1e5b0a5d64d51d3e0ae0f2b191381d957516fdff68f357c8 Memory:2200 CPUs:2 DiskSize:20000 VMDriver: Driver:kvm2 HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.24.4 ClusterName:test-preload-898644 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntim
e:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP:}
I0530 20:22:07.886716 165817 iso.go:125] acquiring lock: {Name:mk9923e18bf75d600cdab13f491588e5e6178295 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.888619 165817 out.go:177] * Starting control plane node test-preload-898644 in cluster test-preload-898644
I0530 20:22:07.889823 165817 preload.go:132] Checking if preload exists for k8s version v1.24.4 and runtime docker
I0530 20:22:07.890032 165817 cache.go:107] acquiring lock: {Name:mkd7ef35553afa40204a4faadf70d5c16df42008 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890037 165817 cache.go:107] acquiring lock: {Name:mk9cc74e2796cea427b64999b8be77ae4731cded Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890099 165817 cache.go:107] acquiring lock: {Name:mk7012c6b07ba263f01356ad844ca8a933c234b2 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890085 165817 cache.go:107] acquiring lock: {Name:mk705525148bf666ced2c319619cc738ba699e9d Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890220 165817 profile.go:148] Saving config to /home/jenkins/minikube-integration/16597-138860/.minikube/profiles/test-preload-898644/config.json ...
I0530 20:22:07.890249 165817 image.go:134] retrieving image: registry.k8s.io/kube-controller-manager:v1.24.4
I0530 20:22:07.890250 165817 image.go:134] retrieving image: gcr.io/k8s-minikube/storage-provisioner:v5
I0530 20:22:07.890260 165817 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/16597-138860/.minikube/profiles/test-preload-898644/config.json: {Name:mk69681f02879c6497603d56d0a60d38449d6341 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0530 20:22:07.890249 165817 image.go:134] retrieving image: registry.k8s.io/kube-proxy:v1.24.4
I0530 20:22:07.890252 165817 cache.go:107] acquiring lock: {Name:mkd4cbe0fec0be188519eea24d2b676d1c40d349 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890271 165817 cache.go:107] acquiring lock: {Name:mk984fa6ebae92dee6fcf7fc8c15de925fb80fc3 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890068 165817 cache.go:107] acquiring lock: {Name:mkc90541d7182a8409c524cce1fb115d8ad16ad0 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890281 165817 cache.go:107] acquiring lock: {Name:mk16a18972cd197e28833a0b8ff932d9c6dbc1e1 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0530 20:22:07.890391 165817 image.go:134] retrieving image: registry.k8s.io/kube-apiserver:v1.24.4
I0530 20:22:07.890404 165817 image.go:134] retrieving image: registry.k8s.io/etcd:3.5.3-0
I0530 20:22:07.890410 165817 cache.go:195] Successfully downloaded all kic artifacts
I0530 20:22:07.890414 165817 image.go:134] retrieving image: registry.k8s.io/pause:3.7
I0530 20:22:07.890421 165817 image.go:134] retrieving image: registry.k8s.io/kube-scheduler:v1.24.4
I0530 20:22:07.890430 165817 start.go:364] acquiring machines lock for test-preload-898644: {Name:mk64bac53f5f1095c60f1ab673e04da09cff8e32 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0530 20:22:07.890468 165817 start.go:368] acquired machines lock for "test-preload-898644" in 27.08µs
I0530 20:22:07.890488 165817 start.go:93] Provisioning new machine with config: &{Name:test-preload-898644 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/16572/minikube-v1.30.1-1684885329-16572-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.39-1685034446-16582@sha256:aa728b22374c829d1e5b0a5d64d51d3e0ae0f2b191381d957516fdff68f357c8 Memory:2200 CPUs:2 DiskSize:20000 VMDriver: Driver:kvm2 HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 Kubernetes
Config:{KubernetesVersion:v1.24.4 ClusterName:test-preload-898644 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSi
ze:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP:} &{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:docker ControlPlane:true Worker:true}
I0530 20:22:07.890584 165817 start.go:125] createHost starting for "" (driver="kvm2")
I0530 20:22:07.890589 165817 image.go:134] retrieving image: registry.k8s.io/coredns/coredns:v1.8.6
I0530 20:22:07.892675 165817 out.go:204] * Creating kvm2 VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
I0530 20:22:07.891569 165817 image.go:177] daemon lookup for registry.k8s.io/kube-proxy:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-proxy:v1.24.4
I0530 20:22:07.892783 165817 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2
I0530 20:22:07.892815 165817 main.go:141] libmachine: Launching plugin server for driver kvm2
I0530 20:22:07.891569 165817 image.go:177] daemon lookup for registry.k8s.io/kube-scheduler:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-scheduler:v1.24.4
I0530 20:22:07.891569 165817 image.go:177] daemon lookup for registry.k8s.io/etcd:3.5.3-0: Error response from daemon: No such image: registry.k8s.io/etcd:3.5.3-0
I0530 20:22:07.891569 165817 image.go:177] daemon lookup for registry.k8s.io/kube-apiserver:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-apiserver:v1.24.4
I0530 20:22:07.891633 165817 image.go:177] daemon lookup for registry.k8s.io/coredns/coredns:v1.8.6: Error response from daemon: No such image: registry.k8s.io/coredns/coredns:v1.8.6
I0530 20:22:07.891634 165817 image.go:177] daemon lookup for gcr.io/k8s-minikube/storage-provisioner:v5: Error response from daemon: No such image: gcr.io/k8s-minikube/storage-provisioner:v5
I0530 20:22:07.891638 165817 image.go:177] daemon lookup for registry.k8s.io/pause:3.7: Error response from daemon: No such image: registry.k8s.io/pause:3.7
I0530 20:22:07.891645 165817 image.go:177] daemon lookup for registry.k8s.io/kube-controller-manager:v1.24.4: Error response from daemon: No such image: registry.k8s.io/kube-controller-manager:v1.24.4
I0530 20:22:07.909480 165817 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:36613
I0530 20:22:07.909861 165817 main.go:141] libmachine: () Calling .GetVersion
I0530 20:22:07.910408 165817 main.go:141] libmachine: Using API Version 1
I0530 20:22:07.910427 165817 main.go:141] libmachine: () Calling .SetConfigRaw
I0530 20:22:07.910778 165817 main.go:141] libmachine: () Calling .GetMachineName
I0530 20:22:07.910973 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetMachineName
I0530 20:22:07.911132 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:07.911283 165817 start.go:159] libmachine.API.Create for "test-preload-898644" (driver="kvm2")
I0530 20:22:07.911306 165817 client.go:168] LocalClient.Create starting
I0530 20:22:07.911335 165817 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca.pem
I0530 20:22:07.911370 165817 main.go:141] libmachine: Decoding PEM data...
I0530 20:22:07.911396 165817 main.go:141] libmachine: Parsing certificate...
I0530 20:22:07.911456 165817 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/16597-138860/.minikube/certs/cert.pem
I0530 20:22:07.911484 165817 main.go:141] libmachine: Decoding PEM data...
I0530 20:22:07.911503 165817 main.go:141] libmachine: Parsing certificate...
I0530 20:22:07.911527 165817 main.go:141] libmachine: Running pre-create checks...
I0530 20:22:07.911539 165817 main.go:141] libmachine: (test-preload-898644) Calling .PreCreateCheck
I0530 20:22:07.911828 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetConfigRaw
I0530 20:22:07.912174 165817 main.go:141] libmachine: Creating machine...
I0530 20:22:07.912189 165817 main.go:141] libmachine: (test-preload-898644) Calling .Create
I0530 20:22:07.912295 165817 main.go:141] libmachine: (test-preload-898644) Creating KVM machine...
I0530 20:22:07.913511 165817 main.go:141] libmachine: (test-preload-898644) DBG | found existing default KVM network
I0530 20:22:07.914266 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:07.914136 165840 network.go:209] using free private subnet 192.168.39.0/24: &{IP:192.168.39.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.39.0/24 Gateway:192.168.39.1 ClientMin:192.168.39.2 ClientMax:192.168.39.254 Broadcast:192.168.39.255 IsPrivate:true Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:} reservation:0xc000029890}
I0530 20:22:07.919488 165817 main.go:141] libmachine: (test-preload-898644) DBG | trying to create private KVM network mk-test-preload-898644 192.168.39.0/24...
I0530 20:22:07.992730 165817 main.go:141] libmachine: (test-preload-898644) DBG | private KVM network mk-test-preload-898644 192.168.39.0/24 created
I0530 20:22:07.992764 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:07.992699 165840 common.go:116] Making disk image using store path: /home/jenkins/minikube-integration/16597-138860/.minikube
I0530 20:22:07.992780 165817 main.go:141] libmachine: (test-preload-898644) Setting up store path in /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644 ...
I0530 20:22:07.992797 165817 main.go:141] libmachine: (test-preload-898644) Building disk image from file:///home/jenkins/minikube-integration/16597-138860/.minikube/cache/iso/amd64/minikube-v1.30.1-1684885329-16572-amd64.iso
I0530 20:22:07.992866 165817 main.go:141] libmachine: (test-preload-898644) Downloading /home/jenkins/minikube-integration/16597-138860/.minikube/cache/boot2docker.iso from file:///home/jenkins/minikube-integration/16597-138860/.minikube/cache/iso/amd64/minikube-v1.30.1-1684885329-16572-amd64.iso...
I0530 20:22:08.100846 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0
I0530 20:22:08.151128 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4
I0530 20:22:08.183102 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4
I0530 20:22:08.183891 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4
I0530 20:22:08.184519 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7
I0530 20:22:08.188328 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4
I0530 20:22:08.198220 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6
I0530 20:22:08.201904 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:08.201820 165840 common.go:123] Creating ssh key: /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa...
I0530 20:22:08.260213 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 exists
I0530 20:22:08.260236 165817 cache.go:96] cache image "registry.k8s.io/pause:3.7" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7" took 370.173592ms
I0530 20:22:08.260246 165817 cache.go:80] save to tar file registry.k8s.io/pause:3.7 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 succeeded
I0530 20:22:08.369463 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:08.369386 165840 common.go:129] Creating raw disk image: /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/test-preload-898644.rawdisk...
I0530 20:22:08.369511 165817 main.go:141] libmachine: (test-preload-898644) DBG | Writing magic tar header
I0530 20:22:08.369529 165817 main.go:141] libmachine: (test-preload-898644) DBG | Writing SSH key tar header
I0530 20:22:08.369724 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:08.369661 165840 common.go:143] Fixing permissions on /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644 ...
I0530 20:22:08.369799 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644
I0530 20:22:08.369820 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/16597-138860/.minikube/machines
I0530 20:22:08.369848 165817 main.go:141] libmachine: (test-preload-898644) Setting executable bit set on /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644 (perms=drwx------)
I0530 20:22:08.369865 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/16597-138860/.minikube
I0530 20:22:08.369878 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/16597-138860
I0530 20:22:08.369896 165817 main.go:141] libmachine: (test-preload-898644) Setting executable bit set on /home/jenkins/minikube-integration/16597-138860/.minikube/machines (perms=drwxrwxr-x)
I0530 20:22:08.369906 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home/jenkins/minikube-integration
I0530 20:22:08.369919 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home/jenkins
I0530 20:22:08.369932 165817 main.go:141] libmachine: (test-preload-898644) DBG | Checking permissions on dir: /home
I0530 20:22:08.369948 165817 main.go:141] libmachine: (test-preload-898644) Setting executable bit set on /home/jenkins/minikube-integration/16597-138860/.minikube (perms=drwxr-xr-x)
I0530 20:22:08.369960 165817 main.go:141] libmachine: (test-preload-898644) Setting executable bit set on /home/jenkins/minikube-integration/16597-138860 (perms=drwxrwxr-x)
I0530 20:22:08.369975 165817 main.go:141] libmachine: (test-preload-898644) Setting executable bit set on /home/jenkins/minikube-integration (perms=drwxrwxr-x)
I0530 20:22:08.369993 165817 main.go:141] libmachine: (test-preload-898644) Setting executable bit set on /home/jenkins (perms=drwxr-xr-x)
I0530 20:22:08.370008 165817 main.go:141] libmachine: (test-preload-898644) DBG | Skipping /home - not owner
I0530 20:22:08.370026 165817 main.go:141] libmachine: (test-preload-898644) Creating domain...
I0530 20:22:08.371413 165817 main.go:141] libmachine: (test-preload-898644) define libvirt domain using xml:
I0530 20:22:08.371437 165817 main.go:141] libmachine: (test-preload-898644) <domain type='kvm'>
I0530 20:22:08.371450 165817 main.go:141] libmachine: (test-preload-898644) <name>test-preload-898644</name>
I0530 20:22:08.371466 165817 main.go:141] libmachine: (test-preload-898644) <memory unit='MiB'>2200</memory>
I0530 20:22:08.371481 165817 main.go:141] libmachine: (test-preload-898644) <vcpu>2</vcpu>
I0530 20:22:08.371491 165817 main.go:141] libmachine: (test-preload-898644) <features>
I0530 20:22:08.371505 165817 main.go:141] libmachine: (test-preload-898644) <acpi/>
I0530 20:22:08.371517 165817 main.go:141] libmachine: (test-preload-898644) <apic/>
I0530 20:22:08.371527 165817 main.go:141] libmachine: (test-preload-898644) <pae/>
I0530 20:22:08.371538 165817 main.go:141] libmachine: (test-preload-898644)
I0530 20:22:08.371545 165817 main.go:141] libmachine: (test-preload-898644) </features>
I0530 20:22:08.371554 165817 main.go:141] libmachine: (test-preload-898644) <cpu mode='host-passthrough'>
I0530 20:22:08.371564 165817 main.go:141] libmachine: (test-preload-898644)
I0530 20:22:08.371577 165817 main.go:141] libmachine: (test-preload-898644) </cpu>
I0530 20:22:08.371586 165817 main.go:141] libmachine: (test-preload-898644) <os>
I0530 20:22:08.371599 165817 main.go:141] libmachine: (test-preload-898644) <type>hvm</type>
I0530 20:22:08.371610 165817 main.go:141] libmachine: (test-preload-898644) <boot dev='cdrom'/>
I0530 20:22:08.371619 165817 main.go:141] libmachine: (test-preload-898644) <boot dev='hd'/>
I0530 20:22:08.371639 165817 main.go:141] libmachine: (test-preload-898644) <bootmenu enable='no'/>
I0530 20:22:08.371651 165817 main.go:141] libmachine: (test-preload-898644) </os>
I0530 20:22:08.371661 165817 main.go:141] libmachine: (test-preload-898644) <devices>
I0530 20:22:08.371674 165817 main.go:141] libmachine: (test-preload-898644) <disk type='file' device='cdrom'>
I0530 20:22:08.371691 165817 main.go:141] libmachine: (test-preload-898644) <source file='/home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/boot2docker.iso'/>
I0530 20:22:08.371704 165817 main.go:141] libmachine: (test-preload-898644) <target dev='hdc' bus='scsi'/>
I0530 20:22:08.371728 165817 main.go:141] libmachine: (test-preload-898644) <readonly/>
I0530 20:22:08.371745 165817 main.go:141] libmachine: (test-preload-898644) </disk>
I0530 20:22:08.371760 165817 main.go:141] libmachine: (test-preload-898644) <disk type='file' device='disk'>
I0530 20:22:08.371776 165817 main.go:141] libmachine: (test-preload-898644) <driver name='qemu' type='raw' cache='default' io='threads' />
I0530 20:22:08.371794 165817 main.go:141] libmachine: (test-preload-898644) <source file='/home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/test-preload-898644.rawdisk'/>
I0530 20:22:08.371804 165817 main.go:141] libmachine: (test-preload-898644) <target dev='hda' bus='virtio'/>
I0530 20:22:08.371812 165817 main.go:141] libmachine: (test-preload-898644) </disk>
I0530 20:22:08.371825 165817 main.go:141] libmachine: (test-preload-898644) <interface type='network'>
I0530 20:22:08.371840 165817 main.go:141] libmachine: (test-preload-898644) <source network='mk-test-preload-898644'/>
I0530 20:22:08.371856 165817 main.go:141] libmachine: (test-preload-898644) <model type='virtio'/>
I0530 20:22:08.371898 165817 main.go:141] libmachine: (test-preload-898644) </interface>
I0530 20:22:08.371920 165817 main.go:141] libmachine: (test-preload-898644) <interface type='network'>
I0530 20:22:08.371933 165817 main.go:141] libmachine: (test-preload-898644) <source network='default'/>
I0530 20:22:08.371946 165817 main.go:141] libmachine: (test-preload-898644) <model type='virtio'/>
I0530 20:22:08.371955 165817 main.go:141] libmachine: (test-preload-898644) </interface>
I0530 20:22:08.371967 165817 main.go:141] libmachine: (test-preload-898644) <serial type='pty'>
I0530 20:22:08.371980 165817 main.go:141] libmachine: (test-preload-898644) <target port='0'/>
I0530 20:22:08.371997 165817 main.go:141] libmachine: (test-preload-898644) </serial>
I0530 20:22:08.372011 165817 main.go:141] libmachine: (test-preload-898644) <console type='pty'>
I0530 20:22:08.372024 165817 main.go:141] libmachine: (test-preload-898644) <target type='serial' port='0'/>
I0530 20:22:08.372037 165817 main.go:141] libmachine: (test-preload-898644) </console>
I0530 20:22:08.372051 165817 main.go:141] libmachine: (test-preload-898644) <rng model='virtio'>
I0530 20:22:08.372069 165817 main.go:141] libmachine: (test-preload-898644) <backend model='random'>/dev/random</backend>
I0530 20:22:08.372083 165817 main.go:141] libmachine: (test-preload-898644) </rng>
I0530 20:22:08.372096 165817 main.go:141] libmachine: (test-preload-898644)
I0530 20:22:08.372108 165817 main.go:141] libmachine: (test-preload-898644)
I0530 20:22:08.372122 165817 main.go:141] libmachine: (test-preload-898644) </devices>
I0530 20:22:08.372133 165817 main.go:141] libmachine: (test-preload-898644) </domain>
I0530 20:22:08.372146 165817 main.go:141] libmachine: (test-preload-898644)
I0530 20:22:08.378620 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:05:7a:f6 in network default
I0530 20:22:08.379289 165817 main.go:141] libmachine: (test-preload-898644) Ensuring networks are active...
I0530 20:22:08.379356 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:08.379940 165817 main.go:141] libmachine: (test-preload-898644) Ensuring network default is active
I0530 20:22:08.380283 165817 main.go:141] libmachine: (test-preload-898644) Ensuring network mk-test-preload-898644 is active
I0530 20:22:08.381031 165817 main.go:141] libmachine: (test-preload-898644) Getting domain xml...
I0530 20:22:08.381815 165817 main.go:141] libmachine: (test-preload-898644) Creating domain...
I0530 20:22:08.652665 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 exists
I0530 20:22:08.652692 165817 cache.go:96] cache image "registry.k8s.io/kube-scheduler:v1.24.4" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4" took 762.528601ms
I0530 20:22:08.652705 165817 cache.go:80] save to tar file registry.k8s.io/kube-scheduler:v1.24.4 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 succeeded
I0530 20:22:08.883066 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 exists
I0530 20:22:08.883100 165817 cache.go:96] cache image "registry.k8s.io/kube-controller-manager:v1.24.4" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4" took 993.070423ms
I0530 20:22:08.883115 165817 cache.go:80] save to tar file registry.k8s.io/kube-controller-manager:v1.24.4 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 succeeded
I0530 20:22:08.890231 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 exists
I0530 20:22:08.890258 165817 cache.go:96] cache image "registry.k8s.io/kube-apiserver:v1.24.4" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4" took 1.00020701s
I0530 20:22:08.890273 165817 cache.go:80] save to tar file registry.k8s.io/kube-apiserver:v1.24.4 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 succeeded
I0530 20:22:08.938949 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 exists
I0530 20:22:08.938979 165817 cache.go:96] cache image "registry.k8s.io/kube-proxy:v1.24.4" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4" took 1.048882149s
I0530 20:22:08.938995 165817 cache.go:80] save to tar file registry.k8s.io/kube-proxy:v1.24.4 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 succeeded
I0530 20:22:09.261357 165817 cache.go:162] opening: /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5
I0530 20:22:09.343307 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 exists
I0530 20:22:09.343335 165817 cache.go:96] cache image "registry.k8s.io/etcd:3.5.3-0" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0" took 1.453162029s
I0530 20:22:09.343349 165817 cache.go:80] save to tar file registry.k8s.io/etcd:3.5.3-0 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 succeeded
I0530 20:22:09.766549 165817 main.go:141] libmachine: (test-preload-898644) Waiting to get IP...
I0530 20:22:09.767377 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:09.767759 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:09.767808 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:09.767750 165840 retry.go:31] will retry after 272.908124ms: waiting for machine to come up
I0530 20:22:10.042363 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:10.042915 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:10.042944 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:10.042868 165840 retry.go:31] will retry after 306.98075ms: waiting for machine to come up
I0530 20:22:10.221559 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 exists
I0530 20:22:10.221596 165817 cache.go:96] cache image "registry.k8s.io/coredns/coredns:v1.8.6" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6" took 2.331348739s
I0530 20:22:10.221616 165817 cache.go:80] save to tar file registry.k8s.io/coredns/coredns:v1.8.6 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 succeeded
I0530 20:22:10.351419 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:10.351767 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:10.351794 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:10.351727 165840 retry.go:31] will retry after 436.247438ms: waiting for machine to come up
I0530 20:22:10.414825 165817 cache.go:157] /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 exists
I0530 20:22:10.414853 165817 cache.go:96] cache image "gcr.io/k8s-minikube/storage-provisioner:v5" -> "/home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5" took 2.524826296s
I0530 20:22:10.414867 165817 cache.go:80] save to tar file gcr.io/k8s-minikube/storage-provisioner:v5 -> /home/jenkins/minikube-integration/16597-138860/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 succeeded
I0530 20:22:10.414882 165817 cache.go:87] Successfully saved all images to host disk.
I0530 20:22:10.789260 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:10.789655 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:10.789680 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:10.789615 165840 retry.go:31] will retry after 513.28977ms: waiting for machine to come up
I0530 20:22:11.304422 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:11.304840 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:11.304862 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:11.304796 165840 retry.go:31] will retry after 755.364614ms: waiting for machine to come up
I0530 20:22:12.061663 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:12.062087 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:12.062121 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:12.062027 165840 retry.go:31] will retry after 622.236694ms: waiting for machine to come up
I0530 20:22:12.685438 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:12.685818 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:12.685845 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:12.685762 165840 retry.go:31] will retry after 1.073409134s: waiting for machine to come up
I0530 20:22:13.760651 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:13.761036 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:13.761066 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:13.761019 165840 retry.go:31] will retry after 1.036079944s: waiting for machine to come up
I0530 20:22:14.799409 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:14.799924 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:14.799956 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:14.799870 165840 retry.go:31] will retry after 1.678795596s: waiting for machine to come up
I0530 20:22:16.482090 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:16.482566 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:16.482597 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:16.482508 165840 retry.go:31] will retry after 1.879929572s: waiting for machine to come up
I0530 20:22:18.364440 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:18.364942 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:18.364982 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:18.364896 165840 retry.go:31] will retry after 2.135184428s: waiting for machine to come up
I0530 20:22:20.503152 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:20.503581 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:20.503642 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:20.503553 165840 retry.go:31] will retry after 3.61632825s: waiting for machine to come up
I0530 20:22:24.121786 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:24.122198 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find current IP address of domain test-preload-898644 in network mk-test-preload-898644
I0530 20:22:24.122226 165817 main.go:141] libmachine: (test-preload-898644) DBG | I0530 20:22:24.122146 165840 retry.go:31] will retry after 4.367282691s: waiting for machine to come up
I0530 20:22:28.490679 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.491041 165817 main.go:141] libmachine: (test-preload-898644) Found IP for machine: 192.168.39.242
I0530 20:22:28.491068 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has current primary IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.491080 165817 main.go:141] libmachine: (test-preload-898644) Reserving static IP address...
I0530 20:22:28.491484 165817 main.go:141] libmachine: (test-preload-898644) DBG | unable to find host DHCP lease matching {name: "test-preload-898644", mac: "52:54:00:06:2c:4f", ip: "192.168.39.242"} in network mk-test-preload-898644
I0530 20:22:28.565238 165817 main.go:141] libmachine: (test-preload-898644) DBG | Getting to WaitForSSH function...
I0530 20:22:28.565272 165817 main.go:141] libmachine: (test-preload-898644) Reserved static IP address: 192.168.39.242
I0530 20:22:28.565291 165817 main.go:141] libmachine: (test-preload-898644) Waiting for SSH to be available...
I0530 20:22:28.567587 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.567968 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:minikube Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:28.568002 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.568086 165817 main.go:141] libmachine: (test-preload-898644) DBG | Using SSH client type: external
I0530 20:22:28.568118 165817 main.go:141] libmachine: (test-preload-898644) DBG | Using SSH private key: /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa (-rw-------)
I0530 20:22:28.568149 165817 main.go:141] libmachine: (test-preload-898644) DBG | &{[-F /dev/null -o ConnectionAttempts=3 -o ConnectTimeout=10 -o ControlMaster=no -o ControlPath=none -o LogLevel=quiet -o PasswordAuthentication=no -o ServerAliveInterval=60 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null docker@192.168.39.242 -o IdentitiesOnly=yes -i /home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa -p 22] /usr/bin/ssh <nil>}
I0530 20:22:28.568174 165817 main.go:141] libmachine: (test-preload-898644) DBG | About to run SSH command:
I0530 20:22:28.568191 165817 main.go:141] libmachine: (test-preload-898644) DBG | exit 0
I0530 20:22:28.661131 165817 main.go:141] libmachine: (test-preload-898644) DBG | SSH cmd err, output: <nil>:
I0530 20:22:28.661408 165817 main.go:141] libmachine: (test-preload-898644) KVM machine creation complete!
I0530 20:22:28.661661 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetConfigRaw
I0530 20:22:28.662202 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:28.662403 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:28.662524 165817 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0530 20:22:28.662542 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetState
I0530 20:22:28.664145 165817 main.go:141] libmachine: Detecting operating system of created instance...
I0530 20:22:28.664160 165817 main.go:141] libmachine: Waiting for SSH to be available...
I0530 20:22:28.664166 165817 main.go:141] libmachine: Getting to WaitForSSH function...
I0530 20:22:28.664174 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:28.666541 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.666863 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:28.666893 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.667010 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:28.667188 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:28.667330 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:28.667445 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:28.667578 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:28.668037 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:28.668051 165817 main.go:141] libmachine: About to run SSH command:
exit 0
I0530 20:22:28.792059 165817 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0530 20:22:28.792092 165817 main.go:141] libmachine: Detecting the provisioner...
I0530 20:22:28.792105 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:28.794898 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.795281 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:28.795307 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.795494 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:28.795707 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:28.795892 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:28.796028 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:28.796188 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:28.796608 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:28.796625 165817 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0530 20:22:28.921783 165817 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2021.02.12-1-g05a3382-dirty
ID=buildroot
VERSION_ID=2021.02.12
PRETTY_NAME="Buildroot 2021.02.12"
I0530 20:22:28.921862 165817 main.go:141] libmachine: found compatible host: buildroot
I0530 20:22:28.921876 165817 main.go:141] libmachine: Provisioning with buildroot...
I0530 20:22:28.921886 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetMachineName
I0530 20:22:28.922162 165817 buildroot.go:166] provisioning hostname "test-preload-898644"
I0530 20:22:28.922191 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetMachineName
I0530 20:22:28.922437 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:28.925010 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.925369 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:28.925407 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:28.925570 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:28.925778 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:28.925968 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:28.926128 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:28.926320 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:28.926765 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:28.926780 165817 main.go:141] libmachine: About to run SSH command:
sudo hostname test-preload-898644 && echo "test-preload-898644" | sudo tee /etc/hostname
I0530 20:22:29.065297 165817 main.go:141] libmachine: SSH cmd err, output: <nil>: test-preload-898644
I0530 20:22:29.065342 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:29.068019 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.068335 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.068359 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.068597 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:29.068813 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.068985 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.069109 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:29.069289 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:29.069887 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:29.069910 165817 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\stest-preload-898644' /etc/hosts; then
if grep -xq '127.0.1.1\s.*' /etc/hosts; then
sudo sed -i 's/^127.0.1.1\s.*/127.0.1.1 test-preload-898644/g' /etc/hosts;
else
echo '127.0.1.1 test-preload-898644' | sudo tee -a /etc/hosts;
fi
fi
I0530 20:22:29.205402 165817 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0530 20:22:29.205436 165817 buildroot.go:172] set auth options {CertDir:/home/jenkins/minikube-integration/16597-138860/.minikube CaCertPath:/home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca.pem CaPrivateKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/home/jenkins/minikube-integration/16597-138860/.minikube/machines/server.pem ServerKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/machines/server-key.pem ClientKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/home/jenkins/minikube-integration/16597-138860/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/home/jenkins/minikube-integration/16597-138860/.minikube}
I0530 20:22:29.205460 165817 buildroot.go:174] setting up certificates
I0530 20:22:29.205473 165817 provision.go:83] configureAuth start
I0530 20:22:29.205485 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetMachineName
I0530 20:22:29.205814 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetIP
I0530 20:22:29.208353 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.208689 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.208728 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.208853 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:29.211066 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.211358 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.211398 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.211531 165817 provision.go:138] copyHostCerts
I0530 20:22:29.211602 165817 exec_runner.go:144] found /home/jenkins/minikube-integration/16597-138860/.minikube/ca.pem, removing ...
I0530 20:22:29.211615 165817 exec_runner.go:203] rm: /home/jenkins/minikube-integration/16597-138860/.minikube/ca.pem
I0530 20:22:29.211701 165817 exec_runner.go:151] cp: /home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca.pem --> /home/jenkins/minikube-integration/16597-138860/.minikube/ca.pem (1082 bytes)
I0530 20:22:29.211813 165817 exec_runner.go:144] found /home/jenkins/minikube-integration/16597-138860/.minikube/cert.pem, removing ...
I0530 20:22:29.211824 165817 exec_runner.go:203] rm: /home/jenkins/minikube-integration/16597-138860/.minikube/cert.pem
I0530 20:22:29.211861 165817 exec_runner.go:151] cp: /home/jenkins/minikube-integration/16597-138860/.minikube/certs/cert.pem --> /home/jenkins/minikube-integration/16597-138860/.minikube/cert.pem (1123 bytes)
I0530 20:22:29.211943 165817 exec_runner.go:144] found /home/jenkins/minikube-integration/16597-138860/.minikube/key.pem, removing ...
I0530 20:22:29.211952 165817 exec_runner.go:203] rm: /home/jenkins/minikube-integration/16597-138860/.minikube/key.pem
I0530 20:22:29.211984 165817 exec_runner.go:151] cp: /home/jenkins/minikube-integration/16597-138860/.minikube/certs/key.pem --> /home/jenkins/minikube-integration/16597-138860/.minikube/key.pem (1675 bytes)
I0530 20:22:29.212053 165817 provision.go:112] generating server cert: /home/jenkins/minikube-integration/16597-138860/.minikube/machines/server.pem ca-key=/home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca.pem private-key=/home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca-key.pem org=jenkins.test-preload-898644 san=[192.168.39.242 192.168.39.242 localhost 127.0.0.1 minikube test-preload-898644]
I0530 20:22:29.288835 165817 provision.go:172] copyRemoteCerts
I0530 20:22:29.288909 165817 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0530 20:22:29.288937 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:29.291517 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.291765 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.291790 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.291932 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:29.292126 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.292293 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:29.292457 165817 sshutil.go:53] new ssh client: &{IP:192.168.39.242 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa Username:docker}
I0530 20:22:29.386541 165817 ssh_runner.go:362] scp /home/jenkins/minikube-integration/16597-138860/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0530 20:22:29.409743 165817 ssh_runner.go:362] scp /home/jenkins/minikube-integration/16597-138860/.minikube/machines/server.pem --> /etc/docker/server.pem (1233 bytes)
I0530 20:22:29.432120 165817 ssh_runner.go:362] scp /home/jenkins/minikube-integration/16597-138860/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0530 20:22:29.454901 165817 provision.go:86] duration metric: configureAuth took 249.413423ms
I0530 20:22:29.454929 165817 buildroot.go:189] setting minikube options for container-runtime
I0530 20:22:29.455105 165817 config.go:182] Loaded profile config "test-preload-898644": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.24.4
I0530 20:22:29.455130 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:29.455440 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:29.458520 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.458897 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.458923 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.459036 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:29.459275 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.459460 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.459649 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:29.459832 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:29.460414 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:29.460433 165817 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0530 20:22:29.586377 165817 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0530 20:22:29.586399 165817 buildroot.go:70] root file system type: tmpfs
I0530 20:22:29.586556 165817 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0530 20:22:29.586590 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:29.589578 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.590000 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.590029 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.590207 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:29.590469 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.590668 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.590828 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:29.590999 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:29.591428 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:29.591500 165817 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %s "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=kvm2 --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I0530 20:22:29.730038 165817 main.go:141] libmachine: SSH cmd err, output: <nil>: [Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=kvm2 --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP $MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
I0530 20:22:29.730073 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:29.732780 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.733196 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:29.733232 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:29.733365 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:29.733566 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.733730 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:29.733874 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:29.734095 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:29.734525 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:29.734551 165817 main.go:141] libmachine: About to run SSH command:
sudo diff -u /lib/systemd/system/docker.service /lib/systemd/system/docker.service.new || { sudo mv /lib/systemd/system/docker.service.new /lib/systemd/system/docker.service; sudo systemctl -f daemon-reload && sudo systemctl -f enable docker && sudo systemctl -f restart docker; }
I0530 20:22:30.524333 165817 main.go:141] libmachine: SSH cmd err, output: <nil>: diff: can't stat '/lib/systemd/system/docker.service': No such file or directory
Created symlink /etc/systemd/system/multi-user.target.wants/docker.service → /usr/lib/systemd/system/docker.service.
I0530 20:22:30.524379 165817 main.go:141] libmachine: Checking connection to Docker...
I0530 20:22:30.524391 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetURL
I0530 20:22:30.525653 165817 main.go:141] libmachine: (test-preload-898644) DBG | Using libvirt version 6000000
I0530 20:22:30.528027 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.528339 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.528363 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.528545 165817 main.go:141] libmachine: Docker is up and running!
I0530 20:22:30.528562 165817 main.go:141] libmachine: Reticulating splines...
I0530 20:22:30.528569 165817 client.go:171] LocalClient.Create took 22.617256734s
I0530 20:22:30.528590 165817 start.go:167] duration metric: libmachine.API.Create for "test-preload-898644" took 22.617315s
I0530 20:22:30.528598 165817 start.go:300] post-start starting for "test-preload-898644" (driver="kvm2")
I0530 20:22:30.528604 165817 start.go:328] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I0530 20:22:30.528619 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:30.528889 165817 ssh_runner.go:195] Run: sudo mkdir -p /etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs
I0530 20:22:30.528918 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:30.530972 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.531292 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.531323 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.531430 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:30.531604 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:30.531772 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:30.531932 165817 sshutil.go:53] new ssh client: &{IP:192.168.39.242 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa Username:docker}
I0530 20:22:30.623061 165817 ssh_runner.go:195] Run: cat /etc/os-release
I0530 20:22:30.627352 165817 info.go:137] Remote host: Buildroot 2021.02.12
I0530 20:22:30.627373 165817 filesync.go:126] Scanning /home/jenkins/minikube-integration/16597-138860/.minikube/addons for local assets ...
I0530 20:22:30.627451 165817 filesync.go:126] Scanning /home/jenkins/minikube-integration/16597-138860/.minikube/files for local assets ...
I0530 20:22:30.627540 165817 filesync.go:149] local asset: /home/jenkins/minikube-integration/16597-138860/.minikube/files/etc/ssl/certs/1464552.pem -> 1464552.pem in /etc/ssl/certs
I0530 20:22:30.627640 165817 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0530 20:22:30.635989 165817 ssh_runner.go:362] scp /home/jenkins/minikube-integration/16597-138860/.minikube/files/etc/ssl/certs/1464552.pem --> /etc/ssl/certs/1464552.pem (1708 bytes)
I0530 20:22:30.657996 165817 start.go:303] post-start completed in 129.383488ms
I0530 20:22:30.658042 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetConfigRaw
I0530 20:22:30.658726 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetIP
I0530 20:22:30.661348 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.661788 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.661818 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.662074 165817 profile.go:148] Saving config to /home/jenkins/minikube-integration/16597-138860/.minikube/profiles/test-preload-898644/config.json ...
I0530 20:22:30.662274 165817 start.go:128] duration metric: createHost completed in 22.771677215s
I0530 20:22:30.662299 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:30.664488 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.664834 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.664869 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.664992 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:30.665161 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:30.665332 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:30.665480 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:30.665661 165817 main.go:141] libmachine: Using SSH client type: native
I0530 20:22:30.666230 165817 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x80dc80] 0x810d20 <nil> [] 0s} 192.168.39.242 22 <nil> <nil>}
I0530 20:22:30.666247 165817 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0530 20:22:30.789838 165817 main.go:141] libmachine: SSH cmd err, output: <nil>: 1685478150.756353823
I0530 20:22:30.789863 165817 fix.go:207] guest clock: 1685478150.756353823
I0530 20:22:30.789872 165817 fix.go:220] Guest: 2023-05-30 20:22:30.756353823 +0000 UTC Remote: 2023-05-30 20:22:30.662288493 +0000 UTC m=+22.872578214 (delta=94.06533ms)
I0530 20:22:30.789890 165817 fix.go:191] guest clock delta is within tolerance: 94.06533ms
I0530 20:22:30.789895 165817 start.go:83] releasing machines lock for "test-preload-898644", held for 22.899420018s
I0530 20:22:30.789936 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:30.790240 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetIP
I0530 20:22:30.792797 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.793115 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.793138 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.793304 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:30.793851 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:30.794044 165817 main.go:141] libmachine: (test-preload-898644) Calling .DriverName
I0530 20:22:30.794128 165817 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0530 20:22:30.794179 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:30.794257 165817 ssh_runner.go:195] Run: cat /version.json
I0530 20:22:30.794285 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHHostname
I0530 20:22:30.796744 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.797056 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.797084 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.797126 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.797297 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:30.797455 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:30.797606 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:30.797614 165817 main.go:141] libmachine: (test-preload-898644) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:06:2c:4f", ip: ""} in network mk-test-preload-898644: {Iface:virbr1 ExpiryTime:2023-05-30 21:22:23 +0000 UTC Type:0 Mac:52:54:00:06:2c:4f Iaid: IPaddr:192.168.39.242 Prefix:24 Hostname:test-preload-898644 Clientid:01:52:54:00:06:2c:4f}
I0530 20:22:30.797643 165817 main.go:141] libmachine: (test-preload-898644) DBG | domain test-preload-898644 has defined IP address 192.168.39.242 and MAC address 52:54:00:06:2c:4f in network mk-test-preload-898644
I0530 20:22:30.797789 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHPort
I0530 20:22:30.797808 165817 sshutil.go:53] new ssh client: &{IP:192.168.39.242 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa Username:docker}
I0530 20:22:30.797938 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHKeyPath
I0530 20:22:30.798070 165817 main.go:141] libmachine: (test-preload-898644) Calling .GetSSHUsername
I0530 20:22:30.798191 165817 sshutil.go:53] new ssh client: &{IP:192.168.39.242 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/16597-138860/.minikube/machines/test-preload-898644/id_rsa Username:docker}
I0530 20:22:30.885868 165817 ssh_runner.go:195] Run: systemctl --version
I0530 20:22:30.912218 165817 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0530 20:22:30.918130 165817 cni.go:208] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0530 20:22:30.918196 165817 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f ( ( -name *bridge* -or -name *podman* ) -and -not -name *.mk_disabled ) -printf "%p, " -exec sh -c "sudo mv {} {}.mk_disabled" ;
I0530 20:22:30.933055 165817 cni.go:261] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0530 20:22:30.933077 165817 preload.go:132] Checking if preload exists for k8s version v1.24.4 and runtime docker
I0530 20:22:30.933094 165817 start.go:481] detecting cgroup driver to use...
I0530 20:22:30.933193 165817 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///run/containerd/containerd.sock
" | sudo tee /etc/crictl.yaml"
I0530 20:22:30.953530 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.7"|' /etc/containerd/config.toml"
I0530 20:22:30.962510 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)restrict_oom_score_adj = .*$|\1restrict_oom_score_adj = false|' /etc/containerd/config.toml"
I0530 20:22:30.971712 165817 containerd.go:145] configuring containerd to use "cgroupfs" as cgroup driver...
I0530 20:22:30.971755 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0530 20:22:30.980887 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runtime.v1.linux"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0530 20:22:30.989873 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0530 20:22:30.998913 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0530 20:22:31.007982 165817 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0530 20:22:31.017328 165817 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)conf_dir = .*$|\1conf_dir = "/etc/cni/net.d"|g' /etc/containerd/config.toml"
I0530 20:22:31.026697 165817 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0530 20:22:31.034864 165817 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0530 20:22:31.043791 165817 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0530 20:22:31.144035 165817 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0530 20:22:31.159956 165817 start.go:481] detecting cgroup driver to use...
I0530 20:22:31.160030 165817 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0530 20:22:31.177980 165817 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0530 20:22:31.191838 165817 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0530 20:22:31.208699 165817 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0530 20:22:31.221138 165817 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0530 20:22:31.233255 165817 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0530 20:22:31.261659 165817 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0530 20:22:31.274834 165817 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I0530 20:22:31.292435 165817 ssh_runner.go:195] Run: which cri-dockerd
I0530 20:22:31.295920 165817 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0530 20:22:31.303661 165817 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0530 20:22:31.318613 165817 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0530 20:22:31.415358 165817 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0530 20:22:31.518603 165817 docker.go:532] configuring docker to use "cgroupfs" as cgroup driver...
I0530 20:22:31.518636 165817 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (144 bytes)
I0530 20:22:31.535745 165817 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0530 20:22:31.629807 165817 ssh_runner.go:195] Run: sudo systemctl restart docker
I0530 20:22:33.015627 165817 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1.385772001s)
I0530 20:22:33.015711 165817 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0530 20:22:33.115684 165817 ssh_runner.go:195] Run: sudo systemctl unmask cri-docker.socket
I0530 20:22:33.220548 165817 ssh_runner.go:195] Run: sudo systemctl enable cri-docker.socket
I0530 20:22:33.331173 165817 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0530 20:22:33.448322 165817 ssh_runner.go:195] Run: sudo systemctl restart cri-docker.socket
I0530 20:22:33.467152 165817 out.go:177]
W0530 20:22:33.468766 165817 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart cri-docker.socket: Process exited with status 1
stdout:
stderr:
Job failed. See "journalctl -xe" for details.
W0530 20:22:33.468785 165817 out.go:239] *
*
W0530 20:22:33.469655 165817 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0530 20:22:33.471600 165817 out.go:177]
** /stderr **
preload_test.go:46: out/minikube-linux-amd64 start -p test-preload-898644 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=kvm2 --kubernetes-version=v1.24.4 failed: exit status 90
panic.go:522: *** TestPreload FAILED at 2023-05-30 20:22:33.490645418 +0000 UTC m=+1795.507508864
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-linux-amd64 status --format={{.Host}} -p test-preload-898644 -n test-preload-898644
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-linux-amd64 status --format={{.Host}} -p test-preload-898644 -n test-preload-898644: exit status 6 (217.07061ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0530 20:22:33.693371 166057 status.go:415] kubeconfig endpoint: extract IP: "test-preload-898644" does not appear in /home/jenkins/minikube-integration/16597-138860/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "test-preload-898644" host is not running, skipping log retrieval (state="Running\nWARNING: Your kubectl is pointing to stale minikube-vm.\nTo fix the kubectl context, run `minikube update-context`")
helpers_test.go:175: Cleaning up "test-preload-898644" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-linux-amd64 delete -p test-preload-898644
--- FAIL: TestPreload (26.79s)