=== RUN TestPreload
preload_test.go:44: (dbg) Run: out/minikube-linux-amd64 start -p test-preload-159708 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=kvm2 --container-runtime=containerd --kubernetes-version=v1.24.4
E0122 20:51:35.695935 158271 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/20288-150966/.minikube/profiles/addons-964261/client.crt: no such file or directory" logger="UnhandledError"
preload_test.go:44: (dbg) Non-zero exit: out/minikube-linux-amd64 start -p test-preload-159708 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=kvm2 --container-runtime=containerd --kubernetes-version=v1.24.4: exit status 100 (44.657496894s)
-- stdout --
* [test-preload-159708] minikube v1.35.0 on Ubuntu 20.04 (kvm/amd64)
- MINIKUBE_LOCATION=20288
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- KUBECONFIG=/home/jenkins/minikube-integration/20288-150966/kubeconfig
- MINIKUBE_HOME=/home/jenkins/minikube-integration/20288-150966/.minikube
- MINIKUBE_BIN=out/minikube-linux-amd64
- MINIKUBE_FORCE_SYSTEMD=
* Using the kvm2 driver based on user configuration
* Starting "test-preload-159708" primary control-plane node in "test-preload-159708" cluster
* Creating kvm2 VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
* Preparing Kubernetes v1.24.4 on containerd 1.7.23 ...
-- /stdout --
** stderr **
I0122 20:51:22.957630 188344 out.go:345] Setting OutFile to fd 1 ...
I0122 20:51:22.957718 188344 out.go:392] TERM=,COLORTERM=, which probably does not support color
I0122 20:51:22.957722 188344 out.go:358] Setting ErrFile to fd 2...
I0122 20:51:22.957727 188344 out.go:392] TERM=,COLORTERM=, which probably does not support color
I0122 20:51:22.957887 188344 root.go:338] Updating PATH: /home/jenkins/minikube-integration/20288-150966/.minikube/bin
I0122 20:51:22.958500 188344 out.go:352] Setting JSON to false
I0122 20:51:22.959457 188344 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-2","uptime":9218,"bootTime":1737569865,"procs":183,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1074-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}
I0122 20:51:22.959547 188344 start.go:139] virtualization: kvm guest
I0122 20:51:22.961562 188344 out.go:177] * [test-preload-159708] minikube v1.35.0 on Ubuntu 20.04 (kvm/amd64)
I0122 20:51:22.962903 188344 notify.go:220] Checking for updates...
I0122 20:51:22.962906 188344 out.go:177] - MINIKUBE_LOCATION=20288
I0122 20:51:22.964209 188344 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0122 20:51:22.965415 188344 out.go:177] - KUBECONFIG=/home/jenkins/minikube-integration/20288-150966/kubeconfig
I0122 20:51:22.966524 188344 out.go:177] - MINIKUBE_HOME=/home/jenkins/minikube-integration/20288-150966/.minikube
I0122 20:51:22.967729 188344 out.go:177] - MINIKUBE_BIN=out/minikube-linux-amd64
I0122 20:51:22.969060 188344 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0122 20:51:22.970344 188344 driver.go:394] Setting default libvirt URI to qemu:///system
I0122 20:51:23.006793 188344 out.go:177] * Using the kvm2 driver based on user configuration
I0122 20:51:23.007933 188344 start.go:297] selected driver: kvm2
I0122 20:51:23.007948 188344 start.go:901] validating driver "kvm2" against <nil>
I0122 20:51:23.007958 188344 start.go:912] status for kvm2: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0122 20:51:23.008777 188344 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.008881 188344 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/minikube-integration/20288-150966/.minikube/bin:/home/jenkins/workspace/KVM_Linux_containerd_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
I0122 20:51:23.024387 188344 install.go:137] /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2 version is 1.35.0
I0122 20:51:23.024433 188344 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0122 20:51:23.024647 188344 start_flags.go:947] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0122 20:51:23.024678 188344 cni.go:84] Creating CNI manager for ""
I0122 20:51:23.024726 188344 cni.go:146] "kvm2" driver + "containerd" runtime found, recommending bridge
I0122 20:51:23.024735 188344 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0122 20:51:23.024774 188344 start.go:340] cluster config:
{Name:test-preload-159708 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.46@sha256:fd2d445ddcc33ebc5c6b68a17e6219ea207ce63c005095ea1525296da2d1a279 Memory:2200 CPUs:2 DiskSize:20000 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:8443 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-159708 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CR
ISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:containerd 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: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAg
entPID:0 GPUs: AutoPauseInterval:1m0s}
I0122 20:51:23.024870 188344 iso.go:125] acquiring lock: {Name:mkc3bf0604e328871936621dd0e0cda10261a449 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.026511 188344 out.go:177] * Starting "test-preload-159708" primary control-plane node in "test-preload-159708" cluster
I0122 20:51:23.027775 188344 preload.go:131] Checking if preload exists for k8s version v1.24.4 and runtime containerd
I0122 20:51:23.028035 188344 cache.go:107] acquiring lock: {Name:mk1665b4cc1b6a34fd0403159eee7d0dca4e7cc4 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028056 188344 cache.go:107] acquiring lock: {Name:mk78d2f1713da5613903a345eba3a750bc36e23f Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028058 188344 cache.go:107] acquiring lock: {Name:mka2650751f71d993171f4ad9b37c37cdeb31da1 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028100 188344 cache.go:107] acquiring lock: {Name:mk591cce79ae6372b83fcbac4a4da005e7893570 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028113 188344 cache.go:107] acquiring lock: {Name:mke2720ea49830336796318b83f6eab272efd2a4 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028142 188344 profile.go:143] Saving config to /home/jenkins/minikube-integration/20288-150966/.minikube/profiles/test-preload-159708/config.json ...
I0122 20:51:23.028160 188344 cache.go:107] acquiring lock: {Name:mke8e367394d76cadc438e2195bc05eedc065b7f Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028174 188344 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/20288-150966/.minikube/profiles/test-preload-159708/config.json: {Name:mkc9c9c2115a05052d4d5bf2a98940e4e69b31f2 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0122 20:51:23.028175 188344 cache.go:107] acquiring lock: {Name:mk19a90a4fa7cd8fc858edf2379fc10b8e45b330 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028249 188344 image.go:135] retrieving image: registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:23.028250 188344 image.go:135] retrieving image: registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:23.028242 188344 cache.go:107] acquiring lock: {Name:mk57c8da366a354b038860542b064f19cbd7f7f0 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0122 20:51:23.028282 188344 image.go:135] retrieving image: registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:23.028313 188344 image.go:135] retrieving image: gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:23.028319 188344 image.go:135] retrieving image: registry.k8s.io/etcd:3.5.3-0
I0122 20:51:23.028360 188344 image.go:135] retrieving image: registry.k8s.io/pause:3.7
I0122 20:51:23.028361 188344 start.go:360] acquireMachinesLock for test-preload-159708: {Name:mkde076c0ff5ffaed1ac7d9ac4f697ecfb6e2cf2 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0122 20:51:23.028396 188344 start.go:364] duration metric: took 19.039µs to acquireMachinesLock for "test-preload-159708"
I0122 20:51:23.028421 188344 image.go:135] retrieving image: registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:23.028415 188344 start.go:93] Provisioning new machine with config: &{Name:test-preload-159708 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube/iso/minikube-v1.35.0-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.46@sha256:fd2d445ddcc33ebc5c6b68a17e6219ea207ce63c005095ea1525296da2d1a279 Memory:2200 CPUs:2 DiskSize:20000 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:8443 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-15
9708 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:containerd 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: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker Bi
naryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:containerd ControlPlane:true Worker:true}
I0122 20:51:23.028558 188344 start.go:125] createHost starting for "" (driver="kvm2")
I0122 20:51:23.029012 188344 image.go:135] retrieving image: registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:23.029867 188344 image.go:178] 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
I0122 20:51:23.029894 188344 image.go:178] daemon lookup for gcr.io/k8s-minikube/storage-provisioner:v5: Error response from daemon: No such image: gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:23.029873 188344 image.go:178] 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
I0122 20:51:23.029949 188344 image.go:178] 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
I0122 20:51:23.029981 188344 image.go:178] 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
I0122 20:51:23.030061 188344 image.go:178] 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
I0122 20:51:23.029875 188344 image.go:178] daemon lookup for registry.k8s.io/pause:3.7: Error response from daemon: No such image: registry.k8s.io/pause:3.7
I0122 20:51:23.029879 188344 image.go:178] 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
I0122 20:51:23.030396 188344 out.go:235] * Creating kvm2 VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
I0122 20:51:23.030537 188344 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0122 20:51:23.030587 188344 main.go:141] libmachine: Launching plugin server for driver kvm2
I0122 20:51:23.046316 188344 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:46861
I0122 20:51:23.046815 188344 main.go:141] libmachine: () Calling .GetVersion
I0122 20:51:23.047414 188344 main.go:141] libmachine: Using API Version 1
I0122 20:51:23.047441 188344 main.go:141] libmachine: () Calling .SetConfigRaw
I0122 20:51:23.047763 188344 main.go:141] libmachine: () Calling .GetMachineName
I0122 20:51:23.047948 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetMachineName
I0122 20:51:23.048076 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:23.048231 188344 start.go:159] libmachine.API.Create for "test-preload-159708" (driver="kvm2")
I0122 20:51:23.048261 188344 client.go:168] LocalClient.Create starting
I0122 20:51:23.048295 188344 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca.pem
I0122 20:51:23.048330 188344 main.go:141] libmachine: Decoding PEM data...
I0122 20:51:23.048347 188344 main.go:141] libmachine: Parsing certificate...
I0122 20:51:23.048408 188344 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/20288-150966/.minikube/certs/cert.pem
I0122 20:51:23.048437 188344 main.go:141] libmachine: Decoding PEM data...
I0122 20:51:23.048455 188344 main.go:141] libmachine: Parsing certificate...
I0122 20:51:23.048479 188344 main.go:141] libmachine: Running pre-create checks...
I0122 20:51:23.048491 188344 main.go:141] libmachine: (test-preload-159708) Calling .PreCreateCheck
I0122 20:51:23.048829 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetConfigRaw
I0122 20:51:23.049169 188344 main.go:141] libmachine: Creating machine...
I0122 20:51:23.049182 188344 main.go:141] libmachine: (test-preload-159708) Calling .Create
I0122 20:51:23.049308 188344 main.go:141] libmachine: (test-preload-159708) creating KVM machine...
I0122 20:51:23.049336 188344 main.go:141] libmachine: (test-preload-159708) creating network...
I0122 20:51:23.050564 188344 main.go:141] libmachine: (test-preload-159708) DBG | found existing default KVM network
I0122 20:51:23.051260 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:23.051112 188368 network.go:206] 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:0xc0002091c0}
I0122 20:51:23.051290 188344 main.go:141] libmachine: (test-preload-159708) DBG | created network xml:
I0122 20:51:23.051304 188344 main.go:141] libmachine: (test-preload-159708) DBG | <network>
I0122 20:51:23.051321 188344 main.go:141] libmachine: (test-preload-159708) DBG | <name>mk-test-preload-159708</name>
I0122 20:51:23.051337 188344 main.go:141] libmachine: (test-preload-159708) DBG | <dns enable='no'/>
I0122 20:51:23.051346 188344 main.go:141] libmachine: (test-preload-159708) DBG |
I0122 20:51:23.051358 188344 main.go:141] libmachine: (test-preload-159708) DBG | <ip address='192.168.39.1' netmask='255.255.255.0'>
I0122 20:51:23.051377 188344 main.go:141] libmachine: (test-preload-159708) DBG | <dhcp>
I0122 20:51:23.051392 188344 main.go:141] libmachine: (test-preload-159708) DBG | <range start='192.168.39.2' end='192.168.39.253'/>
I0122 20:51:23.051405 188344 main.go:141] libmachine: (test-preload-159708) DBG | </dhcp>
I0122 20:51:23.051423 188344 main.go:141] libmachine: (test-preload-159708) DBG | </ip>
I0122 20:51:23.051435 188344 main.go:141] libmachine: (test-preload-159708) DBG |
I0122 20:51:23.051448 188344 main.go:141] libmachine: (test-preload-159708) DBG | </network>
I0122 20:51:23.051461 188344 main.go:141] libmachine: (test-preload-159708) DBG |
I0122 20:51:23.056499 188344 main.go:141] libmachine: (test-preload-159708) DBG | trying to create private KVM network mk-test-preload-159708 192.168.39.0/24...
I0122 20:51:23.128474 188344 main.go:141] libmachine: (test-preload-159708) DBG | private KVM network mk-test-preload-159708 192.168.39.0/24 created
I0122 20:51:23.128512 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:23.128459 188368 common.go:144] Making disk image using store path: /home/jenkins/minikube-integration/20288-150966/.minikube
I0122 20:51:23.128527 188344 main.go:141] libmachine: (test-preload-159708) setting up store path in /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708 ...
I0122 20:51:23.128544 188344 main.go:141] libmachine: (test-preload-159708) building disk image from file:///home/jenkins/minikube-integration/20288-150966/.minikube/cache/iso/amd64/minikube-v1.35.0-amd64.iso
I0122 20:51:23.128727 188344 main.go:141] libmachine: (test-preload-159708) Downloading /home/jenkins/minikube-integration/20288-150966/.minikube/cache/boot2docker.iso from file:///home/jenkins/minikube-integration/20288-150966/.minikube/cache/iso/amd64/minikube-v1.35.0-amd64.iso...
I0122 20:51:23.242249 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4
I0122 20:51:23.249137 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0
I0122 20:51:23.254769 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7
I0122 20:51:23.267654 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4
I0122 20:51:23.274217 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4
I0122 20:51:23.282394 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4
I0122 20:51:23.289115 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6
I0122 20:51:23.336573 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 exists
I0122 20:51:23.336606 188344 cache.go:96] cache image "registry.k8s.io/pause:3.7" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7" took 308.519497ms
I0122 20:51:23.336629 188344 cache.go:80] save to tar file registry.k8s.io/pause:3.7 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 succeeded
I0122 20:51:23.394601 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:23.394481 188368 common.go:151] Creating ssh key: /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa...
I0122 20:51:23.523601 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:23.523528 188368 common.go:157] Creating raw disk image: /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/test-preload-159708.rawdisk...
I0122 20:51:23.523638 188344 main.go:141] libmachine: (test-preload-159708) DBG | Writing magic tar header
I0122 20:51:23.523658 188344 main.go:141] libmachine: (test-preload-159708) DBG | Writing SSH key tar header
I0122 20:51:23.523722 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:23.523678 188368 common.go:171] Fixing permissions on /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708 ...
I0122 20:51:23.523853 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708
I0122 20:51:23.523877 188344 main.go:141] libmachine: (test-preload-159708) setting executable bit set on /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708 (perms=drwx------)
I0122 20:51:23.523889 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home/jenkins/minikube-integration/20288-150966/.minikube/machines
I0122 20:51:23.523903 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home/jenkins/minikube-integration/20288-150966/.minikube
I0122 20:51:23.523917 188344 main.go:141] libmachine: (test-preload-159708) setting executable bit set on /home/jenkins/minikube-integration/20288-150966/.minikube/machines (perms=drwxr-xr-x)
I0122 20:51:23.523923 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home/jenkins/minikube-integration/20288-150966
I0122 20:51:23.523936 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home/jenkins/minikube-integration
I0122 20:51:23.523945 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home/jenkins
I0122 20:51:23.523970 188344 main.go:141] libmachine: (test-preload-159708) setting executable bit set on /home/jenkins/minikube-integration/20288-150966/.minikube (perms=drwxr-xr-x)
I0122 20:51:23.523984 188344 main.go:141] libmachine: (test-preload-159708) DBG | checking permissions on dir: /home
I0122 20:51:23.523996 188344 main.go:141] libmachine: (test-preload-159708) setting executable bit set on /home/jenkins/minikube-integration/20288-150966 (perms=drwxrwxr-x)
I0122 20:51:23.524005 188344 main.go:141] libmachine: (test-preload-159708) setting executable bit set on /home/jenkins/minikube-integration (perms=drwxrwxr-x)
I0122 20:51:23.524011 188344 main.go:141] libmachine: (test-preload-159708) setting executable bit set on /home/jenkins (perms=drwxr-xr-x)
I0122 20:51:23.524018 188344 main.go:141] libmachine: (test-preload-159708) creating domain...
I0122 20:51:23.524025 188344 main.go:141] libmachine: (test-preload-159708) DBG | skipping /home - not owner
I0122 20:51:23.525261 188344 main.go:141] libmachine: (test-preload-159708) define libvirt domain using xml:
I0122 20:51:23.525275 188344 main.go:141] libmachine: (test-preload-159708) <domain type='kvm'>
I0122 20:51:23.525290 188344 main.go:141] libmachine: (test-preload-159708) <name>test-preload-159708</name>
I0122 20:51:23.525297 188344 main.go:141] libmachine: (test-preload-159708) <memory unit='MiB'>2200</memory>
I0122 20:51:23.525306 188344 main.go:141] libmachine: (test-preload-159708) <vcpu>2</vcpu>
I0122 20:51:23.525319 188344 main.go:141] libmachine: (test-preload-159708) <features>
I0122 20:51:23.525327 188344 main.go:141] libmachine: (test-preload-159708) <acpi/>
I0122 20:51:23.525336 188344 main.go:141] libmachine: (test-preload-159708) <apic/>
I0122 20:51:23.525345 188344 main.go:141] libmachine: (test-preload-159708) <pae/>
I0122 20:51:23.525352 188344 main.go:141] libmachine: (test-preload-159708)
I0122 20:51:23.525379 188344 main.go:141] libmachine: (test-preload-159708) </features>
I0122 20:51:23.525403 188344 main.go:141] libmachine: (test-preload-159708) <cpu mode='host-passthrough'>
I0122 20:51:23.525430 188344 main.go:141] libmachine: (test-preload-159708)
I0122 20:51:23.525451 188344 main.go:141] libmachine: (test-preload-159708) </cpu>
I0122 20:51:23.525461 188344 main.go:141] libmachine: (test-preload-159708) <os>
I0122 20:51:23.525468 188344 main.go:141] libmachine: (test-preload-159708) <type>hvm</type>
I0122 20:51:23.525477 188344 main.go:141] libmachine: (test-preload-159708) <boot dev='cdrom'/>
I0122 20:51:23.525485 188344 main.go:141] libmachine: (test-preload-159708) <boot dev='hd'/>
I0122 20:51:23.525500 188344 main.go:141] libmachine: (test-preload-159708) <bootmenu enable='no'/>
I0122 20:51:23.525507 188344 main.go:141] libmachine: (test-preload-159708) </os>
I0122 20:51:23.525526 188344 main.go:141] libmachine: (test-preload-159708) <devices>
I0122 20:51:23.525537 188344 main.go:141] libmachine: (test-preload-159708) <disk type='file' device='cdrom'>
I0122 20:51:23.525547 188344 main.go:141] libmachine: (test-preload-159708) <source file='/home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/boot2docker.iso'/>
I0122 20:51:23.525557 188344 main.go:141] libmachine: (test-preload-159708) <target dev='hdc' bus='scsi'/>
I0122 20:51:23.525563 188344 main.go:141] libmachine: (test-preload-159708) <readonly/>
I0122 20:51:23.525572 188344 main.go:141] libmachine: (test-preload-159708) </disk>
I0122 20:51:23.525579 188344 main.go:141] libmachine: (test-preload-159708) <disk type='file' device='disk'>
I0122 20:51:23.525591 188344 main.go:141] libmachine: (test-preload-159708) <driver name='qemu' type='raw' cache='default' io='threads' />
I0122 20:51:23.525603 188344 main.go:141] libmachine: (test-preload-159708) <source file='/home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/test-preload-159708.rawdisk'/>
I0122 20:51:23.525611 188344 main.go:141] libmachine: (test-preload-159708) <target dev='hda' bus='virtio'/>
I0122 20:51:23.525617 188344 main.go:141] libmachine: (test-preload-159708) </disk>
I0122 20:51:23.525626 188344 main.go:141] libmachine: (test-preload-159708) <interface type='network'>
I0122 20:51:23.525634 188344 main.go:141] libmachine: (test-preload-159708) <source network='mk-test-preload-159708'/>
I0122 20:51:23.525644 188344 main.go:141] libmachine: (test-preload-159708) <model type='virtio'/>
I0122 20:51:23.525653 188344 main.go:141] libmachine: (test-preload-159708) </interface>
I0122 20:51:23.525662 188344 main.go:141] libmachine: (test-preload-159708) <interface type='network'>
I0122 20:51:23.525677 188344 main.go:141] libmachine: (test-preload-159708) <source network='default'/>
I0122 20:51:23.525714 188344 main.go:141] libmachine: (test-preload-159708) <model type='virtio'/>
I0122 20:51:23.525733 188344 main.go:141] libmachine: (test-preload-159708) </interface>
I0122 20:51:23.525745 188344 main.go:141] libmachine: (test-preload-159708) <serial type='pty'>
I0122 20:51:23.525767 188344 main.go:141] libmachine: (test-preload-159708) <target port='0'/>
I0122 20:51:23.525774 188344 main.go:141] libmachine: (test-preload-159708) </serial>
I0122 20:51:23.525787 188344 main.go:141] libmachine: (test-preload-159708) <console type='pty'>
I0122 20:51:23.525795 188344 main.go:141] libmachine: (test-preload-159708) <target type='serial' port='0'/>
I0122 20:51:23.525808 188344 main.go:141] libmachine: (test-preload-159708) </console>
I0122 20:51:23.525840 188344 main.go:141] libmachine: (test-preload-159708) <rng model='virtio'>
I0122 20:51:23.525856 188344 main.go:141] libmachine: (test-preload-159708) <backend model='random'>/dev/random</backend>
I0122 20:51:23.525866 188344 main.go:141] libmachine: (test-preload-159708) </rng>
I0122 20:51:23.525873 188344 main.go:141] libmachine: (test-preload-159708)
I0122 20:51:23.525881 188344 main.go:141] libmachine: (test-preload-159708)
I0122 20:51:23.525888 188344 main.go:141] libmachine: (test-preload-159708) </devices>
I0122 20:51:23.525895 188344 main.go:141] libmachine: (test-preload-159708) </domain>
I0122 20:51:23.525901 188344 main.go:141] libmachine: (test-preload-159708)
I0122 20:51:23.530435 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:f2:66:cc in network default
I0122 20:51:23.531167 188344 main.go:141] libmachine: (test-preload-159708) starting domain...
I0122 20:51:23.531215 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:23.531228 188344 main.go:141] libmachine: (test-preload-159708) ensuring networks are active...
I0122 20:51:23.531980 188344 main.go:141] libmachine: (test-preload-159708) Ensuring network default is active
I0122 20:51:23.532392 188344 main.go:141] libmachine: (test-preload-159708) Ensuring network mk-test-preload-159708 is active
I0122 20:51:23.532977 188344 main.go:141] libmachine: (test-preload-159708) getting domain XML...
I0122 20:51:23.533879 188344 main.go:141] libmachine: (test-preload-159708) creating domain...
I0122 20:51:23.740140 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 exists
I0122 20:51:23.740169 188344 cache.go:96] cache image "registry.k8s.io/coredns/coredns:v1.8.6" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6" took 711.930527ms
I0122 20:51:23.740184 188344 cache.go:80] save to tar file registry.k8s.io/coredns/coredns:v1.8.6 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 succeeded
I0122 20:51:23.829273 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 exists
I0122 20:51:23.829304 188344 cache.go:96] cache image "registry.k8s.io/kube-scheduler:v1.24.4" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4" took 801.208346ms
I0122 20:51:23.829363 188344 cache.go:80] save to tar file registry.k8s.io/kube-scheduler:v1.24.4 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 succeeded
I0122 20:51:23.869398 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 exists
I0122 20:51:23.869435 188344 cache.go:96] cache image "registry.k8s.io/kube-apiserver:v1.24.4" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4" took 841.396112ms
I0122 20:51:23.869452 188344 cache.go:80] save to tar file registry.k8s.io/kube-apiserver:v1.24.4 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 succeeded
I0122 20:51:23.957497 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 exists
I0122 20:51:23.957635 188344 cache.go:96] cache image "registry.k8s.io/kube-controller-manager:v1.24.4" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4" took 929.620327ms
I0122 20:51:23.957673 188344 cache.go:80] save to tar file registry.k8s.io/kube-controller-manager:v1.24.4 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 succeeded
I0122 20:51:24.062492 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 exists
I0122 20:51:24.062519 188344 cache.go:96] cache image "registry.k8s.io/kube-proxy:v1.24.4" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4" took 1.034356873s
I0122 20:51:24.062531 188344 cache.go:80] save to tar file registry.k8s.io/kube-proxy:v1.24.4 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 succeeded
I0122 20:51:24.142101 188344 cache.go:162] opening: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5
I0122 20:51:24.451680 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 exists
I0122 20:51:24.451711 188344 cache.go:96] cache image "gcr.io/k8s-minikube/storage-provisioner:v5" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5" took 1.423670032s
I0122 20:51:24.451723 188344 cache.go:80] save to tar file gcr.io/k8s-minikube/storage-provisioner:v5 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 succeeded
I0122 20:51:24.884743 188344 cache.go:157] /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 exists
I0122 20:51:24.884773 188344 cache.go:96] cache image "registry.k8s.io/etcd:3.5.3-0" -> "/home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0" took 1.8566131s
I0122 20:51:24.884791 188344 cache.go:80] save to tar file registry.k8s.io/etcd:3.5.3-0 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 succeeded
I0122 20:51:24.884810 188344 cache.go:87] Successfully saved all images to host disk.
I0122 20:51:24.910925 188344 main.go:141] libmachine: (test-preload-159708) waiting for IP...
I0122 20:51:24.911750 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:24.912155 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:24.912219 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:24.912143 188368 retry.go:31] will retry after 261.264182ms: waiting for domain to come up
I0122 20:51:25.174892 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:25.175273 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:25.175303 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:25.175224 188368 retry.go:31] will retry after 257.566437ms: waiting for domain to come up
I0122 20:51:25.435828 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:25.436352 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:25.436376 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:25.436325 188368 retry.go:31] will retry after 464.578746ms: waiting for domain to come up
I0122 20:51:25.903025 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:25.903598 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:25.903630 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:25.903574 188368 retry.go:31] will retry after 468.119665ms: waiting for domain to come up
I0122 20:51:26.373142 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:26.373583 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:26.373616 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:26.373536 188368 retry.go:31] will retry after 611.134699ms: waiting for domain to come up
I0122 20:51:26.986318 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:26.986689 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:26.986729 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:26.986679 188368 retry.go:31] will retry after 833.661702ms: waiting for domain to come up
I0122 20:51:27.821657 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:27.822177 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:27.822199 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:27.822148 188368 retry.go:31] will retry after 1.102739291s: waiting for domain to come up
I0122 20:51:28.926258 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:28.926703 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:28.926733 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:28.926665 188368 retry.go:31] will retry after 912.677954ms: waiting for domain to come up
I0122 20:51:29.840780 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:29.841162 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:29.841186 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:29.841134 188368 retry.go:31] will retry after 1.826688053s: waiting for domain to come up
I0122 20:51:31.670067 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:31.670544 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:31.670584 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:31.670471 188368 retry.go:31] will retry after 1.722527014s: waiting for domain to come up
I0122 20:51:33.395072 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:33.395546 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:33.395571 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:33.395512 188368 retry.go:31] will retry after 2.067913616s: waiting for domain to come up
I0122 20:51:35.465842 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:35.466321 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:35.466349 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:35.466278 188368 retry.go:31] will retry after 3.226442333s: waiting for domain to come up
I0122 20:51:38.694247 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:38.694680 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:38.694718 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:38.694645 188368 retry.go:31] will retry after 2.753521074s: waiting for domain to come up
I0122 20:51:41.451684 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:41.452047 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find current IP address of domain test-preload-159708 in network mk-test-preload-159708
I0122 20:51:41.452066 188344 main.go:141] libmachine: (test-preload-159708) DBG | I0122 20:51:41.452025 188368 retry.go:31] will retry after 4.19181868s: waiting for domain to come up
I0122 20:51:45.647109 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.647544 188344 main.go:141] libmachine: (test-preload-159708) found domain IP: 192.168.39.64
I0122 20:51:45.647565 188344 main.go:141] libmachine: (test-preload-159708) reserving static IP address...
I0122 20:51:45.647579 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has current primary IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.647981 188344 main.go:141] libmachine: (test-preload-159708) DBG | unable to find host DHCP lease matching {name: "test-preload-159708", mac: "52:54:00:8f:59:8e", ip: "192.168.39.64"} in network mk-test-preload-159708
I0122 20:51:45.719921 188344 main.go:141] libmachine: (test-preload-159708) reserved static IP address 192.168.39.64 for domain test-preload-159708
I0122 20:51:45.719951 188344 main.go:141] libmachine: (test-preload-159708) waiting for SSH...
I0122 20:51:45.719964 188344 main.go:141] libmachine: (test-preload-159708) DBG | Getting to WaitForSSH function...
I0122 20:51:45.722732 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.723110 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:minikube Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:45.723145 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.723282 188344 main.go:141] libmachine: (test-preload-159708) DBG | Using SSH client type: external
I0122 20:51:45.723308 188344 main.go:141] libmachine: (test-preload-159708) DBG | Using SSH private key: /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa (-rw-------)
I0122 20:51:45.723347 188344 main.go:141] libmachine: (test-preload-159708) 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.64 -o IdentitiesOnly=yes -i /home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa -p 22] /usr/bin/ssh <nil>}
I0122 20:51:45.723363 188344 main.go:141] libmachine: (test-preload-159708) DBG | About to run SSH command:
I0122 20:51:45.723379 188344 main.go:141] libmachine: (test-preload-159708) DBG | exit 0
I0122 20:51:45.849755 188344 main.go:141] libmachine: (test-preload-159708) DBG | SSH cmd err, output: <nil>:
I0122 20:51:45.850092 188344 main.go:141] libmachine: (test-preload-159708) KVM machine creation complete
I0122 20:51:45.850411 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetConfigRaw
I0122 20:51:45.850947 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:45.851182 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:45.851383 188344 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0122 20:51:45.851398 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetState
I0122 20:51:45.852759 188344 main.go:141] libmachine: Detecting operating system of created instance...
I0122 20:51:45.852775 188344 main.go:141] libmachine: Waiting for SSH to be available...
I0122 20:51:45.852782 188344 main.go:141] libmachine: Getting to WaitForSSH function...
I0122 20:51:45.852791 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:45.855017 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.855291 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:45.855329 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.855412 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:45.855584 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:45.855731 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:45.855892 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:45.856073 188344 main.go:141] libmachine: Using SSH client type: native
I0122 20:51:45.856292 188344 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x8641c0] 0x866ea0 <nil> [] 0s} 192.168.39.64 22 <nil> <nil>}
I0122 20:51:45.856303 188344 main.go:141] libmachine: About to run SSH command:
exit 0
I0122 20:51:45.965115 188344 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0122 20:51:45.965138 188344 main.go:141] libmachine: Detecting the provisioner...
I0122 20:51:45.965145 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:45.967946 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.968310 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:45.968342 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:45.968554 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:45.968757 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:45.968924 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:45.969036 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:45.969167 188344 main.go:141] libmachine: Using SSH client type: native
I0122 20:51:45.969352 188344 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x8641c0] 0x866ea0 <nil> [] 0s} 192.168.39.64 22 <nil> <nil>}
I0122 20:51:45.969366 188344 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0122 20:51:46.082583 188344 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2023.02.9-dirty
ID=buildroot
VERSION_ID=2023.02.9
PRETTY_NAME="Buildroot 2023.02.9"
I0122 20:51:46.082632 188344 main.go:141] libmachine: found compatible host: buildroot
I0122 20:51:46.082637 188344 main.go:141] libmachine: Provisioning with buildroot...
I0122 20:51:46.082645 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetMachineName
I0122 20:51:46.082881 188344 buildroot.go:166] provisioning hostname "test-preload-159708"
I0122 20:51:46.082907 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetMachineName
I0122 20:51:46.083102 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.085757 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.086100 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.086128 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.086284 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.086476 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.086639 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.086774 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.086910 188344 main.go:141] libmachine: Using SSH client type: native
I0122 20:51:46.087105 188344 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x8641c0] 0x866ea0 <nil> [] 0s} 192.168.39.64 22 <nil> <nil>}
I0122 20:51:46.087118 188344 main.go:141] libmachine: About to run SSH command:
sudo hostname test-preload-159708 && echo "test-preload-159708" | sudo tee /etc/hostname
I0122 20:51:46.212981 188344 main.go:141] libmachine: SSH cmd err, output: <nil>: test-preload-159708
I0122 20:51:46.213009 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.215770 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.216109 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.216136 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.216379 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.216585 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.216734 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.216897 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.217048 188344 main.go:141] libmachine: Using SSH client type: native
I0122 20:51:46.217207 188344 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x8641c0] 0x866ea0 <nil> [] 0s} 192.168.39.64 22 <nil> <nil>}
I0122 20:51:46.217222 188344 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\stest-preload-159708' /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-159708/g' /etc/hosts;
else
echo '127.0.1.1 test-preload-159708' | sudo tee -a /etc/hosts;
fi
fi
I0122 20:51:46.338605 188344 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0122 20:51:46.338644 188344 buildroot.go:172] set auth options {CertDir:/home/jenkins/minikube-integration/20288-150966/.minikube CaCertPath:/home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca.pem CaPrivateKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/home/jenkins/minikube-integration/20288-150966/.minikube/machines/server.pem ServerKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/machines/server-key.pem ClientKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/home/jenkins/minikube-integration/20288-150966/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/home/jenkins/minikube-integration/20288-150966/.minikube}
I0122 20:51:46.338664 188344 buildroot.go:174] setting up certificates
I0122 20:51:46.338676 188344 provision.go:84] configureAuth start
I0122 20:51:46.338686 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetMachineName
I0122 20:51:46.338945 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetIP
I0122 20:51:46.341434 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.341790 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.341823 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.341980 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.344085 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.344363 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.344387 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.344646 188344 provision.go:143] copyHostCerts
I0122 20:51:46.344704 188344 exec_runner.go:144] found /home/jenkins/minikube-integration/20288-150966/.minikube/ca.pem, removing ...
I0122 20:51:46.344716 188344 exec_runner.go:203] rm: /home/jenkins/minikube-integration/20288-150966/.minikube/ca.pem
I0122 20:51:46.344778 188344 exec_runner.go:151] cp: /home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca.pem --> /home/jenkins/minikube-integration/20288-150966/.minikube/ca.pem (1078 bytes)
I0122 20:51:46.344869 188344 exec_runner.go:144] found /home/jenkins/minikube-integration/20288-150966/.minikube/cert.pem, removing ...
I0122 20:51:46.344878 188344 exec_runner.go:203] rm: /home/jenkins/minikube-integration/20288-150966/.minikube/cert.pem
I0122 20:51:46.344902 188344 exec_runner.go:151] cp: /home/jenkins/minikube-integration/20288-150966/.minikube/certs/cert.pem --> /home/jenkins/minikube-integration/20288-150966/.minikube/cert.pem (1123 bytes)
I0122 20:51:46.344953 188344 exec_runner.go:144] found /home/jenkins/minikube-integration/20288-150966/.minikube/key.pem, removing ...
I0122 20:51:46.344960 188344 exec_runner.go:203] rm: /home/jenkins/minikube-integration/20288-150966/.minikube/key.pem
I0122 20:51:46.344981 188344 exec_runner.go:151] cp: /home/jenkins/minikube-integration/20288-150966/.minikube/certs/key.pem --> /home/jenkins/minikube-integration/20288-150966/.minikube/key.pem (1675 bytes)
I0122 20:51:46.345028 188344 provision.go:117] generating server cert: /home/jenkins/minikube-integration/20288-150966/.minikube/machines/server.pem ca-key=/home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca.pem private-key=/home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca-key.pem org=jenkins.test-preload-159708 san=[127.0.0.1 192.168.39.64 localhost minikube test-preload-159708]
I0122 20:51:46.497487 188344 provision.go:177] copyRemoteCerts
I0122 20:51:46.497548 188344 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0122 20:51:46.497576 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.500448 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.500822 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.500857 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.501091 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.501312 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.501485 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.501620 188344 sshutil.go:53] new ssh client: &{IP:192.168.39.64 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa Username:docker}
I0122 20:51:46.588579 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0122 20:51:46.610834 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0122 20:51:46.632480 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/machines/server.pem --> /etc/docker/server.pem (1224 bytes)
I0122 20:51:46.653519 188344 provision.go:87] duration metric: took 314.829856ms to configureAuth
I0122 20:51:46.653555 188344 buildroot.go:189] setting minikube options for container-runtime
I0122 20:51:46.653753 188344 config.go:182] Loaded profile config "test-preload-159708": Driver=kvm2, ContainerRuntime=containerd, KubernetesVersion=v1.24.4
I0122 20:51:46.653776 188344 main.go:141] libmachine: Checking connection to Docker...
I0122 20:51:46.653798 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetURL
I0122 20:51:46.654939 188344 main.go:141] libmachine: (test-preload-159708) DBG | using libvirt version 6000000
I0122 20:51:46.656760 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.657064 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.657095 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.657262 188344 main.go:141] libmachine: Docker is up and running!
I0122 20:51:46.657287 188344 main.go:141] libmachine: Reticulating splines...
I0122 20:51:46.657297 188344 client.go:171] duration metric: took 23.609025186s to LocalClient.Create
I0122 20:51:46.657327 188344 start.go:167] duration metric: took 23.609096748s to libmachine.API.Create "test-preload-159708"
I0122 20:51:46.657340 188344 start.go:293] postStartSetup for "test-preload-159708" (driver="kvm2")
I0122 20:51:46.657355 188344 start.go:322] 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]
I0122 20:51:46.657377 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:46.657602 188344 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
I0122 20:51:46.657627 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.659524 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.659848 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.659880 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.659975 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.660164 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.660335 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.660459 188344 sshutil.go:53] new ssh client: &{IP:192.168.39.64 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa Username:docker}
I0122 20:51:46.744077 188344 ssh_runner.go:195] Run: cat /etc/os-release
I0122 20:51:46.748178 188344 info.go:137] Remote host: Buildroot 2023.02.9
I0122 20:51:46.748201 188344 filesync.go:126] Scanning /home/jenkins/minikube-integration/20288-150966/.minikube/addons for local assets ...
I0122 20:51:46.748276 188344 filesync.go:126] Scanning /home/jenkins/minikube-integration/20288-150966/.minikube/files for local assets ...
I0122 20:51:46.748352 188344 filesync.go:149] local asset: /home/jenkins/minikube-integration/20288-150966/.minikube/files/etc/ssl/certs/1582712.pem -> 1582712.pem in /etc/ssl/certs
I0122 20:51:46.748433 188344 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0122 20:51:46.757594 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/files/etc/ssl/certs/1582712.pem --> /etc/ssl/certs/1582712.pem (1708 bytes)
I0122 20:51:46.779771 188344 start.go:296] duration metric: took 122.414025ms for postStartSetup
I0122 20:51:46.779824 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetConfigRaw
I0122 20:51:46.780546 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetIP
I0122 20:51:46.783445 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.783770 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.783815 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.784067 188344 profile.go:143] Saving config to /home/jenkins/minikube-integration/20288-150966/.minikube/profiles/test-preload-159708/config.json ...
I0122 20:51:46.784237 188344 start.go:128] duration metric: took 23.755652597s to createHost
I0122 20:51:46.784263 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.786618 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.786919 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.786946 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.787091 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.787262 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.787450 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.787566 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.787717 188344 main.go:141] libmachine: Using SSH client type: native
I0122 20:51:46.787914 188344 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x8641c0] 0x866ea0 <nil> [] 0s} 192.168.39.64 22 <nil> <nil>}
I0122 20:51:46.787928 188344 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0122 20:51:46.898614 188344 main.go:141] libmachine: SSH cmd err, output: <nil>: 1737579106.866980369
I0122 20:51:46.898642 188344 fix.go:216] guest clock: 1737579106.866980369
I0122 20:51:46.898650 188344 fix.go:229] Guest: 2025-01-22 20:51:46.866980369 +0000 UTC Remote: 2025-01-22 20:51:46.784249933 +0000 UTC m=+23.862560389 (delta=82.730436ms)
I0122 20:51:46.898671 188344 fix.go:200] guest clock delta is within tolerance: 82.730436ms
I0122 20:51:46.898676 188344 start.go:83] releasing machines lock for "test-preload-159708", held for 23.870272518s
I0122 20:51:46.898698 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:46.898993 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetIP
I0122 20:51:46.901931 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.902321 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.902350 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.902494 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:46.903070 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:46.903255 188344 main.go:141] libmachine: (test-preload-159708) Calling .DriverName
I0122 20:51:46.903328 188344 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0122 20:51:46.903402 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.903450 188344 ssh_runner.go:195] Run: cat /version.json
I0122 20:51:46.903476 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHHostname
I0122 20:51:46.905838 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.906206 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.906231 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.906251 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.906419 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.906597 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.906624 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:46.906648 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:46.906742 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.906845 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHPort
I0122 20:51:46.906898 188344 sshutil.go:53] new ssh client: &{IP:192.168.39.64 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa Username:docker}
I0122 20:51:46.907015 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHKeyPath
I0122 20:51:46.907163 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetSSHUsername
I0122 20:51:46.907296 188344 sshutil.go:53] new ssh client: &{IP:192.168.39.64 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/20288-150966/.minikube/machines/test-preload-159708/id_rsa Username:docker}
I0122 20:51:46.986869 188344 ssh_runner.go:195] Run: systemctl --version
I0122 20:51:47.007176 188344 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0122 20:51:47.012736 188344 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0122 20:51:47.012819 188344 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" ;
I0122 20:51:47.029593 188344 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0122 20:51:47.029619 188344 start.go:495] detecting cgroup driver to use...
I0122 20:51:47.029679 188344 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0122 20:51:47.061432 188344 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0122 20:51:47.074088 188344 docker.go:217] disabling cri-docker service (if available) ...
I0122 20:51:47.074147 188344 ssh_runner.go:195] Run: sudo systemctl stop -f cri-docker.socket
I0122 20:51:47.086978 188344 ssh_runner.go:195] Run: sudo systemctl stop -f cri-docker.service
I0122 20:51:47.099608 188344 ssh_runner.go:195] Run: sudo systemctl disable cri-docker.socket
I0122 20:51:47.204929 188344 ssh_runner.go:195] Run: sudo systemctl mask cri-docker.service
I0122 20:51:47.344455 188344 docker.go:233] disabling docker service ...
I0122 20:51:47.344553 188344 ssh_runner.go:195] Run: sudo systemctl stop -f docker.socket
I0122 20:51:47.357900 188344 ssh_runner.go:195] Run: sudo systemctl stop -f docker.service
I0122 20:51:47.369942 188344 ssh_runner.go:195] Run: sudo systemctl disable docker.socket
I0122 20:51:47.509033 188344 ssh_runner.go:195] Run: sudo systemctl mask docker.service
I0122 20:51:47.624393 188344 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service docker
I0122 20:51:47.638323 188344 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"
I0122 20:51:47.656408 188344 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"
I0122 20:51:47.666007 188344 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"
I0122 20:51:47.675611 188344 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0122 20:51:47.675681 188344 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0122 20:51:47.685098 188344 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"
I0122 20:51:47.694693 188344 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0122 20:51:47.704376 188344 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0122 20:51:47.714046 188344 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0122 20:51:47.723771 188344 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"
I0122 20:51:47.733657 188344 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0122 20:51:47.743242 188344 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)\[plugins."io.containerd.grpc.v1.cri"\]|&\n\1 enable_unprivileged_ports = true|' /etc/containerd/config.toml"
I0122 20:51:47.752803 188344 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0122 20:51:47.761285 188344 crio.go:166] couldn't verify netfilter by "sudo sysctl net.bridge.bridge-nf-call-iptables" which might be okay. error: sudo sysctl net.bridge.bridge-nf-call-iptables: Process exited with status 255
stdout:
stderr:
sysctl: cannot stat /proc/sys/net/bridge/bridge-nf-call-iptables: No such file or directory
I0122 20:51:47.761351 188344 ssh_runner.go:195] Run: sudo modprobe br_netfilter
I0122 20:51:47.773936 188344 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0122 20:51:47.782771 188344 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0122 20:51:47.887860 188344 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0122 20:51:47.914750 188344 start.go:542] Will wait 60s for socket path /run/containerd/containerd.sock
I0122 20:51:47.914835 188344 ssh_runner.go:195] Run: stat /run/containerd/containerd.sock
I0122 20:51:47.920088 188344 retry.go:31] will retry after 857.834211ms: stat /run/containerd/containerd.sock: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/run/containerd/containerd.sock': No such file or directory
I0122 20:51:48.778233 188344 ssh_runner.go:195] Run: stat /run/containerd/containerd.sock
I0122 20:51:48.783177 188344 start.go:563] Will wait 60s for crictl version
I0122 20:51:48.783238 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:48.786633 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl version
I0122 20:51:48.818347 188344 start.go:579] Version: 0.1.0
RuntimeName: containerd
RuntimeVersion: v1.7.23
RuntimeApiVersion: v1
I0122 20:51:48.818412 188344 ssh_runner.go:195] Run: containerd --version
I0122 20:51:48.840492 188344 ssh_runner.go:195] Run: containerd --version
I0122 20:51:48.864943 188344 out.go:177] * Preparing Kubernetes v1.24.4 on containerd 1.7.23 ...
I0122 20:51:48.866323 188344 main.go:141] libmachine: (test-preload-159708) Calling .GetIP
I0122 20:51:48.869004 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:48.869377 188344 main.go:141] libmachine: (test-preload-159708) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:8f:59:8e", ip: ""} in network mk-test-preload-159708: {Iface:virbr1 ExpiryTime:2025-01-22 21:51:37 +0000 UTC Type:0 Mac:52:54:00:8f:59:8e Iaid: IPaddr:192.168.39.64 Prefix:24 Hostname:test-preload-159708 Clientid:01:52:54:00:8f:59:8e}
I0122 20:51:48.869396 188344 main.go:141] libmachine: (test-preload-159708) DBG | domain test-preload-159708 has defined IP address 192.168.39.64 and MAC address 52:54:00:8f:59:8e in network mk-test-preload-159708
I0122 20:51:48.869617 188344 ssh_runner.go:195] Run: grep 192.168.39.1 host.minikube.internal$ /etc/hosts
I0122 20:51:48.873445 188344 ssh_runner.go:195] Run: /bin/bash -c "{ grep -v $'\thost.minikube.internal$' "/etc/hosts"; echo "192.168.39.1 host.minikube.internal"; } > /tmp/h.$$; sudo cp /tmp/h.$$ "/etc/hosts""
I0122 20:51:48.884906 188344 kubeadm.go:883] updating cluster {Name:test-preload-159708 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube/iso/minikube-v1.35.0-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.46@sha256:fd2d445ddcc33ebc5c6b68a17e6219ea207ce63c005095ea1525296da2d1a279 Memory:2200 CPUs:2 DiskSize:20000 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:8443 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-159708 Namespace:defa
ult APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:192.168.39.64 Port:8443 KubernetesVersion:v1.24.4 ContainerRuntime:containerd 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: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMi
rror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} ...
I0122 20:51:48.885003 188344 preload.go:131] Checking if preload exists for k8s version v1.24.4 and runtime containerd
I0122 20:51:48.885042 188344 ssh_runner.go:195] Run: sudo crictl images --output json
I0122 20:51:48.914363 188344 containerd.go:623] couldn't find preloaded image for "registry.k8s.io/kube-apiserver:v1.24.4". assuming images are not preloaded.
I0122 20:51:48.914391 188344 cache_images.go:88] LoadCachedImages start: [registry.k8s.io/kube-apiserver:v1.24.4 registry.k8s.io/kube-controller-manager:v1.24.4 registry.k8s.io/kube-scheduler:v1.24.4 registry.k8s.io/kube-proxy:v1.24.4 registry.k8s.io/pause:3.7 registry.k8s.io/etcd:3.5.3-0 registry.k8s.io/coredns/coredns:v1.8.6 gcr.io/k8s-minikube/storage-provisioner:v5]
I0122 20:51:48.914432 188344 image.go:135] retrieving image: gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:48.914464 188344 image.go:135] retrieving image: registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:48.914480 188344 image.go:135] retrieving image: registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:48.914493 188344 image.go:135] retrieving image: registry.k8s.io/etcd:3.5.3-0
I0122 20:51:48.914525 188344 image.go:135] retrieving image: registry.k8s.io/pause:3.7
I0122 20:51:48.914528 188344 image.go:135] retrieving image: registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:48.914463 188344 image.go:135] retrieving image: registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:48.914573 188344 image.go:135] retrieving image: registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:48.915686 188344 image.go:178] 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
I0122 20:51:48.915704 188344 image.go:178] 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
I0122 20:51:48.915730 188344 image.go:178] 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
I0122 20:51:48.915692 188344 image.go:178] 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
I0122 20:51:48.915790 188344 image.go:178] 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
I0122 20:51:48.915693 188344 image.go:178] daemon lookup for registry.k8s.io/pause:3.7: Error response from daemon: No such image: registry.k8s.io/pause:3.7
I0122 20:51:48.915693 188344 image.go:178] daemon lookup for gcr.io/k8s-minikube/storage-provisioner:v5: Error response from daemon: No such image: gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:48.915818 188344 image.go:178] 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
I0122 20:51:49.045155 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/coredns/coredns:v1.8.6" and sha "a4ca41631cc7ac19ce1be3ebf0314ac5f47af7c711f17066006db82ee3b75b03"
I0122 20:51:49.045215 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:49.057329 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/kube-controller-manager:v1.24.4" and sha "1f99cb6da9a82e81081f65acdad10cdca2e5ec4084f91009bdcff31dd6151d48"
I0122 20:51:49.057387 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:49.058374 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/kube-proxy:v1.24.4" and sha "7a53d1e08ef58144850b48d05908b4ef5b611bff99a5a66dbcba7ab9f79433f7"
I0122 20:51:49.058419 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:49.065559 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/pause:3.7" and sha "221177c6082a88ea4f6240ab2450d540955ac6f4d5454f0e15751b653ebda165"
I0122 20:51:49.065597 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/pause:3.7
I0122 20:51:49.074936 188344 cache_images.go:116] "registry.k8s.io/coredns/coredns:v1.8.6" needs transfer: "registry.k8s.io/coredns/coredns:v1.8.6" does not exist at hash "a4ca41631cc7ac19ce1be3ebf0314ac5f47af7c711f17066006db82ee3b75b03" in container runtime
I0122 20:51:49.074970 188344 cri.go:218] Removing image: registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:49.074995 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.080798 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/etcd:3.5.3-0" and sha "aebe758cef4cd05b9f8cee39758227714d02f42ef3088023c1e3cd454f927a2b"
I0122 20:51:49.080849 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/etcd:3.5.3-0
I0122 20:51:49.097852 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/kube-apiserver:v1.24.4" and sha "6cab9d1bed1be49c215505c1a438ce0af66eb54b4e95f06e52037fcd36631f3d"
I0122 20:51:49.097905 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:49.098695 188344 containerd.go:267] Checking existence of image with name "registry.k8s.io/kube-scheduler:v1.24.4" and sha "03fa22539fc1ccdb96fb15098e7a02fff03d0e366ce5d80891eb0a3a8594a0c9"
I0122 20:51:49.098737 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:49.109185 188344 cache_images.go:116] "registry.k8s.io/kube-controller-manager:v1.24.4" needs transfer: "registry.k8s.io/kube-controller-manager:v1.24.4" does not exist at hash "1f99cb6da9a82e81081f65acdad10cdca2e5ec4084f91009bdcff31dd6151d48" in container runtime
I0122 20:51:49.109232 188344 cri.go:218] Removing image: registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:49.109263 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.113423 188344 cache_images.go:116] "registry.k8s.io/pause:3.7" needs transfer: "registry.k8s.io/pause:3.7" does not exist at hash "221177c6082a88ea4f6240ab2450d540955ac6f4d5454f0e15751b653ebda165" in container runtime
I0122 20:51:49.113453 188344 cri.go:218] Removing image: registry.k8s.io/pause:3.7
I0122 20:51:49.113480 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.113523 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:49.113689 188344 cache_images.go:116] "registry.k8s.io/kube-proxy:v1.24.4" needs transfer: "registry.k8s.io/kube-proxy:v1.24.4" does not exist at hash "7a53d1e08ef58144850b48d05908b4ef5b611bff99a5a66dbcba7ab9f79433f7" in container runtime
I0122 20:51:49.113724 188344 cri.go:218] Removing image: registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:49.113760 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.146262 188344 cache_images.go:116] "registry.k8s.io/etcd:3.5.3-0" needs transfer: "registry.k8s.io/etcd:3.5.3-0" does not exist at hash "aebe758cef4cd05b9f8cee39758227714d02f42ef3088023c1e3cd454f927a2b" in container runtime
I0122 20:51:49.146322 188344 cri.go:218] Removing image: registry.k8s.io/etcd:3.5.3-0
I0122 20:51:49.146371 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.156482 188344 cache_images.go:116] "registry.k8s.io/kube-scheduler:v1.24.4" needs transfer: "registry.k8s.io/kube-scheduler:v1.24.4" does not exist at hash "03fa22539fc1ccdb96fb15098e7a02fff03d0e366ce5d80891eb0a3a8594a0c9" in container runtime
I0122 20:51:49.156533 188344 cri.go:218] Removing image: registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:49.156567 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.156588 188344 cache_images.go:116] "registry.k8s.io/kube-apiserver:v1.24.4" needs transfer: "registry.k8s.io/kube-apiserver:v1.24.4" does not exist at hash "6cab9d1bed1be49c215505c1a438ce0af66eb54b4e95f06e52037fcd36631f3d" in container runtime
I0122 20:51:49.156611 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/pause:3.7
I0122 20:51:49.156631 188344 cri.go:218] Removing image: registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:49.156669 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:49.156569 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:49.183101 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:49.183101 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:49.183155 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/etcd:3.5.3-0
I0122 20:51:49.236082 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:49.236193 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:49.236215 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:49.236311 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/pause:3.7
I0122 20:51:49.307781 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/coredns/coredns:v1.8.6
I0122 20:51:49.307838 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/etcd:3.5.3-0
I0122 20:51:49.309967 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:49.400761 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-controller-manager:v1.24.4
I0122 20:51:49.400806 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:49.400833 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:49.400895 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/pause:3.7
I0122 20:51:49.442955 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6
I0122 20:51:49.443066 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/etcd:3.5.3-0
I0122 20:51:49.443080 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/coredns_v1.8.6
I0122 20:51:49.443066 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-proxy:v1.24.4
I0122 20:51:49.488497 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-scheduler:v1.24.4
I0122 20:51:49.517121 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7
I0122 20:51:49.517224 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/pause_3.7
I0122 20:51:49.520731 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi registry.k8s.io/kube-apiserver:v1.24.4
I0122 20:51:49.520730 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/coredns_v1.8.6: stat -c "%s %y" /var/lib/minikube/images/coredns_v1.8.6: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/coredns_v1.8.6': No such file or directory
I0122 20:51:49.520806 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 --> /var/lib/minikube/images/coredns_v1.8.6 (13586432 bytes)
I0122 20:51:49.520828 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4
I0122 20:51:49.520886 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-controller-manager_v1.24.4
I0122 20:51:49.612480 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0
I0122 20:51:49.612541 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4
I0122 20:51:49.612595 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/etcd_3.5.3-0
I0122 20:51:49.612692 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-proxy_v1.24.4
I0122 20:51:49.616654 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4
I0122 20:51:49.616708 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4
I0122 20:51:49.616722 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-controller-manager_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-controller-manager_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-controller-manager_v1.24.4': No such file or directory
I0122 20:51:49.616753 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 --> /var/lib/minikube/images/kube-controller-manager_v1.24.4 (31047168 bytes)
I0122 20:51:49.616784 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-apiserver_v1.24.4
I0122 20:51:49.616756 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/kube-scheduler_v1.24.4
I0122 20:51:49.616656 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/pause_3.7: stat -c "%s %y" /var/lib/minikube/images/pause_3.7: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/pause_3.7': No such file or directory
I0122 20:51:49.616825 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 --> /var/lib/minikube/images/pause_3.7 (311296 bytes)
I0122 20:51:49.653665 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/etcd_3.5.3-0: stat -c "%s %y" /var/lib/minikube/images/etcd_3.5.3-0: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/etcd_3.5.3-0': No such file or directory
I0122 20:51:49.653725 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 --> /var/lib/minikube/images/etcd_3.5.3-0 (102146048 bytes)
I0122 20:51:49.653729 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-proxy_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-proxy_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-proxy_v1.24.4': No such file or directory
I0122 20:51:49.653766 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 --> /var/lib/minikube/images/kube-proxy_v1.24.4 (39519744 bytes)
I0122 20:51:49.654281 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-apiserver_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-apiserver_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-apiserver_v1.24.4': No such file or directory
I0122 20:51:49.654314 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 --> /var/lib/minikube/images/kube-apiserver_v1.24.4 (33814016 bytes)
I0122 20:51:49.654323 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/kube-scheduler_v1.24.4: stat -c "%s %y" /var/lib/minikube/images/kube-scheduler_v1.24.4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/kube-scheduler_v1.24.4': No such file or directory
I0122 20:51:49.654344 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 --> /var/lib/minikube/images/kube-scheduler_v1.24.4 (15491584 bytes)
I0122 20:51:49.739652 188344 containerd.go:285] Loading image: /var/lib/minikube/images/pause_3.7
I0122 20:51:49.739725 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/pause_3.7
I0122 20:51:49.791643 188344 containerd.go:267] Checking existence of image with name "gcr.io/k8s-minikube/storage-provisioner:v5" and sha "6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562"
I0122 20:51:49.791716 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images ls name==gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:50.347710 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/pause_3.7 from cache
I0122 20:51:50.347758 188344 containerd.go:285] Loading image: /var/lib/minikube/images/coredns_v1.8.6
I0122 20:51:50.347776 188344 cache_images.go:116] "gcr.io/k8s-minikube/storage-provisioner:v5" needs transfer: "gcr.io/k8s-minikube/storage-provisioner:v5" does not exist at hash "6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562" in container runtime
I0122 20:51:50.347814 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/coredns_v1.8.6
I0122 20:51:50.347819 188344 cri.go:218] Removing image: gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:50.347866 188344 ssh_runner.go:195] Run: which crictl
I0122 20:51:51.468082 188344 ssh_runner.go:235] Completed: sudo ctr -n=k8s.io images import /var/lib/minikube/images/coredns_v1.8.6: (1.120237838s)
I0122 20:51:51.468119 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/coredns/coredns_v1.8.6 from cache
I0122 20:51:51.468127 188344 ssh_runner.go:235] Completed: which crictl: (1.120237984s)
I0122 20:51:51.468147 188344 containerd.go:285] Loading image: /var/lib/minikube/images/kube-scheduler_v1.24.4
I0122 20:51:51.468184 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-scheduler_v1.24.4
I0122 20:51:51.468184 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:52.654492 188344 ssh_runner.go:235] Completed: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-scheduler_v1.24.4: (1.186285221s)
I0122 20:51:52.654519 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-scheduler_v1.24.4 from cache
I0122 20:51:52.654519 188344 ssh_runner.go:235] Completed: sudo /usr/bin/crictl rmi gcr.io/k8s-minikube/storage-provisioner:v5: (1.186312522s)
I0122 20:51:52.654546 188344 containerd.go:285] Loading image: /var/lib/minikube/images/kube-apiserver_v1.24.4
I0122 20:51:52.654597 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:52.654605 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-apiserver_v1.24.4
I0122 20:51:54.594006 188344 ssh_runner.go:235] Completed: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-apiserver_v1.24.4: (1.93937381s)
I0122 20:51:54.594037 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-apiserver_v1.24.4 from cache
I0122 20:51:54.594049 188344 ssh_runner.go:235] Completed: sudo /usr/bin/crictl rmi gcr.io/k8s-minikube/storage-provisioner:v5: (1.939429778s)
I0122 20:51:54.594075 188344 containerd.go:285] Loading image: /var/lib/minikube/images/kube-controller-manager_v1.24.4
I0122 20:51:54.594133 188344 ssh_runner.go:195] Run: sudo /usr/bin/crictl rmi gcr.io/k8s-minikube/storage-provisioner:v5
I0122 20:51:54.594141 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-controller-manager_v1.24.4
I0122 20:51:56.967677 188344 ssh_runner.go:235] Completed: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-controller-manager_v1.24.4: (2.373513798s)
I0122 20:51:56.967707 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-controller-manager_v1.24.4 from cache
I0122 20:51:56.967707 188344 ssh_runner.go:235] Completed: sudo /usr/bin/crictl rmi gcr.io/k8s-minikube/storage-provisioner:v5: (2.37354597s)
I0122 20:51:56.967740 188344 containerd.go:285] Loading image: /var/lib/minikube/images/kube-proxy_v1.24.4
I0122 20:51:56.967773 188344 cache_images.go:289] Loading image from: /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5
I0122 20:51:56.967792 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-proxy_v1.24.4
I0122 20:51:56.967868 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/images/storage-provisioner_v5
I0122 20:51:56.972635 188344 ssh_runner.go:352] existence check for /var/lib/minikube/images/storage-provisioner_v5: stat -c "%s %y" /var/lib/minikube/images/storage-provisioner_v5: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/images/storage-provisioner_v5': No such file or directory
I0122 20:51:56.972674 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 --> /var/lib/minikube/images/storage-provisioner_v5 (9060352 bytes)
I0122 20:51:59.446665 188344 ssh_runner.go:235] Completed: sudo ctr -n=k8s.io images import /var/lib/minikube/images/kube-proxy_v1.24.4: (2.478844928s)
I0122 20:51:59.446693 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/kube-proxy_v1.24.4 from cache
I0122 20:51:59.446716 188344 containerd.go:285] Loading image: /var/lib/minikube/images/etcd_3.5.3-0
I0122 20:51:59.446764 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/etcd_3.5.3-0
I0122 20:52:05.020346 188344 ssh_runner.go:235] Completed: sudo ctr -n=k8s.io images import /var/lib/minikube/images/etcd_3.5.3-0: (5.573549926s)
I0122 20:52:05.020377 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/registry.k8s.io/etcd_3.5.3-0 from cache
I0122 20:52:05.020407 188344 containerd.go:285] Loading image: /var/lib/minikube/images/storage-provisioner_v5
I0122 20:52:05.020456 188344 ssh_runner.go:195] Run: sudo ctr -n=k8s.io images import /var/lib/minikube/images/storage-provisioner_v5
I0122 20:52:05.586012 188344 cache_images.go:321] Transferred and loaded /home/jenkins/minikube-integration/20288-150966/.minikube/cache/images/amd64/gcr.io/k8s-minikube/storage-provisioner_v5 from cache
I0122 20:52:05.586061 188344 cache_images.go:123] Successfully loaded all cached images
I0122 20:52:05.586069 188344 cache_images.go:92] duration metric: took 16.671666215s to LoadCachedImages
I0122 20:52:05.586085 188344 kubeadm.go:934] updating node { 192.168.39.64 8443 v1.24.4 containerd true true} ...
I0122 20:52:05.586212 188344 kubeadm.go:946] kubelet [Unit]
Wants=containerd.service
[Service]
ExecStart=
ExecStart=/var/lib/minikube/binaries/v1.24.4/kubelet --bootstrap-kubeconfig=/etc/kubernetes/bootstrap-kubelet.conf --config=/var/lib/kubelet/config.yaml --container-runtime-endpoint=unix:///run/containerd/containerd.sock --hostname-override=test-preload-159708 --kubeconfig=/etc/kubernetes/kubelet.conf --node-ip=192.168.39.64
[Install]
config:
{KubernetesVersion:v1.24.4 ClusterName:test-preload-159708 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:}
I0122 20:52:05.586278 188344 ssh_runner.go:195] Run: sudo crictl info
I0122 20:52:05.619010 188344 cni.go:84] Creating CNI manager for ""
I0122 20:52:05.619077 188344 cni.go:146] "kvm2" driver + "containerd" runtime found, recommending bridge
I0122 20:52:05.619094 188344 kubeadm.go:84] Using pod CIDR: 10.244.0.0/16
I0122 20:52:05.619120 188344 kubeadm.go:189] kubeadm options: {CertDir:/var/lib/minikube/certs ServiceCIDR:10.96.0.0/12 PodSubnet:10.244.0.0/16 AdvertiseAddress:192.168.39.64 APIServerPort:8443 KubernetesVersion:v1.24.4 EtcdDataDir:/var/lib/minikube/etcd EtcdExtraArgs:map[] ClusterName:test-preload-159708 NodeName:test-preload-159708 DNSDomain:cluster.local CRISocket:/run/containerd/containerd.sock ImageRepository: ComponentOptions:[{Component:apiServer ExtraArgs:map[enable-admission-plugins:NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota] Pairs:map[certSANs:["127.0.0.1", "localhost", "192.168.39.64"]]} {Component:controllerManager ExtraArgs:map[allocate-node-cidrs:true leader-elect:false] Pairs:map[]} {Component:scheduler ExtraArgs:map[leader-elect:false] Pairs:map[]}] FeatureArgs:map[] NodeIP:192.168.39.64 CgroupDriver:cgroupfs ClientCAFile:/var/lib/minikube/certs/ca.crt Sta
ticPodPath:/etc/kubernetes/manifests ControlPlaneAddress:control-plane.minikube.internal KubeProxyOptions:map[] ResolvConfSearchRegression:false KubeletConfigOpts:map[hairpinMode:hairpin-veth runtimeRequestTimeout:15m] PrependCriSocketUnix:true}
I0122 20:52:05.619251 188344 kubeadm.go:195] kubeadm config:
apiVersion: kubeadm.k8s.io/v1beta3
kind: InitConfiguration
localAPIEndpoint:
advertiseAddress: 192.168.39.64
bindPort: 8443
bootstrapTokens:
- groups:
- system:bootstrappers:kubeadm:default-node-token
ttl: 24h0m0s
usages:
- signing
- authentication
nodeRegistration:
criSocket: unix:///run/containerd/containerd.sock
name: "test-preload-159708"
kubeletExtraArgs:
node-ip: 192.168.39.64
taints: []
---
apiVersion: kubeadm.k8s.io/v1beta3
kind: ClusterConfiguration
apiServer:
certSANs: ["127.0.0.1", "localhost", "192.168.39.64"]
extraArgs:
enable-admission-plugins: "NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota"
controllerManager:
extraArgs:
allocate-node-cidrs: "true"
leader-elect: "false"
scheduler:
extraArgs:
leader-elect: "false"
certificatesDir: /var/lib/minikube/certs
clusterName: mk
controlPlaneEndpoint: control-plane.minikube.internal:8443
etcd:
local:
dataDir: /var/lib/minikube/etcd
extraArgs:
proxy-refresh-interval: "70000"
kubernetesVersion: v1.24.4
networking:
dnsDomain: cluster.local
podSubnet: "10.244.0.0/16"
serviceSubnet: 10.96.0.0/12
---
apiVersion: kubelet.config.k8s.io/v1beta1
kind: KubeletConfiguration
authentication:
x509:
clientCAFile: /var/lib/minikube/certs/ca.crt
cgroupDriver: cgroupfs
hairpinMode: hairpin-veth
runtimeRequestTimeout: 15m
clusterDomain: "cluster.local"
# disable disk resource management by default
imageGCHighThresholdPercent: 100
evictionHard:
nodefs.available: "0%"
nodefs.inodesFree: "0%"
imagefs.available: "0%"
failSwapOn: false
staticPodPath: /etc/kubernetes/manifests
---
apiVersion: kubeproxy.config.k8s.io/v1alpha1
kind: KubeProxyConfiguration
clusterCIDR: "10.244.0.0/16"
metricsBindAddress: 0.0.0.0:10249
conntrack:
maxPerCore: 0
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_established"
tcpEstablishedTimeout: 0s
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_close"
tcpCloseWaitTimeout: 0s
I0122 20:52:05.619336 188344 ssh_runner.go:195] Run: sudo ls /var/lib/minikube/binaries/v1.24.4
I0122 20:52:05.628955 188344 binaries.go:47] Didn't find k8s binaries: sudo ls /var/lib/minikube/binaries/v1.24.4: Process exited with status 2
stdout:
stderr:
ls: cannot access '/var/lib/minikube/binaries/v1.24.4': No such file or directory
Initiating transfer...
I0122 20:52:05.629027 188344 ssh_runner.go:195] Run: sudo mkdir -p /var/lib/minikube/binaries/v1.24.4
I0122 20:52:05.638273 188344 download.go:108] Downloading: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl.sha256 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubectl
I0122 20:52:05.638305 188344 download.go:108] Downloading: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubeadm.sha256 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubeadm
I0122 20:52:05.638277 188344 download.go:108] Downloading: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubelet?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubelet.sha256 -> /home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubelet
I0122 20:52:06.648278 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubeadm
I0122 20:52:06.655624 188344 ssh_runner.go:352] existence check for /var/lib/minikube/binaries/v1.24.4/kubeadm: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubeadm: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/binaries/v1.24.4/kubeadm': No such file or directory
I0122 20:52:06.655665 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubeadm --> /var/lib/minikube/binaries/v1.24.4/kubeadm (44384256 bytes)
I0122 20:52:07.120746 188344 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service kubelet
I0122 20:52:07.134603 188344 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubelet
I0122 20:52:07.138805 188344 ssh_runner.go:352] existence check for /var/lib/minikube/binaries/v1.24.4/kubelet: stat -c "%s %y" /var/lib/minikube/binaries/v1.24.4/kubelet: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/binaries/v1.24.4/kubelet': No such file or directory
I0122 20:52:07.138849 188344 ssh_runner.go:362] scp /home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubelet --> /var/lib/minikube/binaries/v1.24.4/kubelet (116062680 bytes)
I0122 20:52:07.564481 188344 out.go:201]
W0122 20:52:07.565914 188344 out.go:270] X Exiting due to K8S_INSTALL_FAILED: Failed to update cluster: update primary control-plane node: downloading binaries: downloading kubectl: download failed: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl.sha256: getter: &{Ctx:context.Background Src:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl.sha256 Dst:/home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubectl.download Pwd: Mode:2 Umask:---------- Detectors:[0x59db560 0x59db560 0x59db560 0x59db560 0x59db560 0x59db560 0x59db560] Decompressors:map[bz2:0xc0007134d8 gz:0xc000713570 tar:0xc000713520 tar.bz2:0xc000713530 tar.gz:0xc000713540 tar.xz:0xc000713550 tar.zst:0xc000713560 tbz2:0xc000713530 tgz:0xc000713540 txz:0xc000713550 tzst:0xc000713560 xz:0xc000713578 zip:0xc000713580 zst:0xc000713590] Getters:map[file
:0xc001c38310 http:0xc0008bb180 https:0xc0008bb400] Dir:false ProgressListener:<nil> Insecure:false DisableSymlinks:false Options:[]}: read tcp 10.138.0.48:57040->151.101.193.55:443: read: connection reset by peer
X Exiting due to K8S_INSTALL_FAILED: Failed to update cluster: update primary control-plane node: downloading binaries: downloading kubectl: download failed: https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl.sha256: getter: &{Ctx:context.Background Src:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.24.4/bin/linux/amd64/kubectl.sha256 Dst:/home/jenkins/minikube-integration/20288-150966/.minikube/cache/linux/amd64/v1.24.4/kubectl.download Pwd: Mode:2 Umask:---------- Detectors:[0x59db560 0x59db560 0x59db560 0x59db560 0x59db560 0x59db560 0x59db560] Decompressors:map[bz2:0xc0007134d8 gz:0xc000713570 tar:0xc000713520 tar.bz2:0xc000713530 tar.gz:0xc000713540 tar.xz:0xc000713550 tar.zst:0xc000713560 tbz2:0xc000713530 tgz:0xc000713540 txz:0xc000713550 tzst:0xc000713560 xz:0xc000713578 zip:0xc000713580 zst:0xc000713590] Getters:map[file:0xc001c38310 http:0xc0008bb180 https:0xc0
008bb400] Dir:false ProgressListener:<nil> Insecure:false DisableSymlinks:false Options:[]}: read tcp 10.138.0.48:57040->151.101.193.55:443: read: connection reset by peer
W0122 20:52:07.565942 188344 out.go:270] *
*
W0122 20:52:07.566963 188344 out.go:293] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0122 20:52:07.568531 188344 out.go:201]
** /stderr **
preload_test.go:46: out/minikube-linux-amd64 start -p test-preload-159708 --memory=2200 --alsologtostderr --wait=true --preload=false --driver=kvm2 --container-runtime=containerd --kubernetes-version=v1.24.4 failed: exit status 100
panic.go:629: *** TestPreload FAILED at 2025-01-22 20:52:07.597046796 +0000 UTC m=+3255.243842349
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-linux-amd64 status --format={{.Host}} -p test-preload-159708 -n test-preload-159708
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-linux-amd64 status --format={{.Host}} -p test-preload-159708 -n test-preload-159708: exit status 6 (237.198225ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0122 20:52:07.818803 188685 status.go:458] kubeconfig endpoint: get endpoint: "test-preload-159708" does not appear in /home/jenkins/minikube-integration/20288-150966/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "test-preload-159708" 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-159708" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-linux-amd64 delete -p test-preload-159708
helpers_test.go:178: (dbg) Done: out/minikube-linux-amd64 delete -p test-preload-159708: (1.23636153s)
--- FAIL: TestPreload (46.15s)