=== RUN TestFunctional/serial/SoftStart
functional_test.go:655: (dbg) Run: out/minikube-darwin-amd64 start -p functional-279000 --alsologtostderr -v=8
E0807 11:57:43.249043 10076 cert_rotation.go:168] key failed with : open /Users/jenkins/minikube-integration/19389-9550/.minikube/profiles/addons-817000/client.crt: no such file or directory
functional_test.go:655: (dbg) Non-zero exit: out/minikube-darwin-amd64 start -p functional-279000 --alsologtostderr -v=8: exit status 90 (2m0.192297665s)
-- stdout --
* [functional-279000] minikube v1.33.1 on Darwin 14.5
- MINIKUBE_LOCATION=19389
- KUBECONFIG=/Users/jenkins/minikube-integration/19389-9550/kubeconfig
- MINIKUBE_BIN=out/minikube-darwin-amd64
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
- MINIKUBE_HOME=/Users/jenkins/minikube-integration/19389-9550/.minikube
- MINIKUBE_FORCE_SYSTEMD=
* Using the hyperkit driver based on existing profile
* Starting "functional-279000" primary control-plane node in "functional-279000" cluster
* Updating the running hyperkit "functional-279000" VM ...
-- /stdout --
** stderr **
I0807 11:57:34.135006 10775 out.go:291] Setting OutFile to fd 1 ...
I0807 11:57:34.135176 10775 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0807 11:57:34.135181 10775 out.go:304] Setting ErrFile to fd 2...
I0807 11:57:34.135185 10775 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0807 11:57:34.135348 10775 root.go:338] Updating PATH: /Users/jenkins/minikube-integration/19389-9550/.minikube/bin
I0807 11:57:34.136820 10775 out.go:298] Setting JSON to false
I0807 11:57:34.159237 10775 start.go:129] hostinfo: {"hostname":"MacOS-Agent-2.local","uptime":8819,"bootTime":1723048235,"procs":485,"os":"darwin","platform":"darwin","platformFamily":"Standalone Workstation","platformVersion":"14.5","kernelVersion":"23.5.0","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"2965c349-98a5-5970-aaa9-9eedd3ae5959"}
W0807 11:57:34.159326 10775 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0807 11:57:34.181204 10775 out.go:177] * [functional-279000] minikube v1.33.1 on Darwin 14.5
I0807 11:57:34.222745 10775 out.go:177] - MINIKUBE_LOCATION=19389
I0807 11:57:34.222773 10775 notify.go:220] Checking for updates...
I0807 11:57:34.264771 10775 out.go:177] - KUBECONFIG=/Users/jenkins/minikube-integration/19389-9550/kubeconfig
I0807 11:57:34.285638 10775 out.go:177] - MINIKUBE_BIN=out/minikube-darwin-amd64
I0807 11:57:34.306905 10775 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0807 11:57:34.328026 10775 out.go:177] - MINIKUBE_HOME=/Users/jenkins/minikube-integration/19389-9550/.minikube
I0807 11:57:34.348789 10775 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0807 11:57:34.370566 10775 config.go:182] Loaded profile config "functional-279000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.3
I0807 11:57:34.370732 10775 driver.go:392] Setting default libvirt URI to qemu:///system
I0807 11:57:34.371463 10775 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0807 11:57:34.371565 10775 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0807 11:57:34.381253 10775 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:55324
I0807 11:57:34.381638 10775 main.go:141] libmachine: () Calling .GetVersion
I0807 11:57:34.382065 10775 main.go:141] libmachine: Using API Version 1
I0807 11:57:34.382092 10775 main.go:141] libmachine: () Calling .SetConfigRaw
I0807 11:57:34.382362 10775 main.go:141] libmachine: () Calling .GetMachineName
I0807 11:57:34.382498 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:34.410680 10775 out.go:177] * Using the hyperkit driver based on existing profile
I0807 11:57:34.452865 10775 start.go:297] selected driver: hyperkit
I0807 11:57:34.452890 10775 start.go:901] validating driver "hyperkit" against &{Name:functional-279000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19339/minikube-v1.33.1-1722248113-19339-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723026928-19389@sha256:7715fe0c5dce35b4eb757765cbbe02d40cd8b5effa0639735e42ad89f4f51ef0 Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperkit 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:8441 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfi
g:{KubernetesVersion:v1.30.3 ClusterName:functional-279000 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:192.169.0.25 Port:8441 KubernetesVersion:v1.30.3 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOpt
ions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0807 11:57:34.453085 10775 start.go:912] status for hyperkit: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0807 11:57:34.453281 10775 install.go:52] acquiring lock: {Name:mk4023283b30b374c3f04c8805d539e68824c0b8 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0807 11:57:34.453462 10775 install.go:117] Validating docker-machine-driver-hyperkit, PATH=/Users/jenkins/minikube-integration/19389-9550/.minikube/bin:/Users/jenkins/workspace/out/:/usr/bin:/bin:/usr/sbin:/sbin:/Users/jenkins/google-cloud-sdk/bin:/usr/local/bin/:/usr/local/go/bin/:/Users/jenkins/go/bin
I0807 11:57:34.463269 10775 install.go:137] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit version is 1.33.1
I0807 11:57:34.467142 10775 install.go:79] stdout: /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0807 11:57:34.467163 10775 install.go:81] /Users/jenkins/workspace/out/docker-machine-driver-hyperkit looks good
I0807 11:57:34.469811 10775 cni.go:84] Creating CNI manager for ""
I0807 11:57:34.469837 10775 cni.go:158] "hyperkit" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0807 11:57:34.469920 10775 start.go:340] cluster config:
{Name:functional-279000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19339/minikube-v1.33.1-1722248113-19339-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723026928-19389@sha256:7715fe0c5dce35b4eb757765cbbe02d40cd8b5effa0639735e42ad89f4f51ef0 Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperkit 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:8441 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.30.3 ClusterName:functional-279000 Namespace:default APISe
rverHAVIP: 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:192.169.0.25 Port:8441 KubernetesVersion:v1.30.3 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/Users:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimization
s:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0807 11:57:34.470017 10775 iso.go:125] acquiring lock: {Name:mke91761ef3e8aa2518f90077a2996d998526bee Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0807 11:57:34.511675 10775 out.go:177] * Starting "functional-279000" primary control-plane node in "functional-279000" cluster
I0807 11:57:34.533006 10775 preload.go:131] Checking if preload exists for k8s version v1.30.3 and runtime docker
I0807 11:57:34.533079 10775 preload.go:146] Found local preload: /Users/jenkins/minikube-integration/19389-9550/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.3-docker-overlay2-amd64.tar.lz4
I0807 11:57:34.533099 10775 cache.go:56] Caching tarball of preloaded images
I0807 11:57:34.533335 10775 preload.go:172] Found /Users/jenkins/minikube-integration/19389-9550/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.30.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0807 11:57:34.533353 10775 cache.go:59] Finished verifying existence of preloaded tar for v1.30.3 on docker
I0807 11:57:34.533499 10775 profile.go:143] Saving config to /Users/jenkins/minikube-integration/19389-9550/.minikube/profiles/functional-279000/config.json ...
I0807 11:57:34.534435 10775 start.go:360] acquireMachinesLock for functional-279000: {Name:mk81f6ee071c6fc54d0297038ae954f1b9ef2a26 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0807 11:57:34.534534 10775 start.go:364] duration metric: took 73.513µs to acquireMachinesLock for "functional-279000"
I0807 11:57:34.534572 10775 start.go:96] Skipping create...Using existing machine configuration
I0807 11:57:34.534588 10775 fix.go:54] fixHost starting:
I0807 11:57:34.535018 10775 main.go:141] libmachine: Found binary path at /Users/jenkins/workspace/out/docker-machine-driver-hyperkit
I0807 11:57:34.535065 10775 main.go:141] libmachine: Launching plugin server for driver hyperkit
I0807 11:57:34.544598 10775 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:55326
I0807 11:57:34.544956 10775 main.go:141] libmachine: () Calling .GetVersion
I0807 11:57:34.545295 10775 main.go:141] libmachine: Using API Version 1
I0807 11:57:34.545305 10775 main.go:141] libmachine: () Calling .SetConfigRaw
I0807 11:57:34.545552 10775 main.go:141] libmachine: () Calling .GetMachineName
I0807 11:57:34.545673 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:34.545771 10775 main.go:141] libmachine: (functional-279000) Calling .GetState
I0807 11:57:34.545859 10775 main.go:141] libmachine: (functional-279000) DBG | exe=/Users/jenkins/workspace/out/docker-machine-driver-hyperkit uid=0
I0807 11:57:34.545932 10775 main.go:141] libmachine: (functional-279000) DBG | hyperkit pid from json: 10753
I0807 11:57:34.546979 10775 fix.go:112] recreateIfNeeded on functional-279000: state=Running err=<nil>
W0807 11:57:34.547000 10775 fix.go:138] unexpected machine state, will restart: <nil>
I0807 11:57:34.567978 10775 out.go:177] * Updating the running hyperkit "functional-279000" VM ...
I0807 11:57:34.609923 10775 machine.go:94] provisionDockerMachine start ...
I0807 11:57:34.609982 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:34.610296 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:34.610508 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:34.610735 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:34.610982 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:34.611190 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:34.611459 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:34.611869 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:34.611883 10775 main.go:141] libmachine: About to run SSH command:
hostname
I0807 11:57:34.671027 10775 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-279000
I0807 11:57:34.671045 10775 main.go:141] libmachine: (functional-279000) Calling .GetMachineName
I0807 11:57:34.671201 10775 buildroot.go:166] provisioning hostname "functional-279000"
I0807 11:57:34.671222 10775 main.go:141] libmachine: (functional-279000) Calling .GetMachineName
I0807 11:57:34.671319 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:34.671460 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:34.671647 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:34.671778 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:34.671878 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:34.672017 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:34.672158 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:34.672180 10775 main.go:141] libmachine: About to run SSH command:
sudo hostname functional-279000 && echo "functional-279000" | sudo tee /etc/hostname
I0807 11:57:34.742561 10775 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-279000
I0807 11:57:34.742581 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:34.742715 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:34.742806 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:34.742898 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:34.743003 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:34.743133 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:34.743282 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:34.743296 10775 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfunctional-279000' /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 functional-279000/g' /etc/hosts;
else
echo '127.0.1.1 functional-279000' | sudo tee -a /etc/hosts;
fi
fi
I0807 11:57:34.804387 10775 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0807 11:57:34.804408 10775 buildroot.go:172] set auth options {CertDir:/Users/jenkins/minikube-integration/19389-9550/.minikube CaCertPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca.pem CaPrivateKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server.pem ServerKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server-key.pem ClientKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/Users/jenkins/minikube-integration/19389-9550/.minikube}
I0807 11:57:34.804430 10775 buildroot.go:174] setting up certificates
I0807 11:57:34.804439 10775 provision.go:84] configureAuth start
I0807 11:57:34.804445 10775 main.go:141] libmachine: (functional-279000) Calling .GetMachineName
I0807 11:57:34.804584 10775 main.go:141] libmachine: (functional-279000) Calling .GetIP
I0807 11:57:34.804686 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:34.804786 10775 provision.go:143] copyHostCerts
I0807 11:57:34.804818 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca.pem -> /Users/jenkins/minikube-integration/19389-9550/.minikube/ca.pem
I0807 11:57:34.804894 10775 exec_runner.go:144] found /Users/jenkins/minikube-integration/19389-9550/.minikube/ca.pem, removing ...
I0807 11:57:34.804903 10775 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19389-9550/.minikube/ca.pem
I0807 11:57:34.805134 10775 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca.pem --> /Users/jenkins/minikube-integration/19389-9550/.minikube/ca.pem (1082 bytes)
I0807 11:57:34.805354 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/cert.pem -> /Users/jenkins/minikube-integration/19389-9550/.minikube/cert.pem
I0807 11:57:34.805386 10775 exec_runner.go:144] found /Users/jenkins/minikube-integration/19389-9550/.minikube/cert.pem, removing ...
I0807 11:57:34.805391 10775 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19389-9550/.minikube/cert.pem
I0807 11:57:34.805463 10775 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/cert.pem --> /Users/jenkins/minikube-integration/19389-9550/.minikube/cert.pem (1123 bytes)
I0807 11:57:34.805621 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/key.pem -> /Users/jenkins/minikube-integration/19389-9550/.minikube/key.pem
I0807 11:57:34.805650 10775 exec_runner.go:144] found /Users/jenkins/minikube-integration/19389-9550/.minikube/key.pem, removing ...
I0807 11:57:34.805654 10775 exec_runner.go:203] rm: /Users/jenkins/minikube-integration/19389-9550/.minikube/key.pem
I0807 11:57:34.805723 10775 exec_runner.go:151] cp: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/key.pem --> /Users/jenkins/minikube-integration/19389-9550/.minikube/key.pem (1679 bytes)
I0807 11:57:34.805873 10775 provision.go:117] generating server cert: /Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server.pem ca-key=/Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca.pem private-key=/Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca-key.pem org=jenkins.functional-279000 san=[127.0.0.1 192.169.0.25 functional-279000 localhost minikube]
I0807 11:57:35.027862 10775 provision.go:177] copyRemoteCerts
I0807 11:57:35.027916 10775 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0807 11:57:35.027932 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.028064 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.028184 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.028277 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.028389 10775 sshutil.go:53] new ssh client: &{IP:192.169.0.25 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/machines/functional-279000/id_rsa Username:docker}
I0807 11:57:35.064594 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca.pem -> /etc/docker/ca.pem
I0807 11:57:35.064670 10775 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19389-9550/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0807 11:57:35.084566 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server.pem -> /etc/docker/server.pem
I0807 11:57:35.084650 10775 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server.pem --> /etc/docker/server.pem (1220 bytes)
I0807 11:57:35.104231 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server-key.pem -> /etc/docker/server-key.pem
I0807 11:57:35.104291 10775 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19389-9550/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0807 11:57:35.124011 10775 provision.go:87] duration metric: took 319.560284ms to configureAuth
I0807 11:57:35.124024 10775 buildroot.go:189] setting minikube options for container-runtime
I0807 11:57:35.124169 10775 config.go:182] Loaded profile config "functional-279000": Driver=hyperkit, ContainerRuntime=docker, KubernetesVersion=v1.30.3
I0807 11:57:35.124184 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:35.124309 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.124396 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.124492 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.124651 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.124730 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.124844 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:35.124966 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:35.124974 10775 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0807 11:57:35.184758 10775 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0807 11:57:35.184769 10775 buildroot.go:70] root file system type: tmpfs
I0807 11:57:35.184856 10775 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0807 11:57:35.184871 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.185031 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.185135 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.185243 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.185328 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.185458 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:35.185596 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:35.185643 10775 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=hyperkit --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
I0807 11:57:35.256435 10775 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=hyperkit --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
I0807 11:57:35.256461 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.256586 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.256684 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.256774 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.256876 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.257018 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:35.257158 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:35.257170 10775 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; }
I0807 11:57:35.318079 10775 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0807 11:57:35.318094 10775 machine.go:97] duration metric: took 708.130782ms to provisionDockerMachine
I0807 11:57:35.318107 10775 start.go:293] postStartSetup for "functional-279000" (driver="hyperkit")
I0807 11:57:35.318115 10775 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]
I0807 11:57:35.318130 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:35.318308 10775 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
I0807 11:57:35.318322 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.318419 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.318504 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.318579 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.318652 10775 sshutil.go:53] new ssh client: &{IP:192.169.0.25 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/machines/functional-279000/id_rsa Username:docker}
I0807 11:57:35.354477 10775 ssh_runner.go:195] Run: cat /etc/os-release
I0807 11:57:35.357524 10775 command_runner.go:130] > NAME=Buildroot
I0807 11:57:35.357533 10775 command_runner.go:130] > VERSION=2023.02.9-dirty
I0807 11:57:35.357537 10775 command_runner.go:130] > ID=buildroot
I0807 11:57:35.357549 10775 command_runner.go:130] > VERSION_ID=2023.02.9
I0807 11:57:35.357556 10775 command_runner.go:130] > PRETTY_NAME="Buildroot 2023.02.9"
I0807 11:57:35.357669 10775 info.go:137] Remote host: Buildroot 2023.02.9
I0807 11:57:35.357681 10775 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19389-9550/.minikube/addons for local assets ...
I0807 11:57:35.357767 10775 filesync.go:126] Scanning /Users/jenkins/minikube-integration/19389-9550/.minikube/files for local assets ...
I0807 11:57:35.357911 10775 filesync.go:149] local asset: /Users/jenkins/minikube-integration/19389-9550/.minikube/files/etc/ssl/certs/100762.pem -> 100762.pem in /etc/ssl/certs
I0807 11:57:35.357917 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/files/etc/ssl/certs/100762.pem -> /etc/ssl/certs/100762.pem
I0807 11:57:35.358066 10775 filesync.go:149] local asset: /Users/jenkins/minikube-integration/19389-9550/.minikube/files/etc/test/nested/copy/10076/hosts -> hosts in /etc/test/nested/copy/10076
I0807 11:57:35.358071 10775 vm_assets.go:164] NewFileAsset: /Users/jenkins/minikube-integration/19389-9550/.minikube/files/etc/test/nested/copy/10076/hosts -> /etc/test/nested/copy/10076/hosts
I0807 11:57:35.358110 10775 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs /etc/test/nested/copy/10076
I0807 11:57:35.365291 10775 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19389-9550/.minikube/files/etc/ssl/certs/100762.pem --> /etc/ssl/certs/100762.pem (1708 bytes)
I0807 11:57:35.385509 10775 ssh_runner.go:362] scp /Users/jenkins/minikube-integration/19389-9550/.minikube/files/etc/test/nested/copy/10076/hosts --> /etc/test/nested/copy/10076/hosts (40 bytes)
I0807 11:57:35.405012 10775 start.go:296] duration metric: took 86.89638ms for postStartSetup
I0807 11:57:35.405036 10775 fix.go:56] duration metric: took 870.450408ms for fixHost
I0807 11:57:35.405051 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.405175 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.405266 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.405354 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.405449 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.405580 10775 main.go:141] libmachine: Using SSH client type: native
I0807 11:57:35.405717 10775 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x5c8a0c0] 0x5c8ce20 <nil> [] 0s} 192.169.0.25 22 <nil> <nil>}
I0807 11:57:35.405724 10775 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0807 11:57:35.465792 10775 main.go:141] libmachine: SSH cmd err, output: <nil>: 1723057055.600357971
I0807 11:57:35.465804 10775 fix.go:216] guest clock: 1723057055.600357971
I0807 11:57:35.465810 10775 fix.go:229] Guest: 2024-08-07 11:57:35.600357971 -0700 PDT Remote: 2024-08-07 11:57:35.405042 -0700 PDT m=+1.305592382 (delta=195.315971ms)
I0807 11:57:35.465826 10775 fix.go:200] guest clock delta is within tolerance: 195.315971ms
I0807 11:57:35.465830 10775 start.go:83] releasing machines lock for "functional-279000", held for 931.283757ms
I0807 11:57:35.465850 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:35.465977 10775 main.go:141] libmachine: (functional-279000) Calling .GetIP
I0807 11:57:35.466069 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:35.466397 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:35.466506 10775 main.go:141] libmachine: (functional-279000) Calling .DriverName
I0807 11:57:35.466588 10775 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0807 11:57:35.466619 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.466666 10775 ssh_runner.go:195] Run: cat /version.json
I0807 11:57:35.466677 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHHostname
I0807 11:57:35.466706 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.466773 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHPort
I0807 11:57:35.466792 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.466870 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.466894 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHKeyPath
I0807 11:57:35.466978 10775 main.go:141] libmachine: (functional-279000) Calling .GetSSHUsername
I0807 11:57:35.467003 10775 sshutil.go:53] new ssh client: &{IP:192.169.0.25 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/machines/functional-279000/id_rsa Username:docker}
I0807 11:57:35.467056 10775 sshutil.go:53] new ssh client: &{IP:192.169.0.25 Port:22 SSHKeyPath:/Users/jenkins/minikube-integration/19389-9550/.minikube/machines/functional-279000/id_rsa Username:docker}
I0807 11:57:35.500001 10775 command_runner.go:130] > {"iso_version": "v1.33.1-1722248113-19339", "kicbase_version": "v0.0.44-1721902582-19326", "minikube_version": "v1.33.1", "commit": "b8389556a97747a5bbaa1906d238251ad536d76e"}
I0807 11:57:35.500255 10775 ssh_runner.go:195] Run: systemctl --version
I0807 11:57:35.546723 10775 command_runner.go:130] > <a href="https://github.com/kubernetes/registry.k8s.io">Temporary Redirect</a>.
I0807 11:57:35.547397 10775 command_runner.go:130] > systemd 252 (252)
I0807 11:57:35.547436 10775 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
I0807 11:57:35.547522 10775 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I0807 11:57:35.552309 10775 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W0807 11:57:35.552327 10775 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0807 11:57:35.552366 10775 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" ;
I0807 11:57:35.560527 10775 cni.go:259] no active bridge cni configs found in "/etc/cni/net.d" - nothing to disable
I0807 11:57:35.560541 10775 start.go:495] detecting cgroup driver to use...
I0807 11:57:35.560640 10775 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"
I0807 11:57:35.574791 10775 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I0807 11:57:35.575048 10775 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.9"|' /etc/containerd/config.toml"
I0807 11:57:35.583909 10775 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"
I0807 11:57:35.592853 10775 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0807 11:57:35.592893 10775 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0807 11:57:35.601804 10775 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"
I0807 11:57:35.610505 10775 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0807 11:57:35.619280 10775 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0807 11:57:35.628098 10775 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0807 11:57:35.637125 10775 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"
I0807 11:57:35.646109 10775 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0807 11:57:35.654950 10775 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"
I0807 11:57:35.663809 10775 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0807 11:57:35.671668 10775 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I0807 11:57:35.671835 10775 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0807 11:57:35.679709 10775 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0807 11:57:35.774453 10775 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0807 11:57:35.793326 10775 start.go:495] detecting cgroup driver to use...
I0807 11:57:35.793412 10775 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0807 11:57:35.807386 10775 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I0807 11:57:35.807823 10775 command_runner.go:130] > [Unit]
I0807 11:57:35.807831 10775 command_runner.go:130] > Description=Docker Application Container Engine
I0807 11:57:35.807835 10775 command_runner.go:130] > Documentation=https://docs.docker.com
I0807 11:57:35.807840 10775 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I0807 11:57:35.807844 10775 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I0807 11:57:35.807849 10775 command_runner.go:130] > StartLimitBurst=3
I0807 11:57:35.807853 10775 command_runner.go:130] > StartLimitIntervalSec=60
I0807 11:57:35.807858 10775 command_runner.go:130] > [Service]
I0807 11:57:35.807863 10775 command_runner.go:130] > Type=notify
I0807 11:57:35.807866 10775 command_runner.go:130] > Restart=on-failure
I0807 11:57:35.807873 10775 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I0807 11:57:35.807882 10775 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I0807 11:57:35.807888 10775 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I0807 11:57:35.807894 10775 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I0807 11:57:35.807900 10775 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I0807 11:57:35.807905 10775 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I0807 11:57:35.807912 10775 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I0807 11:57:35.807920 10775 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I0807 11:57:35.807925 10775 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I0807 11:57:35.807929 10775 command_runner.go:130] > ExecStart=
I0807 11:57:35.807942 10775 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=hyperkit --insecure-registry 10.96.0.0/12
I0807 11:57:35.807948 10775 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I0807 11:57:35.807955 10775 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I0807 11:57:35.807960 10775 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I0807 11:57:35.807984 10775 command_runner.go:130] > LimitNOFILE=infinity
I0807 11:57:35.807992 10775 command_runner.go:130] > LimitNPROC=infinity
I0807 11:57:35.807996 10775 command_runner.go:130] > LimitCORE=infinity
I0807 11:57:35.808001 10775 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I0807 11:57:35.808006 10775 command_runner.go:130] > # Only systemd 226 and above support this version.
I0807 11:57:35.808010 10775 command_runner.go:130] > TasksMax=infinity
I0807 11:57:35.808013 10775 command_runner.go:130] > TimeoutStartSec=0
I0807 11:57:35.808018 10775 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I0807 11:57:35.808022 10775 command_runner.go:130] > Delegate=yes
I0807 11:57:35.808027 10775 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I0807 11:57:35.808039 10775 command_runner.go:130] > KillMode=process
I0807 11:57:35.808043 10775 command_runner.go:130] > [Install]
I0807 11:57:35.808052 10775 command_runner.go:130] > WantedBy=multi-user.target
I0807 11:57:35.808127 10775 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0807 11:57:35.819809 10775 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0807 11:57:35.833941 10775 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0807 11:57:35.844156 10775 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0807 11:57:35.854533 10775 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"
I0807 11:57:35.870188 10775 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I0807 11:57:35.870647 10775 ssh_runner.go:195] Run: which cri-dockerd
I0807 11:57:35.873240 10775 command_runner.go:130] > /usr/bin/cri-dockerd
I0807 11:57:35.873407 10775 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0807 11:57:35.880471 10775 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0807 11:57:35.893851 10775 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0807 11:57:35.992989 10775 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0807 11:57:36.100752 10775 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0807 11:57:36.100842 10775 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0807 11:57:36.115760 10775 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0807 11:57:36.232450 10775 ssh_runner.go:195] Run: sudo systemctl restart docker
I0807 11:59:34.106973 10775 command_runner.go:130] ! Job for docker.service failed because the control process exited with error code.
I0807 11:59:34.106991 10775 command_runner.go:130] ! See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
I0807 11:59:34.107000 10775 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m57.874054373s)
I0807 11:59:34.107058 10775 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0807 11:59:34.116925 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 systemd[1]: Starting Docker Application Container Engine...
I0807 11:59:34.116937 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.474587822Z" level=info msg="Starting up"
I0807 11:59:34.116945 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.475048743Z" level=info msg="containerd not running, starting managed containerd"
I0807 11:59:34.116962 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.475634583Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=544
I0807 11:59:34.116971 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.491110680Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
I0807 11:59:34.116981 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506233149Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
I0807 11:59:34.116992 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506254613Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
I0807 11:59:34.117001 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506290084Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
I0807 11:59:34.117012 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506300181Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117023 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506354340Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
I0807 11:59:34.117040 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506387121Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117060 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506533631Z" 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
I0807 11:59:34.117070 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506568095Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117080 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506579792Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
I0807 11:59:34.117093 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506593693Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117124 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506661498Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117136 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506816772Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117151 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508313051Z" 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
I0807 11:59:34.117166 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508369021Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
I0807 11:59:34.117193 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508531020Z" 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
I0807 11:59:34.117203 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508573097Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
I0807 11:59:34.117213 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508668301Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
I0807 11:59:34.117222 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508735920Z" level=info msg="metadata content store policy set" policy=shared
I0807 11:59:34.117230 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511380632Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
I0807 11:59:34.117240 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511512259Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
I0807 11:59:34.117248 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511559777Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
I0807 11:59:34.117257 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511593506Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
I0807 11:59:34.117268 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511631280Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
I0807 11:59:34.117276 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511729244Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
I0807 11:59:34.117285 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511968353Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
I0807 11:59:34.117294 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512083908Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
I0807 11:59:34.117302 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512123931Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
I0807 11:59:34.117312 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512154940Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
I0807 11:59:34.117321 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512189323Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117331 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512220647Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117340 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512254004Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117349 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512285053Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117361 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512316729Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117370 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512347399Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117379 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512376693Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117492 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512405415Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
I0807 11:59:34.117503 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512440716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117512 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512475123Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117520 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512507609Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117528 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512538607Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117537 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512568712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117546 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512669302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117555 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512712798Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117563 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512746723Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117572 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512786072Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117580 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512926643Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117589 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512984433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117598 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513021391Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117606 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513056120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117621 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513096252Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
I0807 11:59:34.117630 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513138081Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117639 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513173436Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117647 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513206351Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
I0807 11:59:34.117657 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513264141Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
I0807 11:59:34.117668 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513321728Z" 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
I0807 11:59:34.117688 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513361104Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
I0807 11:59:34.117826 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513394870Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
I0807 11:59:34.117838 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513427754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
I0807 11:59:34.117852 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513500293Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
I0807 11:59:34.117860 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513692738Z" level=info msg="NRI interface is disabled by configuration."
I0807 11:59:34.117869 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514254266Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
I0807 11:59:34.117883 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514310714Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
I0807 11:59:34.117892 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514337399Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
I0807 11:59:34.117899 10775 command_runner.go:130] > Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514370761Z" level=info msg="containerd successfully booted in 0.023908s"
I0807 11:59:34.117907 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.500110644Z" level=info msg="[graphdriver] trying configured driver: overlay2"
I0807 11:59:34.117915 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.506485718Z" level=info msg="Loading containers: start."
I0807 11:59:34.117935 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.594983621Z" 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)"
I0807 11:59:34.117944 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.684863675Z" level=info msg="Loading containers: done."
I0807 11:59:34.117953 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.692259053Z" level=info msg="Docker daemon" commit=cc13f95 containerd-snapshotter=false storage-driver=overlay2 version=27.1.1
I0807 11:59:34.117967 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.692377148Z" level=info msg="Daemon has completed initialization"
I0807 11:59:34.117975 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.723576202Z" level=info msg="API listen on /var/run/docker.sock"
I0807 11:59:34.117982 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.723635500Z" level=info msg="API listen on [::]:2376"
I0807 11:59:34.117989 10775 command_runner.go:130] > Aug 07 18:56:30 functional-279000 systemd[1]: Started Docker Application Container Engine.
I0807 11:59:34.117996 10775 command_runner.go:130] > Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.848625319Z" level=info msg="Processing signal 'terminated'"
I0807 11:59:34.118005 10775 command_runner.go:130] > Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849505845Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
I0807 11:59:34.118011 10775 command_runner.go:130] > Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849639004Z" level=info msg="Daemon shutdown complete"
I0807 11:59:34.118021 10775 command_runner.go:130] > Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849701465Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
I0807 11:59:34.118029 10775 command_runner.go:130] > Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849715699Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
I0807 11:59:34.118034 10775 command_runner.go:130] > Aug 07 18:56:31 functional-279000 systemd[1]: Stopping Docker Application Container Engine...
I0807 11:59:34.118039 10775 command_runner.go:130] > Aug 07 18:56:32 functional-279000 systemd[1]: docker.service: Deactivated successfully.
I0807 11:59:34.118044 10775 command_runner.go:130] > Aug 07 18:56:32 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
I0807 11:59:34.118050 10775 command_runner.go:130] > Aug 07 18:56:32 functional-279000 systemd[1]: Starting Docker Application Container Engine...
I0807 11:59:34.118056 10775 command_runner.go:130] > Aug 07 18:56:32 functional-279000 dockerd[946]: time="2024-08-07T18:56:32.891349082Z" level=info msg="Starting up"
I0807 11:59:34.118165 10775 command_runner.go:130] > Aug 07 18:57:33 functional-279000 dockerd[946]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0807 11:59:34.118176 10775 command_runner.go:130] > Aug 07 18:57:33 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0807 11:59:34.118187 10775 command_runner.go:130] > Aug 07 18:57:33 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
I0807 11:59:34.118194 10775 command_runner.go:130] > Aug 07 18:57:33 functional-279000 systemd[1]: Failed to start Docker Application Container Engine.
I0807 11:59:34.118200 10775 command_runner.go:130] > Aug 07 18:57:34 functional-279000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
I0807 11:59:34.118206 10775 command_runner.go:130] > Aug 07 18:57:34 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
I0807 11:59:34.118212 10775 command_runner.go:130] > Aug 07 18:57:34 functional-279000 systemd[1]: Starting Docker Application Container Engine...
I0807 11:59:34.118219 10775 command_runner.go:130] > Aug 07 18:57:34 functional-279000 dockerd[978]: time="2024-08-07T18:57:34.194894539Z" level=info msg="Starting up"
I0807 11:59:34.118226 10775 command_runner.go:130] > Aug 07 18:57:36 functional-279000 dockerd[978]: time="2024-08-07T18:57:36.378094386Z" level=info msg="Processing signal 'terminated'"
I0807 11:59:34.118237 10775 command_runner.go:130] > Aug 07 18:58:34 functional-279000 dockerd[978]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0807 11:59:34.118244 10775 command_runner.go:130] > Aug 07 18:58:34 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0807 11:59:34.118277 10775 command_runner.go:130] > Aug 07 18:58:34 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
I0807 11:59:34.118289 10775 command_runner.go:130] > Aug 07 18:58:34 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
I0807 11:59:34.118300 10775 command_runner.go:130] > Aug 07 18:58:34 functional-279000 systemd[1]: Starting Docker Application Container Engine...
I0807 11:59:34.118311 10775 command_runner.go:130] > Aug 07 18:58:34 functional-279000 dockerd[1191]: time="2024-08-07T18:58:34.244134024Z" level=info msg="Starting up"
I0807 11:59:34.118323 10775 command_runner.go:130] > Aug 07 18:59:34 functional-279000 dockerd[1191]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0807 11:59:34.118330 10775 command_runner.go:130] > Aug 07 18:59:34 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0807 11:59:34.118336 10775 command_runner.go:130] > Aug 07 18:59:34 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
I0807 11:59:34.118342 10775 command_runner.go:130] > Aug 07 18:59:34 functional-279000 systemd[1]: Failed to start Docker Application Container Engine.
I0807 11:59:34.143816 10775 out.go:177]
W0807 11:59:34.165766 10775 out.go:239] 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 07 18:56:29 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.474587822Z" level=info msg="Starting up"
Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.475048743Z" level=info msg="containerd not running, starting managed containerd"
Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.475634583Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=544
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.491110680Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506233149Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506254613Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506290084Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506300181Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506354340Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506387121Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506533631Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506568095Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506579792Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506593693Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506661498Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506816772Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508313051Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508369021Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508531020Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508573097Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508668301Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508735920Z" level=info msg="metadata content store policy set" policy=shared
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511380632Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511512259Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511559777Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511593506Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511631280Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511729244Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511968353Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512083908Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512123931Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512154940Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512189323Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512220647Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512254004Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512285053Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512316729Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512347399Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512376693Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512405415Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512440716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512475123Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512507609Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512538607Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512568712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512669302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512712798Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512746723Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512786072Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512926643Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512984433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513021391Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513056120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513096252Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513138081Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513173436Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513206351Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513264141Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513321728Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513361104Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513394870Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513427754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513500293Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513692738Z" level=info msg="NRI interface is disabled by configuration."
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514254266Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514310714Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514337399Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514370761Z" level=info msg="containerd successfully booted in 0.023908s"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.500110644Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.506485718Z" level=info msg="Loading containers: start."
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.594983621Z" 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 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.684863675Z" level=info msg="Loading containers: done."
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.692259053Z" level=info msg="Docker daemon" commit=cc13f95 containerd-snapshotter=false storage-driver=overlay2 version=27.1.1
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.692377148Z" level=info msg="Daemon has completed initialization"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.723576202Z" level=info msg="API listen on /var/run/docker.sock"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.723635500Z" level=info msg="API listen on [::]:2376"
Aug 07 18:56:30 functional-279000 systemd[1]: Started Docker Application Container Engine.
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.848625319Z" level=info msg="Processing signal 'terminated'"
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849505845Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849639004Z" level=info msg="Daemon shutdown complete"
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849701465Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849715699Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Aug 07 18:56:31 functional-279000 systemd[1]: Stopping Docker Application Container Engine...
Aug 07 18:56:32 functional-279000 systemd[1]: docker.service: Deactivated successfully.
Aug 07 18:56:32 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
Aug 07 18:56:32 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:56:32 functional-279000 dockerd[946]: time="2024-08-07T18:56:32.891349082Z" level=info msg="Starting up"
Aug 07 18:57:33 functional-279000 dockerd[946]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 07 18:57:33 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 07 18:57:33 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 07 18:57:33 functional-279000 systemd[1]: Failed to start Docker Application Container Engine.
Aug 07 18:57:34 functional-279000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Aug 07 18:57:34 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
Aug 07 18:57:34 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:57:34 functional-279000 dockerd[978]: time="2024-08-07T18:57:34.194894539Z" level=info msg="Starting up"
Aug 07 18:57:36 functional-279000 dockerd[978]: time="2024-08-07T18:57:36.378094386Z" level=info msg="Processing signal 'terminated'"
Aug 07 18:58:34 functional-279000 dockerd[978]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 07 18:58:34 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 07 18:58:34 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 07 18:58:34 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
Aug 07 18:58:34 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:58:34 functional-279000 dockerd[1191]: time="2024-08-07T18:58:34.244134024Z" level=info msg="Starting up"
Aug 07 18:59:34 functional-279000 dockerd[1191]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 07 18:59:34 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 07 18:59:34 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 07 18:59:34 functional-279000 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 07 18:56:29 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.474587822Z" level=info msg="Starting up"
Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.475048743Z" level=info msg="containerd not running, starting managed containerd"
Aug 07 18:56:29 functional-279000 dockerd[537]: time="2024-08-07T18:56:29.475634583Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=544
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.491110680Z" level=info msg="starting containerd" revision=8fc6bcff51318944179630522a095cc9dbf9f353 version=v1.7.20
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506233149Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506254613Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506290084Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506300181Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506354340Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506387121Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506533631Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506568095Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506579792Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506593693Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506661498Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.506816772Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508313051Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508369021Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508531020Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508573097Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508668301Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.508735920Z" level=info msg="metadata content store policy set" policy=shared
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511380632Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511512259Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511559777Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511593506Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511631280Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511729244Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.511968353Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512083908Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512123931Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512154940Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512189323Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512220647Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512254004Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512285053Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512316729Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512347399Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512376693Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512405415Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512440716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512475123Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512507609Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512538607Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512568712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512669302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512712798Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512746723Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512786072Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512926643Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.512984433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513021391Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513056120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513096252Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513138081Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513173436Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513206351Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513264141Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513321728Z" 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 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513361104Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513394870Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513427754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513500293Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.513692738Z" level=info msg="NRI interface is disabled by configuration."
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514254266Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514310714Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514337399Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Aug 07 18:56:29 functional-279000 dockerd[544]: time="2024-08-07T18:56:29.514370761Z" level=info msg="containerd successfully booted in 0.023908s"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.500110644Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.506485718Z" level=info msg="Loading containers: start."
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.594983621Z" 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 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.684863675Z" level=info msg="Loading containers: done."
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.692259053Z" level=info msg="Docker daemon" commit=cc13f95 containerd-snapshotter=false storage-driver=overlay2 version=27.1.1
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.692377148Z" level=info msg="Daemon has completed initialization"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.723576202Z" level=info msg="API listen on /var/run/docker.sock"
Aug 07 18:56:30 functional-279000 dockerd[537]: time="2024-08-07T18:56:30.723635500Z" level=info msg="API listen on [::]:2376"
Aug 07 18:56:30 functional-279000 systemd[1]: Started Docker Application Container Engine.
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.848625319Z" level=info msg="Processing signal 'terminated'"
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849505845Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849639004Z" level=info msg="Daemon shutdown complete"
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849701465Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Aug 07 18:56:31 functional-279000 dockerd[537]: time="2024-08-07T18:56:31.849715699Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Aug 07 18:56:31 functional-279000 systemd[1]: Stopping Docker Application Container Engine...
Aug 07 18:56:32 functional-279000 systemd[1]: docker.service: Deactivated successfully.
Aug 07 18:56:32 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
Aug 07 18:56:32 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:56:32 functional-279000 dockerd[946]: time="2024-08-07T18:56:32.891349082Z" level=info msg="Starting up"
Aug 07 18:57:33 functional-279000 dockerd[946]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 07 18:57:33 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 07 18:57:33 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 07 18:57:33 functional-279000 systemd[1]: Failed to start Docker Application Container Engine.
Aug 07 18:57:34 functional-279000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Aug 07 18:57:34 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
Aug 07 18:57:34 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:57:34 functional-279000 dockerd[978]: time="2024-08-07T18:57:34.194894539Z" level=info msg="Starting up"
Aug 07 18:57:36 functional-279000 dockerd[978]: time="2024-08-07T18:57:36.378094386Z" level=info msg="Processing signal 'terminated'"
Aug 07 18:58:34 functional-279000 dockerd[978]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 07 18:58:34 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 07 18:58:34 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 07 18:58:34 functional-279000 systemd[1]: Stopped Docker Application Container Engine.
Aug 07 18:58:34 functional-279000 systemd[1]: Starting Docker Application Container Engine...
Aug 07 18:58:34 functional-279000 dockerd[1191]: time="2024-08-07T18:58:34.244134024Z" level=info msg="Starting up"
Aug 07 18:59:34 functional-279000 dockerd[1191]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Aug 07 18:59:34 functional-279000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Aug 07 18:59:34 functional-279000 systemd[1]: docker.service: Failed with result 'exit-code'.
Aug 07 18:59:34 functional-279000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0807 11:59:34.165833 10775 out.go:239] *
*
W0807 11:59:34.166423 10775 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0807 11:59:34.249771 10775 out.go:177]
** /stderr **
functional_test.go:657: failed to soft start minikube. args "out/minikube-darwin-amd64 start -p functional-279000 --alsologtostderr -v=8": exit status 90
functional_test.go:659: soft start took 2m0.207323962s for "functional-279000" cluster.
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-279000 -n functional-279000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-darwin-amd64 status --format={{.Host}} -p functional-279000 -n functional-279000: exit status 6 (150.028468ms)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E0807 11:59:34.426564 10807 status.go:417] kubeconfig endpoint: get endpoint: "functional-279000" does not appear in /Users/jenkins/minikube-integration/19389-9550/kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "functional-279000" 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: TestFunctional/serial/SoftStart (120.36s)