=== RUN TestAddons/Setup
addons_test.go:109: (dbg) Run: out/minikube-windows-amd64.exe start -p addons-586600 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --addons=nvidia-device-plugin --addons=yakd --driver=hyperv --addons=ingress --addons=ingress-dns --addons=helm-tiller
addons_test.go:109: (dbg) Non-zero exit: out/minikube-windows-amd64.exe start -p addons-586600 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --addons=nvidia-device-plugin --addons=yakd --driver=hyperv --addons=ingress --addons=ingress-dns --addons=helm-tiller: exit status 90 (3m35.6001391s)
-- stdout --
* [addons-586600] minikube v1.33.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4291 Build 19045.4291
- KUBECONFIG=C:\Users\jenkins.minikube1\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube1\minikube-integration\.minikube
- MINIKUBE_LOCATION=18703
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on user configuration
* Starting "addons-586600" primary control-plane node in "addons-586600" cluster
* Creating hyperv VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
W0419 16:59:38.355641 10836 main.go:291] Unable to resolve the current Docker CLI context "default": context "default": context not found: open C:\Users\jenkins.minikube1\.docker\contexts\meta\37a8eec1ce19687d132fe29051dca629d164e2c4958ba141d5f4133a33f0688f\meta.json: The system cannot find the path specified.
I0419 16:59:38.357353 10836 out.go:291] Setting OutFile to fd 920 ...
I0419 16:59:38.358077 10836 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0419 16:59:38.358077 10836 out.go:304] Setting ErrFile to fd 924...
I0419 16:59:38.358077 10836 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0419 16:59:38.385999 10836 out.go:298] Setting JSON to false
I0419 16:59:38.394612 10836 start.go:129] hostinfo: {"hostname":"minikube1","uptime":9637,"bootTime":1713561541,"procs":204,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19045.4291 Build 19045.4291","kernelVersion":"10.0.19045.4291 Build 19045.4291","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"f3192dc9-6fb2-4797-bdaa-5f567903ef41"}
W0419 16:59:38.394612 10836 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0419 16:59:38.399638 10836 out.go:177] * [addons-586600] minikube v1.33.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4291 Build 19045.4291
I0419 16:59:38.403479 10836 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube1\minikube-integration\kubeconfig
I0419 16:59:38.403479 10836 notify.go:220] Checking for updates...
I0419 16:59:38.406535 10836 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0419 16:59:38.407705 10836 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube1\minikube-integration\.minikube
I0419 16:59:38.411184 10836 out.go:177] - MINIKUBE_LOCATION=18703
I0419 16:59:38.414106 10836 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0419 16:59:38.416910 10836 driver.go:392] Setting default libvirt URI to qemu:///system
I0419 16:59:44.050519 10836 out.go:177] * Using the hyperv driver based on user configuration
I0419 16:59:44.054159 10836 start.go:297] selected driver: hyperv
I0419 16:59:44.054159 10836 start.go:901] validating driver "hyperv" against <nil>
I0419 16:59:44.054159 10836 start.go:912] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0419 16:59:44.112421 10836 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0419 16:59:44.113246 10836 start_flags.go:947] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0419 16:59:44.114025 10836 cni.go:84] Creating CNI manager for ""
I0419 16:59:44.114025 10836 cni.go:158] "hyperv" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0419 16:59:44.114025 10836 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0419 16:59:44.114245 10836 start.go:340] cluster config:
{Name:addons-586600 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.43@sha256:7ff490df401cc0fbf19a4521544ae8f4a00cc163e92a95017a8d8bfdb1422737 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:8443 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.30.0 ClusterName:addons-586600 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: Netwo
rkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.30.0 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.minikube1:/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: SSHAgentPI
D:0 GPUs: AutoPauseInterval:1m0s}
I0419 16:59:44.114245 10836 iso.go:125] acquiring lock: {Name:mk297f2abb67cbbcd36490c866afe693892d0c05 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0419 16:59:44.118390 10836 out.go:177] * Starting "addons-586600" primary control-plane node in "addons-586600" cluster
I0419 16:59:44.120702 10836 preload.go:132] Checking if preload exists for k8s version v1.30.0 and runtime docker
I0419 16:59:44.121054 10836 preload.go:147] Found local preload: C:\Users\jenkins.minikube1\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.30.0-docker-overlay2-amd64.tar.lz4
I0419 16:59:44.121112 10836 cache.go:56] Caching tarball of preloaded images
I0419 16:59:44.121112 10836 preload.go:173] Found C:\Users\jenkins.minikube1\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.30.0-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0419 16:59:44.121112 10836 cache.go:59] Finished verifying existence of preloaded tar for v1.30.0 on docker
I0419 16:59:44.121737 10836 profile.go:143] Saving config to C:\Users\jenkins.minikube1\minikube-integration\.minikube\profiles\addons-586600\config.json ...
I0419 16:59:44.122306 10836 lock.go:35] WriteFile acquiring C:\Users\jenkins.minikube1\minikube-integration\.minikube\profiles\addons-586600\config.json: {Name:mk3ef7e7ddbcea8576d0f69254ae0a09198d70aa Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0419 16:59:44.123425 10836 start.go:360] acquireMachinesLock for addons-586600: {Name:mk1756dfe9622c208593bf9b718faf6c1651aea2 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0419 16:59:44.123425 10836 start.go:364] duration metric: took 0s to acquireMachinesLock for "addons-586600"
I0419 16:59:44.123425 10836 start.go:93] Provisioning new machine with config: &{Name:addons-586600 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube/iso/minikube-v1.33.0-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase:v0.0.43@sha256:7ff490df401cc0fbf19a4521544ae8f4a00cc163e92a95017a8d8bfdb1422737 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:8443 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{KubernetesVersion:v1.30.0 ClusterName:addons-586600 Names
pace: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: Port:8443 KubernetesVersion:v1.30.0 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.minikube1:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryM
irror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.30.0 ContainerRuntime:docker ControlPlane:true Worker:true}
I0419 16:59:44.124143 10836 start.go:125] createHost starting for "" (driver="hyperv")
I0419 16:59:44.125997 10836 out.go:204] * Creating hyperv VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
I0419 16:59:44.125997 10836 start.go:159] libmachine.API.Create for "addons-586600" (driver="hyperv")
I0419 16:59:44.125997 10836 client.go:168] LocalClient.Create starting
I0419 16:59:44.127605 10836 main.go:141] libmachine: Creating CA: C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca.pem
I0419 16:59:44.424238 10836 main.go:141] libmachine: Creating client certificate: C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\cert.pem
I0419 16:59:45.146420 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @(Get-Module -ListAvailable hyper-v).Name | Get-Unique
I0419 16:59:47.616246 10836 main.go:141] libmachine: [stdout =====>] : Hyper-V
I0419 16:59:47.617060 10836 main.go:141] libmachine: [stderr =====>] :
I0419 16:59:47.617130 10836 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")))
I0419 16:59:49.508042 10836 main.go:141] libmachine: [stdout =====>] : False
I0419 16:59:49.508042 10836 main.go:141] libmachine: [stderr =====>] :
I0419 16:59:49.508127 10836 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")
I0419 16:59:51.066688 10836 main.go:141] libmachine: [stdout =====>] : True
I0419 16:59:51.066688 10836 main.go:141] libmachine: [stderr =====>] :
I0419 16:59:51.067579 10836 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)
I0419 16:59:55.194668 10836 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0419 16:59:55.194668 10836 main.go:141] libmachine: [stderr =====>] :
I0419 16:59:55.197266 10836 main.go:141] libmachine: Downloading C:\Users\jenkins.minikube1\minikube-integration\.minikube\cache\boot2docker.iso from file://C:/Users/jenkins.minikube1/minikube-integration/.minikube/cache/iso/amd64/minikube-v1.33.0-amd64.iso...
I0419 16:59:55.718073 10836 main.go:141] libmachine: Creating SSH key...
I0419 16:59:55.885687 10836 main.go:141] libmachine: Creating VM...
I0419 16:59:55.885687 10836 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)
I0419 16:59:58.886922 10836 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0419 16:59:58.886922 10836 main.go:141] libmachine: [stderr =====>] :
I0419 16:59:58.886922 10836 main.go:141] libmachine: Using switch "Default Switch"
I0419 16:59:58.886922 10836 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")
I0419 17:00:00.766156 10836 main.go:141] libmachine: [stdout =====>] : True
I0419 17:00:00.766253 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:00.766253 10836 main.go:141] libmachine: Creating VHD
I0419 17:00:00.766335 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VHD -Path 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\fixed.vhd' -SizeBytes 10MB -Fixed
I0419 17:00:04.534279 10836 main.go:141] libmachine: [stdout =====>] :
ComputerName : minikube1
Path : C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\fixed.vhd
VhdFormat : VHD
VhdType : Fixed
FileSize : 10486272
Size : 10485760
MinimumSize :
LogicalSectorSize : 512
PhysicalSectorSize : 512
BlockSize : 0
ParentPath :
DiskIdentifier : C47C1731-18AA-46AD-A8C3-7B4388B14D3A
FragmentationPercentage : 0
Alignment : 1
Attached : False
DiskNumber :
IsPMEMCompatible : False
AddressAbstractionType : None
Number :
I0419 17:00:04.535286 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:04.535286 10836 main.go:141] libmachine: Writing magic tar header
I0419 17:00:04.535345 10836 main.go:141] libmachine: Writing SSH key tar header
I0419 17:00:04.544986 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Convert-VHD -Path 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\fixed.vhd' -DestinationPath 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\disk.vhd' -VHDType Dynamic -DeleteSource
I0419 17:00:07.770700 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:07.770700 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:07.770781 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Resize-VHD -Path 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\disk.vhd' -SizeBytes 20000MB
I0419 17:00:10.313828 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:10.313828 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:10.313942 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VM addons-586600 -Path 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600' -SwitchName 'Default Switch' -MemoryStartupBytes 4000MB
I0419 17:00:14.095052 10836 main.go:141] libmachine: [stdout =====>] :
Name State CPUUsage(%) MemoryAssigned(M) Uptime Status Version
---- ----- ----------- ----------------- ------ ------ -------
addons-586600 Off 0 0 00:00:00 Operating normally 9.0
I0419 17:00:14.095206 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:14.095268 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMMemory -VMName addons-586600 -DynamicMemoryEnabled $false
I0419 17:00:16.306476 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:16.306476 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:16.306476 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMProcessor addons-586600 -Count 2
I0419 17:00:18.465459 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:18.465541 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:18.465541 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMDvdDrive -VMName addons-586600 -Path 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\boot2docker.iso'
I0419 17:00:21.063471 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:21.063471 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:21.063471 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Add-VMHardDiskDrive -VMName addons-586600 -Path 'C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\disk.vhd'
I0419 17:00:23.750060 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:23.750293 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:23.750293 10836 main.go:141] libmachine: Starting VM...
I0419 17:00:23.750370 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Start-VM addons-586600
I0419 17:00:26.890221 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:26.890937 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:26.890971 10836 main.go:141] libmachine: Waiting for host to start...
I0419 17:00:26.890971 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:29.194132 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:29.194132 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:29.194132 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:00:31.736064 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:31.736335 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:32.743809 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:34.934414 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:34.934473 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:34.934473 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:00:37.479246 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:37.479246 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:38.483523 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:40.652224 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:40.652224 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:40.653016 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:00:43.161703 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:43.161703 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:44.175478 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:46.338641 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:46.338641 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:46.338641 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:00:48.819669 10836 main.go:141] libmachine: [stdout =====>] :
I0419 17:00:48.819737 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:49.833881 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:52.047029 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:52.047846 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:52.047846 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:00:54.683883 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:00:54.684027 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:54.684120 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:56.825149 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:56.825205 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:56.825205 10836 machine.go:94] provisionDockerMachine start ...
I0419 17:00:56.825205 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:00:58.958339 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:00:58.959466 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:00:58.959466 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:01.567784 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:01.567784 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:01.574791 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:01.589923 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:01.589923 10836 main.go:141] libmachine: About to run SSH command:
hostname
I0419 17:01:01.728026 10836 main.go:141] libmachine: SSH cmd err, output: <nil>: minikube
I0419 17:01:01.728255 10836 buildroot.go:166] provisioning hostname "addons-586600"
I0419 17:01:01.728255 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:03.872292 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:03.872368 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:03.872368 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:06.445624 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:06.445624 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:06.453048 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:06.453201 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:06.453201 10836 main.go:141] libmachine: About to run SSH command:
sudo hostname addons-586600 && echo "addons-586600" | sudo tee /etc/hostname
I0419 17:01:06.617142 10836 main.go:141] libmachine: SSH cmd err, output: <nil>: addons-586600
I0419 17:01:06.617263 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:08.725096 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:08.725096 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:08.725096 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:11.264643 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:11.264643 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:11.271832 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:11.272553 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:11.272585 10836 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\saddons-586600' /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 addons-586600/g' /etc/hosts;
else
echo '127.0.1.1 addons-586600' | sudo tee -a /etc/hosts;
fi
fi
I0419 17:01:11.436604 10836 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0419 17:01:11.436723 10836 buildroot.go:172] set auth options {CertDir:C:\Users\jenkins.minikube1\minikube-integration\.minikube CaCertPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca.pem CaPrivateKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\server.pem ServerKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\server-key.pem ClientKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\cert.pem ServerCertSANs:[] StorePath:C:\Users\jenkins.minikube1\minikube-integration\.minikube}
I0419 17:01:11.436834 10836 buildroot.go:174] setting up certificates
I0419 17:01:11.436895 10836 provision.go:84] configureAuth start
I0419 17:01:11.436895 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:13.559976 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:13.559976 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:13.560057 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:16.147495 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:16.147495 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:16.148012 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:18.247773 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:18.247773 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:18.248527 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:20.842250 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:20.842291 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:20.842413 10836 provision.go:143] copyHostCerts
I0419 17:01:20.843135 10836 exec_runner.go:151] cp: C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca.pem --> C:\Users\jenkins.minikube1\minikube-integration\.minikube/ca.pem (1082 bytes)
I0419 17:01:20.844609 10836 exec_runner.go:151] cp: C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\cert.pem --> C:\Users\jenkins.minikube1\minikube-integration\.minikube/cert.pem (1123 bytes)
I0419 17:01:20.845998 10836 exec_runner.go:151] cp: C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\key.pem --> C:\Users\jenkins.minikube1\minikube-integration\.minikube/key.pem (1675 bytes)
I0419 17:01:20.846650 10836 provision.go:117] generating server cert: C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\server.pem ca-key=C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca.pem private-key=C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca-key.pem org=jenkins.addons-586600 san=[127.0.0.1 172.19.38.109 addons-586600 localhost minikube]
I0419 17:01:21.122023 10836 provision.go:177] copyRemoteCerts
I0419 17:01:21.135013 10836 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0419 17:01:21.135013 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:23.261364 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:23.261453 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:23.261453 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:25.809305 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:25.809652 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:25.809731 10836 sshutil.go:53] new ssh client: &{IP:172.19.38.109 Port:22 SSHKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\id_rsa Username:docker}
I0419 17:01:25.929969 10836 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (4.7949445s)
I0419 17:01:25.929969 10836 ssh_runner.go:362] scp C:\Users\jenkins.minikube1\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1082 bytes)
I0419 17:01:25.981958 10836 ssh_runner.go:362] scp C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1208 bytes)
I0419 17:01:26.030370 10836 ssh_runner.go:362] scp C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0419 17:01:26.082493 10836 provision.go:87] duration metric: took 14.6454356s to configureAuth
I0419 17:01:26.082535 10836 buildroot.go:189] setting minikube options for container-runtime
I0419 17:01:26.083257 10836 config.go:182] Loaded profile config "addons-586600": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.30.0
I0419 17:01:26.083308 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:28.222778 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:28.222778 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:28.223705 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:30.775094 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:30.775698 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:30.781624 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:30.782369 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:30.782369 10836 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0419 17:01:30.938482 10836 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0419 17:01:30.938565 10836 buildroot.go:70] root file system type: tmpfs
I0419 17:01:30.938785 10836 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0419 17:01:30.938819 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:33.023542 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:33.024536 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:33.024536 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:35.593915 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:35.593915 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:35.604672 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:35.605512 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:35.605512 10836 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
I0419 17:01:35.773394 10836 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
I0419 17:01:35.773394 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:37.865134 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:37.865134 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:37.865591 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:40.375666 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:40.375666 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:40.382697 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:40.383243 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:40.383424 10836 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; }
I0419 17:01:42.584422 10836 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.
I0419 17:01:42.584977 10836 machine.go:97] duration metric: took 45.7596625s to provisionDockerMachine
I0419 17:01:42.584977 10836 client.go:171] duration metric: took 1m58.458696s to LocalClient.Create
I0419 17:01:42.585108 10836 start.go:167] duration metric: took 1m58.4587915s to libmachine.API.Create "addons-586600"
I0419 17:01:42.585198 10836 start.go:293] postStartSetup for "addons-586600" (driver="hyperv")
I0419 17:01:42.585198 10836 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]
I0419 17:01:42.600029 10836 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
I0419 17:01:42.600029 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:44.721087 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:44.721733 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:44.721733 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:47.252160 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:47.252375 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:47.252375 10836 sshutil.go:53] new ssh client: &{IP:172.19.38.109 Port:22 SSHKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\id_rsa Username:docker}
I0419 17:01:47.359838 10836 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.7597978s)
I0419 17:01:47.373822 10836 ssh_runner.go:195] Run: cat /etc/os-release
I0419 17:01:47.380500 10836 info.go:137] Remote host: Buildroot 2023.02.9
I0419 17:01:47.380636 10836 filesync.go:126] Scanning C:\Users\jenkins.minikube1\minikube-integration\.minikube\addons for local assets ...
I0419 17:01:47.381077 10836 filesync.go:126] Scanning C:\Users\jenkins.minikube1\minikube-integration\.minikube\files for local assets ...
I0419 17:01:47.381306 10836 start.go:296] duration metric: took 4.7960968s for postStartSetup
I0419 17:01:47.384632 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:49.514341 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:49.514341 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:49.514619 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:52.046299 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:52.047151 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:52.047388 10836 profile.go:143] Saving config to C:\Users\jenkins.minikube1\minikube-integration\.minikube\profiles\addons-586600\config.json ...
I0419 17:01:52.050104 10836 start.go:128] duration metric: took 2m7.9256204s to createHost
I0419 17:01:52.050342 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:54.166018 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:54.166018 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:54.166688 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:01:56.628119 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:01:56.628254 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:56.634652 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:01:56.635374 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:01:56.635374 10836 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0419 17:01:56.772717 10836 main.go:141] libmachine: SSH cmd err, output: <nil>: 1713571316.777220859
I0419 17:01:56.772874 10836 fix.go:216] guest clock: 1713571316.777220859
I0419 17:01:56.772874 10836 fix.go:229] Guest: 2024-04-19 17:01:56.777220859 -0700 PDT Remote: 2024-04-19 17:01:52.0501045 -0700 PDT m=+133.798047801 (delta=4.727116359s)
I0419 17:01:56.772990 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:01:58.787917 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:01:58.801737 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:01:58.801737 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:02:01.258256 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:02:01.270419 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:01.277844 10836 main.go:141] libmachine: Using SSH client type: native
I0419 17:02:01.278037 10836 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x78a1c0] 0x78cda0 <nil> [] 0s} 172.19.38.109 22 <nil> <nil>}
I0419 17:02:01.278037 10836 main.go:141] libmachine: About to run SSH command:
sudo date -s @1713571316
I0419 17:02:01.429553 10836 main.go:141] libmachine: SSH cmd err, output: <nil>: Sat Apr 20 00:01:56 UTC 2024
I0419 17:02:01.429553 10836 fix.go:236] clock set: Sat Apr 20 00:01:56 UTC 2024
(err=<nil>)
I0419 17:02:01.429553 10836 start.go:83] releasing machines lock for "addons-586600", held for 2m17.3057978s
I0419 17:02:01.429859 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:02:03.423765 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:02:03.423765 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:03.435927 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:02:05.872097 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:02:05.872097 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:05.888874 10836 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0419 17:02:05.889129 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:02:05.901123 10836 ssh_runner.go:195] Run: cat /version.json
I0419 17:02:05.901123 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-586600 ).state
I0419 17:02:07.981669 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:02:07.981669 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:07.981761 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:02:07.981881 10836 main.go:141] libmachine: [stdout =====>] : Running
I0419 17:02:07.982049 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:07.982080 10836 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-586600 ).networkadapters[0]).ipaddresses[0]
I0419 17:02:10.459023 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:02:10.459023 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:10.472028 10836 sshutil.go:53] new ssh client: &{IP:172.19.38.109 Port:22 SSHKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\id_rsa Username:docker}
I0419 17:02:10.493773 10836 main.go:141] libmachine: [stdout =====>] : 172.19.38.109
I0419 17:02:10.493773 10836 main.go:141] libmachine: [stderr =====>] :
I0419 17:02:10.495045 10836 sshutil.go:53] new ssh client: &{IP:172.19.38.109 Port:22 SSHKeyPath:C:\Users\jenkins.minikube1\minikube-integration\.minikube\machines\addons-586600\id_rsa Username:docker}
I0419 17:02:10.564077 10836 ssh_runner.go:235] Completed: cat /version.json: (4.6629421s)
I0419 17:02:10.577946 10836 ssh_runner.go:195] Run: systemctl --version
I0419 17:02:10.677003 10836 ssh_runner.go:235] Completed: curl -sS -m 2 https://registry.k8s.io/: (4.7880332s)
I0419 17:02:10.690104 10836 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0419 17:02:10.699438 10836 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0419 17:02:10.713115 10836 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" ;
I0419 17:02:10.745413 10836 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0419 17:02:10.745529 10836 start.go:494] detecting cgroup driver to use...
I0419 17:02:10.745810 10836 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"
I0419 17:02:10.801893 10836 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"
I0419 17:02:10.836684 10836 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"
I0419 17:02:10.855984 10836 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0419 17:02:10.871838 10836 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0419 17:02:10.908797 10836 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"
I0419 17:02:10.945056 10836 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0419 17:02:10.980537 10836 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0419 17:02:11.019148 10836 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0419 17:02:11.053968 10836 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"
I0419 17:02:11.088928 10836 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0419 17:02:11.127141 10836 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"
I0419 17:02:11.164997 10836 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0419 17:02:11.207632 10836 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0419 17:02:11.239940 10836 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0419 17:02:11.443782 10836 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0419 17:02:11.476450 10836 start.go:494] detecting cgroup driver to use...
I0419 17:02:11.493248 10836 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0419 17:02:11.531656 10836 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0419 17:02:11.566482 10836 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0419 17:02:11.622980 10836 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0419 17:02:11.664595 10836 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0419 17:02:11.705169 10836 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0419 17:02:11.774423 10836 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0419 17:02:11.792285 10836 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"
I0419 17:02:11.845639 10836 ssh_runner.go:195] Run: which cri-dockerd
I0419 17:02:11.864617 10836 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0419 17:02:11.882506 10836 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0419 17:02:11.926050 10836 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0419 17:02:12.130317 10836 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0419 17:02:12.322319 10836 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0419 17:02:12.322319 10836 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0419 17:02:12.367786 10836 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0419 17:02:12.589129 10836 ssh_runner.go:195] Run: sudo systemctl restart docker
I0419 17:03:13.732651 10836 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.1433746s)
I0419 17:03:13.746487 10836 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0419 17:03:13.782978 10836 out.go:177]
W0419 17:03:13.783270 10836 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 --
Apr 20 00:01:40 addons-586600 systemd[1]: Starting Docker Application Container Engine...
Apr 20 00:01:41 addons-586600 dockerd[669]: time="2024-04-20T00:01:41.021742991Z" level=info msg="Starting up"
Apr 20 00:01:41 addons-586600 dockerd[669]: time="2024-04-20T00:01:41.022902411Z" level=info msg="containerd not running, starting managed containerd"
Apr 20 00:01:41 addons-586600 dockerd[669]: time="2024-04-20T00:01:41.023936228Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=675
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.059775332Z" level=info msg="starting containerd" revision=926c9586fe4a6236699318391cd44976a98e31f1 version=v1.7.15
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.089876440Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090015542Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090166245Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090189745Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090312947Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090408749Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090764555Z" 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
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090909857Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090934458Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.091000959Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.091107460Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.091580968Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094558819Z" 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
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094716021Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094891624Z" 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
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094991126Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.095107228Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.095291831Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.095392633Z" level=info msg="metadata content store policy set" policy=shared
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122121383Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122249585Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122279286Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122299986Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122317687Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122446489Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123035499Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123219802Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123360404Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123384205Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123407405Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123424105Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123440006Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123458806Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123514307Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123531107Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123545707Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123559108Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123589208Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123607008Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123622209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123637809Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123652209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123667509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123680610Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123787411Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123883713Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123978815Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124000415Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124015415Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124029615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124047816Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124079016Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124095217Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124109917Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124162718Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124183918Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124198018Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124212119Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124343121Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124426622Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124446722Z" level=info msg="NRI interface is disabled by configuration."
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124779828Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124920930Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124994632Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.125035532Z" level=info msg="containerd successfully booted in 0.067072s"
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.103920252Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.139705417Z" level=info msg="Loading containers: start."
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.440313825Z" level=info msg="Loading containers: done."
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.463875669Z" level=info msg="Docker daemon" commit=60b9add7 containerd-snapshotter=false storage-driver=overlay2 version=26.0.1
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.464102573Z" level=info msg="Daemon has completed initialization"
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.587018584Z" level=info msg="API listen on /var/run/docker.sock"
Apr 20 00:01:42 addons-586600 systemd[1]: Started Docker Application Container Engine.
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.589392329Z" level=info msg="API listen on [::]:2376"
Apr 20 00:02:12 addons-586600 systemd[1]: Stopping Docker Application Container Engine...
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.622976832Z" level=info msg="Processing signal 'terminated'"
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.624877138Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.625625340Z" level=info msg="Daemon shutdown complete"
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.625691440Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.625754841Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Apr 20 00:02:13 addons-586600 systemd[1]: docker.service: Deactivated successfully.
Apr 20 00:02:13 addons-586600 systemd[1]: Stopped Docker Application Container Engine.
Apr 20 00:02:13 addons-586600 systemd[1]: Starting Docker Application Container Engine...
Apr 20 00:02:13 addons-586600 dockerd[1020]: time="2024-04-20T00:02:13.705997981Z" level=info msg="Starting up"
Apr 20 00:03:13 addons-586600 dockerd[1020]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Apr 20 00:03:13 addons-586600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 00:03:13 addons-586600 systemd[1]: docker.service: Failed with result 'exit-code'.
Apr 20 00:03:13 addons-586600 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 --
Apr 20 00:01:40 addons-586600 systemd[1]: Starting Docker Application Container Engine...
Apr 20 00:01:41 addons-586600 dockerd[669]: time="2024-04-20T00:01:41.021742991Z" level=info msg="Starting up"
Apr 20 00:01:41 addons-586600 dockerd[669]: time="2024-04-20T00:01:41.022902411Z" level=info msg="containerd not running, starting managed containerd"
Apr 20 00:01:41 addons-586600 dockerd[669]: time="2024-04-20T00:01:41.023936228Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=675
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.059775332Z" level=info msg="starting containerd" revision=926c9586fe4a6236699318391cd44976a98e31f1 version=v1.7.15
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.089876440Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090015542Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090166245Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090189745Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090312947Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090408749Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090764555Z" 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
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090909857Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.090934458Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.091000959Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.091107460Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.091580968Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094558819Z" 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
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094716021Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094891624Z" 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
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.094991126Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.095107228Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.095291831Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.095392633Z" level=info msg="metadata content store policy set" policy=shared
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122121383Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122249585Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122279286Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122299986Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122317687Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.122446489Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123035499Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123219802Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123360404Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123384205Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123407405Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123424105Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123440006Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123458806Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123514307Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123531107Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123545707Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123559108Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123589208Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123607008Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123622209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123637809Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123652209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123667509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123680610Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123787411Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123883713Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.123978815Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124000415Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124015415Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124029615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124047816Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124079016Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124095217Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124109917Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124162718Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124183918Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124198018Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124212119Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124343121Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124426622Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124446722Z" level=info msg="NRI interface is disabled by configuration."
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124779828Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124920930Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.124994632Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Apr 20 00:01:41 addons-586600 dockerd[675]: time="2024-04-20T00:01:41.125035532Z" level=info msg="containerd successfully booted in 0.067072s"
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.103920252Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.139705417Z" level=info msg="Loading containers: start."
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.440313825Z" level=info msg="Loading containers: done."
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.463875669Z" level=info msg="Docker daemon" commit=60b9add7 containerd-snapshotter=false storage-driver=overlay2 version=26.0.1
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.464102573Z" level=info msg="Daemon has completed initialization"
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.587018584Z" level=info msg="API listen on /var/run/docker.sock"
Apr 20 00:01:42 addons-586600 systemd[1]: Started Docker Application Container Engine.
Apr 20 00:01:42 addons-586600 dockerd[669]: time="2024-04-20T00:01:42.589392329Z" level=info msg="API listen on [::]:2376"
Apr 20 00:02:12 addons-586600 systemd[1]: Stopping Docker Application Container Engine...
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.622976832Z" level=info msg="Processing signal 'terminated'"
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.624877138Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.625625340Z" level=info msg="Daemon shutdown complete"
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.625691440Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Apr 20 00:02:12 addons-586600 dockerd[669]: time="2024-04-20T00:02:12.625754841Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Apr 20 00:02:13 addons-586600 systemd[1]: docker.service: Deactivated successfully.
Apr 20 00:02:13 addons-586600 systemd[1]: Stopped Docker Application Container Engine.
Apr 20 00:02:13 addons-586600 systemd[1]: Starting Docker Application Container Engine...
Apr 20 00:02:13 addons-586600 dockerd[1020]: time="2024-04-20T00:02:13.705997981Z" level=info msg="Starting up"
Apr 20 00:03:13 addons-586600 dockerd[1020]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Apr 20 00:03:13 addons-586600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 00:03:13 addons-586600 systemd[1]: docker.service: Failed with result 'exit-code'.
Apr 20 00:03:13 addons-586600 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0419 17:03:13.786221 10836 out.go:239] *
*
W0419 17:03:13.787711 10836 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0419 17:03:13.796290 10836 out.go:177]
** /stderr **
addons_test.go:111: out/minikube-windows-amd64.exe start -p addons-586600 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=storage-provisioner-rancher --addons=nvidia-device-plugin --addons=yakd --driver=hyperv --addons=ingress --addons=ingress-dns --addons=helm-tiller failed: exit status 90
--- FAIL: TestAddons/Setup (215.75s)