=== RUN TestFunctional/serial/SoftStart
functional_test.go:655: (dbg) Run: out/minikube-windows-amd64.exe start -p functional-583600 --alsologtostderr -v=8
E0229 01:04:28.520011 3312 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\addons-611800\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-583600 --alsologtostderr -v=8: exit status 90 (2m49.3479815s)
-- stdout --
* [functional-583600] minikube v1.32.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4046 Build 19045.4046
- KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
- MINIKUBE_LOCATION=18063
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on existing profile
* Starting control plane node functional-583600 in cluster functional-583600
* Updating the running hyperv "functional-583600" VM ...
-- /stdout --
** stderr **
W0229 01:02:39.536763 13184 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.
I0229 01:02:39.588787 13184 out.go:291] Setting OutFile to fd 840 ...
I0229 01:02:39.589113 13184 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0229 01:02:39.589113 13184 out.go:304] Setting ErrFile to fd 516...
I0229 01:02:39.589113 13184 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0229 01:02:39.611049 13184 out.go:298] Setting JSON to false
I0229 01:02:39.614142 13184 start.go:129] hostinfo: {"hostname":"minikube5","uptime":264786,"bootTime":1708903772,"procs":201,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19045.4046 Build 19045.4046","kernelVersion":"10.0.19045.4046 Build 19045.4046","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"b047c2aa-b84e-4b82-894c-ed46f3580f4d"}
W0229 01:02:39.614241 13184 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0229 01:02:39.615416 13184 out.go:177] * [functional-583600] minikube v1.32.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4046 Build 19045.4046
I0229 01:02:39.615966 13184 notify.go:220] Checking for updates...
I0229 01:02:39.615966 13184 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
I0229 01:02:39.616703 13184 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0229 01:02:39.617458 13184 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
I0229 01:02:39.617458 13184 out.go:177] - MINIKUBE_LOCATION=18063
I0229 01:02:39.618132 13184 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0229 01:02:39.619636 13184 config.go:182] Loaded profile config "functional-583600": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I0229 01:02:39.619901 13184 driver.go:392] Setting default libvirt URI to qemu:///system
I0229 01:02:44.658842 13184 out.go:177] * Using the hyperv driver based on existing profile
I0229 01:02:44.659553 13184 start.go:299] selected driver: hyperv
I0229 01:02:44.659553 13184 start.go:903] validating driver "hyperv" against &{Name:functional-583600 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18020/minikube-v1.32.1-1708638130-18020-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1708944392-18244@sha256:8610dac8144c3f59a6cf50871eb10395cea122e148262744231a04c396033b08 Memory:4000 CPUs:2 DiskSize:20000 VMDriver: 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:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesCo
nfig:{KubernetesVersion:v1.28.4 ClusterName:functional-583600 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8441 NodeName:} Nodes:[{Name: IP:172.19.5.240 Port:8441 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:doc
ker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0229 01:02:44.659553 13184 start.go:914] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0229 01:02:44.704873 13184 cni.go:84] Creating CNI manager for ""
I0229 01:02:44.704873 13184 cni.go:158] "hyperv" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0229 01:02:44.704873 13184 start_flags.go:323] config:
{Name:functional-583600 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18020/minikube-v1.32.1-1708638130-18020-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1708944392-18244@sha256:8610dac8144c3f59a6cf50871eb10395cea122e148262744231a04c396033b08 Memory:4000 CPUs:2 DiskSize:20000 VMDriver: 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:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.28.4 ClusterName:functional-583600 Namespace:default
APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8441 NodeName:} Nodes:[{Name: IP:172.19.5.240 Port:8441 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID
:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0229 01:02:44.705594 13184 iso.go:125] acquiring lock: {Name:mk91f2ee29fbed5605669750e8cfa308a1229357 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0229 01:02:44.706922 13184 out.go:177] * Starting control plane node functional-583600 in cluster functional-583600
I0229 01:02:44.706922 13184 preload.go:132] Checking if preload exists for k8s version v1.28.4 and runtime docker
I0229 01:02:44.706922 13184 preload.go:148] Found local preload: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4
I0229 01:02:44.706922 13184 cache.go:56] Caching tarball of preloaded images
I0229 01:02:44.707909 13184 preload.go:174] Found C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0229 01:02:44.708100 13184 cache.go:59] Finished verifying existence of preloaded tar for v1.28.4 on docker
I0229 01:02:44.708281 13184 profile.go:148] Saving config to C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\functional-583600\config.json ...
I0229 01:02:44.710260 13184 start.go:365] acquiring machines lock for functional-583600: {Name:mkcc4972200741852cdd82af2325146d8aedcde8 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0229 01:02:44.710435 13184 start.go:369] acquired machines lock for "functional-583600" in 114.6µs
I0229 01:02:44.710610 13184 start.go:96] Skipping create...Using existing machine configuration
I0229 01:02:44.710610 13184 fix.go:54] fixHost starting:
I0229 01:02:44.711130 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:02:47.354064 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:02:47.354064 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:02:47.354064 13184 fix.go:102] recreateIfNeeded on functional-583600: state=Running err=<nil>
W0229 01:02:47.354064 13184 fix.go:128] unexpected machine state, will restart: <nil>
I0229 01:02:47.354688 13184 out.go:177] * Updating the running hyperv "functional-583600" VM ...
I0229 01:02:47.355271 13184 machine.go:88] provisioning docker machine ...
I0229 01:02:47.355271 13184 buildroot.go:166] provisioning hostname "functional-583600"
I0229 01:02:47.355271 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:02:49.407626 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:02:49.407678 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:02:49.407678 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:02:51.829620 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:02:51.830098 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:02:51.833716 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:02:51.834316 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:02:51.834316 13184 main.go:141] libmachine: About to run SSH command:
sudo hostname functional-583600 && echo "functional-583600" | sudo tee /etc/hostname
I0229 01:02:51.994232 13184 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-583600
I0229 01:02:51.994336 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:02:54.039644 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:02:54.039644 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:02:54.039644 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:02:56.491837 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:02:56.491837 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:02:56.496149 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:02:56.496149 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:02:56.496678 13184 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfunctional-583600' /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-583600/g' /etc/hosts;
else
echo '127.0.1.1 functional-583600' | sudo tee -a /etc/hosts;
fi
fi
I0229 01:02:56.623110 13184 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0229 01:02:56.623110 13184 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}
I0229 01:02:56.623232 13184 buildroot.go:174] setting up certificates
I0229 01:02:56.623232 13184 provision.go:83] configureAuth start
I0229 01:02:56.623232 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:02:58.624297 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:02:58.624297 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:02:58.624810 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:01.072851 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:01.072851 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:01.072851 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:03.041742 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:03.041742 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:03.041742 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:05.440928 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:05.440928 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:05.440928 13184 provision.go:138] copyHostCerts
I0229 01:03:05.441886 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem -> C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem
I0229 01:03:05.442157 13184 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem, removing ...
I0229 01:03:05.442157 13184 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\ca.pem
I0229 01:03:05.442520 13184 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)
I0229 01:03:05.443501 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem -> C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem
I0229 01:03:05.443579 13184 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem, removing ...
I0229 01:03:05.443579 13184 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cert.pem
I0229 01:03:05.443579 13184 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)
I0229 01:03:05.444552 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\key.pem -> C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem
I0229 01:03:05.444552 13184 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem, removing ...
I0229 01:03:05.444552 13184 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\key.pem
I0229 01:03:05.444552 13184 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)
I0229 01:03:05.445350 13184 provision.go:112] 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-583600 san=[172.19.5.240 172.19.5.240 localhost 127.0.0.1 minikube functional-583600]
I0229 01:03:05.651675 13184 provision.go:172] copyRemoteCerts
I0229 01:03:05.660199 13184 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0229 01:03:05.661202 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:07.679344 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:07.679709 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:07.679785 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:10.106739 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:10.106890 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:10.107248 13184 sshutil.go:53] new ssh client: &{IP:172.19.5.240 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-583600\id_rsa Username:docker}
I0229 01:03:10.223622 13184 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (4.5621648s)
I0229 01:03:10.223622 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem -> /etc/docker/ca.pem
I0229 01:03:10.223622 13184 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0229 01:03:10.268427 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem -> /etc/docker/server.pem
I0229 01:03:10.269000 13184 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1229 bytes)
I0229 01:03:10.314777 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem -> /etc/docker/server-key.pem
I0229 01:03:10.315090 13184 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0229 01:03:10.360962 13184 provision.go:86] duration metric: configureAuth took 13.7369606s
I0229 01:03:10.360962 13184 buildroot.go:189] setting minikube options for container-runtime
I0229 01:03:10.361872 13184 config.go:182] Loaded profile config "functional-583600": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I0229 01:03:10.361983 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:12.379674 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:12.380164 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:12.380164 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:14.777703 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:14.777703 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:14.781961 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:03:14.782352 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:03:14.782352 13184 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0229 01:03:14.914758 13184 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0229 01:03:14.914758 13184 buildroot.go:70] root file system type: tmpfs
I0229 01:03:14.914758 13184 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0229 01:03:14.914758 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:16.922082 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:16.922412 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:16.922506 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:19.340168 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:19.340168 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:19.344047 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:03:19.344660 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:03:19.344660 13184 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
I0229 01:03:19.498714 13184 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
I0229 01:03:19.498714 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:21.506370 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:21.506370 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:21.506370 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:23.863375 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:23.863375 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:23.867628 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:03:23.868237 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:03:23.868237 13184 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; }
I0229 01:03:24.013707 13184 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0229 01:03:24.013707 13184 machine.go:91] provisioned docker machine in 36.6563835s
I0229 01:03:24.013707 13184 start.go:300] post-start starting for "functional-583600" (driver="hyperv")
I0229 01:03:24.013707 13184 start.go:329] 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]
I0229 01:03:24.022880 13184 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
I0229 01:03:24.022880 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:26.072620 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:26.072620 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:26.072620 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:28.484570 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:28.485246 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:28.485364 13184 sshutil.go:53] new ssh client: &{IP:172.19.5.240 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-583600\id_rsa Username:docker}
I0229 01:03:28.592891 13184 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.5696505s)
I0229 01:03:28.602163 13184 ssh_runner.go:195] Run: cat /etc/os-release
I0229 01:03:28.610014 13184 command_runner.go:130] > NAME=Buildroot
I0229 01:03:28.610100 13184 command_runner.go:130] > VERSION=2023.02.9-dirty
I0229 01:03:28.610100 13184 command_runner.go:130] > ID=buildroot
I0229 01:03:28.610100 13184 command_runner.go:130] > VERSION_ID=2023.02.9
I0229 01:03:28.610100 13184 command_runner.go:130] > PRETTY_NAME="Buildroot 2023.02.9"
I0229 01:03:28.610268 13184 info.go:137] Remote host: Buildroot 2023.02.9
I0229 01:03:28.610370 13184 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\addons for local assets ...
I0229 01:03:28.610911 13184 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\files for local assets ...
I0229 01:03:28.611701 13184 filesync.go:149] local asset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\33122.pem -> 33122.pem in /etc/ssl/certs
I0229 01:03:28.611701 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\33122.pem -> /etc/ssl/certs/33122.pem
I0229 01:03:28.611701 13184 filesync.go:149] local asset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\test\nested\copy\3312\hosts -> hosts in /etc/test/nested/copy/3312
I0229 01:03:28.611701 13184 vm_assets.go:163] NewFileAsset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\test\nested\copy\3312\hosts -> /etc/test/nested/copy/3312/hosts
I0229 01:03:28.622699 13184 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs /etc/test/nested/copy/3312
I0229 01:03:28.643196 13184 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\33122.pem --> /etc/ssl/certs/33122.pem (1708 bytes)
I0229 01:03:28.689246 13184 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\test\nested\copy\3312\hosts --> /etc/test/nested/copy/3312/hosts (40 bytes)
I0229 01:03:28.737804 13184 start.go:303] post-start completed in 4.7238322s
I0229 01:03:28.737944 13184 fix.go:56] fixHost completed within 44.0248681s
I0229 01:03:28.737999 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:30.753913 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:30.754156 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:30.754156 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:33.173934 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:33.173934 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:33.178890 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:03:33.179493 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:03:33.179493 13184 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0229 01:03:33.309403 13184 main.go:141] libmachine: SSH cmd err, output: <nil>: 1709168613.481654338
I0229 01:03:33.309555 13184 fix.go:206] guest clock: 1709168613.481654338
I0229 01:03:33.309555 13184 fix.go:219] Guest: 2024-02-29 01:03:33.481654338 +0000 UTC Remote: 2024-02-29 01:03:28.7379441 +0000 UTC m=+49.286202401 (delta=4.743710238s)
I0229 01:03:33.309682 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:35.344695 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:35.345646 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:35.345742 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:37.766101 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:37.766101 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:37.771852 13184 main.go:141] libmachine: Using SSH client type: native
I0229 01:03:37.771852 13184 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x1079d80] 0x107c960 <nil> [] 0s} 172.19.5.240 22 <nil> <nil>}
I0229 01:03:37.772375 13184 main.go:141] libmachine: About to run SSH command:
sudo date -s @1709168613
I0229 01:03:37.913830 13184 main.go:141] libmachine: SSH cmd err, output: <nil>: Thu Feb 29 01:03:33 UTC 2024
I0229 01:03:37.913830 13184 fix.go:226] clock set: Thu Feb 29 01:03:33 UTC 2024
(err=<nil>)
I0229 01:03:37.913830 13184 start.go:83] releasing machines lock for "functional-583600", held for 53.2003598s
I0229 01:03:37.914401 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:39.931270 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:39.931270 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:39.931345 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:42.338065 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:42.338065 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:42.342111 13184 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0229 01:03:42.342293 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:42.349513 13184 ssh_runner.go:195] Run: cat /version.json
I0229 01:03:42.350594 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-583600 ).state
I0229 01:03:44.395322 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:44.395322 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:44.395322 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:44.395322 13184 main.go:141] libmachine: [stdout =====>] : Running
I0229 01:03:44.395810 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:44.395906 13184 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-583600 ).networkadapters[0]).ipaddresses[0]
I0229 01:03:46.867426 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:46.867719 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:46.868005 13184 sshutil.go:53] new ssh client: &{IP:172.19.5.240 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-583600\id_rsa Username:docker}
I0229 01:03:46.891650 13184 main.go:141] libmachine: [stdout =====>] : 172.19.5.240
I0229 01:03:46.892629 13184 main.go:141] libmachine: [stderr =====>] :
I0229 01:03:46.893087 13184 sshutil.go:53] new ssh client: &{IP:172.19.5.240 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\functional-583600\id_rsa Username:docker}
I0229 01:03:47.034309 13184 command_runner.go:130] > <a href="https://github.com/kubernetes/registry.k8s.io">Temporary Redirect</a>.
I0229 01:03:47.035001 13184 command_runner.go:130] > {"iso_version": "v1.32.1-1708638130-18020", "kicbase_version": "v0.0.42-1708008208-17936", "minikube_version": "v1.32.0", "commit": "d80143d2abd5a004b09b48bbc118a104326900af"}
I0229 01:03:47.035001 13184 ssh_runner.go:235] Completed: cat /version.json: (4.6842114s)
I0229 01:03:47.035175 13184 ssh_runner.go:235] Completed: curl -sS -m 2 https://registry.k8s.io/: (4.6926545s)
I0229 01:03:47.044187 13184 ssh_runner.go:195] Run: systemctl --version
I0229 01:03:47.053997 13184 command_runner.go:130] > systemd 252 (252)
I0229 01:03:47.053997 13184 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
I0229 01:03:47.065527 13184 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I0229 01:03:47.073661 13184 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W0229 01:03:47.074297 13184 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0229 01:03:47.083184 13184 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" ;
I0229 01:03:47.100136 13184 cni.go:259] no active bridge cni configs found in "/etc/cni/net.d" - nothing to disable
I0229 01:03:47.100173 13184 start.go:475] detecting cgroup driver to use...
I0229 01:03:47.100173 13184 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"
I0229 01:03:47.135004 13184 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I0229 01:03:47.144183 13184 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"
I0229 01:03:47.173831 13184 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"
I0229 01:03:47.193286 13184 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0229 01:03:47.201781 13184 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0229 01:03:47.233649 13184 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"
I0229 01:03:47.264538 13184 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0229 01:03:47.291934 13184 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0229 01:03:47.324210 13184 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0229 01:03:47.354326 13184 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"
I0229 01:03:47.383677 13184 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0229 01:03:47.403610 13184 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I0229 01:03:47.412504 13184 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0229 01:03:47.444479 13184 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0229 01:03:47.650596 13184 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0229 01:03:47.682277 13184 start.go:475] detecting cgroup driver to use...
I0229 01:03:47.693925 13184 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0229 01:03:47.716663 13184 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I0229 01:03:47.718251 13184 command_runner.go:130] > [Unit]
I0229 01:03:47.718251 13184 command_runner.go:130] > Description=Docker Application Container Engine
I0229 01:03:47.718251 13184 command_runner.go:130] > Documentation=https://docs.docker.com
I0229 01:03:47.718251 13184 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I0229 01:03:47.718251 13184 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I0229 01:03:47.718251 13184 command_runner.go:130] > StartLimitBurst=3
I0229 01:03:47.718251 13184 command_runner.go:130] > StartLimitIntervalSec=60
I0229 01:03:47.718251 13184 command_runner.go:130] > [Service]
I0229 01:03:47.718251 13184 command_runner.go:130] > Type=notify
I0229 01:03:47.718251 13184 command_runner.go:130] > Restart=on-failure
I0229 01:03:47.718251 13184 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I0229 01:03:47.718251 13184 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I0229 01:03:47.718251 13184 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I0229 01:03:47.718251 13184 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I0229 01:03:47.718251 13184 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I0229 01:03:47.718251 13184 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I0229 01:03:47.718251 13184 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I0229 01:03:47.718564 13184 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I0229 01:03:47.718564 13184 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I0229 01:03:47.718564 13184 command_runner.go:130] > ExecStart=
I0229 01:03:47.718564 13184 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
I0229 01:03:47.718651 13184 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I0229 01:03:47.718651 13184 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I0229 01:03:47.718651 13184 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I0229 01:03:47.718711 13184 command_runner.go:130] > LimitNOFILE=infinity
I0229 01:03:47.718711 13184 command_runner.go:130] > LimitNPROC=infinity
I0229 01:03:47.718711 13184 command_runner.go:130] > LimitCORE=infinity
I0229 01:03:47.718757 13184 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I0229 01:03:47.718757 13184 command_runner.go:130] > # Only systemd 226 and above support this version.
I0229 01:03:47.718757 13184 command_runner.go:130] > TasksMax=infinity
I0229 01:03:47.718787 13184 command_runner.go:130] > TimeoutStartSec=0
I0229 01:03:47.718787 13184 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I0229 01:03:47.718831 13184 command_runner.go:130] > Delegate=yes
I0229 01:03:47.718831 13184 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I0229 01:03:47.718831 13184 command_runner.go:130] > KillMode=process
I0229 01:03:47.718831 13184 command_runner.go:130] > [Install]
I0229 01:03:47.718831 13184 command_runner.go:130] > WantedBy=multi-user.target
I0229 01:03:47.730126 13184 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0229 01:03:47.758634 13184 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0229 01:03:47.799700 13184 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0229 01:03:47.833621 13184 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0229 01:03:47.860373 13184 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"
I0229 01:03:47.895679 13184 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I0229 01:03:47.905214 13184 ssh_runner.go:195] Run: which cri-dockerd
I0229 01:03:47.910931 13184 command_runner.go:130] > /usr/bin/cri-dockerd
I0229 01:03:47.924315 13184 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0229 01:03:47.941930 13184 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0229 01:03:47.983624 13184 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0229 01:03:48.185294 13184 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0229 01:03:48.368319 13184 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0229 01:03:48.368319 13184 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0229 01:03:48.418633 13184 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0229 01:03:48.617439 13184 ssh_runner.go:195] Run: sudo systemctl restart docker
I0229 01:05:28.695843 13184 command_runner.go:130] ! Job for docker.service failed because the control process exited with error code.
I0229 01:05:28.696359 13184 command_runner.go:130] ! See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
I0229 01:05:28.696359 13184 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m40.0733159s)
I0229 01:05:28.707690 13184 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0229 01:05:28.731281 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 systemd[1]: Starting Docker Application Container Engine...
I0229 01:05:28.731281 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.270724284Z" level=info msg="Starting up"
I0229 01:05:28.731281 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.271600588Z" level=info msg="containerd not running, starting managed containerd"
I0229 01:05:28.731281 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.272822672Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=662
I0229 01:05:28.731437 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.302335125Z" level=info msg="starting containerd" revision=64b8a811b07ba6288238eefc14d898ee0b5b99ba version=v1.7.11
I0229 01:05:28.731437 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330394940Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
I0229 01:05:28.731437 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330541874Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
I0229 01:05:28.731508 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330606589Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
I0229 01:05:28.731508 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330622893Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731553 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330711314Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
I0229 01:05:28.731577 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330734119Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731577 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331015184Z" 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
I0229 01:05:28.731641 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331111006Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731641 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331130811Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
I0229 01:05:28.731704 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331141813Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731704 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331235735Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731704 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331672837Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731704 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.334892184Z" 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
I0229 01:05:28.731704 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335058023Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
I0229 01:05:28.731820 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335218960Z" 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
I0229 01:05:28.731820 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335519930Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
I0229 01:05:28.731820 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335716776Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
I0229 01:05:28.731909 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335997141Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
I0229 01:05:28.731964 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.336114268Z" level=info msg="metadata content store policy set" policy=shared
I0229 01:05:28.731964 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345670587Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
I0229 01:05:28.732009 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345723999Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
I0229 01:05:28.732009 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345743804Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
I0229 01:05:28.732009 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345761908Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
I0229 01:05:28.732009 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345778412Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
I0229 01:05:28.732009 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345976858Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
I0229 01:05:28.732121 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.346887370Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
I0229 01:05:28.732121 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347090617Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
I0229 01:05:28.732239 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347203343Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
I0229 01:05:28.732239 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347224248Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
I0229 01:05:28.732239 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347240252Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732239 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347255955Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732341 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347271659Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732341 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347286962Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732341 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347303566Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732341 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347323871Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732449 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347337874Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732449 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347351277Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
I0229 01:05:28.732449 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347376483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732449 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347394387Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732449 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347408691Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732559 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347453601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732559 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347469705Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732559 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347483808Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732559 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347499312Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732559 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347514115Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732667 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347528619Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732667 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347634443Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732667 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347732966Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732741 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347749870Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732741 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347771375Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732741 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347796081Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347827288Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347935413Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347956718Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348086248Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348121656Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348138660Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348150463Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348327504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348417125Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
I0229 01:05:28.732805 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348468137Z" level=info msg="NRI interface is disabled by configuration."
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348924843Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349067876Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349126390Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349152196Z" level=info msg="containerd successfully booted in 0.047945s"
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.381075108Z" level=info msg="[graphdriver] trying configured driver: overlay2"
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.394008111Z" level=info msg="Loading containers: start."
I0229 01:05:28.733020 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.646849219Z" level=info msg="Loading containers: done."
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.663366278Z" level=info msg="Docker daemon" commit=311b9ff graphdriver=overlay2 version=24.0.7
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.663775373Z" level=info msg="Daemon has completed initialization"
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.715844539Z" level=info msg="API listen on [::]:2376"
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.715977470Z" level=info msg="API listen on /var/run/docker.sock"
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:00:57 functional-583600 systemd[1]: Started Docker Application Container Engine.
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:01:27 functional-583600 systemd[1]: Stopping Docker Application Container Engine...
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.082774000Z" level=info msg="Processing signal 'terminated'"
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.084438866Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.084969887Z" level=info msg="Daemon shutdown complete"
I0229 01:05:28.733124 13184 command_runner.go:130] > Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.085649314Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
I0229 01:05:28.733267 13184 command_runner.go:130] > Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.085809320Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
I0229 01:05:28.733267 13184 command_runner.go:130] > Feb 29 01:01:28 functional-583600 systemd[1]: docker.service: Deactivated successfully.
I0229 01:05:28.733267 13184 command_runner.go:130] > Feb 29 01:01:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
I0229 01:05:28.733267 13184 command_runner.go:130] > Feb 29 01:01:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
I0229 01:05:28.733267 13184 command_runner.go:130] > Feb 29 01:01:28 functional-583600 dockerd[999]: time="2024-02-29T01:01:28.160307636Z" level=info msg="Starting up"
I0229 01:05:28.733267 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 dockerd[999]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0229 01:05:28.733380 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0229 01:05:28.733380 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:02:28 functional-583600 dockerd[1010]: time="2024-02-29T01:02:28.574763226Z" level=info msg="Starting up"
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 dockerd[1010]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0229 01:05:28.733479 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:28 functional-583600 dockerd[1223]: time="2024-02-29T01:03:28.781864216Z" level=info msg="Starting up"
I0229 01:05:28.733577 13184 command_runner.go:130] > Feb 29 01:03:48 functional-583600 dockerd[1223]: time="2024-02-29T01:03:48.815476282Z" level=info msg="Processing signal 'terminated'"
I0229 01:05:28.733679 13184 command_runner.go:130] > Feb 29 01:04:28 functional-583600 dockerd[1223]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0229 01:05:28.733679 13184 command_runner.go:130] > Feb 29 01:04:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0229 01:05:28.733679 13184 command_runner.go:130] > Feb 29 01:04:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
I0229 01:05:28.733679 13184 command_runner.go:130] > Feb 29 01:04:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
I0229 01:05:28.733679 13184 command_runner.go:130] > Feb 29 01:04:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
I0229 01:05:28.733768 13184 command_runner.go:130] > Feb 29 01:04:28 functional-583600 dockerd[1418]: time="2024-02-29T01:04:28.860805006Z" level=info msg="Starting up"
I0229 01:05:28.733768 13184 command_runner.go:130] > Feb 29 01:05:28 functional-583600 dockerd[1418]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I0229 01:05:28.733768 13184 command_runner.go:130] > Feb 29 01:05:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I0229 01:05:28.733768 13184 command_runner.go:130] > Feb 29 01:05:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
I0229 01:05:28.733768 13184 command_runner.go:130] > Feb 29 01:05:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
I0229 01:05:28.740450 13184 out.go:177]
W0229 01:05:28.741586 13184 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 --
Feb 29 01:00:57 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.270724284Z" level=info msg="Starting up"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.271600588Z" level=info msg="containerd not running, starting managed containerd"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.272822672Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=662
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.302335125Z" level=info msg="starting containerd" revision=64b8a811b07ba6288238eefc14d898ee0b5b99ba version=v1.7.11
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330394940Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330541874Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330606589Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330622893Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330711314Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330734119Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331015184Z" 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
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331111006Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331130811Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331141813Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331235735Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331672837Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.334892184Z" 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
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335058023Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335218960Z" 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
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335519930Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335716776Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335997141Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.336114268Z" level=info msg="metadata content store policy set" policy=shared
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345670587Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345723999Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345743804Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345761908Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345778412Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345976858Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.346887370Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347090617Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347203343Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347224248Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347240252Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347255955Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347271659Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347286962Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347303566Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347323871Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347337874Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347351277Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347376483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347394387Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347408691Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347453601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347469705Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347483808Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347499312Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347514115Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347528619Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347634443Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347732966Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347749870Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347771375Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347796081Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347827288Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347935413Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347956718Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348086248Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348121656Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348138660Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348150463Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348327504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348417125Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348468137Z" level=info msg="NRI interface is disabled by configuration."
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348924843Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349067876Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349126390Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349152196Z" level=info msg="containerd successfully booted in 0.047945s"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.381075108Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.394008111Z" level=info msg="Loading containers: start."
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.646849219Z" level=info msg="Loading containers: done."
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.663366278Z" level=info msg="Docker daemon" commit=311b9ff graphdriver=overlay2 version=24.0.7
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.663775373Z" level=info msg="Daemon has completed initialization"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.715844539Z" level=info msg="API listen on [::]:2376"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.715977470Z" level=info msg="API listen on /var/run/docker.sock"
Feb 29 01:00:57 functional-583600 systemd[1]: Started Docker Application Container Engine.
Feb 29 01:01:27 functional-583600 systemd[1]: Stopping Docker Application Container Engine...
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.082774000Z" level=info msg="Processing signal 'terminated'"
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.084438866Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.084969887Z" level=info msg="Daemon shutdown complete"
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.085649314Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.085809320Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Feb 29 01:01:28 functional-583600 systemd[1]: docker.service: Deactivated successfully.
Feb 29 01:01:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:01:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:01:28 functional-583600 dockerd[999]: time="2024-02-29T01:01:28.160307636Z" level=info msg="Starting up"
Feb 29 01:02:28 functional-583600 dockerd[999]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:02:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Feb 29 01:02:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:02:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:02:28 functional-583600 dockerd[1010]: time="2024-02-29T01:02:28.574763226Z" level=info msg="Starting up"
Feb 29 01:03:28 functional-583600 dockerd[1010]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:03:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
Feb 29 01:03:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:03:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:03:28 functional-583600 dockerd[1223]: time="2024-02-29T01:03:28.781864216Z" level=info msg="Starting up"
Feb 29 01:03:48 functional-583600 dockerd[1223]: time="2024-02-29T01:03:48.815476282Z" level=info msg="Processing signal 'terminated'"
Feb 29 01:04:28 functional-583600 dockerd[1223]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:04:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:04:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:04:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:04:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:04:28 functional-583600 dockerd[1418]: time="2024-02-29T01:04:28.860805006Z" level=info msg="Starting up"
Feb 29 01:05:28 functional-583600 dockerd[1418]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:05:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:05:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:05:28 functional-583600 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 --
Feb 29 01:00:57 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.270724284Z" level=info msg="Starting up"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.271600588Z" level=info msg="containerd not running, starting managed containerd"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.272822672Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=662
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.302335125Z" level=info msg="starting containerd" revision=64b8a811b07ba6288238eefc14d898ee0b5b99ba version=v1.7.11
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330394940Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330541874Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330606589Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330622893Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330711314Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.330734119Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331015184Z" 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
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331111006Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331130811Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331141813Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331235735Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.331672837Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.334892184Z" 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
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335058023Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335218960Z" 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
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335519930Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335716776Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.335997141Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.336114268Z" level=info msg="metadata content store policy set" policy=shared
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345670587Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345723999Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345743804Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345761908Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345778412Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.345976858Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.346887370Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347090617Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347203343Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347224248Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347240252Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347255955Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347271659Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347286962Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347303566Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347323871Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347337874Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347351277Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347376483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347394387Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347408691Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347453601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347469705Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347483808Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347499312Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347514115Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347528619Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347634443Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347732966Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347749870Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347771375Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347796081Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347827288Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347935413Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.347956718Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348086248Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348121656Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348138660Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348150463Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348327504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348417125Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348468137Z" level=info msg="NRI interface is disabled by configuration."
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.348924843Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349067876Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349126390Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Feb 29 01:00:57 functional-583600 dockerd[662]: time="2024-02-29T01:00:57.349152196Z" level=info msg="containerd successfully booted in 0.047945s"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.381075108Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.394008111Z" level=info msg="Loading containers: start."
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.646849219Z" level=info msg="Loading containers: done."
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.663366278Z" level=info msg="Docker daemon" commit=311b9ff graphdriver=overlay2 version=24.0.7
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.663775373Z" level=info msg="Daemon has completed initialization"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.715844539Z" level=info msg="API listen on [::]:2376"
Feb 29 01:00:57 functional-583600 dockerd[656]: time="2024-02-29T01:00:57.715977470Z" level=info msg="API listen on /var/run/docker.sock"
Feb 29 01:00:57 functional-583600 systemd[1]: Started Docker Application Container Engine.
Feb 29 01:01:27 functional-583600 systemd[1]: Stopping Docker Application Container Engine...
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.082774000Z" level=info msg="Processing signal 'terminated'"
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.084438866Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.084969887Z" level=info msg="Daemon shutdown complete"
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.085649314Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Feb 29 01:01:27 functional-583600 dockerd[656]: time="2024-02-29T01:01:27.085809320Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Feb 29 01:01:28 functional-583600 systemd[1]: docker.service: Deactivated successfully.
Feb 29 01:01:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:01:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:01:28 functional-583600 dockerd[999]: time="2024-02-29T01:01:28.160307636Z" level=info msg="Starting up"
Feb 29 01:02:28 functional-583600 dockerd[999]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:02:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
Feb 29 01:02:28 functional-583600 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Feb 29 01:02:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:02:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:02:28 functional-583600 dockerd[1010]: time="2024-02-29T01:02:28.574763226Z" level=info msg="Starting up"
Feb 29 01:03:28 functional-583600 dockerd[1010]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:03:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
Feb 29 01:03:28 functional-583600 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
Feb 29 01:03:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:03:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:03:28 functional-583600 dockerd[1223]: time="2024-02-29T01:03:28.781864216Z" level=info msg="Starting up"
Feb 29 01:03:48 functional-583600 dockerd[1223]: time="2024-02-29T01:03:48.815476282Z" level=info msg="Processing signal 'terminated'"
Feb 29 01:04:28 functional-583600 dockerd[1223]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:04:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:04:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:04:28 functional-583600 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 01:04:28 functional-583600 systemd[1]: Starting Docker Application Container Engine...
Feb 29 01:04:28 functional-583600 dockerd[1418]: time="2024-02-29T01:04:28.860805006Z" level=info msg="Starting up"
Feb 29 01:05:28 functional-583600 dockerd[1418]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 01:05:28 functional-583600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 01:05:28 functional-583600 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 01:05:28 functional-583600 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0229 01:05:28.741586 13184 out.go:239] *
*
W0229 01:05:28.743422 13184 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0229 01:05:28.743969 13184 out.go:177]
** /stderr **
functional_test.go:657: failed to soft start minikube. args "out/minikube-windows-amd64.exe start -p functional-583600 --alsologtostderr -v=8": exit status 90
functional_test.go:659: soft start took 2m49.4923968s for "functional-583600" cluster.
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.Host}} -p functional-583600 -n functional-583600
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-windows-amd64.exe status --format={{.Host}} -p functional-583600 -n functional-583600: exit status 6 (11.3480475s)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
W0229 01:05:29.042901 9768 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.
E0229 01:05:40.213691 9768 status.go:415] kubeconfig endpoint: extract IP: "functional-583600" 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-583600" 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.84s)