=== RUN TestFunctional/serial/SoftStart
functional_test.go:655: (dbg) Run: out/minikube-windows-amd64.exe start -p functional-107700 --alsologtostderr -v=8
E0327 17:52:59.620126 13308 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\addons-254500\client.crt: The system cannot find the path specified.
functional_test.go:655: (dbg) Non-zero exit: out/minikube-windows-amd64.exe start -p functional-107700 --alsologtostderr -v=8: exit status 90 (2m49.2171463s)
-- stdout --
* [functional-107700] minikube v1.33.0-beta.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4170 Build 19045.4170
- KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
- MINIKUBE_LOCATION=18517
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on existing profile
* Starting "functional-107700" primary control-plane node in "functional-107700" cluster
* Updating the running hyperv "functional-107700" VM ...
-- /stdout --
** stderr **
W0327 17:51:53.050234 13628 main.go:291] Unable to resolve the current Docker CLI context "default": context "default": context not found: open C:\Users\jenkins.minikube5\.docker\contexts\meta\37a8eec1ce19687d132fe29051dca629d164e2c4958ba141d5f4133a33f0688f\meta.json: The system cannot find the path specified.
I0327 17:51:53.104222 13628 out.go:291] Setting OutFile to fd 1012 ...
I0327 17:51:53.104891 13628 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0327 17:51:53.104891 13628 out.go:304] Setting ErrFile to fd 984...
I0327 17:51:53.104891 13628 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0327 17:51:53.121754 13628 out.go:298] Setting JSON to false
I0327 17:51:53.125014 13628 start.go:129] hostinfo: {"hostname":"minikube5","uptime":92329,"bootTime":1711469583,"procs":191,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19045.4170 Build 19045.4170","kernelVersion":"10.0.19045.4170 Build 19045.4170","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"b047c2aa-b84e-4b82-894c-ed46f3580f4d"}
W0327 17:51:53.125014 13628 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0327 17:51:53.126399 13628 out.go:177] * [functional-107700] minikube v1.33.0-beta.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4170 Build 19045.4170
I0327 17:51:53.127088 13628 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
I0327 17:51:53.126986 13628 notify.go:220] Checking for updates...
I0327 17:51:53.127387 13628 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0327 17:51:53.128300 13628 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
I0327 17:51:53.128726 13628 out.go:177] - MINIKUBE_LOCATION=18517
I0327 17:51:53.129133 13628 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0327 17:51:53.130926 13628 config.go:182] Loaded profile config "functional-107700": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.29.3
I0327 17:51:53.131351 13628 driver.go:392] Setting default libvirt URI to qemu:///system
I0327 17:51:58.069046 13628 out.go:177] * Using the hyperv driver based on existing profile
I0327 17:51:58.069046 13628 start.go:297] selected driver: hyperv
I0327 17:51:58.069578 13628 start.go:901] validating driver "hyperv" against &{Name:functional-107700 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube/iso/minikube-v1.33.0-beta.0-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.43-beta.0@sha256:185c97a62a2e62a78b853e29e445f05ffbcf36149614c192af3643aa3888c4e8 Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperv 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.29.3 ClusterName:fun
ctional-107700 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:172.23.198.75 Port:8441 KubernetesVersion:v1.29.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:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 Mou
ntType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0327 17:51:58.069668 13628 start.go:912] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0327 17:51:58.111198 13628 cni.go:84] Creating CNI manager for ""
I0327 17:51:58.111198 13628 cni.go:158] "hyperv" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0327 17:51:58.111198 13628 start.go:340] cluster config:
{Name:functional-107700 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube/iso/minikube-v1.33.0-beta.0-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.43-beta.0@sha256:185c97a62a2e62a78b853e29e445f05ffbcf36149614c192af3643aa3888c4e8 Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperv 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.29.3 ClusterName:functional-107700 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APISer
verNames:[] 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:172.23.198.75 Port:8441 KubernetesVersion:v1.29.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:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetric
s:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0327 17:51:58.112208 13628 iso.go:125] acquiring lock: {Name:mk089c4d7c6c81209033c8c1f492e383b20e615f Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0327 17:51:58.113361 13628 out.go:177] * Starting "functional-107700" primary control-plane node in "functional-107700" cluster
I0327 17:51:58.113793 13628 preload.go:132] Checking if preload exists for k8s version v1.29.3 and runtime docker
I0327 17:51:58.113793 13628 preload.go:147] Found local preload: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4
I0327 17:51:58.113793 13628 cache.go:56] Caching tarball of preloaded images
I0327 17:51:58.114353 13628 preload.go:173] Found C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0327 17:51:58.114631 13628 cache.go:59] Finished verifying existence of preloaded tar for v1.29.3 on docker
I0327 17:51:58.114771 13628 profile.go:142] Saving config to C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\functional-107700\config.json ...
I0327 17:51:58.116396 13628 start.go:360] acquireMachinesLock for functional-107700: {Name:mkcc4972200741852cdd82af2325146d8aedcde8 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0327 17:51:58.116396 13628 start.go:364] duration metric: took 0s to acquireMachinesLock for "functional-107700"
I0327 17:51:58.116766 13628 start.go:96] Skipping create...Using existing machine configuration
I0327 17:51:58.116766 13628 fix.go:54] fixHost starting:
I0327 17:51:58.116920 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:00.731443 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:00.731443 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:00.731544 13628 fix.go:112] recreateIfNeeded on functional-107700: state=Running err=<nil>
W0327 17:52:00.731544 13628 fix.go:138] unexpected machine state, will restart: <nil>
I0327 17:52:00.731801 13628 out.go:177] * Updating the running hyperv "functional-107700" VM ...
I0327 17:52:00.732822 13628 machine.go:94] provisionDockerMachine start ...
I0327 17:52:00.732906 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:02.720822 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:02.720908 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:02.720908 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:05.088792 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:05.088792 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:05.093687 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:05.094243 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:05.094284 13628 main.go:141] libmachine: About to run SSH command:
hostname
I0327 17:52:05.234692 13628 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-107700
I0327 17:52:05.234692 13628 buildroot.go:166] provisioning hostname "functional-107700"
I0327 17:52:05.234692 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:07.223416 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:07.223416 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:07.223818 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:09.648143 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:09.648143 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:09.652710 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:09.653224 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:09.653224 13628 main.go:141] libmachine: About to run SSH command:
sudo hostname functional-107700 && echo "functional-107700" | sudo tee /etc/hostname
I0327 17:52:09.812620 13628 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-107700
I0327 17:52:09.812792 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:11.796498 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:11.796541 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:11.796608 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:14.202104 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:14.202104 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:14.202104 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:14.202104 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:14.202104 13628 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfunctional-107700' /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-107700/g' /etc/hosts;
else
echo '127.0.1.1 functional-107700' | sudo tee -a /etc/hosts;
fi
fi
I0327 17:52:14.343556 13628 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0327 17:52:14.343556 13628 buildroot.go:172] set auth options {CertDir:C:\Users\jenkins.minikube5\minikube-integration\.minikube CaCertPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem CaPrivateKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem ServerKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem ClientKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem ServerCertSANs:[] StorePath:C:\Users\jenkins.minikube5\minikube-integration\.minikube}
I0327 17:52:14.344161 13628 buildroot.go:174] setting up certificates
I0327 17:52:14.344161 13628 provision.go:84] configureAuth start
I0327 17:52:14.344257 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:16.378172 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:16.378172 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:16.378536 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:18.826272 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:18.826547 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:18.826547 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:20.832963 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:20.832963 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:20.833043 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:23.230280 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:23.230280 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:23.230280 13628 provision.go:143] copyHostCerts
I0327 17:52:23.230470 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem -> C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem
I0327 17:52:23.230739 13628 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem, removing ...
I0327 17:52:23.230739 13628 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\ca.pem
I0327 17:52:23.231166 13628 exec_runner.go:151] cp: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem --> C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem (1078 bytes)
I0327 17:52:23.231971 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem -> C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem
I0327 17:52:23.232207 13628 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem, removing ...
I0327 17:52:23.232207 13628 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cert.pem
I0327 17:52:23.232207 13628 exec_runner.go:151] cp: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem --> C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem (1123 bytes)
I0327 17:52:23.233286 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\key.pem -> C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem
I0327 17:52:23.233385 13628 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem, removing ...
I0327 17:52:23.233512 13628 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\key.pem
I0327 17:52:23.233755 13628 exec_runner.go:151] cp: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\key.pem --> C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem (1675 bytes)
I0327 17:52:23.234403 13628 provision.go:117] generating server cert: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem ca-key=C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem private-key=C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca-key.pem org=jenkins.functional-107700 san=[127.0.0.1 172.23.198.75 functional-107700 localhost minikube]
I0327 17:52:23.469129 13628 provision.go:177] copyRemoteCerts
I0327 17:52:23.478185 13628 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0327 17:52:23.478185 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:25.471437 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:25.471437 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:25.472378 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:27.892356 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:27.892557 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:27.892922 13628 sshutil.go:53] new ssh client: &{IP:172.23.198.75 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-107700\id_rsa Username:docker}
I0327 17:52:28.008658 13628 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (4.5302211s)
I0327 17:52:28.009000 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem -> /etc/docker/server.pem
I0327 17:52:28.009703 13628 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1220 bytes)
I0327 17:52:28.058470 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem -> /etc/docker/server-key.pem
I0327 17:52:28.059275 13628 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0327 17:52:28.104449 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem -> /etc/docker/ca.pem
I0327 17:52:28.104449 13628 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0327 17:52:28.152724 13628 provision.go:87] duration metric: took 13.8077557s to configureAuth
I0327 17:52:28.152757 13628 buildroot.go:189] setting minikube options for container-runtime
I0327 17:52:28.153256 13628 config.go:182] Loaded profile config "functional-107700": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.29.3
I0327 17:52:28.153256 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:30.180021 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:30.180021 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:30.180021 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:32.610331 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:32.610331 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:32.614904 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:32.615637 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:32.615637 13628 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0327 17:52:32.751084 13628 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0327 17:52:32.751128 13628 buildroot.go:70] root file system type: tmpfs
I0327 17:52:32.751286 13628 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0327 17:52:32.751383 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:34.797523 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:34.797523 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:34.798137 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:37.215145 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:37.215145 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:37.220445 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:37.220508 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:37.221034 13628 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=hyperv --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
I0327 17:52:37.395322 13628 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=hyperv --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
I0327 17:52:37.395517 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:39.404618 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:39.405300 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:39.405443 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:41.822615 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:41.823510 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:41.827285 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:41.827285 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:41.827285 13628 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; }
I0327 17:52:41.974962 13628 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0327 17:52:41.975062 13628 machine.go:97] duration metric: took 41.2399369s to provisionDockerMachine
I0327 17:52:41.975062 13628 start.go:293] postStartSetup for "functional-107700" (driver="hyperv")
I0327 17:52:41.975062 13628 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]
I0327 17:52:41.984897 13628 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
I0327 17:52:41.984897 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:43.962153 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:43.962153 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:43.962320 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:46.395302 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:46.395836 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:46.396162 13628 sshutil.go:53] new ssh client: &{IP:172.23.198.75 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-107700\id_rsa Username:docker}
I0327 17:52:46.521346 13628 ssh_runner.go:235] Completed: 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: (4.5361965s)
I0327 17:52:46.532049 13628 ssh_runner.go:195] Run: cat /etc/os-release
I0327 17:52:46.538258 13628 command_runner.go:130] > NAME=Buildroot
I0327 17:52:46.538258 13628 command_runner.go:130] > VERSION=2023.02.9-dirty
I0327 17:52:46.538258 13628 command_runner.go:130] > ID=buildroot
I0327 17:52:46.538258 13628 command_runner.go:130] > VERSION_ID=2023.02.9
I0327 17:52:46.538258 13628 command_runner.go:130] > PRETTY_NAME="Buildroot 2023.02.9"
I0327 17:52:46.539117 13628 info.go:137] Remote host: Buildroot 2023.02.9
I0327 17:52:46.539117 13628 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\addons for local assets ...
I0327 17:52:46.539117 13628 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\files for local assets ...
I0327 17:52:46.539117 13628 filesync.go:149] local asset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\133082.pem -> 133082.pem in /etc/ssl/certs
I0327 17:52:46.539117 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\133082.pem -> /etc/ssl/certs/133082.pem
I0327 17:52:46.540256 13628 filesync.go:149] local asset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\test\nested\copy\13308\hosts -> hosts in /etc/test/nested/copy/13308
I0327 17:52:46.540256 13628 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\test\nested\copy\13308\hosts -> /etc/test/nested/copy/13308/hosts
I0327 17:52:46.548696 13628 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs /etc/test/nested/copy/13308
I0327 17:52:46.567906 13628 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\133082.pem --> /etc/ssl/certs/133082.pem (1708 bytes)
I0327 17:52:46.615866 13628 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\test\nested\copy\13308\hosts --> /etc/test/nested/copy/13308/hosts (40 bytes)
I0327 17:52:46.665467 13628 start.go:296] duration metric: took 4.690143s for postStartSetup
I0327 17:52:46.665467 13628 fix.go:56] duration metric: took 48.5459896s for fixHost
I0327 17:52:46.665467 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:48.668425 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:48.668425 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:48.668425 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:51.092190 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:51.092190 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:51.098831 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:51.099562 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:51.099562 13628 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0327 17:52:51.240471 13628 main.go:141] libmachine: SSH cmd err, output: <nil>: 1711561971.410176482
I0327 17:52:51.240471 13628 fix.go:216] guest clock: 1711561971.410176482
I0327 17:52:51.240471 13628 fix.go:229] Guest: 2024-03-27 17:52:51.410176482 +0000 UTC Remote: 2024-03-27 17:52:46.6654672 +0000 UTC m=+53.698808101 (delta=4.744709282s)
I0327 17:52:51.241197 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:53.249763 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:53.249763 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:53.250171 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:52:55.678845 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:52:55.679323 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:55.683001 13628 main.go:141] libmachine: Using SSH client type: native
I0327 17:52:55.683426 13628 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x909f80] 0x90cb60 <nil> [] 0s} 172.23.198.75 22 <nil> <nil>}
I0327 17:52:55.683426 13628 main.go:141] libmachine: About to run SSH command:
sudo date -s @1711561971
I0327 17:52:55.834465 13628 main.go:141] libmachine: SSH cmd err, output: <nil>: Wed Mar 27 17:52:51 UTC 2024
I0327 17:52:55.834548 13628 fix.go:236] clock set: Wed Mar 27 17:52:51 UTC 2024
(err=<nil>)
I0327 17:52:55.834548 13628 start.go:83] releasing machines lock for "functional-107700", held for 57.7149293s
I0327 17:52:55.834750 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:52:57.849442 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:52:57.849970 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:52:57.849970 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:53:00.257076 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:53:00.257076 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:53:00.260473 13628 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0327 17:53:00.260579 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:53:00.270286 13628 ssh_runner.go:195] Run: cat /version.json
I0327 17:53:00.270286 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-107700 ).state
I0327 17:53:02.304251 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:53:02.304251 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:53:02.304251 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:53:02.304251 13628 main.go:141] libmachine: [stdout =====>] : Running
I0327 17:53:02.304782 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:53:02.304920 13628 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-107700 ).networkadapters[0]).ipaddresses[0]
I0327 17:53:04.787638 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:53:04.787638 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:53:04.788234 13628 sshutil.go:53] new ssh client: &{IP:172.23.198.75 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-107700\id_rsa Username:docker}
I0327 17:53:04.810802 13628 main.go:141] libmachine: [stdout =====>] : 172.23.198.75
I0327 17:53:04.811663 13628 main.go:141] libmachine: [stderr =====>] :
I0327 17:53:04.811989 13628 sshutil.go:53] new ssh client: &{IP:172.23.198.75 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-107700\id_rsa Username:docker}
I0327 17:53:04.952061 13628 command_runner.go:130] > <a href="https://github.com/kubernetes/registry.k8s.io">Temporary Redirect</a>.
I0327 17:53:04.952776 13628 command_runner.go:130] > {"iso_version": "v1.33.0-beta.0", "kicbase_version": "v0.0.42-1710284843-18375", "minikube_version": "v1.33.0-beta.0", "commit": "3e67840d02a296d807adca10af96b93dacfc918d"}
I0327 17:53:04.952845 13628 ssh_runner.go:235] Completed: cat /version.json: (4.6822973s)
I0327 17:53:04.952776 13628 ssh_runner.go:235] Completed: curl -sS -m 2 https://registry.k8s.io/: (4.6920419s)
I0327 17:53:04.962531 13628 ssh_runner.go:195] Run: systemctl --version
I0327 17:53:04.972181 13628 command_runner.go:130] > systemd 252 (252)
I0327 17:53:04.972237 13628 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
I0327 17:53:04.980581 13628 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I0327 17:53:04.989173 13628 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W0327 17:53:04.989969 13628 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0327 17:53:05.001013 13628 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" ;
I0327 17:53:05.018635 13628 cni.go:259] no active bridge cni configs found in "/etc/cni/net.d" - nothing to disable
I0327 17:53:05.018635 13628 start.go:494] detecting cgroup driver to use...
I0327 17:53:05.018635 13628 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"
I0327 17:53:05.054704 13628 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I0327 17:53:05.064231 13628 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"
I0327 17:53:05.093809 13628 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"
I0327 17:53:05.114327 13628 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0327 17:53:05.123335 13628 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0327 17:53:05.155395 13628 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"
I0327 17:53:05.184484 13628 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0327 17:53:05.217045 13628 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0327 17:53:05.250100 13628 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0327 17:53:05.280980 13628 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"
I0327 17:53:05.311065 13628 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0327 17:53:05.339822 13628 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"
I0327 17:53:05.370140 13628 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0327 17:53:05.388696 13628 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I0327 17:53:05.397480 13628 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0327 17:53:05.430082 13628 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0327 17:53:05.631686 13628 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0327 17:53:05.665124 13628 start.go:494] detecting cgroup driver to use...
I0327 17:53:05.675362 13628 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0327 17:53:05.699545 13628 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I0327 17:53:05.699545 13628 command_runner.go:130] > [Unit]
I0327 17:53:05.699635 13628 command_runner.go:130] > Description=Docker Application Container Engine
I0327 17:53:05.699635 13628 command_runner.go:130] > Documentation=https://docs.docker.com
I0327 17:53:05.699635 13628 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I0327 17:53:05.699635 13628 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I0327 17:53:05.699724 13628 command_runner.go:130] > StartLimitBurst=3
I0327 17:53:05.699724 13628 command_runner.go:130] > StartLimitIntervalSec=60
I0327 17:53:05.699724 13628 command_runner.go:130] > [Service]
I0327 17:53:05.699724 13628 command_runner.go:130] > Type=notify
I0327 17:53:05.699724 13628 command_runner.go:130] > Restart=on-failure
I0327 17:53:05.699798 13628 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I0327 17:53:05.699840 13628 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I0327 17:53:05.699840 13628 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I0327 17:53:05.699840 13628 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I0327 17:53:05.699912 13628 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I0327 17:53:05.699912 13628 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I0327 17:53:05.699949 13628 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I0327 17:53:05.699949 13628 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I0327 17:53:05.700019 13628 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I0327 17:53:05.700019 13628 command_runner.go:130] > ExecStart=
I0327 17:53:05.700089 13628 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=hyperv --insecure-registry 10.96.0.0/12
I0327 17:53:05.700119 13628 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I0327 17:53:05.700119 13628 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I0327 17:53:05.700177 13628 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I0327 17:53:05.700177 13628 command_runner.go:130] > LimitNOFILE=infinity
I0327 17:53:05.700215 13628 command_runner.go:130] > LimitNPROC=infinity
I0327 17:53:05.700215 13628 command_runner.go:130] > LimitCORE=infinity
I0327 17:53:05.700215 13628 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I0327 17:53:05.700215 13628 command_runner.go:130] > # Only systemd 226 and above support this version.
I0327 17:53:05.700215 13628 command_runner.go:130] > TasksMax=infinity
I0327 17:53:05.700310 13628 command_runner.go:130] > TimeoutStartSec=0
I0327 17:53:05.700339 13628 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I0327 17:53:05.700339 13628 command_runner.go:130] > Delegate=yes
I0327 17:53:05.700376 13628 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I0327 17:53:05.700376 13628 command_runner.go:130] > KillMode=process
I0327 17:53:05.700402 13628 command_runner.go:130] > [Install]
I0327 17:53:05.700456 13628 command_runner.go:130] > WantedBy=multi-user.target
I0327 17:53:05.709478 13628 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0327 17:53:05.744691 13628 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0327 17:53:05.780231 13628 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0327 17:53:05.813012 13628 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0327 17:53:05.835775 13628 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"
I0327 17:53:05.868510 13628 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I0327 17:53:05.882953 13628 ssh_runner.go:195] Run: which cri-dockerd
I0327 17:53:05.889821 13628 command_runner.go:130] > /usr/bin/cri-dockerd
I0327 17:53:05.901471 13628 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0327 17:53:05.921047 13628 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0327 17:53:05.963625 13628 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0327 17:53:06.171480 13628 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0327 17:53:06.371987 13628 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0327 17:53:06.372393 13628 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0327 17:53:06.413642 13628 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0327 17:53:06.613569 13628 ssh_runner.go:195] Run: sudo systemctl restart docker
I0327 17:54:42.076644 13628 command_runner.go:130] ! Job for docker.service failed because the control process exited with error code.
I0327 17:54:42.076644 13628 command_runner.go:130] ! See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
I0327 17:54:42.076644 13628 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m35.4577481s)
I0327 17:54:42.088399 13628 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0327 17:54:42.114260 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 systemd[1]: Starting Docker Application Container Engine...
I0327 17:54:42.114357 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.085622091Z" level=info msg="Starting up"
I0327 17:54:42.114357 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.086661659Z" level=info msg="containerd not running, starting managed containerd"
I0327 17:54:42.114455 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.087706529Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=668
I0327 17:54:42.114455 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.118019549Z" level=info msg="starting containerd" revision=dcf2847247e18caba8dce86522029642f60fe96b version=v1.7.14
I0327 17:54:42.114547 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146062183Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
I0327 17:54:42.114547 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146115597Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
I0327 17:54:42.114547 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146189316Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
I0327 17:54:42.114654 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146205420Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.114654 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146306947Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
I0327 17:54:42.114763 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146407773Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.114869 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146710251Z" 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
I0327 17:54:42.114869 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146813077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.114869 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146833983Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
I0327 17:54:42.114972 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146846486Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.114972 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.147076945Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.115078 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.147421134Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.115078 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150530436Z" 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
I0327 17:54:42.115181 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150680275Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
I0327 17:54:42.115284 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150839816Z" 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
I0327 17:54:42.115284 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150964948Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
I0327 17:54:42.115284 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151111086Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
I0327 17:54:42.115390 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151254423Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
I0327 17:54:42.115390 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151371153Z" level=info msg="metadata content store policy set" policy=shared
I0327 17:54:42.115486 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161518571Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
I0327 17:54:42.115486 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161682913Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
I0327 17:54:42.115486 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161706319Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
I0327 17:54:42.115594 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161724224Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
I0327 17:54:42.115594 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161739828Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
I0327 17:54:42.115594 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161856058Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
I0327 17:54:42.115692 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162139231Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
I0327 17:54:42.115692 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162343484Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
I0327 17:54:42.115692 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162443109Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
I0327 17:54:42.115812 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162468516Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
I0327 17:54:42.115812 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162484820Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
I0327 17:54:42.115916 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162499724Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
I0327 17:54:42.115916 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162563841Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
I0327 17:54:42.115916 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162597149Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
I0327 17:54:42.116032 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162617054Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
I0327 17:54:42.116032 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162630658Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
I0327 17:54:42.116127 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162644261Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
I0327 17:54:42.116127 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162657465Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
I0327 17:54:42.116237 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162678970Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116237 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162693074Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116237 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162706977Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116340 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162721681Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116340 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162734885Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116340 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162748388Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116466 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162760791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116466 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162796401Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116466 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162811404Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116563 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162827509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116563 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162840712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116671 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162853615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116671 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162867319Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116671 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162884823Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
I0327 17:54:42.116776 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162935736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116776 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162951741Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116776 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162964544Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
I0327 17:54:42.116882 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163009556Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
I0327 17:54:42.116882 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163028360Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
I0327 17:54:42.116988 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163041564Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
I0327 17:54:42.116988 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163053567Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
I0327 17:54:42.116988 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163150792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
I0327 17:54:42.116988 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163251518Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
I0327 17:54:42.117133 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163271223Z" level=info msg="NRI interface is disabled by configuration."
I0327 17:54:42.117133 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163626615Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
I0327 17:54:42.117253 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163754248Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
I0327 17:54:42.117253 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163795658Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
I0327 17:54:42.117344 13628 command_runner.go:130] > Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163884181Z" level=info msg="containerd successfully booted in 0.047309s"
I0327 17:54:42.117344 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.131270378Z" level=info msg="[graphdriver] trying configured driver: overlay2"
I0327 17:54:42.117344 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.149190070Z" level=info msg="Loading containers: start."
I0327 17:54:42.117450 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.398409779Z" level=info msg="Loading containers: done."
I0327 17:54:42.117450 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.414483652Z" level=info msg="Docker daemon" commit=061aa95 containerd-snapshotter=false storage-driver=overlay2 version=25.0.4
I0327 17:54:42.117450 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.414612784Z" level=info msg="Daemon has completed initialization"
I0327 17:54:42.117450 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.471578966Z" level=info msg="API listen on /var/run/docker.sock"
I0327 17:54:42.117553 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 systemd[1]: Started Docker Application Container Engine.
I0327 17:54:42.117553 13628 command_runner.go:130] > Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.471944856Z" level=info msg="API listen on [::]:2376"
I0327 17:54:42.117553 13628 command_runner.go:130] > Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.612094058Z" level=info msg="Processing signal 'terminated'"
I0327 17:54:42.117661 13628 command_runner.go:130] > Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613243421Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
I0327 17:54:42.117661 13628 command_runner.go:130] > Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613907757Z" level=info msg="Daemon shutdown complete"
I0327 17:54:42.117661 13628 command_runner.go:130] > Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613989562Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
I0327 17:54:42.117766 13628 command_runner.go:130] > Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.614140170Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
I0327 17:54:42.117766 13628 command_runner.go:130] > Mar 27 17:50:40 functional-107700 systemd[1]: Stopping Docker Application Container Engine...
I0327 17:54:42.117872 13628 command_runner.go:130] > Mar 27 17:50:41 functional-107700 systemd[1]: docker.service: Deactivated successfully.
I0327 17:54:42.117872 13628 command_runner.go:130] > Mar 27 17:50:41 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
I0327 17:54:42.117872 13628 command_runner.go:130] > Mar 27 17:50:41 functional-107700 systemd[1]: Starting Docker Application Container Engine...
I0327 17:54:42.117872 13628 command_runner.go:130] > Mar 27 17:50:41 functional-107700 dockerd[1016]: time="2024-03-27T17:50:41.694649313Z" level=info msg="Starting up"
I0327 17:54:42.117977 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 dockerd[1016]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0327 17:54:42.117977 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0327 17:54:42.118082 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
I0327 17:54:42.118082 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
I0327 17:54:42.118082 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
I0327 17:54:42.118192 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
I0327 17:54:42.118192 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 systemd[1]: Starting Docker Application Container Engine...
I0327 17:54:42.118192 13628 command_runner.go:130] > Mar 27 17:51:41 functional-107700 dockerd[1039]: time="2024-03-27T17:51:41.914861955Z" level=info msg="Starting up"
I0327 17:54:42.118287 13628 command_runner.go:130] > Mar 27 17:52:41 functional-107700 dockerd[1039]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0327 17:54:42.118287 13628 command_runner.go:130] > Mar 27 17:52:41 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0327 17:54:42.118392 13628 command_runner.go:130] > Mar 27 17:52:41 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
I0327 17:54:42.118392 13628 command_runner.go:130] > Mar 27 17:52:41 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
I0327 17:54:42.118392 13628 command_runner.go:130] > Mar 27 17:52:42 functional-107700 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
I0327 17:54:42.118392 13628 command_runner.go:130] > Mar 27 17:52:42 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
I0327 17:54:42.118496 13628 command_runner.go:130] > Mar 27 17:52:42 functional-107700 systemd[1]: Starting Docker Application Container Engine...
I0327 17:54:42.118496 13628 command_runner.go:130] > Mar 27 17:52:42 functional-107700 dockerd[1264]: time="2024-03-27T17:52:42.120653651Z" level=info msg="Starting up"
I0327 17:54:42.118496 13628 command_runner.go:130] > Mar 27 17:53:06 functional-107700 dockerd[1264]: time="2024-03-27T17:53:06.809264232Z" level=info msg="Processing signal 'terminated'"
I0327 17:54:42.118496 13628 command_runner.go:130] > Mar 27 17:53:42 functional-107700 dockerd[1264]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0327 17:54:42.118601 13628 command_runner.go:130] > Mar 27 17:53:42 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0327 17:54:42.118601 13628 command_runner.go:130] > Mar 27 17:53:42 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
I0327 17:54:42.118601 13628 command_runner.go:130] > Mar 27 17:53:42 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
I0327 17:54:42.118707 13628 command_runner.go:130] > Mar 27 17:53:42 functional-107700 systemd[1]: Starting Docker Application Container Engine...
I0327 17:54:42.118707 13628 command_runner.go:130] > Mar 27 17:53:42 functional-107700 dockerd[1490]: time="2024-03-27T17:53:42.221897767Z" level=info msg="Starting up"
I0327 17:54:42.118707 13628 command_runner.go:130] > Mar 27 17:54:42 functional-107700 dockerd[1490]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0327 17:54:42.118707 13628 command_runner.go:130] > Mar 27 17:54:42 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0327 17:54:42.118834 13628 command_runner.go:130] > Mar 27 17:54:42 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
I0327 17:54:42.118834 13628 command_runner.go:130] > Mar 27 17:54:42 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
I0327 17:54:42.125157 13628 out.go:177]
W0327 17:54:42.126426 13628 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 --
Mar 27 17:50:10 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.085622091Z" level=info msg="Starting up"
Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.086661659Z" level=info msg="containerd not running, starting managed containerd"
Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.087706529Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=668
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.118019549Z" level=info msg="starting containerd" revision=dcf2847247e18caba8dce86522029642f60fe96b version=v1.7.14
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146062183Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146115597Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146189316Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146205420Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146306947Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146407773Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146710251Z" 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
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146813077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146833983Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146846486Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.147076945Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.147421134Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150530436Z" 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
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150680275Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150839816Z" 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
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150964948Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151111086Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151254423Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151371153Z" level=info msg="metadata content store policy set" policy=shared
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161518571Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161682913Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161706319Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161724224Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161739828Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161856058Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162139231Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162343484Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162443109Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162468516Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162484820Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162499724Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162563841Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162597149Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162617054Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162630658Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162644261Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162657465Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162678970Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162693074Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162706977Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162721681Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162734885Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162748388Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162760791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162796401Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162811404Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162827509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162840712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162853615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162867319Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162884823Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162935736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162951741Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162964544Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163009556Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163028360Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163041564Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163053567Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163150792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163251518Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163271223Z" level=info msg="NRI interface is disabled by configuration."
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163626615Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163754248Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163795658Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163884181Z" level=info msg="containerd successfully booted in 0.047309s"
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.131270378Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.149190070Z" level=info msg="Loading containers: start."
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.398409779Z" level=info msg="Loading containers: done."
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.414483652Z" level=info msg="Docker daemon" commit=061aa95 containerd-snapshotter=false storage-driver=overlay2 version=25.0.4
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.414612784Z" level=info msg="Daemon has completed initialization"
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.471578966Z" level=info msg="API listen on /var/run/docker.sock"
Mar 27 17:50:11 functional-107700 systemd[1]: Started Docker Application Container Engine.
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.471944856Z" level=info msg="API listen on [::]:2376"
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.612094058Z" level=info msg="Processing signal 'terminated'"
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613243421Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613907757Z" level=info msg="Daemon shutdown complete"
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613989562Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.614140170Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Mar 27 17:50:40 functional-107700 systemd[1]: Stopping Docker Application Container Engine...
Mar 27 17:50:41 functional-107700 systemd[1]: docker.service: Deactivated successfully.
Mar 27 17:50:41 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:50:41 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:50:41 functional-107700 dockerd[1016]: time="2024-03-27T17:50:41.694649313Z" level=info msg="Starting up"
Mar 27 17:51:41 functional-107700 dockerd[1016]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:51:41 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Mar 27 17:51:41 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:51:41 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:51:41 functional-107700 dockerd[1039]: time="2024-03-27T17:51:41.914861955Z" level=info msg="Starting up"
Mar 27 17:52:41 functional-107700 dockerd[1039]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:52:41 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:52:41 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:52:41 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
Mar 27 17:52:42 functional-107700 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
Mar 27 17:52:42 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:52:42 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:52:42 functional-107700 dockerd[1264]: time="2024-03-27T17:52:42.120653651Z" level=info msg="Starting up"
Mar 27 17:53:06 functional-107700 dockerd[1264]: time="2024-03-27T17:53:06.809264232Z" level=info msg="Processing signal 'terminated'"
Mar 27 17:53:42 functional-107700 dockerd[1264]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:53:42 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:53:42 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:53:42 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:53:42 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:53:42 functional-107700 dockerd[1490]: time="2024-03-27T17:53:42.221897767Z" level=info msg="Starting up"
Mar 27 17:54:42 functional-107700 dockerd[1490]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:54:42 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:54:42 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:54:42 functional-107700 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 --
Mar 27 17:50:10 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.085622091Z" level=info msg="Starting up"
Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.086661659Z" level=info msg="containerd not running, starting managed containerd"
Mar 27 17:50:10 functional-107700 dockerd[661]: time="2024-03-27T17:50:10.087706529Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=668
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.118019549Z" level=info msg="starting containerd" revision=dcf2847247e18caba8dce86522029642f60fe96b version=v1.7.14
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146062183Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146115597Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146189316Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146205420Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146306947Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146407773Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146710251Z" 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
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146813077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146833983Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.146846486Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.147076945Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.147421134Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150530436Z" 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
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150680275Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150839816Z" 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
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.150964948Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151111086Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151254423Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.151371153Z" level=info msg="metadata content store policy set" policy=shared
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161518571Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161682913Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161706319Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161724224Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161739828Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.161856058Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162139231Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162343484Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162443109Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162468516Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162484820Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162499724Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162563841Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162597149Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162617054Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162630658Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162644261Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162657465Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162678970Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162693074Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162706977Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162721681Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162734885Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162748388Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162760791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162796401Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162811404Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162827509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162840712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162853615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162867319Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162884823Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162935736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162951741Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.162964544Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163009556Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163028360Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163041564Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163053567Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163150792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163251518Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163271223Z" level=info msg="NRI interface is disabled by configuration."
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163626615Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163754248Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163795658Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Mar 27 17:50:10 functional-107700 dockerd[668]: time="2024-03-27T17:50:10.163884181Z" level=info msg="containerd successfully booted in 0.047309s"
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.131270378Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.149190070Z" level=info msg="Loading containers: start."
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.398409779Z" level=info msg="Loading containers: done."
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.414483652Z" level=info msg="Docker daemon" commit=061aa95 containerd-snapshotter=false storage-driver=overlay2 version=25.0.4
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.414612784Z" level=info msg="Daemon has completed initialization"
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.471578966Z" level=info msg="API listen on /var/run/docker.sock"
Mar 27 17:50:11 functional-107700 systemd[1]: Started Docker Application Container Engine.
Mar 27 17:50:11 functional-107700 dockerd[661]: time="2024-03-27T17:50:11.471944856Z" level=info msg="API listen on [::]:2376"
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.612094058Z" level=info msg="Processing signal 'terminated'"
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613243421Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613907757Z" level=info msg="Daemon shutdown complete"
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.613989562Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Mar 27 17:50:40 functional-107700 dockerd[661]: time="2024-03-27T17:50:40.614140170Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Mar 27 17:50:40 functional-107700 systemd[1]: Stopping Docker Application Container Engine...
Mar 27 17:50:41 functional-107700 systemd[1]: docker.service: Deactivated successfully.
Mar 27 17:50:41 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:50:41 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:50:41 functional-107700 dockerd[1016]: time="2024-03-27T17:50:41.694649313Z" level=info msg="Starting up"
Mar 27 17:51:41 functional-107700 dockerd[1016]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:51:41 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
Mar 27 17:51:41 functional-107700 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Mar 27 17:51:41 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:51:41 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:51:41 functional-107700 dockerd[1039]: time="2024-03-27T17:51:41.914861955Z" level=info msg="Starting up"
Mar 27 17:52:41 functional-107700 dockerd[1039]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:52:41 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:52:41 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:52:41 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
Mar 27 17:52:42 functional-107700 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
Mar 27 17:52:42 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:52:42 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:52:42 functional-107700 dockerd[1264]: time="2024-03-27T17:52:42.120653651Z" level=info msg="Starting up"
Mar 27 17:53:06 functional-107700 dockerd[1264]: time="2024-03-27T17:53:06.809264232Z" level=info msg="Processing signal 'terminated'"
Mar 27 17:53:42 functional-107700 dockerd[1264]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:53:42 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:53:42 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:53:42 functional-107700 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 17:53:42 functional-107700 systemd[1]: Starting Docker Application Container Engine...
Mar 27 17:53:42 functional-107700 dockerd[1490]: time="2024-03-27T17:53:42.221897767Z" level=info msg="Starting up"
Mar 27 17:54:42 functional-107700 dockerd[1490]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 17:54:42 functional-107700 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 17:54:42 functional-107700 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 17:54:42 functional-107700 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0327 17:54:42.126426 13628 out.go:239] *
*
W0327 17:54:42.127663 13628 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0327 17:54:42.128591 13628 out.go:177]
** /stderr **
functional_test.go:657: failed to soft start minikube. args "out/minikube-windows-amd64.exe start -p functional-107700 --alsologtostderr -v=8": exit status 90
functional_test.go:659: soft start took 2m49.3661681s for "functional-107700" cluster.
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.Host}} -p functional-107700 -n functional-107700
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-windows-amd64.exe status --format={{.Host}} -p functional-107700 -n functional-107700: exit status 6 (11.322399s)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
W0327 17:54:42.425343 7072 main.go:291] Unable to resolve the current Docker CLI context "default": context "default": context not found: open C:\Users\jenkins.minikube5\.docker\contexts\meta\37a8eec1ce19687d132fe29051dca629d164e2c4958ba141d5f4133a33f0688f\meta.json: The system cannot find the path specified.
E0327 17:54:53.584837 7072 status.go:417] kubeconfig endpoint: get endpoint: "functional-107700" does not appear in C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "functional-107700" 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 (180.69s)