=== RUN TestNetworkPlugins/group/kindnet/Start
net_test.go:112: (dbg) Run: out/minikube-windows-amd64.exe start -p kindnet-863900 --memory=3072 --alsologtostderr --wait=true --wait-timeout=15m --cni=kindnet --driver=hyperv
net_test.go:112: (dbg) Non-zero exit: out/minikube-windows-amd64.exe start -p kindnet-863900 --memory=3072 --alsologtostderr --wait=true --wait-timeout=15m --cni=kindnet --driver=hyperv: exit status 90 (6m11.7038915s)
-- stdout --
* [kindnet-863900] minikube v1.32.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4046 Build 19045.4046
- KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
- MINIKUBE_LOCATION=18259
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on user configuration
* Starting control plane node kindnet-863900 in cluster kindnet-863900
* Creating hyperv VM (CPUs=2, Memory=3072MB, Disk=20000MB) ...
-- /stdout --
** stderr **
W0229 19:59:46.661754 14320 main.go:291] Unable to resolve the current Docker CLI context "default": context "default": context not found: open C:\Users\jenkins.minikube5\.docker\contexts\meta\37a8eec1ce19687d132fe29051dca629d164e2c4958ba141d5f4133a33f0688f\meta.json: The system cannot find the path specified.
I0229 19:59:46.708769 14320 out.go:291] Setting OutFile to fd 900 ...
I0229 19:59:46.709769 14320 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0229 19:59:46.709769 14320 out.go:304] Setting ErrFile to fd 1040...
I0229 19:59:46.709769 14320 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0229 19:59:46.730762 14320 out.go:298] Setting JSON to false
I0229 19:59:46.733761 14320 start.go:129] hostinfo: {"hostname":"minikube5","uptime":58523,"bootTime":1709178263,"procs":199,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19045.4046 Build 19045.4046","kernelVersion":"10.0.19045.4046 Build 19045.4046","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"b047c2aa-b84e-4b82-894c-ed46f3580f4d"}
W0229 19:59:46.734763 14320 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0229 19:59:46.735761 14320 out.go:177] * [kindnet-863900] minikube v1.32.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4046 Build 19045.4046
I0229 19:59:46.735761 14320 notify.go:220] Checking for updates...
I0229 19:59:46.736764 14320 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
I0229 19:59:46.737761 14320 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0229 19:59:46.737761 14320 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
I0229 19:59:46.738762 14320 out.go:177] - MINIKUBE_LOCATION=18259
I0229 19:59:46.738762 14320 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0229 19:59:46.740768 14320 config.go:182] Loaded profile config "NoKubernetes-737000": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I0229 19:59:46.740768 14320 config.go:182] Loaded profile config "auto-863900": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I0229 19:59:46.740768 14320 config.go:182] Loaded profile config "pause-027300": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I0229 19:59:46.741771 14320 driver.go:392] Setting default libvirt URI to qemu:///system
I0229 19:59:52.090304 14320 out.go:177] * Using the hyperv driver based on user configuration
I0229 19:59:52.091001 14320 start.go:299] selected driver: hyperv
I0229 19:59:52.091001 14320 start.go:903] validating driver "hyperv" against <nil>
I0229 19:59:52.091001 14320 start.go:914] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0229 19:59:52.133930 14320 start_flags.go:309] no existing cluster config was found, will generate one from the flags
I0229 19:59:52.135199 14320 start_flags.go:931] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0229 19:59:52.135199 14320 cni.go:84] Creating CNI manager for "kindnet"
I0229 19:59:52.135199 14320 start_flags.go:318] Found "CNI" CNI - setting NetworkPlugin=cni
I0229 19:59:52.135199 14320 start_flags.go:323] config:
{Name:kindnet-863900 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1708944392-18244@sha256:8610dac8144c3f59a6cf50871eb10395cea122e148262744231a04c396033b08 Memory:3072 CPUs:2 DiskSize:20000 VMDriver: Driver:hyperv HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.28.4 ClusterName:kindnet-863900 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker
CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:kindnet NodeIP: NodePort:8443 NodeName:} Nodes:[] 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:15m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0229 19:59:52.135940 14320 iso.go:125] acquiring lock: {Name:mk91f2ee29fbed5605669750e8cfa308a1229357 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0229 19:59:52.136943 14320 out.go:177] * Starting control plane node kindnet-863900 in cluster kindnet-863900
I0229 19:59:52.137942 14320 preload.go:132] Checking if preload exists for k8s version v1.28.4 and runtime docker
I0229 19:59:52.137942 14320 preload.go:148] Found local preload: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4
I0229 19:59:52.137942 14320 cache.go:56] Caching tarball of preloaded images
I0229 19:59:52.137942 14320 preload.go:174] Found C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.28.4-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0229 19:59:52.137942 14320 cache.go:59] Finished verifying existence of preloaded tar for v1.28.4 on docker
I0229 19:59:52.138947 14320 profile.go:148] Saving config to C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\kindnet-863900\config.json ...
I0229 19:59:52.138947 14320 lock.go:35] WriteFile acquiring C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\kindnet-863900\config.json: {Name:mkda732c5f352f2e525032e7075f3af87ecaa556 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0229 19:59:52.139940 14320 start.go:365] acquiring machines lock for kindnet-863900: {Name:mkcc4972200741852cdd82af2325146d8aedcde8 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0229 20:02:40.641452 14320 start.go:369] acquired machines lock for "kindnet-863900" in 2m48.4920974s
I0229 20:02:40.641775 14320 start.go:93] Provisioning new machine with config: &{Name:kindnet-863900 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18020/minikube-v1.32.1-1708638130-18020-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.42-1708944392-18244@sha256:8610dac8144c3f59a6cf50871eb10395cea122e148262744231a04c396033b08 Memory:3072 CPUs:2 DiskSize:20000 VMDriver: Driver:hyperv HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.59.1/24 HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork:default KVMQemuURI:qemu:///system KVMGPU:false KVMHidden:false KVMNUMACount:1 APIServerPort:0 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesCon
fig:{KubernetesVersion:v1.28.4 ClusterName:kindnet-863900 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:kindnet NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:15m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker Moun
tIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.28.4 ContainerRuntime:docker ControlPlane:true Worker:true}
I0229 20:02:40.641977 14320 start.go:125] createHost starting for "" (driver="hyperv")
I0229 20:02:40.672491 14320 out.go:204] * Creating hyperv VM (CPUs=2, Memory=3072MB, Disk=20000MB) ...
I0229 20:02:40.672885 14320 start.go:159] libmachine.API.Create for "kindnet-863900" (driver="hyperv")
I0229 20:02:40.673127 14320 client.go:168] LocalClient.Create starting
I0229 20:02:40.673459 14320 main.go:141] libmachine: Reading certificate data from C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem
I0229 20:02:40.673459 14320 main.go:141] libmachine: Decoding PEM data...
I0229 20:02:40.673459 14320 main.go:141] libmachine: Parsing certificate...
I0229 20:02:40.673459 14320 main.go:141] libmachine: Reading certificate data from C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem
I0229 20:02:40.674126 14320 main.go:141] libmachine: Decoding PEM data...
I0229 20:02:40.674126 14320 main.go:141] libmachine: Parsing certificate...
I0229 20:02:40.674252 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @(Get-Module -ListAvailable hyper-v).Name | Get-Unique
I0229 20:02:42.497434 14320 main.go:141] libmachine: [stdout =====>] : Hyper-V
I0229 20:02:42.497434 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:42.497434 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @([Security.Principal.WindowsPrincipal][Security.Principal.WindowsIdentity]::GetCurrent()).IsInRole(([System.Security.Principal.SecurityIdentifier]::new("S-1-5-32-578")))
I0229 20:02:44.245334 14320 main.go:141] libmachine: [stdout =====>] : False
I0229 20:02:44.245334 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:44.245521 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @([Security.Principal.WindowsPrincipal][Security.Principal.WindowsIdentity]::GetCurrent()).IsInRole([Security.Principal.WindowsBuiltInRole] "Administrator")
I0229 20:02:45.698953 14320 main.go:141] libmachine: [stdout =====>] : True
I0229 20:02:45.698953 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:45.699168 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive [Console]::OutputEncoding = [Text.Encoding]::UTF8; ConvertTo-Json @(Hyper-V\Get-VMSwitch|Select Id, Name, SwitchType|Where-Object {($_.SwitchType -eq 'External') -or ($_.Id -eq 'c08cb7b8-9b3c-408e-8e30-5e16a3aeb444')}|Sort-Object -Property SwitchType)
I0229 20:02:49.502107 14320 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0229 20:02:49.503018 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:49.504992 14320 main.go:141] libmachine: Downloading C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\boot2docker.iso from file://C:/Users/jenkins.minikube5/minikube-integration/.minikube/cache/iso/amd64/minikube-v1.32.1-1708638130-18020-amd64.iso...
I0229 20:02:49.839044 14320 main.go:141] libmachine: Creating SSH key...
I0229 20:02:50.019645 14320 main.go:141] libmachine: Creating VM...
I0229 20:02:50.019645 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive [Console]::OutputEncoding = [Text.Encoding]::UTF8; ConvertTo-Json @(Hyper-V\Get-VMSwitch|Select Id, Name, SwitchType|Where-Object {($_.SwitchType -eq 'External') -or ($_.Id -eq 'c08cb7b8-9b3c-408e-8e30-5e16a3aeb444')}|Sort-Object -Property SwitchType)
I0229 20:02:52.924308 14320 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0229 20:02:52.924308 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:52.924397 14320 main.go:141] libmachine: Using switch "Default Switch"
I0229 20:02:52.924550 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @([Security.Principal.WindowsPrincipal][Security.Principal.WindowsIdentity]::GetCurrent()).IsInRole([Security.Principal.WindowsBuiltInRole] "Administrator")
I0229 20:02:54.627969 14320 main.go:141] libmachine: [stdout =====>] : True
I0229 20:02:54.628334 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:54.628334 14320 main.go:141] libmachine: Creating VHD
I0229 20:02:54.628427 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VHD -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\fixed.vhd' -SizeBytes 10MB -Fixed
I0229 20:02:58.616159 14320 main.go:141] libmachine: [stdout =====>] :
ComputerName : minikube5
Path : C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\fixed.vhd
VhdFormat : VHD
VhdType : Fixed
FileSize : 10486272
Size : 10485760
MinimumSize :
LogicalSectorSize : 512
PhysicalSectorSize : 512
BlockSize : 0
ParentPath :
DiskIdentifier : 287EF437-4032-435E-B02E-6AB69386F53E
FragmentationPercentage : 0
Alignment : 1
Attached : False
DiskNumber :
IsPMEMCompatible : False
AddressAbstractionType : None
Number :
I0229 20:02:58.616159 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:02:58.616238 14320 main.go:141] libmachine: Writing magic tar header
I0229 20:02:58.616238 14320 main.go:141] libmachine: Writing SSH key tar header
I0229 20:02:58.625413 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Convert-VHD -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\fixed.vhd' -DestinationPath 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\disk.vhd' -VHDType Dynamic -DeleteSource
I0229 20:03:01.922593 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:01.922593 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:01.922593 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Resize-VHD -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\disk.vhd' -SizeBytes 20000MB
I0229 20:03:04.462395 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:04.462395 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:04.462587 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VM kindnet-863900 -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900' -SwitchName 'Default Switch' -MemoryStartupBytes 3072MB
I0229 20:03:09.414106 14320 main.go:141] libmachine: [stdout =====>] :
Name State CPUUsage(%) MemoryAssigned(M) Uptime Status Version
---- ----- ----------- ----------------- ------ ------ -------
kindnet-863900 Off 0 0 00:00:00 Operating normally 9.0
I0229 20:03:09.414106 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:09.414432 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMMemory -VMName kindnet-863900 -DynamicMemoryEnabled $false
I0229 20:03:11.556851 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:11.556851 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:11.557548 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMProcessor kindnet-863900 -Count 2
I0229 20:03:13.604454 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:13.604454 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:13.604702 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMDvdDrive -VMName kindnet-863900 -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\boot2docker.iso'
I0229 20:03:15.999835 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:15.999835 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:15.999835 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Add-VMHardDiskDrive -VMName kindnet-863900 -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\disk.vhd'
I0229 20:03:18.456915 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:18.456915 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:18.456915 14320 main.go:141] libmachine: Starting VM...
I0229 20:03:18.457919 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Start-VM kindnet-863900
I0229 20:03:21.148275 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:21.148275 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:21.148275 14320 main.go:141] libmachine: Waiting for host to start...
I0229 20:03:21.148479 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:23.260920 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:23.260920 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:23.260920 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:25.625796 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:25.625796 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:26.630528 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:28.664499 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:28.664499 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:28.664722 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:31.009362 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:31.009362 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:32.014938 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:34.034262 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:34.034262 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:34.034262 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:36.396088 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:36.396369 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:37.397011 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:39.744438 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:39.744438 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:39.744519 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:42.301462 14320 main.go:141] libmachine: [stdout =====>] :
I0229 20:03:42.301462 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:43.316419 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:45.527914 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:45.527914 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:45.528017 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:47.997699 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:03:47.997699 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:47.997699 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:49.993395 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:49.993873 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:49.993873 14320 machine.go:88] provisioning docker machine ...
I0229 20:03:49.993962 14320 buildroot.go:166] provisioning hostname "kindnet-863900"
I0229 20:03:49.994041 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:52.001302 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:52.001302 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:52.001302 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:54.379563 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:03:54.379563 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:54.384766 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:03:54.385435 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:03:54.385435 14320 main.go:141] libmachine: About to run SSH command:
sudo hostname kindnet-863900 && echo "kindnet-863900" | sudo tee /etc/hostname
I0229 20:03:54.544225 14320 main.go:141] libmachine: SSH cmd err, output: <nil>: kindnet-863900
I0229 20:03:54.544225 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:03:56.534469 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:03:56.534469 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:56.534469 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:03:58.901725 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:03:58.901725 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:03:58.909549 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:03:58.910215 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:03:58.910215 14320 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\skindnet-863900' /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 kindnet-863900/g' /etc/hosts;
else
echo '127.0.1.1 kindnet-863900' | sudo tee -a /etc/hosts;
fi
fi
I0229 20:03:59.065193 14320 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0229 20:03:59.065193 14320 buildroot.go:172] set auth options {CertDir:C:\Users\jenkins.minikube5\minikube-integration\.minikube CaCertPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem CaPrivateKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem ServerKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem ClientKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem ServerCertSANs:[] StorePath:C:\Users\jenkins.minikube5\minikube-integration\.minikube}
I0229 20:03:59.065193 14320 buildroot.go:174] setting up certificates
I0229 20:03:59.065775 14320 provision.go:83] configureAuth start
I0229 20:03:59.065864 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:01.053409 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:01.054299 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:01.054360 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:03.435185 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:03.435185 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:03.435185 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:05.416831 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:05.417328 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:05.417388 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:07.788742 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:07.788947 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:07.788947 14320 provision.go:138] copyHostCerts
I0229 20:04:07.789263 14320 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem, removing ...
I0229 20:04:07.789336 14320 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\ca.pem
I0229 20:04:07.789652 14320 exec_runner.go:151] cp: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem --> C:\Users\jenkins.minikube5\minikube-integration\.minikube/ca.pem (1078 bytes)
I0229 20:04:07.790565 14320 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem, removing ...
I0229 20:04:07.790565 14320 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cert.pem
I0229 20:04:07.790930 14320 exec_runner.go:151] cp: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem --> C:\Users\jenkins.minikube5\minikube-integration\.minikube/cert.pem (1123 bytes)
I0229 20:04:07.791767 14320 exec_runner.go:144] found C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem, removing ...
I0229 20:04:07.791767 14320 exec_runner.go:203] rm: C:\Users\jenkins.minikube5\minikube-integration\.minikube\key.pem
I0229 20:04:07.791767 14320 exec_runner.go:151] cp: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\key.pem --> C:\Users\jenkins.minikube5\minikube-integration\.minikube/key.pem (1679 bytes)
I0229 20:04:07.792739 14320 provision.go:112] generating server cert: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem ca-key=C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem private-key=C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca-key.pem org=jenkins.kindnet-863900 san=[172.26.48.88 172.26.48.88 localhost 127.0.0.1 minikube kindnet-863900]
I0229 20:04:08.166473 14320 provision.go:172] copyRemoteCerts
I0229 20:04:08.175118 14320 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0229 20:04:08.175118 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:10.149576 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:10.149693 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:10.149693 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:12.525582 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:12.525582 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:12.525898 14320 sshutil.go:53] new ssh client: &{IP:172.26.48.88 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\id_rsa Username:docker}
I0229 20:04:12.635624 14320 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (4.4602585s)
I0229 20:04:12.635867 14320 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0229 20:04:12.686286 14320 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1220 bytes)
I0229 20:04:12.749102 14320 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0229 20:04:12.796705 14320 provision.go:86] duration metric: configureAuth took 13.7301673s
I0229 20:04:12.796773 14320 buildroot.go:189] setting minikube options for container-runtime
I0229 20:04:12.797326 14320 config.go:182] Loaded profile config "kindnet-863900": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.28.4
I0229 20:04:12.797436 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:14.781697 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:14.781697 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:14.781697 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:17.184681 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:17.184681 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:17.188607 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:04:17.188734 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:04:17.188734 14320 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0229 20:04:17.321143 14320 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0229 20:04:17.321143 14320 buildroot.go:70] root file system type: tmpfs
I0229 20:04:17.321143 14320 provision.go:309] Updating docker unit: /lib/systemd/system/docker.service ...
I0229 20:04:17.321143 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:19.346647 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:19.346842 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:19.346842 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:21.845627 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:21.845724 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:21.851424 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:04:21.852147 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:04:21.852147 14320 main.go:141] libmachine: About to run SSH command:
sudo mkdir -p /lib/systemd/system && printf %s "[Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperv --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP \$MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
" | sudo tee /lib/systemd/system/docker.service.new
I0229 20:04:22.012781 14320 main.go:141] libmachine: SSH cmd err, output: <nil>: [Unit]
Description=Docker Application Container Engine
Documentation=https://docs.docker.com
After=network.target minikube-automount.service docker.socket
Requires= minikube-automount.service docker.socket
StartLimitBurst=3
StartLimitIntervalSec=60
[Service]
Type=notify
Restart=on-failure
# This file is a systemd drop-in unit that inherits from the base dockerd configuration.
# The base configuration already specifies an 'ExecStart=...' command. The first directive
# here is to clear out that command inherited from the base configuration. Without this,
# the command from the base configuration and the command specified here are treated as
# a sequence of commands, which is not the desired behavior, nor is it valid -- systemd
# will catch this invalid input and refuse to start the service with an error like:
# Service has more than one ExecStart= setting, which is only allowed for Type=oneshot services.
# NOTE: default-ulimit=nofile is set to an arbitrary number for consistency with other
# container runtimes. If left unlimited, it may result in OOM issues with MySQL.
ExecStart=
ExecStart=/usr/bin/dockerd -H tcp://0.0.0.0:2376 -H unix:///var/run/docker.sock --default-ulimit=nofile=1048576:1048576 --tlsverify --tlscacert /etc/docker/ca.pem --tlscert /etc/docker/server.pem --tlskey /etc/docker/server-key.pem --label provider=hyperv --insecure-registry 10.96.0.0/12
ExecReload=/bin/kill -s HUP $MAINPID
# Having non-zero Limit*s causes performance problems due to accounting overhead
# in the kernel. We recommend using cgroups to do container-local accounting.
LimitNOFILE=infinity
LimitNPROC=infinity
LimitCORE=infinity
# Uncomment TasksMax if your systemd version supports it.
# Only systemd 226 and above support this version.
TasksMax=infinity
TimeoutStartSec=0
# set delegate yes so that systemd does not reset the cgroups of docker containers
Delegate=yes
# kill only the docker process, not all processes in the cgroup
KillMode=process
[Install]
WantedBy=multi-user.target
I0229 20:04:22.013344 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:24.062929 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:24.062929 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:24.062929 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:26.500275 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:26.501007 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:26.506537 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:04:26.507082 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:04:26.507206 14320 main.go:141] libmachine: About to run SSH command:
sudo diff -u /lib/systemd/system/docker.service /lib/systemd/system/docker.service.new || { sudo mv /lib/systemd/system/docker.service.new /lib/systemd/system/docker.service; sudo systemctl -f daemon-reload && sudo systemctl -f enable docker && sudo systemctl -f restart docker; }
I0229 20:04:27.583359 14320 main.go:141] libmachine: SSH cmd err, output: <nil>: diff: can't stat '/lib/systemd/system/docker.service': No such file or directory
Created symlink /etc/systemd/system/multi-user.target.wants/docker.service → /usr/lib/systemd/system/docker.service.
I0229 20:04:27.583891 14320 machine.go:91] provisioned docker machine in 37.5879278s
I0229 20:04:27.583937 14320 client.go:171] LocalClient.Create took 1m46.9048664s
I0229 20:04:27.584059 14320 start.go:167] duration metric: libmachine.API.Create for "kindnet-863900" took 1m46.9052311s
I0229 20:04:27.584129 14320 start.go:300] post-start starting for "kindnet-863900" (driver="hyperv")
I0229 20:04:27.584155 14320 start.go:329] creating required directories: [/etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs]
I0229 20:04:27.593522 14320 ssh_runner.go:195] Run: sudo mkdir -p /etc/kubernetes/addons /etc/kubernetes/manifests /var/tmp/minikube /var/lib/minikube /var/lib/minikube/certs /var/lib/minikube/images /var/lib/minikube/binaries /tmp/gvisor /usr/share/ca-certificates /etc/ssl/certs
I0229 20:04:27.593522 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:29.646418 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:29.646418 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:29.646418 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:32.066676 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:32.066676 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:32.067668 14320 sshutil.go:53] new ssh client: &{IP:172.26.48.88 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\id_rsa Username:docker}
I0229 20:04:32.168311 14320 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.5745354s)
I0229 20:04:32.177500 14320 ssh_runner.go:195] Run: cat /etc/os-release
I0229 20:04:32.185117 14320 info.go:137] Remote host: Buildroot 2023.02.9
I0229 20:04:32.185117 14320 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\addons for local assets ...
I0229 20:04:32.185117 14320 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\files for local assets ...
I0229 20:04:32.186753 14320 filesync.go:149] local asset: C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\43562.pem -> 43562.pem in /etc/ssl/certs
I0229 20:04:32.196225 14320 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0229 20:04:32.214976 14320 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\files\etc\ssl\certs\43562.pem --> /etc/ssl/certs/43562.pem (1708 bytes)
I0229 20:04:32.262737 14320 start.go:303] post-start completed in 4.6783226s
I0229 20:04:32.265938 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:34.260310 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:34.260310 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:34.260310 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:36.693483 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:36.693483 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:36.693483 14320 profile.go:148] Saving config to C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\kindnet-863900\config.json ...
I0229 20:04:36.695758 14320 start.go:128] duration metric: createHost completed in 1m56.0472841s
I0229 20:04:36.695830 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:38.706572 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:38.706572 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:38.706656 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:41.091849 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:41.091849 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:41.096547 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:04:41.097144 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:04:41.097144 14320 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0229 20:04:41.228638 14320 main.go:141] libmachine: SSH cmd err, output: <nil>: 1709237081.396897006
I0229 20:04:41.228638 14320 fix.go:206] guest clock: 1709237081.396897006
I0229 20:04:41.228638 14320 fix.go:219] Guest: 2024-02-29 20:04:41.396897006 +0000 UTC Remote: 2024-02-29 20:04:36.6958304 +0000 UTC m=+290.111521801 (delta=4.701066606s)
I0229 20:04:41.228638 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:43.211107 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:43.211107 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:43.211107 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:45.614028 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:45.614028 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:45.618791 14320 main.go:141] libmachine: Using SSH client type: native
I0229 20:04:45.619074 14320 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xbe9d80] 0xbec960 <nil> [] 0s} 172.26.48.88 22 <nil> <nil>}
I0229 20:04:45.619074 14320 main.go:141] libmachine: About to run SSH command:
sudo date -s @1709237081
I0229 20:04:45.760271 14320 main.go:141] libmachine: SSH cmd err, output: <nil>: Thu Feb 29 20:04:41 UTC 2024
I0229 20:04:45.760334 14320 fix.go:226] clock set: Thu Feb 29 20:04:41 UTC 2024
(err=<nil>)
I0229 20:04:45.760334 14320 start.go:83] releasing machines lock for "kindnet-863900", held for 2m5.1118167s
I0229 20:04:45.760506 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:47.811803 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:47.811918 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:47.811918 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:50.286886 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:50.286886 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:50.291286 14320 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0229 20:04:50.291506 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:50.299454 14320 ssh_runner.go:195] Run: cat /version.json
I0229 20:04:50.300441 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM kindnet-863900 ).state
I0229 20:04:52.590218 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:52.590218 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:52.590218 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:52.592210 14320 main.go:141] libmachine: [stdout =====>] : Running
I0229 20:04:52.592210 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:52.592210 14320 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM kindnet-863900 ).networkadapters[0]).ipaddresses[0]
I0229 20:04:55.168927 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:55.168927 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:55.168927 14320 sshutil.go:53] new ssh client: &{IP:172.26.48.88 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\id_rsa Username:docker}
I0229 20:04:55.190289 14320 main.go:141] libmachine: [stdout =====>] : 172.26.48.88
I0229 20:04:55.190289 14320 main.go:141] libmachine: [stderr =====>] :
I0229 20:04:55.190289 14320 sshutil.go:53] new ssh client: &{IP:172.26.48.88 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\kindnet-863900\id_rsa Username:docker}
I0229 20:04:55.257697 14320 ssh_runner.go:235] Completed: cat /version.json: (4.9579676s)
I0229 20:04:55.273140 14320 ssh_runner.go:195] Run: systemctl --version
I0229 20:04:55.342135 14320 ssh_runner.go:235] Completed: curl -sS -m 2 https://registry.k8s.io/: (5.0504812s)
I0229 20:04:55.352579 14320 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0229 20:04:55.363086 14320 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0229 20:04:55.377390 14320 ssh_runner.go:195] Run: sudo find /etc/cni/net.d -maxdepth 1 -type f ( ( -name *bridge* -or -name *podman* ) -and -not -name *.mk_disabled ) -printf "%p, " -exec sh -c "sudo mv {} {}.mk_disabled" ;
I0229 20:04:55.415651 14320 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0229 20:04:55.415752 14320 start.go:475] detecting cgroup driver to use...
I0229 20:04:55.416006 14320 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///run/containerd/containerd.sock
" | sudo tee /etc/crictl.yaml"
I0229 20:04:55.472994 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.9"|' /etc/containerd/config.toml"
I0229 20:04:55.502995 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)restrict_oom_score_adj = .*$|\1restrict_oom_score_adj = false|' /etc/containerd/config.toml"
I0229 20:04:55.523724 14320 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0229 20:04:55.532605 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0229 20:04:55.562188 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runtime.v1.linux"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0229 20:04:55.590452 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0229 20:04:55.623913 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0229 20:04:55.652750 14320 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0229 20:04:55.681449 14320 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)conf_dir = .*$|\1conf_dir = "/etc/cni/net.d"|g' /etc/containerd/config.toml"
I0229 20:04:55.710783 14320 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0229 20:04:55.750094 14320 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0229 20:04:55.785251 14320 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0229 20:04:55.997413 14320 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0229 20:04:56.029256 14320 start.go:475] detecting cgroup driver to use...
I0229 20:04:56.043253 14320 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0229 20:04:56.085395 14320 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0229 20:04:56.118363 14320 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0229 20:04:56.163512 14320 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0229 20:04:56.199598 14320 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0229 20:04:56.234106 14320 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0229 20:04:56.285227 14320 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0229 20:04:56.309368 14320 ssh_runner.go:195] Run: /bin/bash -c "sudo mkdir -p /etc && printf %s "runtime-endpoint: unix:///var/run/cri-dockerd.sock
" | sudo tee /etc/crictl.yaml"
I0229 20:04:56.354388 14320 ssh_runner.go:195] Run: which cri-dockerd
I0229 20:04:56.374421 14320 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0229 20:04:56.398310 14320 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0229 20:04:56.443749 14320 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0229 20:04:56.641367 14320 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0229 20:04:56.829503 14320 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0229 20:04:56.829726 14320 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0229 20:04:56.872626 14320 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0229 20:04:57.068206 14320 ssh_runner.go:195] Run: sudo systemctl restart docker
I0229 20:05:58.178700 14320 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.1069579s)
I0229 20:05:58.187685 14320 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0229 20:05:58.223880 14320 out.go:177]
W0229 20:05:58.224511 14320 out.go:239] X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
sudo journalctl --no-pager -u docker:
-- stdout --
Feb 29 20:04:27 kindnet-863900 systemd[1]: Starting Docker Application Container Engine...
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.278927957Z" level=info msg="Starting up"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.279797262Z" level=info msg="containerd not running, starting managed containerd"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.281050557Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=652
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.310426673Z" level=info msg="starting containerd" revision=64b8a811b07ba6288238eefc14d898ee0b5b99ba version=v1.7.11
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.338571599Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339143234Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339401695Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339464610Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339674059Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339752077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.341011274Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.341681031Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.341926589Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.342143040Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.342385397Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.342742181Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.345663869Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.345759492Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346275713Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346375437Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346487363Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346539075Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346553479Z" level=info msg="metadata content store policy set" policy=shared
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360220396Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360378934Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360403039Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360470655Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360643596Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360770126Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361175921Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361312653Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361417178Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361439183Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361455687Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361476592Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361490895Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361515601Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361533906Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361549509Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361563312Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361576316Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361597721Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361614825Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361629028Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361643531Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361665036Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361680940Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361694843Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361709747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361724150Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361741754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361755658Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361770161Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361783864Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361800868Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361822974Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361837377Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361850480Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361910094Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362181158Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362198962Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362211565Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362309988Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362408011Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362423215Z" level=info msg="NRI interface is disabled by configuration."
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362876022Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.363201198Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.363418149Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.363539078Z" level=info msg="containerd successfully booted in 0.054330s"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.395471696Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.416187273Z" level=info msg="Loading containers: start."
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.665156473Z" level=info msg="Loading containers: done."
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.681999363Z" level=info msg="Docker daemon" commit=311b9ff graphdriver=overlay2 version=24.0.7
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.682116191Z" level=info msg="Daemon has completed initialization"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.749754315Z" level=info msg="API listen on /var/run/docker.sock"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.749843236Z" level=info msg="API listen on [::]:2376"
Feb 29 20:04:27 kindnet-863900 systemd[1]: Started Docker Application Container Engine.
Feb 29 20:04:57 kindnet-863900 systemd[1]: Stopping Docker Application Container Engine...
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.263854038Z" level=info msg="Processing signal 'terminated'"
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.265556706Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.265686611Z" level=info msg="Daemon shutdown complete"
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.265852918Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.266057726Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Feb 29 20:04:58 kindnet-863900 systemd[1]: docker.service: Deactivated successfully.
Feb 29 20:04:58 kindnet-863900 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 20:04:58 kindnet-863900 systemd[1]: Starting Docker Application Container Engine...
Feb 29 20:04:58 kindnet-863900 dockerd[991]: time="2024-02-29T20:04:58.338259352Z" level=info msg="Starting up"
Feb 29 20:05:58 kindnet-863900 dockerd[991]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 20:05:58 kindnet-863900 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 20:05:58 kindnet-863900 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 20:05:58 kindnet-863900 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
X Exiting due to RUNTIME_ENABLE: Failed to enable container runtime: sudo systemctl restart docker: Process exited with status 1
stdout:
stderr:
Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.
sudo journalctl --no-pager -u docker:
-- stdout --
Feb 29 20:04:27 kindnet-863900 systemd[1]: Starting Docker Application Container Engine...
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.278927957Z" level=info msg="Starting up"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.279797262Z" level=info msg="containerd not running, starting managed containerd"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.281050557Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=652
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.310426673Z" level=info msg="starting containerd" revision=64b8a811b07ba6288238eefc14d898ee0b5b99ba version=v1.7.11
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.338571599Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339143234Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339401695Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339464610Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339674059Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.339752077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.341011274Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.341681031Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.341926589Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.342143040Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.342385397Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.342742181Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.345663869Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.10.207\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.345759492Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346275713Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346375437Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346487363Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346539075Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.346553479Z" level=info msg="metadata content store policy set" policy=shared
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360220396Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360378934Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360403039Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360470655Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360643596Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.360770126Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361175921Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361312653Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361417178Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361439183Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361455687Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361476592Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361490895Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361515601Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361533906Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361549509Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361563312Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361576316Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361597721Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361614825Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361629028Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361643531Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361665036Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361680940Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361694843Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361709747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361724150Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361741754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361755658Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361770161Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361783864Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361800868Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361822974Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361837377Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361850480Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.361910094Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362181158Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362198962Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362211565Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362309988Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362408011Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362423215Z" level=info msg="NRI interface is disabled by configuration."
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.362876022Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.363201198Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.363418149Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Feb 29 20:04:27 kindnet-863900 dockerd[652]: time="2024-02-29T20:04:27.363539078Z" level=info msg="containerd successfully booted in 0.054330s"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.395471696Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.416187273Z" level=info msg="Loading containers: start."
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.665156473Z" level=info msg="Loading containers: done."
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.681999363Z" level=info msg="Docker daemon" commit=311b9ff graphdriver=overlay2 version=24.0.7
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.682116191Z" level=info msg="Daemon has completed initialization"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.749754315Z" level=info msg="API listen on /var/run/docker.sock"
Feb 29 20:04:27 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:27.749843236Z" level=info msg="API listen on [::]:2376"
Feb 29 20:04:27 kindnet-863900 systemd[1]: Started Docker Application Container Engine.
Feb 29 20:04:57 kindnet-863900 systemd[1]: Stopping Docker Application Container Engine...
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.263854038Z" level=info msg="Processing signal 'terminated'"
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.265556706Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.265686611Z" level=info msg="Daemon shutdown complete"
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.265852918Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Feb 29 20:04:57 kindnet-863900 dockerd[646]: time="2024-02-29T20:04:57.266057726Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Feb 29 20:04:58 kindnet-863900 systemd[1]: docker.service: Deactivated successfully.
Feb 29 20:04:58 kindnet-863900 systemd[1]: Stopped Docker Application Container Engine.
Feb 29 20:04:58 kindnet-863900 systemd[1]: Starting Docker Application Container Engine...
Feb 29 20:04:58 kindnet-863900 dockerd[991]: time="2024-02-29T20:04:58.338259352Z" level=info msg="Starting up"
Feb 29 20:05:58 kindnet-863900 dockerd[991]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Feb 29 20:05:58 kindnet-863900 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Feb 29 20:05:58 kindnet-863900 systemd[1]: docker.service: Failed with result 'exit-code'.
Feb 29 20:05:58 kindnet-863900 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0229 20:05:58.225109 14320 out.go:239] *
*
W0229 20:05:58.226313 14320 out.go:239] ╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
╭─────────────────────────────────────────────────────────────────────────────────────────────╮
│ │
│ * If the above advice does not help, please let us know: │
│ https://github.com/kubernetes/minikube/issues/new/choose │
│ │
│ * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0229 20:05:58.227494 14320 out.go:177]
** /stderr **
net_test.go:114: failed start: exit status 90
--- FAIL: TestNetworkPlugins/group/kindnet/Start (371.86s)