=== RUN TestAddons/Setup
addons_test.go:109: (dbg) Run: out/minikube-windows-amd64.exe start -p addons-257600 --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-257600 --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 (3m18.3805883s)
-- stdout --
* [addons-257600] minikube v1.33.0-beta.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4291 Build 19045.4291
- KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
- MINIKUBE_LOCATION=18649
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on user configuration
* Starting "addons-257600" primary control-plane node in "addons-257600" cluster
* Creating hyperv VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
W0416 16:21:42.373918 11816 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.
I0416 16:21:42.419810 11816 out.go:291] Setting OutFile to fd 772 ...
I0416 16:21:42.420361 11816 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0416 16:21:42.420361 11816 out.go:304] Setting ErrFile to fd 776...
I0416 16:21:42.420361 11816 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0416 16:21:42.438993 11816 out.go:298] Setting JSON to false
I0416 16:21:42.442096 11816 start.go:129] hostinfo: {"hostname":"minikube5","uptime":22132,"bootTime":1713262370,"procs":202,"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":"b047c2aa-b84e-4b82-894c-ed46f3580f4d"}
W0416 16:21:42.442264 11816 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0416 16:21:42.443731 11816 out.go:177] * [addons-257600] minikube v1.33.0-beta.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4291 Build 19045.4291
I0416 16:21:42.443804 11816 notify.go:220] Checking for updates...
I0416 16:21:42.444599 11816 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube5\minikube-integration\kubeconfig
I0416 16:21:42.445191 11816 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0416 16:21:42.445833 11816 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube5\minikube-integration\.minikube
I0416 16:21:42.446420 11816 out.go:177] - MINIKUBE_LOCATION=18649
I0416 16:21:42.447030 11816 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0416 16:21:42.447744 11816 driver.go:392] Setting default libvirt URI to qemu:///system
I0416 16:21:47.267241 11816 out.go:177] * Using the hyperv driver based on user configuration
I0416 16:21:47.267859 11816 start.go:297] selected driver: hyperv
I0416 16:21:47.267859 11816 start.go:901] validating driver "hyperv" against <nil>
I0416 16:21:47.267859 11816 start.go:912] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0416 16:21:47.307569 11816 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0416 16:21:47.308984 11816 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]
I0416 16:21:47.309052 11816 cni.go:84] Creating CNI manager for ""
I0416 16:21:47.309145 11816 cni.go:158] "hyperv" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0416 16:21:47.309145 11816 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0416 16:21:47.309261 11816 start.go:340] cluster config:
{Name:addons-257600 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.43-1713236840-18649@sha256:c67dbc47b437ffe7d18f65acebd2213336466a75b1de10cec62939ffc450543e 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.29.3 ClusterName:addons-257600 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime
:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.29.3 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP:
SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0416 16:21:47.309435 11816 iso.go:125] acquiring lock: {Name:mka3f8eef32f5becd06d05d1d837c2a92a8fa70c Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0416 16:21:47.310761 11816 out.go:177] * Starting "addons-257600" primary control-plane node in "addons-257600" cluster
I0416 16:21:47.311175 11816 preload.go:132] Checking if preload exists for k8s version v1.29.3 and runtime docker
I0416 16:21:47.311321 11816 preload.go:147] Found local preload: C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4
I0416 16:21:47.311393 11816 cache.go:56] Caching tarball of preloaded images
I0416 16:21:47.311775 11816 preload.go:173] Found C:\Users\jenkins.minikube5\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0416 16:21:47.311926 11816 cache.go:59] Finished verifying existence of preloaded tar for v1.29.3 on docker
I0416 16:21:47.312451 11816 profile.go:143] Saving config to C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\addons-257600\config.json ...
I0416 16:21:47.312668 11816 lock.go:35] WriteFile acquiring C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\addons-257600\config.json: {Name:mk9f03a501872fe3505d31019eb0526d11c00bff Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0416 16:21:47.313450 11816 start.go:360] acquireMachinesLock for addons-257600: {Name:mkcc4972200741852cdd82af2325146d8aedcde8 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0416 16:21:47.313450 11816 start.go:364] duration metric: took 0s to acquireMachinesLock for "addons-257600"
I0416 16:21:47.313450 11816 start.go:93] Provisioning new machine with config: &{Name:addons-257600 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18649/minikube-v1.33.0-1713236417-18649-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.43-1713236840-18649@sha256:c67dbc47b437ffe7d18f65acebd2213336466a75b1de10cec62939ffc450543e 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:{Kub
ernetesVersion:v1.29.3 ClusterName:addons-257600 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.29.3 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube5:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptio
ns:[] 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.29.3 ContainerRuntime:docker ControlPlane:true Worker:true}
I0416 16:21:47.313975 11816 start.go:125] createHost starting for "" (driver="hyperv")
I0416 16:21:47.314701 11816 out.go:204] * Creating hyperv VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
I0416 16:21:47.314701 11816 start.go:159] libmachine.API.Create for "addons-257600" (driver="hyperv")
I0416 16:21:47.314701 11816 client.go:168] LocalClient.Create starting
I0416 16:21:47.315262 11816 main.go:141] libmachine: Creating CA: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem
I0416 16:21:47.545518 11816 main.go:141] libmachine: Creating client certificate: C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\cert.pem
I0416 16:21:47.682325 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @(Get-Module -ListAvailable hyper-v).Name | Get-Unique
I0416 16:21:49.544992 11816 main.go:141] libmachine: [stdout =====>] : Hyper-V
I0416 16:21:49.544992 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:21:49.545759 11816 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")))
I0416 16:21:51.077425 11816 main.go:141] libmachine: [stdout =====>] : False
I0416 16:21:51.077495 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:21:51.077495 11816 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")
I0416 16:21:52.428278 11816 main.go:141] libmachine: [stdout =====>] : True
I0416 16:21:52.428278 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:21:52.428488 11816 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)
I0416 16:21:55.848714 11816 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0416 16:21:55.848783 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:21:55.850031 11816 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.33.0-1713236417-18649-amd64.iso...
I0416 16:21:56.154178 11816 main.go:141] libmachine: Creating SSH key...
I0416 16:21:56.572381 11816 main.go:141] libmachine: Creating VM...
I0416 16:21:56.572381 11816 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)
I0416 16:21:59.071735 11816 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0416 16:21:59.071811 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:21:59.071871 11816 main.go:141] libmachine: Using switch "Default Switch"
I0416 16:21:59.071871 11816 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")
I0416 16:22:00.610477 11816 main.go:141] libmachine: [stdout =====>] : True
I0416 16:22:00.611481 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:00.611666 11816 main.go:141] libmachine: Creating VHD
I0416 16:22:00.611666 11816 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\addons-257600\fixed.vhd' -SizeBytes 10MB -Fixed
I0416 16:22:04.054006 11816 main.go:141] libmachine: [stdout =====>] :
ComputerName : minikube5
Path : C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\fixed.vhd
VhdFormat : VHD
VhdType : Fixed
FileSize : 10486272
Size : 10485760
MinimumSize :
LogicalSectorSize : 512
PhysicalSectorSize : 512
BlockSize : 0
ParentPath :
DiskIdentifier : 5C8D573B-63DA-4D2D-9CF4-D38D4D1DC825
FragmentationPercentage : 0
Alignment : 1
Attached : False
DiskNumber :
IsPMEMCompatible : False
AddressAbstractionType : None
Number :
I0416 16:22:04.054006 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:04.054006 11816 main.go:141] libmachine: Writing magic tar header
I0416 16:22:04.054908 11816 main.go:141] libmachine: Writing SSH key tar header
I0416 16:22:04.065472 11816 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\addons-257600\fixed.vhd' -DestinationPath 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\disk.vhd' -VHDType Dynamic -DeleteSource
I0416 16:22:07.083456 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:07.083456 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:07.083456 11816 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\addons-257600\disk.vhd' -SizeBytes 20000MB
I0416 16:22:09.470866 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:09.470866 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:09.470941 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VM addons-257600 -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600' -SwitchName 'Default Switch' -MemoryStartupBytes 4000MB
I0416 16:22:12.745337 11816 main.go:141] libmachine: [stdout =====>] :
Name State CPUUsage(%) MemoryAssigned(M) Uptime Status Version
---- ----- ----------- ----------------- ------ ------ -------
addons-257600 Off 0 0 00:00:00 Operating normally 9.0
I0416 16:22:12.745337 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:12.745337 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMMemory -VMName addons-257600 -DynamicMemoryEnabled $false
I0416 16:22:14.761020 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:14.761020 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:14.761877 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMProcessor addons-257600 -Count 2
I0416 16:22:16.664580 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:16.664580 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:16.664651 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMDvdDrive -VMName addons-257600 -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\boot2docker.iso'
I0416 16:22:18.982098 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:18.982098 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:18.982098 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Add-VMHardDiskDrive -VMName addons-257600 -Path 'C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\disk.vhd'
I0416 16:22:21.359598 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:21.360115 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:21.360159 11816 main.go:141] libmachine: Starting VM...
I0416 16:22:21.360159 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Start-VM addons-257600
I0416 16:22:23.909010 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:23.909010 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:23.909010 11816 main.go:141] libmachine: Waiting for host to start...
I0416 16:22:23.909010 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:25.946485 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:25.946860 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:25.946939 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:28.202408 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:28.203040 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:29.206945 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:31.192764 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:31.192764 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:31.193434 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:33.413519 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:33.413519 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:34.426873 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:36.357434 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:36.358171 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:36.358171 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:38.592161 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:38.592161 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:39.593552 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:41.567648 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:41.567648 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:41.568378 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:43.793025 11816 main.go:141] libmachine: [stdout =====>] :
I0416 16:22:43.793025 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:44.794153 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:46.808897 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:46.808897 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:46.809611 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:49.120312 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:22:49.120312 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:49.120797 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:51.033716 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:51.033716 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:51.033716 11816 machine.go:94] provisionDockerMachine start ...
I0416 16:22:51.034780 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:53.017358 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:53.017358 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:53.018464 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:55.360338 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:22:55.360338 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:55.365094 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:22:55.374193 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:22:55.374264 11816 main.go:141] libmachine: About to run SSH command:
hostname
I0416 16:22:55.516120 11816 main.go:141] libmachine: SSH cmd err, output: <nil>: minikube
I0416 16:22:55.516296 11816 buildroot.go:166] provisioning hostname "addons-257600"
I0416 16:22:55.516498 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:22:57.429214 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:22:57.429830 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:57.429830 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:22:59.689092 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:22:59.689092 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:22:59.694624 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:22:59.695068 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:22:59.695068 11816 main.go:141] libmachine: About to run SSH command:
sudo hostname addons-257600 && echo "addons-257600" | sudo tee /etc/hostname
I0416 16:22:59.858577 11816 main.go:141] libmachine: SSH cmd err, output: <nil>: addons-257600
I0416 16:22:59.858750 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:01.695743 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:01.695743 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:01.696416 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:03.905300 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:03.905300 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:03.911067 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:23:03.911789 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:23:03.911864 11816 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\saddons-257600' /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-257600/g' /etc/hosts;
else
echo '127.0.1.1 addons-257600' | sudo tee -a /etc/hosts;
fi
fi
I0416 16:23:04.066818 11816 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0416 16:23:04.066818 11816 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}
I0416 16:23:04.067029 11816 buildroot.go:174] setting up certificates
I0416 16:23:04.067029 11816 provision.go:84] configureAuth start
I0416 16:23:04.067064 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:05.987882 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:05.987882 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:05.988320 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:08.271865 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:08.271865 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:08.272632 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:10.191020 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:10.191020 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:10.191020 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:12.570804 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:12.571024 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:12.571024 11816 provision.go:143] copyHostCerts
I0416 16:23:12.571787 11816 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)
I0416 16:23:12.572828 11816 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)
I0416 16:23:12.573673 11816 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)
I0416 16:23:12.574319 11816 provision.go:117] generating server cert: C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem ca-key=C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem private-key=C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca-key.pem org=jenkins.addons-257600 san=[127.0.0.1 172.19.88.77 addons-257600 localhost minikube]
I0416 16:23:12.910315 11816 provision.go:177] copyRemoteCerts
I0416 16:23:12.920966 11816 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0416 16:23:12.922112 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:14.821483 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:14.821483 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:14.822369 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:17.079660 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:17.079660 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:17.079840 11816 sshutil.go:53] new ssh client: &{IP:172.19.88.77 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\id_rsa Username:docker}
I0416 16:23:17.182983 11816 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (4.2606302s)
I0416 16:23:17.184005 11816 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0416 16:23:17.225537 11816 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1208 bytes)
I0416 16:23:17.265370 11816 ssh_runner.go:362] scp C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0416 16:23:17.309132 11816 provision.go:87] duration metric: took 13.2412514s to configureAuth
I0416 16:23:17.309202 11816 buildroot.go:189] setting minikube options for container-runtime
I0416 16:23:17.309594 11816 config.go:182] Loaded profile config "addons-257600": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.29.3
I0416 16:23:17.309634 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:19.182599 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:19.182892 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:19.182981 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:21.385528 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:21.385528 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:21.390141 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:23:21.390545 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:23:21.390545 11816 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0416 16:23:21.521889 11816 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0416 16:23:21.521889 11816 buildroot.go:70] root file system type: tmpfs
I0416 16:23:21.521889 11816 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0416 16:23:21.521889 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:23.462039 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:23.463052 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:23.463236 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:25.756476 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:25.757509 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:25.763068 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:23:25.763612 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:23:25.763713 11816 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
I0416 16:23:25.918327 11816 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
I0416 16:23:25.918327 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:27.804755 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:27.804755 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:27.804755 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:30.018920 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:30.018920 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:30.022577 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:23:30.022880 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:23:30.022880 11816 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; }
I0416 16:23:31.951756 11816 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.
I0416 16:23:31.951756 11816 machine.go:97] duration metric: took 40.9147545s to provisionDockerMachine
I0416 16:23:31.951756 11816 client.go:171] duration metric: took 1m44.6311281s to LocalClient.Create
I0416 16:23:31.951756 11816 start.go:167] duration metric: took 1m44.6311281s to libmachine.API.Create "addons-257600"
I0416 16:23:31.951756 11816 start.go:293] postStartSetup for "addons-257600" (driver="hyperv")
I0416 16:23:31.951756 11816 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]
I0416 16:23:31.961087 11816 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
I0416 16:23:31.961087 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:33.857817 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:33.857888 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:33.857888 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:36.099673 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:36.099673 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:36.100770 11816 sshutil.go:53] new ssh client: &{IP:172.19.88.77 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\id_rsa Username:docker}
I0416 16:23:36.211073 11816 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.2497459s)
I0416 16:23:36.219590 11816 ssh_runner.go:195] Run: cat /etc/os-release
I0416 16:23:36.227693 11816 info.go:137] Remote host: Buildroot 2023.02.9
I0416 16:23:36.227693 11816 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\addons for local assets ...
I0416 16:23:36.228403 11816 filesync.go:126] Scanning C:\Users\jenkins.minikube5\minikube-integration\.minikube\files for local assets ...
I0416 16:23:36.228767 11816 start.go:296] duration metric: took 4.2767688s for postStartSetup
I0416 16:23:36.232262 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:38.146755 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:38.146864 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:38.146942 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:40.463966 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:40.463966 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:40.464517 11816 profile.go:143] Saving config to C:\Users\jenkins.minikube5\minikube-integration\.minikube\profiles\addons-257600\config.json ...
I0416 16:23:40.467067 11816 start.go:128] duration metric: took 1m53.1466832s to createHost
I0416 16:23:40.467067 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:42.345983 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:42.345983 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:42.347097 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:44.611617 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:44.612436 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:44.617962 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:23:44.617962 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:23:44.617962 11816 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0416 16:23:44.758221 11816 main.go:141] libmachine: SSH cmd err, output: <nil>: 1713284624.928867691
I0416 16:23:44.758221 11816 fix.go:216] guest clock: 1713284624.928867691
I0416 16:23:44.758221 11816 fix.go:229] Guest: 2024-04-16 16:23:44.928867691 +0000 UTC Remote: 2024-04-16 16:23:40.4670676 +0000 UTC m=+118.170225901 (delta=4.461800091s)
I0416 16:23:44.758221 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:46.695902 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:46.695902 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:46.695902 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:48.991519 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:48.991519 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:48.996730 11816 main.go:141] libmachine: Using SSH client type: native
I0416 16:23:48.996730 11816 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0xaea1c0] 0xaecda0 <nil> [] 0s} 172.19.88.77 22 <nil> <nil>}
I0416 16:23:48.996730 11816 main.go:141] libmachine: About to run SSH command:
sudo date -s @1713284624
I0416 16:23:49.153176 11816 main.go:141] libmachine: SSH cmd err, output: <nil>: Tue Apr 16 16:23:44 UTC 2024
I0416 16:23:49.153176 11816 fix.go:236] clock set: Tue Apr 16 16:23:44 UTC 2024
(err=<nil>)
I0416 16:23:49.153176 11816 start.go:83] releasing machines lock for "addons-257600", held for 2m1.8328254s
I0416 16:23:49.153176 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:51.041769 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:51.041769 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:51.041769 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:53.396115 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:53.396115 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:53.399693 11816 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0416 16:23:53.399848 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:53.407641 11816 ssh_runner.go:195] Run: cat /version.json
I0416 16:23:53.407699 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-257600 ).state
I0416 16:23:55.369531 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:55.369603 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:55.369603 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:55.388582 11816 main.go:141] libmachine: [stdout =====>] : Running
I0416 16:23:55.388582 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:55.389203 11816 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-257600 ).networkadapters[0]).ipaddresses[0]
I0416 16:23:57.736568 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:57.736568 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:57.737261 11816 sshutil.go:53] new ssh client: &{IP:172.19.88.77 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\id_rsa Username:docker}
I0416 16:23:57.768488 11816 main.go:141] libmachine: [stdout =====>] : 172.19.88.77
I0416 16:23:57.768488 11816 main.go:141] libmachine: [stderr =====>] :
I0416 16:23:57.768564 11816 sshutil.go:53] new ssh client: &{IP:172.19.88.77 Port:22 SSHKeyPath:C:\Users\jenkins.minikube5\minikube-integration\.minikube\machines\addons-257600\id_rsa Username:docker}
I0416 16:23:57.841016 11816 ssh_runner.go:235] Completed: cat /version.json: (4.4330841s)
I0416 16:23:57.851830 11816 ssh_runner.go:195] Run: systemctl --version
I0416 16:23:57.919757 11816 ssh_runner.go:235] Completed: curl -sS -m 2 https://registry.k8s.io/: (4.5196799s)
I0416 16:23:57.930665 11816 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0416 16:23:57.939070 11816 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0416 16:23:57.947594 11816 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" ;
I0416 16:23:57.974317 11816 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0416 16:23:57.974317 11816 start.go:494] detecting cgroup driver to use...
I0416 16:23:57.974536 11816 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"
I0416 16:23:58.013422 11816 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"
I0416 16:23:58.039537 11816 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"
I0416 16:23:58.057738 11816 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0416 16:23:58.067391 11816 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0416 16:23:58.092344 11816 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"
I0416 16:23:58.118841 11816 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0416 16:23:58.145878 11816 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0416 16:23:58.173689 11816 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0416 16:23:58.206805 11816 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"
I0416 16:23:58.236994 11816 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0416 16:23:58.269830 11816 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"
I0416 16:23:58.295970 11816 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0416 16:23:58.321074 11816 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0416 16:23:58.348425 11816 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0416 16:23:58.531500 11816 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0416 16:23:58.563908 11816 start.go:494] detecting cgroup driver to use...
I0416 16:23:58.576727 11816 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0416 16:23:58.605714 11816 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0416 16:23:58.633745 11816 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0416 16:23:58.679013 11816 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0416 16:23:58.715450 11816 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0416 16:23:58.748164 11816 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0416 16:23:58.793977 11816 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0416 16:23:58.816396 11816 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"
I0416 16:23:58.857748 11816 ssh_runner.go:195] Run: which cri-dockerd
I0416 16:23:58.873049 11816 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0416 16:23:58.890434 11816 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0416 16:23:58.932636 11816 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0416 16:23:59.112832 11816 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0416 16:23:59.265734 11816 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0416 16:23:59.265734 11816 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0416 16:23:59.304198 11816 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0416 16:23:59.473605 11816 ssh_runner.go:195] Run: sudo systemctl restart docker
I0416 16:25:00.587371 11816 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.1103066s)
I0416 16:25:00.595271 11816 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0416 16:25:00.622818 11816 out.go:177]
W0416 16:25:00.623250 11816 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 16 16:23:30 addons-257600 systemd[1]: Starting Docker Application Container Engine...
Apr 16 16:23:30 addons-257600 dockerd[670]: time="2024-04-16T16:23:30.749326486Z" level=info msg="Starting up"
Apr 16 16:23:30 addons-257600 dockerd[670]: time="2024-04-16T16:23:30.750241271Z" level=info msg="containerd not running, starting managed containerd"
Apr 16 16:23:30 addons-257600 dockerd[670]: time="2024-04-16T16:23:30.751787784Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=676
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.781706434Z" level=info msg="starting containerd" revision=926c9586fe4a6236699318391cd44976a98e31f1 version=v1.7.15
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803424027Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803591260Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803828508Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803916926Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804000043Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804090962Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804330410Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804440632Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804460536Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804472139Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804634371Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.805015649Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.807826617Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.807929938Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808084569Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808194692Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808282509Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808332919Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808406334Z" level=info msg="metadata content store policy set" policy=shared
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817238321Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817344842Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817362946Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817375948Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817388251Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817477169Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817951165Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818123600Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818216118Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818229521Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818243124Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818253226Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818262428Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818273030Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818284432Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818294534Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818304436Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818314838Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818330041Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818340743Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818350946Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818360447Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818369349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818379351Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818388253Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818399855Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818409257Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818420860Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818429061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818438263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818447965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818464168Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818531482Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818543685Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818552986Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818583993Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818595195Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818603597Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818611598Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818912959Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818996976Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819007878Z" level=info msg="NRI interface is disabled by configuration."
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819177013Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819281134Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819384455Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819399658Z" level=info msg="containerd successfully booted in 0.039112s"
Apr 16 16:23:31 addons-257600 dockerd[670]: time="2024-04-16T16:23:31.801036438Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Apr 16 16:23:31 addons-257600 dockerd[670]: time="2024-04-16T16:23:31.818560803Z" level=info msg="Loading containers: start."
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.041272460Z" level=info msg="Loading containers: done."
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.059396888Z" level=info msg="Docker daemon" commit=60b9add7 containerd-snapshotter=false storage-driver=overlay2 version=26.0.1
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.059524600Z" level=info msg="Daemon has completed initialization"
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.119555823Z" level=info msg="API listen on /var/run/docker.sock"
Apr 16 16:23:32 addons-257600 systemd[1]: Started Docker Application Container Engine.
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.121832240Z" level=info msg="API listen on [::]:2376"
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.668538845Z" level=info msg="Processing signal 'terminated'"
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670180911Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670691232Z" level=info msg="Daemon shutdown complete"
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670883439Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670916041Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Apr 16 16:23:59 addons-257600 systemd[1]: Stopping Docker Application Container Engine...
Apr 16 16:24:00 addons-257600 systemd[1]: docker.service: Deactivated successfully.
Apr 16 16:24:00 addons-257600 systemd[1]: Stopped Docker Application Container Engine.
Apr 16 16:24:00 addons-257600 systemd[1]: Starting Docker Application Container Engine...
Apr 16 16:24:00 addons-257600 dockerd[1020]: time="2024-04-16T16:24:00.738036609Z" level=info msg="Starting up"
Apr 16 16:25:00 addons-257600 dockerd[1020]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Apr 16 16:25:00 addons-257600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Apr 16 16:25:00 addons-257600 systemd[1]: docker.service: Failed with result 'exit-code'.
Apr 16 16:25:00 addons-257600 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 16 16:23:30 addons-257600 systemd[1]: Starting Docker Application Container Engine...
Apr 16 16:23:30 addons-257600 dockerd[670]: time="2024-04-16T16:23:30.749326486Z" level=info msg="Starting up"
Apr 16 16:23:30 addons-257600 dockerd[670]: time="2024-04-16T16:23:30.750241271Z" level=info msg="containerd not running, starting managed containerd"
Apr 16 16:23:30 addons-257600 dockerd[670]: time="2024-04-16T16:23:30.751787784Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=676
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.781706434Z" level=info msg="starting containerd" revision=926c9586fe4a6236699318391cd44976a98e31f1 version=v1.7.15
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803424027Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803591260Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803828508Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.803916926Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804000043Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804090962Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804330410Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804440632Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804460536Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804472139Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.804634371Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.805015649Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.807826617Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.807929938Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808084569Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808194692Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808282509Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808332919Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.808406334Z" level=info msg="metadata content store policy set" policy=shared
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817238321Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817344842Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817362946Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817375948Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817388251Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817477169Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.817951165Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818123600Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818216118Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818229521Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818243124Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818253226Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818262428Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818273030Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818284432Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818294534Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818304436Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818314838Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818330041Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818340743Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818350946Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818360447Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818369349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818379351Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818388253Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818399855Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818409257Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818420860Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818429061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818438263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818447965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818464168Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818531482Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818543685Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818552986Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818583993Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818595195Z" 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 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818603597Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818611598Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818912959Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.818996976Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819007878Z" level=info msg="NRI interface is disabled by configuration."
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819177013Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819281134Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819384455Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Apr 16 16:23:30 addons-257600 dockerd[676]: time="2024-04-16T16:23:30.819399658Z" level=info msg="containerd successfully booted in 0.039112s"
Apr 16 16:23:31 addons-257600 dockerd[670]: time="2024-04-16T16:23:31.801036438Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Apr 16 16:23:31 addons-257600 dockerd[670]: time="2024-04-16T16:23:31.818560803Z" level=info msg="Loading containers: start."
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.041272460Z" level=info msg="Loading containers: done."
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.059396888Z" level=info msg="Docker daemon" commit=60b9add7 containerd-snapshotter=false storage-driver=overlay2 version=26.0.1
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.059524600Z" level=info msg="Daemon has completed initialization"
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.119555823Z" level=info msg="API listen on /var/run/docker.sock"
Apr 16 16:23:32 addons-257600 systemd[1]: Started Docker Application Container Engine.
Apr 16 16:23:32 addons-257600 dockerd[670]: time="2024-04-16T16:23:32.121832240Z" level=info msg="API listen on [::]:2376"
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.668538845Z" level=info msg="Processing signal 'terminated'"
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670180911Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670691232Z" level=info msg="Daemon shutdown complete"
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670883439Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Apr 16 16:23:59 addons-257600 dockerd[670]: time="2024-04-16T16:23:59.670916041Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Apr 16 16:23:59 addons-257600 systemd[1]: Stopping Docker Application Container Engine...
Apr 16 16:24:00 addons-257600 systemd[1]: docker.service: Deactivated successfully.
Apr 16 16:24:00 addons-257600 systemd[1]: Stopped Docker Application Container Engine.
Apr 16 16:24:00 addons-257600 systemd[1]: Starting Docker Application Container Engine...
Apr 16 16:24:00 addons-257600 dockerd[1020]: time="2024-04-16T16:24:00.738036609Z" level=info msg="Starting up"
Apr 16 16:25:00 addons-257600 dockerd[1020]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Apr 16 16:25:00 addons-257600 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Apr 16 16:25:00 addons-257600 systemd[1]: docker.service: Failed with result 'exit-code'.
Apr 16 16:25:00 addons-257600 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0416 16:25:00.623912 11816 out.go:239] *
*
W0416 16:25:00.625068 11816 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0416 16:25:00.625657 11816 out.go:177]
** /stderr **
addons_test.go:111: out/minikube-windows-amd64.exe start -p addons-257600 --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 (198.49s)