=== RUN TestMultiNode/serial/FreshStart2Nodes
multinode_test.go:96: (dbg) Run: out/minikube-linux-amd64 start -p multinode-956988 --wait=true --memory=2200 --nodes=2 -v=8 --alsologtostderr --driver=kvm2
E0816 12:54:10.002808 10846 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19423-3666/.minikube/profiles/addons-516481/client.crt: no such file or directory" logger="UnhandledError"
multinode_test.go:96: (dbg) Non-zero exit: out/minikube-linux-amd64 start -p multinode-956988 --wait=true --memory=2200 --nodes=2 -v=8 --alsologtostderr --driver=kvm2 : exit status 90 (1m25.240886237s)
-- stdout --
* [multinode-956988] minikube v1.33.1 on Ubuntu 20.04 (kvm/amd64)
- MINIKUBE_LOCATION=19423
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- KUBECONFIG=/home/jenkins/minikube-integration/19423-3666/kubeconfig
- MINIKUBE_HOME=/home/jenkins/minikube-integration/19423-3666/.minikube
- MINIKUBE_BIN=out/minikube-linux-amd64
- MINIKUBE_FORCE_SYSTEMD=
* Using the kvm2 driver based on user configuration
* Starting "multinode-956988" primary control-plane node in "multinode-956988" cluster
* Creating kvm2 VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
-- /stdout --
** stderr **
I0816 12:52:55.579269 32808 out.go:345] Setting OutFile to fd 1 ...
I0816 12:52:55.579357 32808 out.go:392] TERM=,COLORTERM=, which probably does not support color
I0816 12:52:55.579365 32808 out.go:358] Setting ErrFile to fd 2...
I0816 12:52:55.579369 32808 out.go:392] TERM=,COLORTERM=, which probably does not support color
I0816 12:52:55.579555 32808 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19423-3666/.minikube/bin
I0816 12:52:55.580101 32808 out.go:352] Setting JSON to false
I0816 12:52:55.580968 32808 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-15","uptime":2076,"bootTime":1723810700,"procs":193,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1066-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}
I0816 12:52:55.581021 32808 start.go:139] virtualization: kvm guest
I0816 12:52:55.582915 32808 out.go:177] * [multinode-956988] minikube v1.33.1 on Ubuntu 20.04 (kvm/amd64)
I0816 12:52:55.584060 32808 out.go:177] - MINIKUBE_LOCATION=19423
I0816 12:52:55.584066 32808 notify.go:220] Checking for updates...
I0816 12:52:55.585077 32808 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0816 12:52:55.586112 32808 out.go:177] - KUBECONFIG=/home/jenkins/minikube-integration/19423-3666/kubeconfig
I0816 12:52:55.587257 32808 out.go:177] - MINIKUBE_HOME=/home/jenkins/minikube-integration/19423-3666/.minikube
I0816 12:52:55.588391 32808 out.go:177] - MINIKUBE_BIN=out/minikube-linux-amd64
I0816 12:52:55.589455 32808 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0816 12:52:55.590643 32808 driver.go:394] Setting default libvirt URI to qemu:///system
I0816 12:52:55.623896 32808 out.go:177] * Using the kvm2 driver based on user configuration
I0816 12:52:55.624869 32808 start.go:297] selected driver: kvm2
I0816 12:52:55.624884 32808 start.go:901] validating driver "kvm2" against <nil>
I0816 12:52:55.624894 32808 start.go:912] status for kvm2: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0816 12:52:55.625514 32808 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0816 12:52:55.625569 32808 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/minikube-integration/19423-3666/.minikube/bin:/home/jenkins/workspace/KVM_Linux_integration/out/:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games:/usr/local/go/bin:/home/jenkins/go/bin:/usr/local/bin/:/usr/local/go/bin/:/home/jenkins/go/bin
I0816 12:52:55.639458 32808 install.go:137] /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2 version is 1.33.1
I0816 12:52:55.639504 32808 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0816 12:52:55.639687 32808 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]
I0816 12:52:55.639715 32808 cni.go:84] Creating CNI manager for ""
I0816 12:52:55.639722 32808 cni.go:136] multinode detected (0 nodes found), recommending kindnet
I0816 12:52:55.639729 32808 start_flags.go:319] Found "CNI" CNI - setting NetworkPlugin=cni
I0816 12:52:55.639781 32808 start.go:340] cluster config:
{Name:multinode-956988 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723650208-19443@sha256:2be48dc5c74cde3c1d15ac913a640f4a2331b48358b81777568fb487d2757002 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.31.0 ClusterName:multinode-956988 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRun
time:docker 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.31.0 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:true 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: SSHAuthSo
ck: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0816 12:52:55.639911 32808 iso.go:125] acquiring lock: {Name:mkf48be7133c2553607c4c02072c5489b70d60e2 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0816 12:52:55.641484 32808 out.go:177] * Starting "multinode-956988" primary control-plane node in "multinode-956988" cluster
I0816 12:52:55.642765 32808 preload.go:131] Checking if preload exists for k8s version v1.31.0 and runtime docker
I0816 12:52:55.642789 32808 preload.go:146] Found local preload: /home/jenkins/minikube-integration/19423-3666/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.0-docker-overlay2-amd64.tar.lz4
I0816 12:52:55.642795 32808 cache.go:56] Caching tarball of preloaded images
I0816 12:52:55.642861 32808 preload.go:172] Found /home/jenkins/minikube-integration/19423-3666/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.0-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0816 12:52:55.642870 32808 cache.go:59] Finished verifying existence of preloaded tar for v1.31.0 on docker
I0816 12:52:55.643165 32808 profile.go:143] Saving config to /home/jenkins/minikube-integration/19423-3666/.minikube/profiles/multinode-956988/config.json ...
I0816 12:52:55.643185 32808 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19423-3666/.minikube/profiles/multinode-956988/config.json: {Name:mkb95b8ab85372022ff7efb9f655795a57ac1889 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0816 12:52:55.643303 32808 start.go:360] acquireMachinesLock for multinode-956988: {Name:mkca69b29529bd4034b087166360bcc3cc2cb453 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0816 12:52:55.643332 32808 start.go:364] duration metric: took 17.799µs to acquireMachinesLock for "multinode-956988"
I0816 12:52:55.643353 32808 start.go:93] Provisioning new machine with config: &{Name:multinode-956988 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19443/minikube-v1.33.1-1723650137-19443-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723650208-19443@sha256:2be48dc5c74cde3c1d15ac913a640f4a2331b48358b81777568fb487d2757002 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:{Ku
bernetesVersion:v1.31.0 ClusterName:multinode-956988 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.31.0 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:true ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] Moun
tPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.31.0 ContainerRuntime:docker ControlPlane:true Worker:true}
I0816 12:52:55.643404 32808 start.go:125] createHost starting for "" (driver="kvm2")
I0816 12:52:55.645078 32808 out.go:235] * Creating kvm2 VM (CPUs=2, Memory=2200MB, Disk=20000MB) ...
I0816 12:52:55.645190 32808 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2
I0816 12:52:55.645225 32808 main.go:141] libmachine: Launching plugin server for driver kvm2
I0816 12:52:55.659393 32808 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:45713
I0816 12:52:55.659846 32808 main.go:141] libmachine: () Calling .GetVersion
I0816 12:52:55.660350 32808 main.go:141] libmachine: Using API Version 1
I0816 12:52:55.660369 32808 main.go:141] libmachine: () Calling .SetConfigRaw
I0816 12:52:55.660677 32808 main.go:141] libmachine: () Calling .GetMachineName
I0816 12:52:55.660872 32808 main.go:141] libmachine: (multinode-956988) Calling .GetMachineName
I0816 12:52:55.661023 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:52:55.661184 32808 start.go:159] libmachine.API.Create for "multinode-956988" (driver="kvm2")
I0816 12:52:55.661208 32808 client.go:168] LocalClient.Create starting
I0816 12:52:55.661248 32808 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem
I0816 12:52:55.661285 32808 main.go:141] libmachine: Decoding PEM data...
I0816 12:52:55.661305 32808 main.go:141] libmachine: Parsing certificate...
I0816 12:52:55.661357 32808 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/19423-3666/.minikube/certs/cert.pem
I0816 12:52:55.661374 32808 main.go:141] libmachine: Decoding PEM data...
I0816 12:52:55.661387 32808 main.go:141] libmachine: Parsing certificate...
I0816 12:52:55.661402 32808 main.go:141] libmachine: Running pre-create checks...
I0816 12:52:55.661414 32808 main.go:141] libmachine: (multinode-956988) Calling .PreCreateCheck
I0816 12:52:55.661703 32808 main.go:141] libmachine: (multinode-956988) Calling .GetConfigRaw
I0816 12:52:55.662051 32808 main.go:141] libmachine: Creating machine...
I0816 12:52:55.662063 32808 main.go:141] libmachine: (multinode-956988) Calling .Create
I0816 12:52:55.662163 32808 main.go:141] libmachine: (multinode-956988) Creating KVM machine...
I0816 12:52:55.663255 32808 main.go:141] libmachine: (multinode-956988) DBG | found existing default KVM network
I0816 12:52:55.663895 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:55.663765 32831 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:0xc0001831f0}
I0816 12:52:55.663918 32808 main.go:141] libmachine: (multinode-956988) DBG | created network xml:
I0816 12:52:55.663930 32808 main.go:141] libmachine: (multinode-956988) DBG | <network>
I0816 12:52:55.663944 32808 main.go:141] libmachine: (multinode-956988) DBG | <name>mk-multinode-956988</name>
I0816 12:52:55.663957 32808 main.go:141] libmachine: (multinode-956988) DBG | <dns enable='no'/>
I0816 12:52:55.663968 32808 main.go:141] libmachine: (multinode-956988) DBG |
I0816 12:52:55.663977 32808 main.go:141] libmachine: (multinode-956988) DBG | <ip address='192.168.39.1' netmask='255.255.255.0'>
I0816 12:52:55.663985 32808 main.go:141] libmachine: (multinode-956988) DBG | <dhcp>
I0816 12:52:55.663991 32808 main.go:141] libmachine: (multinode-956988) DBG | <range start='192.168.39.2' end='192.168.39.253'/>
I0816 12:52:55.663998 32808 main.go:141] libmachine: (multinode-956988) DBG | </dhcp>
I0816 12:52:55.664005 32808 main.go:141] libmachine: (multinode-956988) DBG | </ip>
I0816 12:52:55.664011 32808 main.go:141] libmachine: (multinode-956988) DBG |
I0816 12:52:55.664044 32808 main.go:141] libmachine: (multinode-956988) DBG | </network>
I0816 12:52:55.664066 32808 main.go:141] libmachine: (multinode-956988) DBG |
I0816 12:52:55.668813 32808 main.go:141] libmachine: (multinode-956988) DBG | trying to create private KVM network mk-multinode-956988 192.168.39.0/24...
I0816 12:52:55.734115 32808 main.go:141] libmachine: (multinode-956988) Setting up store path in /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988 ...
I0816 12:52:55.734140 32808 main.go:141] libmachine: (multinode-956988) Building disk image from file:///home/jenkins/minikube-integration/19423-3666/.minikube/cache/iso/amd64/minikube-v1.33.1-1723650137-19443-amd64.iso
I0816 12:52:55.734151 32808 main.go:141] libmachine: (multinode-956988) DBG | private KVM network mk-multinode-956988 192.168.39.0/24 created
I0816 12:52:55.734168 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:55.734062 32831 common.go:145] Making disk image using store path: /home/jenkins/minikube-integration/19423-3666/.minikube
I0816 12:52:55.734201 32808 main.go:141] libmachine: (multinode-956988) Downloading /home/jenkins/minikube-integration/19423-3666/.minikube/cache/boot2docker.iso from file:///home/jenkins/minikube-integration/19423-3666/.minikube/cache/iso/amd64/minikube-v1.33.1-1723650137-19443-amd64.iso...
I0816 12:52:55.967392 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:55.967286 32831 common.go:152] Creating ssh key: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa...
I0816 12:52:56.105181 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:56.105063 32831 common.go:158] Creating raw disk image: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/multinode-956988.rawdisk...
I0816 12:52:56.105207 32808 main.go:141] libmachine: (multinode-956988) DBG | Writing magic tar header
I0816 12:52:56.105219 32808 main.go:141] libmachine: (multinode-956988) DBG | Writing SSH key tar header
I0816 12:52:56.105227 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:56.105180 32831 common.go:172] Fixing permissions on /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988 ...
I0816 12:52:56.105256 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988
I0816 12:52:56.105307 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19423-3666/.minikube/machines
I0816 12:52:56.105325 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19423-3666/.minikube
I0816 12:52:56.105363 32808 main.go:141] libmachine: (multinode-956988) Setting executable bit set on /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988 (perms=drwx------)
I0816 12:52:56.105388 32808 main.go:141] libmachine: (multinode-956988) Setting executable bit set on /home/jenkins/minikube-integration/19423-3666/.minikube/machines (perms=drwxr-xr-x)
I0816 12:52:56.105406 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19423-3666
I0816 12:52:56.105421 32808 main.go:141] libmachine: (multinode-956988) Setting executable bit set on /home/jenkins/minikube-integration/19423-3666/.minikube (perms=drwxr-xr-x)
I0816 12:52:56.105435 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home/jenkins/minikube-integration
I0816 12:52:56.105446 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home/jenkins
I0816 12:52:56.105457 32808 main.go:141] libmachine: (multinode-956988) DBG | Checking permissions on dir: /home
I0816 12:52:56.105466 32808 main.go:141] libmachine: (multinode-956988) DBG | Skipping /home - not owner
I0816 12:52:56.105475 32808 main.go:141] libmachine: (multinode-956988) Setting executable bit set on /home/jenkins/minikube-integration/19423-3666 (perms=drwxrwxr-x)
I0816 12:52:56.105487 32808 main.go:141] libmachine: (multinode-956988) Setting executable bit set on /home/jenkins/minikube-integration (perms=drwxrwxr-x)
I0816 12:52:56.105500 32808 main.go:141] libmachine: (multinode-956988) Setting executable bit set on /home/jenkins (perms=drwxr-xr-x)
I0816 12:52:56.105512 32808 main.go:141] libmachine: (multinode-956988) Creating domain...
I0816 12:52:56.106508 32808 main.go:141] libmachine: (multinode-956988) define libvirt domain using xml:
I0816 12:52:56.106525 32808 main.go:141] libmachine: (multinode-956988) <domain type='kvm'>
I0816 12:52:56.106532 32808 main.go:141] libmachine: (multinode-956988) <name>multinode-956988</name>
I0816 12:52:56.106538 32808 main.go:141] libmachine: (multinode-956988) <memory unit='MiB'>2200</memory>
I0816 12:52:56.106546 32808 main.go:141] libmachine: (multinode-956988) <vcpu>2</vcpu>
I0816 12:52:56.106560 32808 main.go:141] libmachine: (multinode-956988) <features>
I0816 12:52:56.106597 32808 main.go:141] libmachine: (multinode-956988) <acpi/>
I0816 12:52:56.106608 32808 main.go:141] libmachine: (multinode-956988) <apic/>
I0816 12:52:56.106620 32808 main.go:141] libmachine: (multinode-956988) <pae/>
I0816 12:52:56.106632 32808 main.go:141] libmachine: (multinode-956988)
I0816 12:52:56.106666 32808 main.go:141] libmachine: (multinode-956988) </features>
I0816 12:52:56.106689 32808 main.go:141] libmachine: (multinode-956988) <cpu mode='host-passthrough'>
I0816 12:52:56.106699 32808 main.go:141] libmachine: (multinode-956988)
I0816 12:52:56.106715 32808 main.go:141] libmachine: (multinode-956988) </cpu>
I0816 12:52:56.106770 32808 main.go:141] libmachine: (multinode-956988) <os>
I0816 12:52:56.106801 32808 main.go:141] libmachine: (multinode-956988) <type>hvm</type>
I0816 12:52:56.106820 32808 main.go:141] libmachine: (multinode-956988) <boot dev='cdrom'/>
I0816 12:52:56.106839 32808 main.go:141] libmachine: (multinode-956988) <boot dev='hd'/>
I0816 12:52:56.106849 32808 main.go:141] libmachine: (multinode-956988) <bootmenu enable='no'/>
I0816 12:52:56.106854 32808 main.go:141] libmachine: (multinode-956988) </os>
I0816 12:52:56.106866 32808 main.go:141] libmachine: (multinode-956988) <devices>
I0816 12:52:56.106876 32808 main.go:141] libmachine: (multinode-956988) <disk type='file' device='cdrom'>
I0816 12:52:56.106905 32808 main.go:141] libmachine: (multinode-956988) <source file='/home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/boot2docker.iso'/>
I0816 12:52:56.106921 32808 main.go:141] libmachine: (multinode-956988) <target dev='hdc' bus='scsi'/>
I0816 12:52:56.106933 32808 main.go:141] libmachine: (multinode-956988) <readonly/>
I0816 12:52:56.106943 32808 main.go:141] libmachine: (multinode-956988) </disk>
I0816 12:52:56.106955 32808 main.go:141] libmachine: (multinode-956988) <disk type='file' device='disk'>
I0816 12:52:56.106965 32808 main.go:141] libmachine: (multinode-956988) <driver name='qemu' type='raw' cache='default' io='threads' />
I0816 12:52:56.106981 32808 main.go:141] libmachine: (multinode-956988) <source file='/home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/multinode-956988.rawdisk'/>
I0816 12:52:56.106997 32808 main.go:141] libmachine: (multinode-956988) <target dev='hda' bus='virtio'/>
I0816 12:52:56.107009 32808 main.go:141] libmachine: (multinode-956988) </disk>
I0816 12:52:56.107019 32808 main.go:141] libmachine: (multinode-956988) <interface type='network'>
I0816 12:52:56.107031 32808 main.go:141] libmachine: (multinode-956988) <source network='mk-multinode-956988'/>
I0816 12:52:56.107041 32808 main.go:141] libmachine: (multinode-956988) <model type='virtio'/>
I0816 12:52:56.107049 32808 main.go:141] libmachine: (multinode-956988) </interface>
I0816 12:52:56.107060 32808 main.go:141] libmachine: (multinode-956988) <interface type='network'>
I0816 12:52:56.107078 32808 main.go:141] libmachine: (multinode-956988) <source network='default'/>
I0816 12:52:56.107092 32808 main.go:141] libmachine: (multinode-956988) <model type='virtio'/>
I0816 12:52:56.107103 32808 main.go:141] libmachine: (multinode-956988) </interface>
I0816 12:52:56.107114 32808 main.go:141] libmachine: (multinode-956988) <serial type='pty'>
I0816 12:52:56.107135 32808 main.go:141] libmachine: (multinode-956988) <target port='0'/>
I0816 12:52:56.107151 32808 main.go:141] libmachine: (multinode-956988) </serial>
I0816 12:52:56.107164 32808 main.go:141] libmachine: (multinode-956988) <console type='pty'>
I0816 12:52:56.107176 32808 main.go:141] libmachine: (multinode-956988) <target type='serial' port='0'/>
I0816 12:52:56.107187 32808 main.go:141] libmachine: (multinode-956988) </console>
I0816 12:52:56.107196 32808 main.go:141] libmachine: (multinode-956988) <rng model='virtio'>
I0816 12:52:56.107217 32808 main.go:141] libmachine: (multinode-956988) <backend model='random'>/dev/random</backend>
I0816 12:52:56.107230 32808 main.go:141] libmachine: (multinode-956988) </rng>
I0816 12:52:56.107245 32808 main.go:141] libmachine: (multinode-956988)
I0816 12:52:56.107258 32808 main.go:141] libmachine: (multinode-956988)
I0816 12:52:56.107282 32808 main.go:141] libmachine: (multinode-956988) </devices>
I0816 12:52:56.107301 32808 main.go:141] libmachine: (multinode-956988) </domain>
I0816 12:52:56.107317 32808 main.go:141] libmachine: (multinode-956988)
I0816 12:52:56.112662 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:a5:d4:09 in network default
I0816 12:52:56.113245 32808 main.go:141] libmachine: (multinode-956988) Ensuring networks are active...
I0816 12:52:56.113261 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:56.113938 32808 main.go:141] libmachine: (multinode-956988) Ensuring network default is active
I0816 12:52:56.114216 32808 main.go:141] libmachine: (multinode-956988) Ensuring network mk-multinode-956988 is active
I0816 12:52:56.114684 32808 main.go:141] libmachine: (multinode-956988) Getting domain xml...
I0816 12:52:56.115319 32808 main.go:141] libmachine: (multinode-956988) Creating domain...
I0816 12:52:57.293348 32808 main.go:141] libmachine: (multinode-956988) Waiting to get IP...
I0816 12:52:57.294102 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:57.294455 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:52:57.294474 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:57.294446 32831 retry.go:31] will retry after 195.361145ms: waiting for machine to come up
I0816 12:52:57.492048 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:57.492562 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:52:57.492604 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:57.492513 32831 retry.go:31] will retry after 376.3129ms: waiting for machine to come up
I0816 12:52:57.869986 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:57.870394 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:52:57.870426 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:57.870369 32831 retry.go:31] will retry after 364.17558ms: waiting for machine to come up
I0816 12:52:58.235861 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:58.236336 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:52:58.236370 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:58.236306 32831 retry.go:31] will retry after 584.699333ms: waiting for machine to come up
I0816 12:52:58.822562 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:58.822934 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:52:58.822957 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:58.822903 32831 retry.go:31] will retry after 588.364968ms: waiting for machine to come up
I0816 12:52:59.412871 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:52:59.413278 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:52:59.413323 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:52:59.413269 32831 retry.go:31] will retry after 587.016629ms: waiting for machine to come up
I0816 12:53:00.002092 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:00.002471 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:00.002544 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:00.002440 32831 retry.go:31] will retry after 969.909198ms: waiting for machine to come up
I0816 12:53:00.974180 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:00.974524 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:00.974554 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:00.974469 32831 retry.go:31] will retry after 1.360032008s: waiting for machine to come up
I0816 12:53:02.336967 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:02.337361 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:02.337390 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:02.337313 32831 retry.go:31] will retry after 1.747683572s: waiting for machine to come up
I0816 12:53:04.087075 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:04.087358 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:04.087389 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:04.087308 32831 retry.go:31] will retry after 2.229887672s: waiting for machine to come up
I0816 12:53:06.318736 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:06.319110 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:06.319137 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:06.319071 32831 retry.go:31] will retry after 2.691309659s: waiting for machine to come up
I0816 12:53:09.013841 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:09.014255 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:09.014282 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:09.014208 32831 retry.go:31] will retry after 3.199034783s: waiting for machine to come up
I0816 12:53:12.214955 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:12.215369 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find current IP address of domain multinode-956988 in network mk-multinode-956988
I0816 12:53:12.215414 32808 main.go:141] libmachine: (multinode-956988) DBG | I0816 12:53:12.215348 32831 retry.go:31] will retry after 3.257244604s: waiting for machine to come up
I0816 12:53:15.476809 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.477212 32808 main.go:141] libmachine: (multinode-956988) Found IP for machine: 192.168.39.144
I0816 12:53:15.477230 32808 main.go:141] libmachine: (multinode-956988) Reserving static IP address...
I0816 12:53:15.477243 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has current primary IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.477576 32808 main.go:141] libmachine: (multinode-956988) DBG | unable to find host DHCP lease matching {name: "multinode-956988", mac: "52:54:00:87:14:81", ip: "192.168.39.144"} in network mk-multinode-956988
I0816 12:53:15.547713 32808 main.go:141] libmachine: (multinode-956988) DBG | Getting to WaitForSSH function...
I0816 12:53:15.547749 32808 main.go:141] libmachine: (multinode-956988) Reserved static IP address: 192.168.39.144
I0816 12:53:15.547762 32808 main.go:141] libmachine: (multinode-956988) Waiting for SSH to be available...
I0816 12:53:15.550458 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.550996 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:minikube Clientid:01:52:54:00:87:14:81}
I0816 12:53:15.551026 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.551177 32808 main.go:141] libmachine: (multinode-956988) DBG | Using SSH client type: external
I0816 12:53:15.551204 32808 main.go:141] libmachine: (multinode-956988) DBG | Using SSH private key: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa (-rw-------)
I0816 12:53:15.551244 32808 main.go:141] libmachine: (multinode-956988) 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.144 -o IdentitiesOnly=yes -i /home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa -p 22] /usr/bin/ssh <nil>}
I0816 12:53:15.551258 32808 main.go:141] libmachine: (multinode-956988) DBG | About to run SSH command:
I0816 12:53:15.551289 32808 main.go:141] libmachine: (multinode-956988) DBG | exit 0
I0816 12:53:15.676504 32808 main.go:141] libmachine: (multinode-956988) DBG | SSH cmd err, output: <nil>:
I0816 12:53:15.676788 32808 main.go:141] libmachine: (multinode-956988) KVM machine creation complete!
I0816 12:53:15.677119 32808 main.go:141] libmachine: (multinode-956988) Calling .GetConfigRaw
I0816 12:53:15.677615 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:15.677815 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:15.677966 32808 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0816 12:53:15.677980 32808 main.go:141] libmachine: (multinode-956988) Calling .GetState
I0816 12:53:15.679171 32808 main.go:141] libmachine: Detecting operating system of created instance...
I0816 12:53:15.679187 32808 main.go:141] libmachine: Waiting for SSH to be available...
I0816 12:53:15.679194 32808 main.go:141] libmachine: Getting to WaitForSSH function...
I0816 12:53:15.679202 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:15.681404 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.681739 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:15.681767 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.681817 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:15.681985 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:15.682137 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:15.682266 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:15.682407 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:15.682590 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:15.682601 32808 main.go:141] libmachine: About to run SSH command:
exit 0
I0816 12:53:15.787751 32808 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0816 12:53:15.787771 32808 main.go:141] libmachine: Detecting the provisioner...
I0816 12:53:15.787778 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:15.790524 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.790922 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:15.790953 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.791104 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:15.791280 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:15.791418 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:15.791582 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:15.791762 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:15.791951 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:15.791964 32808 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0816 12:53:15.897098 32808 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"
I0816 12:53:15.897190 32808 main.go:141] libmachine: found compatible host: buildroot
I0816 12:53:15.897203 32808 main.go:141] libmachine: Provisioning with buildroot...
I0816 12:53:15.897214 32808 main.go:141] libmachine: (multinode-956988) Calling .GetMachineName
I0816 12:53:15.897496 32808 buildroot.go:166] provisioning hostname "multinode-956988"
I0816 12:53:15.897518 32808 main.go:141] libmachine: (multinode-956988) Calling .GetMachineName
I0816 12:53:15.897688 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:15.900028 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.900333 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:15.900362 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:15.900486 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:15.900660 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:15.900807 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:15.900919 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:15.901094 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:15.901262 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:15.901274 32808 main.go:141] libmachine: About to run SSH command:
sudo hostname multinode-956988 && echo "multinode-956988" | sudo tee /etc/hostname
I0816 12:53:16.023066 32808 main.go:141] libmachine: SSH cmd err, output: <nil>: multinode-956988
I0816 12:53:16.023091 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:16.025719 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.026003 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.026033 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.026158 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:16.026326 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.026463 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.026565 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:16.026747 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:16.026921 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:16.026936 32808 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\smultinode-956988' /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 multinode-956988/g' /etc/hosts;
else
echo '127.0.1.1 multinode-956988' | sudo tee -a /etc/hosts;
fi
fi
I0816 12:53:16.141217 32808 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0816 12:53:16.141260 32808 buildroot.go:172] set auth options {CertDir:/home/jenkins/minikube-integration/19423-3666/.minikube CaCertPath:/home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem CaPrivateKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/home/jenkins/minikube-integration/19423-3666/.minikube/machines/server.pem ServerKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/machines/server-key.pem ClientKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/home/jenkins/minikube-integration/19423-3666/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/home/jenkins/minikube-integration/19423-3666/.minikube}
I0816 12:53:16.141313 32808 buildroot.go:174] setting up certificates
I0816 12:53:16.141332 32808 provision.go:84] configureAuth start
I0816 12:53:16.141350 32808 main.go:141] libmachine: (multinode-956988) Calling .GetMachineName
I0816 12:53:16.141658 32808 main.go:141] libmachine: (multinode-956988) Calling .GetIP
I0816 12:53:16.144334 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.144706 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.144732 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.144826 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:16.147070 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.147359 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.147393 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.147508 32808 provision.go:143] copyHostCerts
I0816 12:53:16.147536 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem -> /home/jenkins/minikube-integration/19423-3666/.minikube/ca.pem
I0816 12:53:16.147576 32808 exec_runner.go:144] found /home/jenkins/minikube-integration/19423-3666/.minikube/ca.pem, removing ...
I0816 12:53:16.147598 32808 exec_runner.go:203] rm: /home/jenkins/minikube-integration/19423-3666/.minikube/ca.pem
I0816 12:53:16.147669 32808 exec_runner.go:151] cp: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem --> /home/jenkins/minikube-integration/19423-3666/.minikube/ca.pem (1082 bytes)
I0816 12:53:16.147775 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/cert.pem -> /home/jenkins/minikube-integration/19423-3666/.minikube/cert.pem
I0816 12:53:16.147802 32808 exec_runner.go:144] found /home/jenkins/minikube-integration/19423-3666/.minikube/cert.pem, removing ...
I0816 12:53:16.147810 32808 exec_runner.go:203] rm: /home/jenkins/minikube-integration/19423-3666/.minikube/cert.pem
I0816 12:53:16.147845 32808 exec_runner.go:151] cp: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/cert.pem --> /home/jenkins/minikube-integration/19423-3666/.minikube/cert.pem (1123 bytes)
I0816 12:53:16.147908 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/key.pem -> /home/jenkins/minikube-integration/19423-3666/.minikube/key.pem
I0816 12:53:16.147931 32808 exec_runner.go:144] found /home/jenkins/minikube-integration/19423-3666/.minikube/key.pem, removing ...
I0816 12:53:16.147940 32808 exec_runner.go:203] rm: /home/jenkins/minikube-integration/19423-3666/.minikube/key.pem
I0816 12:53:16.147968 32808 exec_runner.go:151] cp: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/key.pem --> /home/jenkins/minikube-integration/19423-3666/.minikube/key.pem (1679 bytes)
I0816 12:53:16.148049 32808 provision.go:117] generating server cert: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/server.pem ca-key=/home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem private-key=/home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca-key.pem org=jenkins.multinode-956988 san=[127.0.0.1 192.168.39.144 localhost minikube multinode-956988]
I0816 12:53:16.321697 32808 provision.go:177] copyRemoteCerts
I0816 12:53:16.321762 32808 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0816 12:53:16.321799 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:16.324454 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.324756 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.324780 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.324995 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:16.325165 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.325308 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:16.325419 32808 sshutil.go:53] new ssh client: &{IP:192.168.39.144 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa Username:docker}
I0816 12:53:16.407162 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem -> /etc/docker/ca.pem
I0816 12:53:16.407242 32808 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19423-3666/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0816 12:53:16.430536 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/server.pem -> /etc/docker/server.pem
I0816 12:53:16.430605 32808 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19423-3666/.minikube/machines/server.pem --> /etc/docker/server.pem (1216 bytes)
I0816 12:53:16.453001 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/machines/server-key.pem -> /etc/docker/server-key.pem
I0816 12:53:16.453070 32808 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19423-3666/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0816 12:53:16.475758 32808 provision.go:87] duration metric: took 334.411355ms to configureAuth
I0816 12:53:16.475782 32808 buildroot.go:189] setting minikube options for container-runtime
I0816 12:53:16.475984 32808 config.go:182] Loaded profile config "multinode-956988": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.0
I0816 12:53:16.476012 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:16.476337 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:16.478626 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.478924 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.478985 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.479077 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:16.479256 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.479397 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.479634 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:16.479802 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:16.479957 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:16.479967 32808 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0816 12:53:16.586069 32808 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0816 12:53:16.586097 32808 buildroot.go:70] root file system type: tmpfs
I0816 12:53:16.586278 32808 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0816 12:53:16.586309 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:16.588794 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.589094 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.589136 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.589311 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:16.589473 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.589628 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.589734 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:16.589873 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:16.590032 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:16.590086 32808 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %s "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=kvm2 --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I0816 12:53:16.710852 32808 main.go:141] libmachine: SSH cmd err, output: <nil>: [Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=kvm2 --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP $MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
I0816 12:53:16.710886 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:16.713376 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.713701 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:16.713739 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:16.713913 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:16.714125 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.714274 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:16.714416 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:16.714547 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:16.714727 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:16.714743 32808 main.go:141] libmachine: About to run SSH command:
sudo diff -u /lib/systemd/system/docker.service /lib/systemd/system/docker.service.new || { sudo mv /lib/systemd/system/docker.service.new /lib/systemd/system/docker.service; sudo systemctl -f daemon-reload && sudo systemctl -f enable docker && sudo systemctl -f restart docker; }
I0816 12:53:18.488527 32808 main.go:141] libmachine: SSH cmd err, output: <nil>: diff: can't stat '/lib/systemd/system/docker.service': No such file or directory
Created symlink /etc/systemd/system/multi-user.target.wants/docker.service → /usr/lib/systemd/system/docker.service.
I0816 12:53:18.488553 32808 main.go:141] libmachine: Checking connection to Docker...
I0816 12:53:18.488565 32808 main.go:141] libmachine: (multinode-956988) Calling .GetURL
I0816 12:53:18.489772 32808 main.go:141] libmachine: (multinode-956988) DBG | Using libvirt version 6000000
I0816 12:53:18.491742 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.492093 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.492112 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.492251 32808 main.go:141] libmachine: Docker is up and running!
I0816 12:53:18.492264 32808 main.go:141] libmachine: Reticulating splines...
I0816 12:53:18.492270 32808 client.go:171] duration metric: took 22.83105247s to LocalClient.Create
I0816 12:53:18.492290 32808 start.go:167] duration metric: took 22.831105168s to libmachine.API.Create "multinode-956988"
I0816 12:53:18.492302 32808 start.go:293] postStartSetup for "multinode-956988" (driver="kvm2")
I0816 12:53:18.492314 32808 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]
I0816 12:53:18.492335 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:18.492565 32808 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
I0816 12:53:18.492622 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:18.494774 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.495098 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.495123 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.495233 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:18.495388 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:18.495529 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:18.495684 32808 sshutil.go:53] new ssh client: &{IP:192.168.39.144 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa Username:docker}
I0816 12:53:18.579037 32808 ssh_runner.go:195] Run: cat /etc/os-release
I0816 12:53:18.583038 32808 command_runner.go:130] > NAME=Buildroot
I0816 12:53:18.583056 32808 command_runner.go:130] > VERSION=2023.02.9-dirty
I0816 12:53:18.583061 32808 command_runner.go:130] > ID=buildroot
I0816 12:53:18.583065 32808 command_runner.go:130] > VERSION_ID=2023.02.9
I0816 12:53:18.583071 32808 command_runner.go:130] > PRETTY_NAME="Buildroot 2023.02.9"
I0816 12:53:18.583187 32808 info.go:137] Remote host: Buildroot 2023.02.9
I0816 12:53:18.583208 32808 filesync.go:126] Scanning /home/jenkins/minikube-integration/19423-3666/.minikube/addons for local assets ...
I0816 12:53:18.583269 32808 filesync.go:126] Scanning /home/jenkins/minikube-integration/19423-3666/.minikube/files for local assets ...
I0816 12:53:18.583337 32808 filesync.go:149] local asset: /home/jenkins/minikube-integration/19423-3666/.minikube/files/etc/ssl/certs/108462.pem -> 108462.pem in /etc/ssl/certs
I0816 12:53:18.583347 32808 vm_assets.go:164] NewFileAsset: /home/jenkins/minikube-integration/19423-3666/.minikube/files/etc/ssl/certs/108462.pem -> /etc/ssl/certs/108462.pem
I0816 12:53:18.583426 32808 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0816 12:53:18.592627 32808 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19423-3666/.minikube/files/etc/ssl/certs/108462.pem --> /etc/ssl/certs/108462.pem (1708 bytes)
I0816 12:53:18.616519 32808 start.go:296] duration metric: took 124.205391ms for postStartSetup
I0816 12:53:18.616567 32808 main.go:141] libmachine: (multinode-956988) Calling .GetConfigRaw
I0816 12:53:18.617132 32808 main.go:141] libmachine: (multinode-956988) Calling .GetIP
I0816 12:53:18.619384 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.619778 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.619802 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.619973 32808 profile.go:143] Saving config to /home/jenkins/minikube-integration/19423-3666/.minikube/profiles/multinode-956988/config.json ...
I0816 12:53:18.620142 32808 start.go:128] duration metric: took 22.97672975s to createHost
I0816 12:53:18.620162 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:18.622305 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.622587 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.622615 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.622757 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:18.622947 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:18.623073 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:18.623174 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:18.623295 32808 main.go:141] libmachine: Using SSH client type: native
I0816 12:53:18.623439 32808 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.144 22 <nil> <nil>}
I0816 12:53:18.623449 32808 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0816 12:53:18.729355 32808 main.go:141] libmachine: SSH cmd err, output: <nil>: 1723812798.703511606
I0816 12:53:18.729378 32808 fix.go:216] guest clock: 1723812798.703511606
I0816 12:53:18.729388 32808 fix.go:229] Guest: 2024-08-16 12:53:18.703511606 +0000 UTC Remote: 2024-08-16 12:53:18.620153956 +0000 UTC m=+23.072828984 (delta=83.35765ms)
I0816 12:53:18.729428 32808 fix.go:200] guest clock delta is within tolerance: 83.35765ms
I0816 12:53:18.729433 32808 start.go:83] releasing machines lock for "multinode-956988", held for 23.0860886s
I0816 12:53:18.729455 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:18.729700 32808 main.go:141] libmachine: (multinode-956988) Calling .GetIP
I0816 12:53:18.732008 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.732351 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.732377 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.732534 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:18.732993 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:18.733171 32808 main.go:141] libmachine: (multinode-956988) Calling .DriverName
I0816 12:53:18.733252 32808 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0816 12:53:18.733301 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:18.733381 32808 ssh_runner.go:195] Run: cat /version.json
I0816 12:53:18.733402 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHHostname
I0816 12:53:18.735858 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.736032 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.736188 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.736224 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.736308 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:18.736427 32808 main.go:141] libmachine: (multinode-956988) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:87:14:81", ip: ""} in network mk-multinode-956988: {Iface:virbr1 ExpiryTime:2024-08-16 13:53:10 +0000 UTC Type:0 Mac:52:54:00:87:14:81 Iaid: IPaddr:192.168.39.144 Prefix:24 Hostname:multinode-956988 Clientid:01:52:54:00:87:14:81}
I0816 12:53:18.736447 32808 main.go:141] libmachine: (multinode-956988) DBG | domain multinode-956988 has defined IP address 192.168.39.144 and MAC address 52:54:00:87:14:81 in network mk-multinode-956988
I0816 12:53:18.736460 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:18.736612 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHPort
I0816 12:53:18.736645 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:18.736803 32808 sshutil.go:53] new ssh client: &{IP:192.168.39.144 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa Username:docker}
I0816 12:53:18.736820 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHKeyPath
I0816 12:53:18.736979 32808 main.go:141] libmachine: (multinode-956988) Calling .GetSSHUsername
I0816 12:53:18.737097 32808 sshutil.go:53] new ssh client: &{IP:192.168.39.144 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19423-3666/.minikube/machines/multinode-956988/id_rsa Username:docker}
I0816 12:53:18.835672 32808 command_runner.go:130] > <a href="https://github.com/kubernetes/registry.k8s.io">Temporary Redirect</a>.
I0816 12:53:18.835785 32808 command_runner.go:130] > {"iso_version": "v1.33.1-1723650137-19443", "kicbase_version": "v0.0.44-1723567951-19429", "minikube_version": "v1.33.1", "commit": "0de88034feeac7cdc6e3fa82af59b9e46ac52b3e"}
I0816 12:53:18.835916 32808 ssh_runner.go:195] Run: systemctl --version
I0816 12:53:18.842716 32808 command_runner.go:130] > systemd 252 (252)
I0816 12:53:18.842744 32808 command_runner.go:130] > -PAM -AUDIT -SELINUX -APPARMOR -IMA -SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD -LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 -BZIP2 +LZ4 +XZ +ZLIB -ZSTD -BPF_FRAMEWORK -XKBCOMMON -UTMP -SYSVINIT default-hierarchy=unified
I0816 12:53:18.842798 32808 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I0816 12:53:18.848173 32808 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W0816 12:53:18.848227 32808 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0816 12:53:18.848280 32808 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" ;
I0816 12:53:18.864128 32808 command_runner.go:139] > /etc/cni/net.d/87-podman-bridge.conflist,
I0816 12:53:18.864152 32808 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0816 12:53:18.864160 32808 start.go:495] detecting cgroup driver to use...
I0816 12:53:18.864281 32808 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"
I0816 12:53:18.882163 32808 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I0816 12:53:18.882238 32808 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.10"|' /etc/containerd/config.toml"
I0816 12:53:18.892187 32808 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"
I0816 12:53:18.902228 32808 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0816 12:53:18.902288 32808 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0816 12:53:18.912354 32808 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"
I0816 12:53:18.922312 32808 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0816 12:53:18.932284 32808 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0816 12:53:18.942225 32808 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0816 12:53:18.952344 32808 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"
I0816 12:53:18.962496 32808 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0816 12:53:18.972438 32808 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"
I0816 12:53:18.982407 32808 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0816 12:53:18.991603 32808 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I0816 12:53:18.991745 32808 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0816 12:53:19.000578 32808 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0816 12:53:19.112574 32808 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0816 12:53:19.138429 32808 start.go:495] detecting cgroup driver to use...
I0816 12:53:19.138501 32808 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0816 12:53:19.164731 32808 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I0816 12:53:19.164757 32808 command_runner.go:130] > [Unit]
I0816 12:53:19.164766 32808 command_runner.go:130] > Description=Docker Application Container Engine
I0816 12:53:19.164774 32808 command_runner.go:130] > Documentation=https://docs.docker.com
I0816 12:53:19.164779 32808 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I0816 12:53:19.164784 32808 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I0816 12:53:19.164790 32808 command_runner.go:130] > StartLimitBurst=3
I0816 12:53:19.164794 32808 command_runner.go:130] > StartLimitIntervalSec=60
I0816 12:53:19.164798 32808 command_runner.go:130] > [Service]
I0816 12:53:19.164802 32808 command_runner.go:130] > Type=notify
I0816 12:53:19.164805 32808 command_runner.go:130] > Restart=on-failure
I0816 12:53:19.164812 32808 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I0816 12:53:19.164824 32808 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I0816 12:53:19.164832 32808 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I0816 12:53:19.164841 32808 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I0816 12:53:19.164848 32808 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I0816 12:53:19.164857 32808 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I0816 12:53:19.164864 32808 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I0816 12:53:19.164880 32808 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I0816 12:53:19.164889 32808 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I0816 12:53:19.164896 32808 command_runner.go:130] > ExecStart=
I0816 12:53:19.164909 32808 command_runner.go:130] > ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=kvm2 --insecure-registry 10.96.0.0/12
I0816 12:53:19.164919 32808 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I0816 12:53:19.164929 32808 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I0816 12:53:19.164938 32808 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I0816 12:53:19.164944 32808 command_runner.go:130] > LimitNOFILE=infinity
I0816 12:53:19.164948 32808 command_runner.go:130] > LimitNPROC=infinity
I0816 12:53:19.164954 32808 command_runner.go:130] > LimitCORE=infinity
I0816 12:53:19.164960 32808 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I0816 12:53:19.164967 32808 command_runner.go:130] > # Only systemd 226 and above support this version.
I0816 12:53:19.164971 32808 command_runner.go:130] > TasksMax=infinity
I0816 12:53:19.164977 32808 command_runner.go:130] > TimeoutStartSec=0
I0816 12:53:19.164983 32808 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I0816 12:53:19.164989 32808 command_runner.go:130] > Delegate=yes
I0816 12:53:19.164995 32808 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I0816 12:53:19.165002 32808 command_runner.go:130] > KillMode=process
I0816 12:53:19.165006 32808 command_runner.go:130] > [Install]
I0816 12:53:19.165015 32808 command_runner.go:130] > WantedBy=multi-user.target
I0816 12:53:19.165066 32808 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0816 12:53:19.180933 32808 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0816 12:53:19.209677 32808 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0816 12:53:19.223288 32808 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0816 12:53:19.236436 32808 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0816 12:53:19.263008 32808 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0816 12:53:19.276525 32808 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I0816 12:53:19.294638 32808 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I0816 12:53:19.294710 32808 ssh_runner.go:195] Run: which cri-dockerd
I0816 12:53:19.298400 32808 command_runner.go:130] > /usr/bin/cri-dockerd
I0816 12:53:19.298482 32808 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0816 12:53:19.307423 32808 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (190 bytes)
I0816 12:53:19.323703 32808 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0816 12:53:19.436704 32808 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0816 12:53:19.556440 32808 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0816 12:53:19.556630 32808 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0816 12:53:19.573892 32808 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0816 12:53:19.686132 32808 ssh_runner.go:195] Run: sudo systemctl restart docker
I0816 12:54:20.754566 32808 command_runner.go:130] ! Job for docker.service failed because the control process exited with error code.
I0816 12:54:20.754594 32808 command_runner.go:130] ! See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
I0816 12:54:20.754771 32808 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.068595057s)
I0816 12:54:20.754844 32808 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0816 12:54:20.768610 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 systemd[1]: Starting Docker Application Container Engine...
I0816 12:54:20.768638 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.095484669Z" level=info msg="Starting up"
I0816 12:54:20.768651 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.096494732Z" level=info msg="containerd not running, starting managed containerd"
I0816 12:54:20.768666 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.097348365Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=533
I0816 12:54:20.768684 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.123751145Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
I0816 12:54:20.768698 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143607985Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
I0816 12:54:20.768711 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143762574Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
I0816 12:54:20.768723 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143847535Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
I0816 12:54:20.768734 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143888687Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768748 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144005428Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
I0816 12:54:20.768759 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144057828Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768781 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144323743Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
I0816 12:54:20.768796 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144385390Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768810 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144428382Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
I0816 12:54:20.768821 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144463713Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768832 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144559443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768845 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144844689Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768861 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147672950Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
I0816 12:54:20.768873 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147755376Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
I0816 12:54:20.768906 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147932055Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
I0816 12:54:20.768942 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147977285Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
I0816 12:54:20.768953 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.148095288Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
I0816 12:54:20.768964 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.148254351Z" level=info msg="metadata content store policy set" policy=shared
I0816 12:54:20.768977 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159458135Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
I0816 12:54:20.768989 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159593643Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
I0816 12:54:20.769002 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159710446Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
I0816 12:54:20.769014 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159768847Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
I0816 12:54:20.769026 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159814663Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
I0816 12:54:20.769038 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159941360Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
I0816 12:54:20.769050 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160465582Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
I0816 12:54:20.769063 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160624224Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
I0816 12:54:20.769075 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160683310Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
I0816 12:54:20.769088 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160783151Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
I0816 12:54:20.769101 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160825075Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769113 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160869598Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769126 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160909632Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769137 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160948411Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769152 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160992844Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769165 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161033701Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769178 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161071345Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769203 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161156689Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
I0816 12:54:20.769215 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161213015Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769227 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161252152Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769238 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161292191Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769250 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161330692Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769262 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161370914Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769274 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161416408Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769285 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161457433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769297 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161498603Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769309 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161535601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769323 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161574298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769334 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161613139Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769358 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161649326Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769373 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161694137Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769385 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161741233Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
I0816 12:54:20.769397 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161800754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769409 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161837898Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769421 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161875027Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
I0816 12:54:20.769433 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161984867Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
I0816 12:54:20.769448 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162185733Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
I0816 12:54:20.769461 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162230501Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
I0816 12:54:20.769476 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162268631Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
I0816 12:54:20.769490 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162307281Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
I0816 12:54:20.769503 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162345950Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
I0816 12:54:20.769514 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162389073Z" level=info msg="NRI interface is disabled by configuration."
I0816 12:54:20.769525 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162789723Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
I0816 12:54:20.769536 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163005894Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
I0816 12:54:20.769547 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163085893Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
I0816 12:54:20.769558 32808 command_runner.go:130] > Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163266752Z" level=info msg="containerd successfully booted in 0.040282s"
I0816 12:54:20.769569 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.136459845Z" level=info msg="[graphdriver] trying configured driver: overlay2"
I0816 12:54:20.769580 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.154946373Z" level=info msg="Loading containers: start."
I0816 12:54:20.769602 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.259855894Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
I0816 12:54:20.769614 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.383921119Z" level=info msg="Loading containers: done."
I0816 12:54:20.769626 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.396360842Z" level=info msg="Docker daemon" commit=f9522e5 containerd-snapshotter=false storage-driver=overlay2 version=27.1.2
I0816 12:54:20.769639 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.396487141Z" level=info msg="Daemon has completed initialization"
I0816 12:54:20.769650 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.460759614Z" level=info msg="API listen on /var/run/docker.sock"
I0816 12:54:20.769659 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.460930891Z" level=info msg="API listen on [::]:2376"
I0816 12:54:20.769668 32808 command_runner.go:130] > Aug 16 12:53:18 multinode-956988 systemd[1]: Started Docker Application Container Engine.
I0816 12:54:20.769676 32808 command_runner.go:130] > Aug 16 12:53:19 multinode-956988 systemd[1]: Stopping Docker Application Container Engine...
I0816 12:54:20.769686 32808 command_runner.go:130] > Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.674677377Z" level=info msg="Processing signal 'terminated'"
I0816 12:54:20.769699 32808 command_runner.go:130] > Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676418653Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
I0816 12:54:20.769709 32808 command_runner.go:130] > Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676593303Z" level=info msg="Daemon shutdown complete"
I0816 12:54:20.769720 32808 command_runner.go:130] > Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676802714Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
I0816 12:54:20.769734 32808 command_runner.go:130] > Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.677057174Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
I0816 12:54:20.769742 32808 command_runner.go:130] > Aug 16 12:53:20 multinode-956988 systemd[1]: docker.service: Deactivated successfully.
I0816 12:54:20.769752 32808 command_runner.go:130] > Aug 16 12:53:20 multinode-956988 systemd[1]: Stopped Docker Application Container Engine.
I0816 12:54:20.769760 32808 command_runner.go:130] > Aug 16 12:53:20 multinode-956988 systemd[1]: Starting Docker Application Container Engine...
I0816 12:54:20.769769 32808 command_runner.go:130] > Aug 16 12:53:20 multinode-956988 dockerd[842]: time="2024-08-16T12:53:20.718587111Z" level=info msg="Starting up"
I0816 12:54:20.769810 32808 command_runner.go:130] > Aug 16 12:54:20 multinode-956988 dockerd[842]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0816 12:54:20.769821 32808 command_runner.go:130] > Aug 16 12:54:20 multinode-956988 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0816 12:54:20.769827 32808 command_runner.go:130] > Aug 16 12:54:20 multinode-956988 systemd[1]: docker.service: Failed with result 'exit-code'.
I0816 12:54:20.769834 32808 command_runner.go:130] > Aug 16 12:54:20 multinode-956988 systemd[1]: Failed to start Docker Application Container Engine.
I0816 12:54:20.775370 32808 out.go:201]
W0816 12:54:20.776664 32808 out.go:270] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
sudo journalctl --no-pager -u docker:
-- stdout --
Aug 16 12:53:17 multinode-956988 systemd[1]: Starting Docker Application Container Engine...
Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.095484669Z" level=info msg="Starting up"
Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.096494732Z" level=info msg="containerd not running, starting managed containerd"
Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.097348365Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=533
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.123751145Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143607985Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143762574Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143847535Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143888687Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144005428Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144057828Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144323743Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144385390Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144428382Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144463713Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144559443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144844689Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147672950Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147755376Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147932055Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147977285Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.148095288Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.148254351Z" level=info msg="metadata content store policy set" policy=shared
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159458135Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159593643Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159710446Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159768847Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159814663Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159941360Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160465582Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160624224Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160683310Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160783151Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160825075Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160869598Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160909632Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160948411Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160992844Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161033701Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161071345Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161156689Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161213015Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161252152Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161292191Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161330692Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161370914Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161416408Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161457433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161498603Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161535601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161574298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161613139Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161649326Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161694137Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161741233Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161800754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161837898Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161875027Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161984867Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162185733Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162230501Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162268631Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162307281Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162345950Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162389073Z" level=info msg="NRI interface is disabled by configuration."
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162789723Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163005894Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163085893Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163266752Z" level=info msg="containerd successfully booted in 0.040282s"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.136459845Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.154946373Z" level=info msg="Loading containers: start."
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.259855894Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.383921119Z" level=info msg="Loading containers: done."
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.396360842Z" level=info msg="Docker daemon" commit=f9522e5 containerd-snapshotter=false storage-driver=overlay2 version=27.1.2
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.396487141Z" level=info msg="Daemon has completed initialization"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.460759614Z" level=info msg="API listen on /var/run/docker.sock"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.460930891Z" level=info msg="API listen on [::]:2376"
Aug 16 12:53:18 multinode-956988 systemd[1]: Started Docker Application Container Engine.
Aug 16 12:53:19 multinode-956988 systemd[1]: Stopping Docker Application Container Engine...
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.674677377Z" level=info msg="Processing signal 'terminated'"
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676418653Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676593303Z" level=info msg="Daemon shutdown complete"
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676802714Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.677057174Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Aug 16 12:53:20 multinode-956988 systemd[1]: docker.service: Deactivated successfully.
Aug 16 12:53:20 multinode-956988 systemd[1]: Stopped Docker Application Container Engine.
Aug 16 12:53:20 multinode-956988 systemd[1]: Starting Docker Application Container Engine...
Aug 16 12:53:20 multinode-956988 dockerd[842]: time="2024-08-16T12:53:20.718587111Z" level=info msg="Starting up"
Aug 16 12:54:20 multinode-956988 dockerd[842]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 16 12:54:20 multinode-956988 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 16 12:54:20 multinode-956988 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 16 12:54:20 multinode-956988 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
sudo journalctl --no-pager -u docker:
-- stdout --
Aug 16 12:53:17 multinode-956988 systemd[1]: Starting Docker Application Container Engine...
Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.095484669Z" level=info msg="Starting up"
Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.096494732Z" level=info msg="containerd not running, starting managed containerd"
Aug 16 12:53:17 multinode-956988 dockerd[526]: time="2024-08-16T12:53:17.097348365Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=533
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.123751145Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143607985Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143762574Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143847535Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.143888687Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144005428Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144057828Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144323743Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144385390Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144428382Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144463713Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144559443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.144844689Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147672950Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147755376Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147932055Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.147977285Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.148095288Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.148254351Z" level=info msg="metadata content store policy set" policy=shared
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159458135Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159593643Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159710446Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159768847Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159814663Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.159941360Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160465582Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160624224Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160683310Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160783151Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160825075Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160869598Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160909632Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160948411Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.160992844Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161033701Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161071345Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161156689Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161213015Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161252152Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161292191Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161330692Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161370914Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161416408Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161457433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161498603Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161535601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161574298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161613139Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161649326Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161694137Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161741233Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161800754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161837898Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161875027Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.161984867Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162185733Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162230501Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162268631Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162307281Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162345950Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162389073Z" level=info msg="NRI interface is disabled by configuration."
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.162789723Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163005894Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163085893Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Aug 16 12:53:17 multinode-956988 dockerd[533]: time="2024-08-16T12:53:17.163266752Z" level=info msg="containerd successfully booted in 0.040282s"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.136459845Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.154946373Z" level=info msg="Loading containers: start."
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.259855894Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.383921119Z" level=info msg="Loading containers: done."
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.396360842Z" level=info msg="Docker daemon" commit=f9522e5 containerd-snapshotter=false storage-driver=overlay2 version=27.1.2
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.396487141Z" level=info msg="Daemon has completed initialization"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.460759614Z" level=info msg="API listen on /var/run/docker.sock"
Aug 16 12:53:18 multinode-956988 dockerd[526]: time="2024-08-16T12:53:18.460930891Z" level=info msg="API listen on [::]:2376"
Aug 16 12:53:18 multinode-956988 systemd[1]: Started Docker Application Container Engine.
Aug 16 12:53:19 multinode-956988 systemd[1]: Stopping Docker Application Container Engine...
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.674677377Z" level=info msg="Processing signal 'terminated'"
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676418653Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676593303Z" level=info msg="Daemon shutdown complete"
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.676802714Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Aug 16 12:53:19 multinode-956988 dockerd[526]: time="2024-08-16T12:53:19.677057174Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Aug 16 12:53:20 multinode-956988 systemd[1]: docker.service: Deactivated successfully.
Aug 16 12:53:20 multinode-956988 systemd[1]: Stopped Docker Application Container Engine.
Aug 16 12:53:20 multinode-956988 systemd[1]: Starting Docker Application Container Engine...
Aug 16 12:53:20 multinode-956988 dockerd[842]: time="2024-08-16T12:53:20.718587111Z" level=info msg="Starting up"
Aug 16 12:54:20 multinode-956988 dockerd[842]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 16 12:54:20 multinode-956988 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 16 12:54:20 multinode-956988 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 16 12:54:20 multinode-956988 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0816 12:54:20.776702 32808 out.go:270] *
*
W0816 12:54:20.777622 32808 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0816 12:54:20.779823 32808 out.go:201]
** /stderr **
multinode_test.go:98: failed to start cluster. args "out/minikube-linux-amd64 start -p multinode-956988 --wait=true --memory=2200 --nodes=2 -v=8 --alsologtostderr --driver=kvm2 " : exit status 90
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-linux-amd64 status --format={{.Host}} -p multinode-956988 -n multinode-956988
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-linux-amd64 status --format={{.Host}} -p multinode-956988 -n multinode-956988: exit status 6 (233.907714ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0816 12:54:21.032123 33256 status.go:417] kubeconfig endpoint: get endpoint: "multinode-956988" does not appear in /home/jenkins/minikube-integration/19423-3666/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "multinode-956988" 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`")
--- FAIL: TestMultiNode/serial/FreshStart2Nodes (85.50s)