=== RUN TestFunctional/serial/SoftStart
I1010 17:58:14.666097 7528 config.go:182] Loaded profile config "functional-285000": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.31.1
functional_test.go:659: (dbg) Run: out/minikube-windows-amd64.exe start -p functional-285000 --alsologtostderr -v=8
functional_test.go:659: (dbg) Non-zero exit: out/minikube-windows-amd64.exe start -p functional-285000 --alsologtostderr -v=8: exit status 90 (2m48.4319237s)
-- stdout --
* [functional-285000] minikube v1.34.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4957 Build 19045.4957
- KUBECONFIG=C:\Users\jenkins.minikube3\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube3\minikube-integration\.minikube
- MINIKUBE_LOCATION=19787
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on existing profile
* Starting "functional-285000" primary control-plane node in "functional-285000" cluster
* Updating the running hyperv "functional-285000" VM ...
-- /stdout --
** stderr **
I1010 17:58:14.779200 10600 out.go:345] Setting OutFile to fd 1036 ...
I1010 17:58:14.859705 10600 out.go:392] TERM=,COLORTERM=, which probably does not support color
I1010 17:58:14.859705 10600 out.go:358] Setting ErrFile to fd 1040...
I1010 17:58:14.859705 10600 out.go:392] TERM=,COLORTERM=, which probably does not support color
I1010 17:58:14.882711 10600 out.go:352] Setting JSON to false
I1010 17:58:14.886344 10600 start.go:129] hostinfo: {"hostname":"minikube3","uptime":74345,"bootTime":1728508749,"procs":199,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19045.4957 Build 19045.4957","kernelVersion":"10.0.19045.4957 Build 19045.4957","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"a0f355d5-8b6e-4346-9071-73232725d096"}
W1010 17:58:14.886344 10600 start.go:137] gopshost.Virtualization returned error: not implemented yet
I1010 17:58:14.890988 10600 out.go:177] * [functional-285000] minikube v1.34.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4957 Build 19045.4957
I1010 17:58:14.898238 10600 notify.go:220] Checking for updates...
I1010 17:58:14.900497 10600 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube3\minikube-integration\kubeconfig
I1010 17:58:14.903293 10600 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I1010 17:58:14.907478 10600 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube3\minikube-integration\.minikube
I1010 17:58:14.909808 10600 out.go:177] - MINIKUBE_LOCATION=19787
I1010 17:58:14.912815 10600 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I1010 17:58:14.916805 10600 config.go:182] Loaded profile config "functional-285000": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.31.1
I1010 17:58:14.916805 10600 driver.go:394] Setting default libvirt URI to qemu:///system
I1010 17:58:20.271212 10600 out.go:177] * Using the hyperv driver based on existing profile
I1010 17:58:20.274821 10600 start.go:297] selected driver: hyperv
I1010 17:58:20.275005 10600 start.go:901] validating driver "hyperv" against &{Name:functional-285000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19774/minikube-v1.34.0-1728382514-19774-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.45-1728382586-19774@sha256:5d8c4f6d838467365e214e2194dd0153a763e3f78723b5f2a8e06ef7b47409ec Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperv HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:8441 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{K
ubernetesVersion:v1.31.1 ClusterName:functional-285000 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:172.17.159.226 Port:8441 KubernetesVersion:v1.31.1 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.minikube3:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSi
ze:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I1010 17:58:20.275005 10600 start.go:912] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I1010 17:58:20.325783 10600 cni.go:84] Creating CNI manager for ""
I1010 17:58:20.325783 10600 cni.go:158] "hyperv" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I1010 17:58:20.325783 10600 start.go:340] cluster config:
{Name:functional-285000 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19774/minikube-v1.34.0-1728382514-19774-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.45-1728382586-19774@sha256:5d8c4f6d838467365e214e2194dd0153a763e3f78723b5f2a8e06ef7b47409ec Memory:4000 CPUs:2 DiskSize:20000 Driver:hyperv HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:8441 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.31.1 ClusterName:functional-285000 Namespace:default APIServ
erHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:172.17.159.226 Port:8441 KubernetesVersion:v1.31.1 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.minikube3:/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}
I1010 17:58:20.325783 10600 iso.go:125] acquiring lock: {Name:mk57db12051a56739c7f278b5c699cbda53518a2 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I1010 17:58:20.329937 10600 out.go:177] * Starting "functional-285000" primary control-plane node in "functional-285000" cluster
I1010 17:58:20.332619 10600 preload.go:131] Checking if preload exists for k8s version v1.31.1 and runtime docker
I1010 17:58:20.332619 10600 preload.go:146] Found local preload: C:\Users\jenkins.minikube3\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4
I1010 17:58:20.332619 10600 cache.go:56] Caching tarball of preloaded images
I1010 17:58:20.333632 10600 preload.go:172] Found C:\Users\jenkins.minikube3\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I1010 17:58:20.333831 10600 cache.go:59] Finished verifying existence of preloaded tar for v1.31.1 on docker
I1010 17:58:20.334059 10600 profile.go:143] Saving config to C:\Users\jenkins.minikube3\minikube-integration\.minikube\profiles\functional-285000\config.json ...
I1010 17:58:20.336281 10600 start.go:360] acquireMachinesLock for functional-285000: {Name:mk88ace50ad3bf72786f3a589a5328076247f3a1 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I1010 17:58:20.336281 10600 start.go:364] duration metric: took 0s to acquireMachinesLock for "functional-285000"
I1010 17:58:20.336497 10600 start.go:96] Skipping create...Using existing machine configuration
I1010 17:58:20.336497 10600 fix.go:54] fixHost starting:
I1010 17:58:20.337156 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:22.999997 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:23.000059 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:23.000059 10600 fix.go:112] recreateIfNeeded on functional-285000: state=Running err=<nil>
W1010 17:58:23.000059 10600 fix.go:138] unexpected machine state, will restart: <nil>
I1010 17:58:23.007344 10600 out.go:177] * Updating the running hyperv "functional-285000" VM ...
I1010 17:58:23.009711 10600 machine.go:93] provisionDockerMachine start ...
I1010 17:58:23.009711 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:25.172726 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:25.172930 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:25.172930 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:27.725913 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:27.726839 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:27.732255 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:58:27.732368 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:58:27.732368 10600 main.go:141] libmachine: About to run SSH command:
hostname
I1010 17:58:27.867053 10600 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-285000
I1010 17:58:27.867053 10600 buildroot.go:166] provisioning hostname "functional-285000"
I1010 17:58:27.867053 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:29.980246 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:29.980365 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:29.980365 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:32.540969 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:32.540969 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:32.548000 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:58:32.548000 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:58:32.548000 10600 main.go:141] libmachine: About to run SSH command:
sudo hostname functional-285000 && echo "functional-285000" | sudo tee /etc/hostname
I1010 17:58:32.701569 10600 main.go:141] libmachine: SSH cmd err, output: <nil>: functional-285000
I1010 17:58:32.701691 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:34.847055 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:34.847312 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:34.847407 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:37.385028 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:37.385028 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:37.391367 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:58:37.392192 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:58:37.392192 10600 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sfunctional-285000' /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-285000/g' /etc/hosts;
else
echo '127.0.1.1 functional-285000' | sudo tee -a /etc/hosts;
fi
fi
I1010 17:58:37.516508 10600 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1010 17:58:37.516647 10600 buildroot.go:172] set auth options {CertDir:C:\Users\jenkins.minikube3\minikube-integration\.minikube CaCertPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca.pem CaPrivateKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server.pem ServerKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server-key.pem ClientKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\cert.pem ServerCertSANs:[] StorePath:C:\Users\jenkins.minikube3\minikube-integration\.minikube}
I1010 17:58:37.516700 10600 buildroot.go:174] setting up certificates
I1010 17:58:37.516700 10600 provision.go:84] configureAuth start
I1010 17:58:37.516820 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:39.620634 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:39.620634 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:39.621693 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:42.136816 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:42.137078 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:42.137078 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:44.251068 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:44.251068 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:44.252130 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:46.752727 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:46.753009 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:46.753107 10600 provision.go:143] copyHostCerts
I1010 17:58:46.753272 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca.pem -> C:\Users\jenkins.minikube3\minikube-integration\.minikube/ca.pem
I1010 17:58:46.753272 10600 exec_runner.go:144] found C:\Users\jenkins.minikube3\minikube-integration\.minikube/ca.pem, removing ...
I1010 17:58:46.753272 10600 exec_runner.go:203] rm: C:\Users\jenkins.minikube3\minikube-integration\.minikube\ca.pem
I1010 17:58:46.754099 10600 exec_runner.go:151] cp: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca.pem --> C:\Users\jenkins.minikube3\minikube-integration\.minikube/ca.pem (1078 bytes)
I1010 17:58:46.755106 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\cert.pem -> C:\Users\jenkins.minikube3\minikube-integration\.minikube/cert.pem
I1010 17:58:46.755106 10600 exec_runner.go:144] found C:\Users\jenkins.minikube3\minikube-integration\.minikube/cert.pem, removing ...
I1010 17:58:46.755653 10600 exec_runner.go:203] rm: C:\Users\jenkins.minikube3\minikube-integration\.minikube\cert.pem
I1010 17:58:46.755948 10600 exec_runner.go:151] cp: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\cert.pem --> C:\Users\jenkins.minikube3\minikube-integration\.minikube/cert.pem (1123 bytes)
I1010 17:58:46.756668 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\key.pem -> C:\Users\jenkins.minikube3\minikube-integration\.minikube/key.pem
I1010 17:58:46.757193 10600 exec_runner.go:144] found C:\Users\jenkins.minikube3\minikube-integration\.minikube/key.pem, removing ...
I1010 17:58:46.757300 10600 exec_runner.go:203] rm: C:\Users\jenkins.minikube3\minikube-integration\.minikube\key.pem
I1010 17:58:46.757490 10600 exec_runner.go:151] cp: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\key.pem --> C:\Users\jenkins.minikube3\minikube-integration\.minikube/key.pem (1679 bytes)
I1010 17:58:46.758766 10600 provision.go:117] generating server cert: C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server.pem ca-key=C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca.pem private-key=C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca-key.pem org=jenkins.functional-285000 san=[127.0.0.1 172.17.159.226 functional-285000 localhost minikube]
I1010 17:58:47.146048 10600 provision.go:177] copyRemoteCerts
I1010 17:58:47.158086 10600 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I1010 17:58:47.158086 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:49.260814 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:49.260814 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:49.261600 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:51.822239 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:51.822239 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:51.822605 10600 sshutil.go:53] new ssh client: &{IP:172.17.159.226 Port:22 SSHKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\functional-285000\id_rsa Username:docker}
I1010 17:58:51.926942 10600 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (4.7688187s)
I1010 17:58:51.927001 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca.pem -> /etc/docker/ca.pem
I1010 17:58:51.927227 10600 ssh_runner.go:362] scp C:\Users\jenkins.minikube3\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1078 bytes)
I1010 17:58:51.971292 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server.pem -> /etc/docker/server.pem
I1010 17:58:51.971547 10600 ssh_runner.go:362] scp C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1220 bytes)
I1010 17:58:52.013812 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server-key.pem -> /etc/docker/server-key.pem
I1010 17:58:52.014328 10600 ssh_runner.go:362] scp C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I1010 17:58:52.057440 10600 provision.go:87] duration metric: took 14.5405619s to configureAuth
I1010 17:58:52.057440 10600 buildroot.go:189] setting minikube options for container-runtime
I1010 17:58:52.058444 10600 config.go:182] Loaded profile config "functional-285000": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.31.1
I1010 17:58:52.058444 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:54.189103 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:54.189103 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:54.189218 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:58:56.733792 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:58:56.733792 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:56.740721 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:58:56.740721 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:58:56.741272 10600 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I1010 17:58:56.872733 10600 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I1010 17:58:56.872733 10600 buildroot.go:70] root file system type: tmpfs
I1010 17:58:56.872733 10600 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I1010 17:58:56.872733 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:58:58.978474 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:58:58.979456 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:58:58.979613 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:01.510343 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:01.510343 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:01.516356 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:59:01.517054 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:59:01.517185 10600 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
I1010 17:59:01.667828 10600 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
I1010 17:59:01.667828 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:03.773191 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:03.773191 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:03.773403 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:06.355383 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:06.355611 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:06.361776 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:59:06.361913 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:59:06.361913 10600 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; }
I1010 17:59:06.500549 10600 main.go:141] libmachine: SSH cmd err, output: <nil>:
I1010 17:59:06.500549 10600 machine.go:96] duration metric: took 43.4905009s to provisionDockerMachine
I1010 17:59:06.500549 10600 start.go:293] postStartSetup for "functional-285000" (driver="hyperv")
I1010 17:59:06.500549 10600 start.go:322] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I1010 17:59:06.513116 10600 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
I1010 17:59:06.514044 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:08.638944 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:08.639109 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:08.639212 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:11.168928 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:11.168928 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:11.169069 10600 sshutil.go:53] new ssh client: &{IP:172.17.159.226 Port:22 SSHKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\functional-285000\id_rsa Username:docker}
I1010 17:59:11.273770 10600 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.7596885s)
I1010 17:59:11.286239 10600 ssh_runner.go:195] Run: cat /etc/os-release
I1010 17:59:11.291665 10600 command_runner.go:130] > NAME=Buildroot
I1010 17:59:11.291665 10600 command_runner.go:130] > VERSION=2023.02.9-dirty
I1010 17:59:11.291665 10600 command_runner.go:130] > ID=buildroot
I1010 17:59:11.291665 10600 command_runner.go:130] > VERSION_ID=2023.02.9
I1010 17:59:11.291665 10600 command_runner.go:130] > PRETTY_NAME="Buildroot 2023.02.9"
I1010 17:59:11.291665 10600 info.go:137] Remote host: Buildroot 2023.02.9
I1010 17:59:11.291665 10600 filesync.go:126] Scanning C:\Users\jenkins.minikube3\minikube-integration\.minikube\addons for local assets ...
I1010 17:59:11.292576 10600 filesync.go:126] Scanning C:\Users\jenkins.minikube3\minikube-integration\.minikube\files for local assets ...
I1010 17:59:11.293527 10600 filesync.go:149] local asset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\files\etc\ssl\certs\75282.pem -> 75282.pem in /etc/ssl/certs
I1010 17:59:11.293568 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\files\etc\ssl\certs\75282.pem -> /etc/ssl/certs/75282.pem
I1010 17:59:11.294444 10600 filesync.go:149] local asset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\files\etc\test\nested\copy\7528\hosts -> hosts in /etc/test/nested/copy/7528
I1010 17:59:11.294444 10600 vm_assets.go:164] NewFileAsset: C:\Users\jenkins.minikube3\minikube-integration\.minikube\files\etc\test\nested\copy\7528\hosts -> /etc/test/nested/copy/7528/hosts
I1010 17:59:11.306297 10600 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs /etc/test/nested/copy/7528
I1010 17:59:11.323583 10600 ssh_runner.go:362] scp C:\Users\jenkins.minikube3\minikube-integration\.minikube\files\etc\ssl\certs\75282.pem --> /etc/ssl/certs/75282.pem (1708 bytes)
I1010 17:59:11.367054 10600 ssh_runner.go:362] scp C:\Users\jenkins.minikube3\minikube-integration\.minikube\files\etc\test\nested\copy\7528\hosts --> /etc/test/nested/copy/7528/hosts (40 bytes)
I1010 17:59:11.411980 10600 start.go:296] duration metric: took 4.9113927s for postStartSetup
I1010 17:59:11.411980 10600 fix.go:56] duration metric: took 51.0750863s for fixHost
I1010 17:59:11.411980 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:13.483835 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:13.484138 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:13.484138 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:16.046446 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:16.046631 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:16.051649 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:59:16.052656 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:59:16.052656 10600 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I1010 17:59:16.179842 10600 main.go:141] libmachine: SSH cmd err, output: <nil>: 1728583156.184464024
I1010 17:59:16.179842 10600 fix.go:216] guest clock: 1728583156.184464024
I1010 17:59:16.179842 10600 fix.go:229] Guest: 2024-10-10 17:59:16.184464024 +0000 UTC Remote: 2024-10-10 17:59:11.4119808 +0000 UTC m=+56.727004301 (delta=4.772483224s)
I1010 17:59:16.179842 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:18.293593 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:18.293665 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:18.293665 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:20.841695 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:20.842659 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:20.848058 10600 main.go:141] libmachine: Using SSH client type: native
I1010 17:59:20.848270 10600 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a23e0] 0x12a4f20 <nil> [] 0s} 172.17.159.226 22 <nil> <nil>}
I1010 17:59:20.848270 10600 main.go:141] libmachine: About to run SSH command:
sudo date -s @1728583156
I1010 17:59:21.000837 10600 main.go:141] libmachine: SSH cmd err, output: <nil>: Thu Oct 10 17:59:16 UTC 2024
I1010 17:59:21.000837 10600 fix.go:236] clock set: Thu Oct 10 17:59:16 UTC 2024
(err=<nil>)
I1010 17:59:21.000837 10600 start.go:83] releasing machines lock for "functional-285000", held for 1m0.6638679s
I1010 17:59:21.000837 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:23.090725 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:23.090725 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:23.090915 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:25.659732 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:25.659732 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:25.665725 10600 ssh_runner.go:195] Run: curl.exe -sS -m 2 https://registry.k8s.io/
I1010 17:59:25.665974 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:25.677075 10600 ssh_runner.go:195] Run: cat /version.json
I1010 17:59:25.677075 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM functional-285000 ).state
I1010 17:59:27.873121 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:27.873121 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:27.873334 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:27.873478 10600 main.go:141] libmachine: [stdout =====>] : Running
I1010 17:59:27.873478 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:27.873478 10600 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM functional-285000 ).networkadapters[0]).ipaddresses[0]
I1010 17:59:30.554667 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:30.554667 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:30.555087 10600 sshutil.go:53] new ssh client: &{IP:172.17.159.226 Port:22 SSHKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\functional-285000\id_rsa Username:docker}
I1010 17:59:30.574251 10600 main.go:141] libmachine: [stdout =====>] : 172.17.159.226
I1010 17:59:30.574318 10600 main.go:141] libmachine: [stderr =====>] :
I1010 17:59:30.574539 10600 sshutil.go:53] new ssh client: &{IP:172.17.159.226 Port:22 SSHKeyPath:C:\Users\jenkins.minikube3\minikube-integration\.minikube\machines\functional-285000\id_rsa Username:docker}
I1010 17:59:30.642382 10600 command_runner.go:130] ! bash: line 1: curl.exe: command not found
I1010 17:59:30.642510 10600 ssh_runner.go:235] Completed: curl.exe -sS -m 2 https://registry.k8s.io/: (4.9766439s)
W1010 17:59:30.642510 10600 start.go:867] [curl.exe -sS -m 2 https://registry.k8s.io/] failed: curl.exe -sS -m 2 https://registry.k8s.io/: Process exited with status 127
stdout:
stderr:
bash: line 1: curl.exe: command not found
I1010 17:59:30.675258 10600 command_runner.go:130] > {"iso_version": "v1.34.0-1728382514-19774", "kicbase_version": "v0.0.45-1728063813-19756", "minikube_version": "v1.34.0", "commit": "cf9f11c2b0369efc07a929c4a1fdb2b4b3c62ee9"}
I1010 17:59:30.675258 10600 ssh_runner.go:235] Completed: cat /version.json: (4.9981437s)
I1010 17:59:30.688958 10600 ssh_runner.go:195] Run: systemctl --version
I1010 17:59:30.697833 10600 command_runner.go:130] > systemd 252 (252)
I1010 17:59:30.697903 10600 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
I1010 17:59:30.712976 10600 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
I1010 17:59:30.721510 10600 command_runner.go:130] ! stat: cannot statx '/etc/cni/net.d/*loopback.conf*': No such file or directory
W1010 17:59:30.722182 10600 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I1010 17:59:30.737643 10600 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" ;
I1010 17:59:30.755522 10600 cni.go:259] no active bridge cni configs found in "/etc/cni/net.d" - nothing to disable
I1010 17:59:30.755522 10600 start.go:495] detecting cgroup driver to use...
I1010 17:59:30.755984 10600 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"
W1010 17:59:30.762020 10600 out.go:270] ! Failing to connect to https://registry.k8s.io/ from inside the minikube VM
! Failing to connect to https://registry.k8s.io/ from inside the minikube VM
W1010 17:59:30.762890 10600 out.go:270] * To pull new external images, you may need to configure a proxy: https://minikube.sigs.k8s.io/docs/reference/networking/proxy/
* To pull new external images, you may need to configure a proxy: https://minikube.sigs.k8s.io/docs/reference/networking/proxy/
I1010 17:59:30.791010 10600 command_runner.go:130] > runtime-endpoint: unix:///run/containerd/containerd.sock
I1010 17:59:30.806198 10600 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.10"|' /etc/containerd/config.toml"
I1010 17:59:30.835588 10600 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"
I1010 17:59:30.859208 10600 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I1010 17:59:30.872242 10600 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I1010 17:59:30.906465 10600 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"
I1010 17:59:30.939166 10600 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I1010 17:59:30.970548 10600 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I1010 17:59:31.000810 10600 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I1010 17:59:31.032002 10600 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"
I1010 17:59:31.064673 10600 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I1010 17:59:31.094638 10600 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)\[plugins."io.containerd.grpc.v1.cri"\]|&\n\1 enable_unprivileged_ports = true|' /etc/containerd/config.toml"
I1010 17:59:31.125982 10600 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I1010 17:59:31.144937 10600 command_runner.go:130] > net.bridge.bridge-nf-call-iptables = 1
I1010 17:59:31.160311 10600 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I1010 17:59:31.191211 10600 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1010 17:59:31.383116 10600 ssh_runner.go:195] Run: sudo systemctl restart containerd
I1010 17:59:31.413775 10600 start.go:495] detecting cgroup driver to use...
I1010 17:59:31.425870 10600 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I1010 17:59:31.450955 10600 command_runner.go:130] > # /usr/lib/systemd/system/docker.service
I1010 17:59:31.451028 10600 command_runner.go:130] > [Unit]
I1010 17:59:31.451100 10600 command_runner.go:130] > Description=Docker Application Container Engine
I1010 17:59:31.451100 10600 command_runner.go:130] > Documentation=https://docs.docker.com
I1010 17:59:31.451213 10600 command_runner.go:130] > After=network.target minikube-automount.service docker.socket
I1010 17:59:31.451241 10600 command_runner.go:130] > Requires= minikube-automount.service docker.socket
I1010 17:59:31.451301 10600 command_runner.go:130] > StartLimitBurst=3
I1010 17:59:31.451357 10600 command_runner.go:130] > StartLimitIntervalSec=60
I1010 17:59:31.451357 10600 command_runner.go:130] > [Service]
I1010 17:59:31.451357 10600 command_runner.go:130] > Type=notify
I1010 17:59:31.451357 10600 command_runner.go:130] > Restart=on-failure
I1010 17:59:31.451357 10600 command_runner.go:130] > # This file is a systemd drop-in unit that inherits from the base dockerd configuration.
I1010 17:59:31.451357 10600 command_runner.go:130] > # The base configuration already specifies an 'ExecStart=...' command. The first directive
I1010 17:59:31.451357 10600 command_runner.go:130] > # here is to clear out that command inherited from the base configuration. Without this,
I1010 17:59:31.451357 10600 command_runner.go:130] > # the command from the base configuration and the command specified here are treated as
I1010 17:59:31.451357 10600 command_runner.go:130] > # a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
I1010 17:59:31.451357 10600 command_runner.go:130] > # will catch this invalid input and refuse to start the service with an error like:
I1010 17:59:31.451357 10600 command_runner.go:130] > # Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
I1010 17:59:31.451357 10600 command_runner.go:130] > # NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
I1010 17:59:31.451357 10600 command_runner.go:130] > # container runtimes. If left unlimited, it may result in OOM issues with MySQL.
I1010 17:59:31.451357 10600 command_runner.go:130] > ExecStart=
I1010 17:59:31.451357 10600 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
I1010 17:59:31.451971 10600 command_runner.go:130] > ExecReload=/bin/kill -s HUP $MAINPID
I1010 17:59:31.451971 10600 command_runner.go:130] > # Having non-zero Limit*s causes performance problems due to accounting overhead
I1010 17:59:31.452125 10600 command_runner.go:130] > # in the kernel. We recommend using cgroups to do container-local accounting.
I1010 17:59:31.452156 10600 command_runner.go:130] > LimitNOFILE=infinity
I1010 17:59:31.452156 10600 command_runner.go:130] > LimitNPROC=infinity
I1010 17:59:31.452156 10600 command_runner.go:130] > LimitCORE=infinity
I1010 17:59:31.452156 10600 command_runner.go:130] > # Uncomment TasksMax if your systemd version supports it.
I1010 17:59:31.452156 10600 command_runner.go:130] > # Only systemd 226 and above support this version.
I1010 17:59:31.452156 10600 command_runner.go:130] > TasksMax=infinity
I1010 17:59:31.452156 10600 command_runner.go:130] > TimeoutStartSec=0
I1010 17:59:31.452156 10600 command_runner.go:130] > # set delegate yes so that systemd does not reset the cgroups of docker containers
I1010 17:59:31.452156 10600 command_runner.go:130] > Delegate=yes
I1010 17:59:31.452156 10600 command_runner.go:130] > # kill only the docker process, not all processes in the cgroup
I1010 17:59:31.452156 10600 command_runner.go:130] > KillMode=process
I1010 17:59:31.452156 10600 command_runner.go:130] > [Install]
I1010 17:59:31.452156 10600 command_runner.go:130] > WantedBy=multi-user.target
I1010 17:59:31.465784 10600 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1010 17:59:31.502717 10600 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I1010 17:59:31.547205 10600 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I1010 17:59:31.585609 10600 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I1010 17:59:31.608486 10600 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"
I1010 17:59:31.641764 10600 command_runner.go:130] > runtime-endpoint: unix:///var/run/cri-dockerd.sock
I1010 17:59:31.655269 10600 ssh_runner.go:195] Run: which cri-dockerd
I1010 17:59:31.661113 10600 command_runner.go:130] > /usr/bin/cri-dockerd
I1010 17:59:31.671645 10600 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I1010 17:59:31.690110 10600 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (190 bytes)
I1010 17:59:31.733336 10600 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I1010 17:59:31.921332 10600 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I1010 17:59:32.099464 10600 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I1010 17:59:32.099464 10600 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I1010 17:59:32.144592 10600 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I1010 17:59:32.327830 10600 ssh_runner.go:195] Run: sudo systemctl restart docker
I1010 18:01:03.001089 10600 command_runner.go:130] ! Job for docker.service failed because the control process exited with error code.
I1010 18:01:03.001187 10600 command_runner.go:130] ! See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
I1010 18:01:03.001371 10600 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m30.6728374s)
I1010 18:01:03.014393 10600 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 systemd[1]: Starting Docker Application Container Engine...
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.839166858Z" level=info msg="Starting up"
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.841967004Z" level=info msg="containerd not running, starting managed containerd"
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.844175141Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=671
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.874362745Z" level=info msg="starting containerd" revision=7f7fdf5fed64eb6a7caf99b3e12efcf9d60e311c version=v1.7.22
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902331512Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902428713Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902769219Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902861621Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902959622Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903102225Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903288328Z" 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
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903378829Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903399529Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903411230Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903497531Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903940339Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.038975 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.906994389Z" 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
I1010 18:01:03.039514 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907103391Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
I1010 18:01:03.039514 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907233793Z" 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
I1010 18:01:03.039589 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907326495Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
I1010 18:01:03.039611 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907434397Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907740902Z" level=info msg="metadata content store policy set" policy=shared
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951131826Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951380030Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951424031Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951448931Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951495032Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951970440Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952342446Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952495749Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952601150Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952712252Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952912956Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952939656Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952954056Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952985957Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953002057Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953015957Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953085258Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953098459Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953120359Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953135159Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953171660Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953211061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953231561Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953246061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953259461Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953272662Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953293462Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
I1010 18:01:03.039638 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953310962Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040202 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953323262Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040202 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953336063Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040202 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953348263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040202 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953364163Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
I1010 18:01:03.040202 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953387764Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040202 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953405264Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040486 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953418864Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
I1010 18:01:03.040486 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953478465Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
I1010 18:01:03.040486 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953577567Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
I1010 18:01:03.040581 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953596267Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
I1010 18:01:03.040581 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953609467Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
I1010 18:01:03.040581 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953696469Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
I1010 18:01:03.040655 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953722969Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
I1010 18:01:03.040655 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953735369Z" level=info msg="NRI interface is disabled by configuration."
I1010 18:01:03.040655 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954056675Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
I1010 18:01:03.040655 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954136976Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
I1010 18:01:03.040718 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954186877Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
I1010 18:01:03.040718 10600 command_runner.go:130] > Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954207777Z" level=info msg="containerd successfully booted in 0.081140s"
I1010 18:01:03.040718 10600 command_runner.go:130] > Oct 10 17:56:29 functional-285000 dockerd[665]: time="2024-10-10T17:56:29.915060708Z" level=info msg="[graphdriver] trying configured driver: overlay2"
I1010 18:01:03.040768 10600 command_runner.go:130] > Oct 10 17:56:29 functional-285000 dockerd[665]: time="2024-10-10T17:56:29.951015870Z" level=info msg="Loading containers: start."
I1010 18:01:03.040786 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.109896649Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
I1010 18:01:03.040844 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.333755019Z" level=info msg="Loading containers: done."
I1010 18:01:03.040880 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363354343Z" level=warning msg="WARNING: bridge-nf-call-iptables is disabled"
I1010 18:01:03.040880 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363396543Z" level=warning msg="WARNING: bridge-nf-call-ip6tables is disabled"
I1010 18:01:03.040880 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363421344Z" level=info msg="Docker daemon" commit=41ca978 containerd-snapshotter=false storage-driver=overlay2 version=27.3.1
I1010 18:01:03.040982 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363570447Z" level=info msg="Daemon has completed initialization"
I1010 18:01:03.040982 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 systemd[1]: Started Docker Application Container Engine.
I1010 18:01:03.040982 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.471818561Z" level=info msg="API listen on [::]:2376"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.472023465Z" level=info msg="API listen on /var/run/docker.sock"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:01 functional-285000 systemd[1]: Stopping Docker Application Container Engine...
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.341467974Z" level=info msg="Processing signal 'terminated'"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343081379Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343316379Z" level=info msg="Daemon shutdown complete"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343510380Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343538680Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:02 functional-285000 systemd[1]: docker.service: Deactivated successfully.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:57:02 functional-285000 dockerd[1080]: time="2024-10-10T17:57:02.400747578Z" level=info msg="Starting up"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 dockerd[1080]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:58:02 functional-285000 dockerd[1105]: time="2024-10-10T17:58:02.594840376Z" level=info msg="Starting up"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 dockerd[1105]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:02 functional-285000 dockerd[1313]: time="2024-10-10T17:59:02.908220581Z" level=info msg="Starting up"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 17:59:32 functional-285000 dockerd[1313]: time="2024-10-10T17:59:32.357499986Z" level=info msg="Processing signal 'terminated'"
I1010 18:01:03.041033 10600 command_runner.go:130] > Oct 10 18:00:02 functional-285000 dockerd[1313]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:00:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:00:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:00:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:00:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:00:02 functional-285000 dockerd[1556]: time="2024-10-10T18:00:02.981310888Z" level=info msg="Starting up"
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:01:03 functional-285000 dockerd[1556]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:01:03 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
I1010 18:01:03.041558 10600 command_runner.go:130] > Oct 10 18:01:03 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
I1010 18:01:03.041683 10600 command_runner.go:130] > Oct 10 18:01:03 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
I1010 18:01:03.054193 10600 out.go:201]
W1010 18:01:03.057193 10600 out.go:270] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
sudo journalctl --no-pager -u docker:
-- stdout --
Oct 10 17:56:28 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.839166858Z" level=info msg="Starting up"
Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.841967004Z" level=info msg="containerd not running, starting managed containerd"
Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.844175141Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=671
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.874362745Z" level=info msg="starting containerd" revision=7f7fdf5fed64eb6a7caf99b3e12efcf9d60e311c version=v1.7.22
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902331512Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902428713Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902769219Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902861621Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902959622Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903102225Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903288328Z" 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
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903378829Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903399529Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903411230Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903497531Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903940339Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.906994389Z" 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
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907103391Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907233793Z" 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
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907326495Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907434397Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907740902Z" level=info msg="metadata content store policy set" policy=shared
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951131826Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951380030Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951424031Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951448931Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951495032Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951970440Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952342446Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952495749Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952601150Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952712252Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952912956Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952939656Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952954056Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952985957Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953002057Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953015957Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953085258Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953098459Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953120359Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953135159Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953171660Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953211061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953231561Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953246061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953259461Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953272662Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953293462Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953310962Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953323262Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953336063Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953348263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953364163Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953387764Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953405264Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953418864Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953478465Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953577567Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953596267Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953609467Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953696469Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953722969Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953735369Z" level=info msg="NRI interface is disabled by configuration."
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954056675Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954136976Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954186877Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954207777Z" level=info msg="containerd successfully booted in 0.081140s"
Oct 10 17:56:29 functional-285000 dockerd[665]: time="2024-10-10T17:56:29.915060708Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Oct 10 17:56:29 functional-285000 dockerd[665]: time="2024-10-10T17:56:29.951015870Z" level=info msg="Loading containers: start."
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.109896649Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.333755019Z" level=info msg="Loading containers: done."
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363354343Z" level=warning msg="WARNING: bridge-nf-call-iptables is disabled"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363396543Z" level=warning msg="WARNING: bridge-nf-call-ip6tables is disabled"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363421344Z" level=info msg="Docker daemon" commit=41ca978 containerd-snapshotter=false storage-driver=overlay2 version=27.3.1
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363570447Z" level=info msg="Daemon has completed initialization"
Oct 10 17:56:30 functional-285000 systemd[1]: Started Docker Application Container Engine.
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.471818561Z" level=info msg="API listen on [::]:2376"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.472023465Z" level=info msg="API listen on /var/run/docker.sock"
Oct 10 17:57:01 functional-285000 systemd[1]: Stopping Docker Application Container Engine...
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.341467974Z" level=info msg="Processing signal 'terminated'"
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343081379Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343316379Z" level=info msg="Daemon shutdown complete"
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343510380Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343538680Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Oct 10 17:57:02 functional-285000 systemd[1]: docker.service: Deactivated successfully.
Oct 10 17:57:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 17:57:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:57:02 functional-285000 dockerd[1080]: time="2024-10-10T17:57:02.400747578Z" level=info msg="Starting up"
Oct 10 17:58:02 functional-285000 dockerd[1080]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 17:58:02 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Oct 10 17:58:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 17:58:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:58:02 functional-285000 dockerd[1105]: time="2024-10-10T17:58:02.594840376Z" level=info msg="Starting up"
Oct 10 17:59:02 functional-285000 dockerd[1105]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 17:59:02 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
Oct 10 17:59:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 17:59:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:59:02 functional-285000 dockerd[1313]: time="2024-10-10T17:59:02.908220581Z" level=info msg="Starting up"
Oct 10 17:59:32 functional-285000 dockerd[1313]: time="2024-10-10T17:59:32.357499986Z" level=info msg="Processing signal 'terminated'"
Oct 10 18:00:02 functional-285000 dockerd[1313]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 18:00:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 18:00:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 18:00:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 18:00:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 18:00:02 functional-285000 dockerd[1556]: time="2024-10-10T18:00:02.981310888Z" level=info msg="Starting up"
Oct 10 18:01:03 functional-285000 dockerd[1556]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 18:01:03 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 18:01:03 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 18:01:03 functional-285000 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 --
Oct 10 17:56:28 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.839166858Z" level=info msg="Starting up"
Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.841967004Z" level=info msg="containerd not running, starting managed containerd"
Oct 10 17:56:28 functional-285000 dockerd[665]: time="2024-10-10T17:56:28.844175141Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=671
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.874362745Z" level=info msg="starting containerd" revision=7f7fdf5fed64eb6a7caf99b3e12efcf9d60e311c version=v1.7.22
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902331512Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902428713Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902769219Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902861621Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.902959622Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903102225Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903288328Z" 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
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903378829Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903399529Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903411230Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903497531Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.903940339Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.906994389Z" 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
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907103391Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907233793Z" 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
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907326495Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907434397Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.907740902Z" level=info msg="metadata content store policy set" policy=shared
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951131826Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951380030Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951424031Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951448931Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951495032Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.951970440Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952342446Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952495749Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952601150Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952712252Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952912956Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952939656Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952954056Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.952985957Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953002057Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953015957Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953085258Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953098459Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953120359Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953135159Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953171660Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953211061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953231561Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953246061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953259461Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953272662Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953293462Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953310962Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953323262Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953336063Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953348263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953364163Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953387764Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953405264Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953418864Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953478465Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953577567Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953596267Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953609467Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953696469Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953722969Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.953735369Z" level=info msg="NRI interface is disabled by configuration."
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954056675Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954136976Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954186877Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Oct 10 17:56:28 functional-285000 dockerd[671]: time="2024-10-10T17:56:28.954207777Z" level=info msg="containerd successfully booted in 0.081140s"
Oct 10 17:56:29 functional-285000 dockerd[665]: time="2024-10-10T17:56:29.915060708Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Oct 10 17:56:29 functional-285000 dockerd[665]: time="2024-10-10T17:56:29.951015870Z" level=info msg="Loading containers: start."
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.109896649Z" level=warning msg="ip6tables is enabled, but cannot set up ip6tables chains" error="failed to create NAT chain DOCKER: iptables failed: ip6tables --wait -t nat -N DOCKER: ip6tables v1.8.9 (legacy): can't initialize ip6tables table `nat': Table does not exist (do you need to insmod?)\nPerhaps ip6tables or your kernel needs to be upgraded.\n (exit status 3)"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.333755019Z" level=info msg="Loading containers: done."
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363354343Z" level=warning msg="WARNING: bridge-nf-call-iptables is disabled"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363396543Z" level=warning msg="WARNING: bridge-nf-call-ip6tables is disabled"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363421344Z" level=info msg="Docker daemon" commit=41ca978 containerd-snapshotter=false storage-driver=overlay2 version=27.3.1
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.363570447Z" level=info msg="Daemon has completed initialization"
Oct 10 17:56:30 functional-285000 systemd[1]: Started Docker Application Container Engine.
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.471818561Z" level=info msg="API listen on [::]:2376"
Oct 10 17:56:30 functional-285000 dockerd[665]: time="2024-10-10T17:56:30.472023465Z" level=info msg="API listen on /var/run/docker.sock"
Oct 10 17:57:01 functional-285000 systemd[1]: Stopping Docker Application Container Engine...
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.341467974Z" level=info msg="Processing signal 'terminated'"
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343081379Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343316379Z" level=info msg="Daemon shutdown complete"
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343510380Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Oct 10 17:57:01 functional-285000 dockerd[665]: time="2024-10-10T17:57:01.343538680Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Oct 10 17:57:02 functional-285000 systemd[1]: docker.service: Deactivated successfully.
Oct 10 17:57:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 17:57:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:57:02 functional-285000 dockerd[1080]: time="2024-10-10T17:57:02.400747578Z" level=info msg="Starting up"
Oct 10 17:58:02 functional-285000 dockerd[1080]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 17:58:02 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
Oct 10 17:58:02 functional-285000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
Oct 10 17:58:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 17:58:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:58:02 functional-285000 dockerd[1105]: time="2024-10-10T17:58:02.594840376Z" level=info msg="Starting up"
Oct 10 17:59:02 functional-285000 dockerd[1105]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 17:59:02 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
Oct 10 17:59:02 functional-285000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
Oct 10 17:59:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 17:59:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 17:59:02 functional-285000 dockerd[1313]: time="2024-10-10T17:59:02.908220581Z" level=info msg="Starting up"
Oct 10 17:59:32 functional-285000 dockerd[1313]: time="2024-10-10T17:59:32.357499986Z" level=info msg="Processing signal 'terminated'"
Oct 10 18:00:02 functional-285000 dockerd[1313]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 18:00:02 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 18:00:02 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 18:00:02 functional-285000 systemd[1]: Stopped Docker Application Container Engine.
Oct 10 18:00:02 functional-285000 systemd[1]: Starting Docker Application Container Engine...
Oct 10 18:00:02 functional-285000 dockerd[1556]: time="2024-10-10T18:00:02.981310888Z" level=info msg="Starting up"
Oct 10 18:01:03 functional-285000 dockerd[1556]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Oct 10 18:01:03 functional-285000 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Oct 10 18:01:03 functional-285000 systemd[1]: docker.service: Failed with result 'exit-code'.
Oct 10 18:01:03 functional-285000 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W1010 18:01:03.057545 10600 out.go:270] *
*
W1010 18:01:03.058191 10600 out.go:293] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I1010 18:01:03.062160 10600 out.go:201]
** /stderr **
functional_test.go:661: failed to soft start minikube. args "out/minikube-windows-amd64.exe start -p functional-285000 --alsologtostderr -v=8": exit status 90
functional_test.go:663: soft start took 2m48.6194081s for "functional-285000" cluster.
I1010 18:01:03.286823 7528 config.go:182] Loaded profile config "functional-285000": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.31.1
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.Host}} -p functional-285000 -n functional-285000
helpers_test.go:239: (dbg) Non-zero exit: out/minikube-windows-amd64.exe status --format={{.Host}} -p functional-285000 -n functional-285000: exit status 6 (12.0337003s)
-- stdout --
Running
WARNING: Your kubectl is pointing to stale minikube-vm.
To fix the kubectl context, run `minikube update-context`
-- /stdout --
** stderr **
E1010 18:01:15.253491 8092 status.go:458] kubeconfig endpoint: get endpoint: "functional-285000" does not appear in C:\Users\jenkins.minikube3\minikube-integration\kubeconfig
** /stderr **
helpers_test.go:239: status error: exit status 6 (may be ok)
helpers_test.go:241: "functional-285000" 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.65s)