=== RUN TestAddons/Setup
addons_test.go:109: (dbg) Run: out/minikube-windows-amd64.exe start -p addons-120100 --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-120100 --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 (3m45.8004461s)
-- stdout --
* [addons-120100] minikube v1.33.0-beta.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4170 Build 19045.4170
- KUBECONFIG=C:\Users\jenkins.minikube6\minikube-integration\kubeconfig
- MINIKUBE_FORCE_SYSTEMD=
- MINIKUBE_HOME=C:\Users\jenkins.minikube6\minikube-integration\.minikube
- MINIKUBE_LOCATION=18485
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the hyperv driver based on user configuration
* Starting "addons-120100" primary control-plane node in "addons-120100" cluster
* Creating hyperv VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
-- /stdout --
** stderr **
W0327 23:29:04.375035 7424 main.go:291] Unable to resolve the current Docker CLI context "default": context "default": context not found: open C:\Users\jenkins.minikube6\.docker\contexts\meta\37a8eec1ce19687d132fe29051dca629d164e2c4958ba141d5f4133a33f0688f\meta.json: The system cannot find the path specified.
I0327 23:29:04.454128 7424 out.go:291] Setting OutFile to fd 884 ...
I0327 23:29:04.454862 7424 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0327 23:29:04.454862 7424 out.go:304] Setting ErrFile to fd 888...
I0327 23:29:04.454862 7424 out.go:338] TERM=,COLORTERM=, which probably does not support color
I0327 23:29:04.479575 7424 out.go:298] Setting JSON to false
I0327 23:29:04.482242 7424 start.go:129] hostinfo: {"hostname":"minikube6","uptime":4805,"bootTime":1711577338,"procs":190,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19045.4170 Build 19045.4170","kernelVersion":"10.0.19045.4170 Build 19045.4170","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"be8185f2-ae84-4027-a4e5-684d168fb2f3"}
W0327 23:29:04.482827 7424 start.go:137] gopshost.Virtualization returned error: not implemented yet
I0327 23:29:04.488255 7424 out.go:177] * [addons-120100] minikube v1.33.0-beta.0 on Microsoft Windows 10 Enterprise N 10.0.19045.4170 Build 19045.4170
I0327 23:29:04.500138 7424 notify.go:220] Checking for updates...
I0327 23:29:04.501849 7424 out.go:177] - KUBECONFIG=C:\Users\jenkins.minikube6\minikube-integration\kubeconfig
I0327 23:29:04.505816 7424 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0327 23:29:04.508653 7424 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins.minikube6\minikube-integration\.minikube
I0327 23:29:04.510311 7424 out.go:177] - MINIKUBE_LOCATION=18485
I0327 23:29:04.513479 7424 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0327 23:29:04.516281 7424 driver.go:392] Setting default libvirt URI to qemu:///system
I0327 23:29:10.364359 7424 out.go:177] * Using the hyperv driver based on user configuration
I0327 23:29:10.368617 7424 start.go:297] selected driver: hyperv
I0327 23:29:10.368617 7424 start.go:901] validating driver "hyperv" against <nil>
I0327 23:29:10.368617 7424 start.go:912] status for hyperv: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0327 23:29:10.419635 7424 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0327 23:29:10.420376 7424 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]
I0327 23:29:10.420376 7424 cni.go:84] Creating CNI manager for ""
I0327 23:29:10.420376 7424 cni.go:158] "hyperv" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge
I0327 23:29:10.420376 7424 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0327 23:29:10.421305 7424 start.go:340] cluster config:
{Name:addons-120100 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.43-1711559786-18485@sha256:2dcab64da240d825290a528fa79ad3c32db45fe5f8be5150468234a7114eff82 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-120100 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.minikube6:/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}
I0327 23:29:10.421305 7424 iso.go:125] acquiring lock: {Name:mk879943e10653d47fd8ae811a43a2f6cff06f02 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0327 23:29:10.427025 7424 out.go:177] * Starting "addons-120100" primary control-plane node in "addons-120100" cluster
I0327 23:29:10.430687 7424 preload.go:132] Checking if preload exists for k8s version v1.29.3 and runtime docker
I0327 23:29:10.430791 7424 preload.go:147] Found local preload: C:\Users\jenkins.minikube6\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4
I0327 23:29:10.430791 7424 cache.go:56] Caching tarball of preloaded images
I0327 23:29:10.430791 7424 preload.go:173] Found C:\Users\jenkins.minikube6\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v18-v1.29.3-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0327 23:29:10.431331 7424 cache.go:59] Finished verifying existence of preloaded tar for v1.29.3 on docker
I0327 23:29:10.432294 7424 profile.go:142] Saving config to C:\Users\jenkins.minikube6\minikube-integration\.minikube\profiles\addons-120100\config.json ...
I0327 23:29:10.432730 7424 lock.go:35] WriteFile acquiring C:\Users\jenkins.minikube6\minikube-integration\.minikube\profiles\addons-120100\config.json: {Name:mk98051522647aea421d8d7665e4bfc3be9ec339 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0327 23:29:10.433463 7424 start.go:360] acquireMachinesLock for addons-120100: {Name:mke1d3e045ff2a4f8d2978e08dff146c93a87110 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0327 23:29:10.434136 7424 start.go:364] duration metric: took 72µs to acquireMachinesLock for "addons-120100"
I0327 23:29:10.434136 7424 start.go:93] Provisioning new machine with config: &{Name:addons-120100 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/18485/minikube-v1.33.0-1711559712-18485-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.43-1711559786-18485@sha256:2dcab64da240d825290a528fa79ad3c32db45fe5f8be5150468234a7114eff82 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-120100 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.minikube6:/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}
I0327 23:29:10.434136 7424 start.go:125] createHost starting for "" (driver="hyperv")
I0327 23:29:10.436929 7424 out.go:204] * Creating hyperv VM (CPUs=2, Memory=4000MB, Disk=20000MB) ...
I0327 23:29:10.436929 7424 start.go:159] libmachine.API.Create for "addons-120100" (driver="hyperv")
I0327 23:29:10.437457 7424 client.go:168] LocalClient.Create starting
I0327 23:29:10.438154 7424 main.go:141] libmachine: Creating CA: C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca.pem
I0327 23:29:10.742174 7424 main.go:141] libmachine: Creating client certificate: C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\cert.pem
I0327 23:29:11.168221 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive @(Get-Module -ListAvailable hyper-v).Name | Get-Unique
I0327 23:29:13.489208 7424 main.go:141] libmachine: [stdout =====>] : Hyper-V
I0327 23:29:13.489208 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:13.489208 7424 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")))
I0327 23:29:15.392361 7424 main.go:141] libmachine: [stdout =====>] : False
I0327 23:29:15.392691 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:15.392691 7424 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")
I0327 23:29:16.983824 7424 main.go:141] libmachine: [stdout =====>] : True
I0327 23:29:16.984070 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:16.984274 7424 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)
I0327 23:29:21.010948 7424 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0327 23:29:21.011554 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:21.013679 7424 main.go:141] libmachine: Downloading C:\Users\jenkins.minikube6\minikube-integration\.minikube\cache\boot2docker.iso from file://C:/Users/jenkins.minikube6/minikube-integration/.minikube/cache/iso/amd64/minikube-v1.33.0-1711559712-18485-amd64.iso...
I0327 23:29:21.515552 7424 main.go:141] libmachine: Creating SSH key...
I0327 23:29:21.709790 7424 main.go:141] libmachine: Creating VM...
I0327 23:29:21.709790 7424 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)
I0327 23:29:24.733727 7424 main.go:141] libmachine: [stdout =====>] : [
{
"Id": "c08cb7b8-9b3c-408e-8e30-5e16a3aeb444",
"Name": "Default Switch",
"SwitchType": 1
}
]
I0327 23:29:24.734394 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:24.734641 7424 main.go:141] libmachine: Using switch "Default Switch"
I0327 23:29:24.734782 7424 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")
I0327 23:29:26.625434 7424 main.go:141] libmachine: [stdout =====>] : True
I0327 23:29:26.626309 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:26.626367 7424 main.go:141] libmachine: Creating VHD
I0327 23:29:26.626367 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VHD -Path 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\fixed.vhd' -SizeBytes 10MB -Fixed
I0327 23:29:30.538160 7424 main.go:141] libmachine: [stdout =====>] :
ComputerName : minikube6
Path : C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\fixed.vhd
VhdFormat : VHD
VhdType : Fixed
FileSize : 10486272
Size : 10485760
MinimumSize :
LogicalSectorSize : 512
PhysicalSectorSize : 512
BlockSize : 0
ParentPath :
DiskIdentifier : 533D11DF-399B-4ED2-B239-6FEC94AFA130
FragmentationPercentage : 0
Alignment : 1
Attached : False
DiskNumber :
IsPMEMCompatible : False
AddressAbstractionType : None
Number :
I0327 23:29:30.538160 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:30.539240 7424 main.go:141] libmachine: Writing magic tar header
I0327 23:29:30.539321 7424 main.go:141] libmachine: Writing SSH key tar header
I0327 23:29:30.547941 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Convert-VHD -Path 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\fixed.vhd' -DestinationPath 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\disk.vhd' -VHDType Dynamic -DeleteSource
I0327 23:29:33.915469 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:33.915721 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:33.915810 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Resize-VHD -Path 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\disk.vhd' -SizeBytes 20000MB
I0327 23:29:36.593495 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:36.594240 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:36.594334 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\New-VM addons-120100 -Path 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100' -SwitchName 'Default Switch' -MemoryStartupBytes 4000MB
I0327 23:29:41.280813 7424 main.go:141] libmachine: [stdout =====>] :
Name State CPUUsage(%) MemoryAssigned(M) Uptime Status Version
---- ----- ----------- ----------------- ------ ------ -------
addons-120100 Off 0 0 00:00:00 Operating normally 9.0
I0327 23:29:41.281270 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:41.281351 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMMemory -VMName addons-120100 -DynamicMemoryEnabled $false
I0327 23:29:43.690487 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:43.691397 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:43.691397 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMProcessor addons-120100 -Count 2
I0327 23:29:46.041087 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:46.041087 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:46.041240 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Set-VMDvdDrive -VMName addons-120100 -Path 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\boot2docker.iso'
I0327 23:29:48.882767 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:48.882767 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:48.883361 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Add-VMHardDiskDrive -VMName addons-120100 -Path 'C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\disk.vhd'
I0327 23:29:51.752166 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:51.752166 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:51.752166 7424 main.go:141] libmachine: Starting VM...
I0327 23:29:51.752426 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive Hyper-V\Start-VM addons-120100
I0327 23:29:55.140178 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:29:55.140331 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:55.140331 7424 main.go:141] libmachine: Waiting for host to start...
I0327 23:29:55.140331 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:29:57.524379 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:29:57.524379 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:29:57.524379 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:00.216040 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:30:00.216179 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:01.225975 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:03.594521 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:03.595450 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:03.595450 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:06.318231 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:30:06.318345 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:07.327739 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:09.704885 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:09.704885 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:09.704885 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:12.432827 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:30:12.432827 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:13.442130 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:15.866927 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:15.867201 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:15.867266 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:18.545990 7424 main.go:141] libmachine: [stdout =====>] :
I0327 23:30:18.545990 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:19.549694 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:21.936837 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:21.936837 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:21.936837 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:24.692613 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:24.692613 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:24.692811 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:26.984195 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:26.984195 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:26.984195 7424 machine.go:94] provisionDockerMachine start ...
I0327 23:30:26.984195 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:29.297710 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:29.298051 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:29.298051 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:32.070538 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:32.070538 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:32.077051 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:30:32.093903 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:30:32.093903 7424 main.go:141] libmachine: About to run SSH command:
hostname
I0327 23:30:32.225963 7424 main.go:141] libmachine: SSH cmd err, output: <nil>: minikube
I0327 23:30:32.225963 7424 buildroot.go:166] provisioning hostname "addons-120100"
I0327 23:30:32.225963 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:34.524639 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:34.524639 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:34.524639 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:37.251736 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:37.252264 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:37.258011 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:30:37.259140 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:30:37.259140 7424 main.go:141] libmachine: About to run SSH command:
sudo hostname addons-120100 && echo "addons-120100" | sudo tee /etc/hostname
I0327 23:30:37.419179 7424 main.go:141] libmachine: SSH cmd err, output: <nil>: addons-120100
I0327 23:30:37.419179 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:39.658973 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:39.658973 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:39.659697 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:42.345677 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:42.345677 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:42.351954 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:30:42.352685 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:30:42.352685 7424 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\saddons-120100' /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-120100/g' /etc/hosts;
else
echo '127.0.1.1 addons-120100' | sudo tee -a /etc/hosts;
fi
fi
I0327 23:30:42.504614 7424 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0327 23:30:42.504614 7424 buildroot.go:172] set auth options {CertDir:C:\Users\jenkins.minikube6\minikube-integration\.minikube CaCertPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca.pem CaPrivateKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\server.pem ServerKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\server-key.pem ClientKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\cert.pem ServerCertSANs:[] StorePath:C:\Users\jenkins.minikube6\minikube-integration\.minikube}
I0327 23:30:42.504614 7424 buildroot.go:174] setting up certificates
I0327 23:30:42.504614 7424 provision.go:84] configureAuth start
I0327 23:30:42.504614 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:44.813794 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:44.813794 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:44.814500 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:47.539666 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:47.539666 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:47.539666 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:49.867775 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:49.867775 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:49.868302 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:52.642346 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:52.642346 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:52.642346 7424 provision.go:143] copyHostCerts
I0327 23:30:52.643842 7424 exec_runner.go:151] cp: C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca.pem --> C:\Users\jenkins.minikube6\minikube-integration\.minikube/ca.pem (1078 bytes)
I0327 23:30:52.645652 7424 exec_runner.go:151] cp: C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\cert.pem --> C:\Users\jenkins.minikube6\minikube-integration\.minikube/cert.pem (1123 bytes)
I0327 23:30:52.647131 7424 exec_runner.go:151] cp: C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\key.pem --> C:\Users\jenkins.minikube6\minikube-integration\.minikube/key.pem (1675 bytes)
I0327 23:30:52.648232 7424 provision.go:117] generating server cert: C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\server.pem ca-key=C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca.pem private-key=C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca-key.pem org=jenkins.addons-120100 san=[127.0.0.1 172.28.232.103 addons-120100 localhost minikube]
I0327 23:30:52.838043 7424 provision.go:177] copyRemoteCerts
I0327 23:30:52.853559 7424 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0327 23:30:52.853559 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:30:55.138204 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:30:55.138285 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:55.138285 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:30:57.880430 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:30:57.880430 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:30:57.881170 7424 sshutil.go:53] new ssh client: &{IP:172.28.232.103 Port:22 SSHKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\id_rsa Username:docker}
I0327 23:30:57.983208 7424 ssh_runner.go:235] Completed: sudo mkdir -p /etc/docker /etc/docker /etc/docker: (5.1296222s)
I0327 23:30:57.983627 7424 ssh_runner.go:362] scp C:\Users\jenkins.minikube6\minikube-integration\.minikube\certs\ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0327 23:30:58.036430 7424 ssh_runner.go:362] scp C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\server.pem --> /etc/docker/server.pem (1208 bytes)
I0327 23:30:58.087705 7424 ssh_runner.go:362] scp C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\server-key.pem --> /etc/docker/server-key.pem (1679 bytes)
I0327 23:30:58.135337 7424 provision.go:87] duration metric: took 15.6306417s to configureAuth
I0327 23:30:58.135337 7424 buildroot.go:189] setting minikube options for container-runtime
I0327 23:30:58.136096 7424 config.go:182] Loaded profile config "addons-120100": Driver=hyperv, ContainerRuntime=docker, KubernetesVersion=v1.29.3
I0327 23:30:58.136096 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:00.434851 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:00.434851 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:00.434851 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:03.198784 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:03.198959 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:03.205488 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:31:03.206146 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:31:03.206146 7424 main.go:141] libmachine: About to run SSH command:
df --output=fstype / | tail -n 1
I0327 23:31:03.332668 7424 main.go:141] libmachine: SSH cmd err, output: <nil>: tmpfs
I0327 23:31:03.332813 7424 buildroot.go:70] root file system type: tmpfs
I0327 23:31:03.333008 7424 provision.go:314] Updating docker unit: /lib/systemd/system/docker.service ...
I0327 23:31:03.333098 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:05.590079 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:05.590171 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:05.590251 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:08.317272 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:08.317272 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:08.323465 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:31:08.324156 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:31:08.324156 7424 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
I0327 23:31:08.476837 7424 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
I0327 23:31:08.476984 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:10.732664 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:10.733543 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:10.733543 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:13.441886 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:13.441886 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:13.448400 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:31:13.449316 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:31:13.449316 7424 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; }
I0327 23:31:15.656672 7424 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.
I0327 23:31:15.656672 7424 machine.go:97] duration metric: took 48.6722238s to provisionDockerMachine
I0327 23:31:15.656672 7424 client.go:171] duration metric: took 2m5.2184738s to LocalClient.Create
I0327 23:31:15.656672 7424 start.go:167] duration metric: took 2m5.2190924s to libmachine.API.Create "addons-120100"
I0327 23:31:15.656672 7424 start.go:293] postStartSetup for "addons-120100" (driver="hyperv")
I0327 23:31:15.656672 7424 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]
I0327 23:31:15.670312 7424 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
I0327 23:31:15.670312 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:18.001210 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:18.001210 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:18.001726 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:20.757360 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:20.757360 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:20.758334 7424 sshutil.go:53] new ssh client: &{IP:172.28.232.103 Port:22 SSHKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\id_rsa Username:docker}
I0327 23:31:20.866561 7424 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: (5.1961291s)
I0327 23:31:20.881346 7424 ssh_runner.go:195] Run: cat /etc/os-release
I0327 23:31:20.888936 7424 info.go:137] Remote host: Buildroot 2023.02.9
I0327 23:31:20.888936 7424 filesync.go:126] Scanning C:\Users\jenkins.minikube6\minikube-integration\.minikube\addons for local assets ...
I0327 23:31:20.888936 7424 filesync.go:126] Scanning C:\Users\jenkins.minikube6\minikube-integration\.minikube\files for local assets ...
I0327 23:31:20.889522 7424 start.go:296] duration metric: took 5.2328223s for postStartSetup
I0327 23:31:20.892414 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:23.213620 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:23.213696 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:23.213780 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:25.999339 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:25.999408 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:25.999663 7424 profile.go:142] Saving config to C:\Users\jenkins.minikube6\minikube-integration\.minikube\profiles\addons-120100\config.json ...
I0327 23:31:26.002563 7424 start.go:128] duration metric: took 2m15.5677218s to createHost
I0327 23:31:26.002665 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:28.313652 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:28.314364 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:28.314364 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:31.110080 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:31.110146 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:31.117096 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:31:31.117238 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:31:31.117238 7424 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0327 23:31:31.241254 7424 main.go:141] libmachine: SSH cmd err, output: <nil>: 1711582291.241521846
I0327 23:31:31.241254 7424 fix.go:216] guest clock: 1711582291.241521846
I0327 23:31:31.241254 7424 fix.go:229] Guest: 2024-03-27 23:31:31.241521846 +0000 UTC Remote: 2024-03-27 23:31:26.0026655 +0000 UTC m=+141.734417201 (delta=5.238856346s)
I0327 23:31:31.241832 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:33.531703 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:33.531703 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:33.532252 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:36.255095 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:36.255095 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:36.261538 7424 main.go:141] libmachine: Using SSH client type: native
I0327 23:31:36.261538 7424 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x12a9f80] 0x12acb60 <nil> [] 0s} 172.28.232.103 22 <nil> <nil>}
I0327 23:31:36.261538 7424 main.go:141] libmachine: About to run SSH command:
sudo date -s @1711582291
I0327 23:31:36.402968 7424 main.go:141] libmachine: SSH cmd err, output: <nil>: Wed Mar 27 23:31:31 UTC 2024
I0327 23:31:36.402968 7424 fix.go:236] clock set: Wed Mar 27 23:31:31 UTC 2024
(err=<nil>)
I0327 23:31:36.402968 7424 start.go:83] releasing machines lock for "addons-120100", held for 2m25.9680726s
I0327 23:31:36.402968 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:38.688676 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:38.689668 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:38.689733 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:41.426688 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:41.426688 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:41.432190 7424 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0327 23:31:41.432344 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:41.443440 7424 ssh_runner.go:195] Run: cat /version.json
I0327 23:31:41.444403 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive ( Hyper-V\Get-VM addons-120100 ).state
I0327 23:31:43.756543 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:43.756757 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:43.756757 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:43.796282 7424 main.go:141] libmachine: [stdout =====>] : Running
I0327 23:31:43.796586 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:43.796586 7424 main.go:141] libmachine: [executing ==>] : C:\WINDOWS\System32\WindowsPowerShell\v1.0\powershell.exe -NoProfile -NonInteractive (( Hyper-V\Get-VM addons-120100 ).networkadapters[0]).ipaddresses[0]
I0327 23:31:46.594702 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:46.594702 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:46.595722 7424 sshutil.go:53] new ssh client: &{IP:172.28.232.103 Port:22 SSHKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\id_rsa Username:docker}
I0327 23:31:46.626518 7424 main.go:141] libmachine: [stdout =====>] : 172.28.232.103
I0327 23:31:46.626518 7424 main.go:141] libmachine: [stderr =====>] :
I0327 23:31:46.627055 7424 sshutil.go:53] new ssh client: &{IP:172.28.232.103 Port:22 SSHKeyPath:C:\Users\jenkins.minikube6\minikube-integration\.minikube\machines\addons-120100\id_rsa Username:docker}
I0327 23:31:46.690549 7424 ssh_runner.go:235] Completed: cat /version.json: (5.2470815s)
I0327 23:31:46.702910 7424 ssh_runner.go:195] Run: systemctl --version
I0327 23:31:46.780996 7424 ssh_runner.go:235] Completed: curl -sS -m 2 https://registry.k8s.io/: (5.348779s)
I0327 23:31:46.793657 7424 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0327 23:31:46.803524 7424 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0327 23:31:46.817563 7424 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" ;
I0327 23:31:46.848886 7424 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0327 23:31:46.848968 7424 start.go:494] detecting cgroup driver to use...
I0327 23:31:46.849472 7424 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"
I0327 23:31:46.899721 7424 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"
I0327 23:31:46.933250 7424 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"
I0327 23:31:46.954995 7424 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0327 23:31:46.968502 7424 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0327 23:31:47.004435 7424 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"
I0327 23:31:47.040711 7424 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0327 23:31:47.075765 7424 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0327 23:31:47.111703 7424 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0327 23:31:47.149702 7424 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"
I0327 23:31:47.186607 7424 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0327 23:31:47.224155 7424 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"
I0327 23:31:47.259012 7424 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0327 23:31:47.292223 7424 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0327 23:31:47.330025 7424 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0327 23:31:47.549398 7424 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0327 23:31:47.587580 7424 start.go:494] detecting cgroup driver to use...
I0327 23:31:47.603620 7424 ssh_runner.go:195] Run: sudo systemctl cat docker.service
I0327 23:31:47.643418 7424 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0327 23:31:47.680874 7424 ssh_runner.go:195] Run: sudo systemctl stop -f containerd
I0327 23:31:47.731772 7424 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service containerd
I0327 23:31:47.775085 7424 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0327 23:31:47.818572 7424 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0327 23:31:47.891994 7424 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0327 23:31:47.920287 7424 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"
I0327 23:31:47.973693 7424 ssh_runner.go:195] Run: which cri-dockerd
I0327 23:31:47.994738 7424 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/cri-docker.service.d
I0327 23:31:48.015737 7424 ssh_runner.go:362] scp memory --> /etc/systemd/system/cri-docker.service.d/10-cni.conf (189 bytes)
I0327 23:31:48.064856 7424 ssh_runner.go:195] Run: sudo systemctl unmask docker.service
I0327 23:31:48.285529 7424 ssh_runner.go:195] Run: sudo systemctl enable docker.socket
I0327 23:31:48.492759 7424 docker.go:574] configuring docker to use "cgroupfs" as cgroup driver...
I0327 23:31:48.493020 7424 ssh_runner.go:362] scp memory --> /etc/docker/daemon.json (130 bytes)
I0327 23:31:48.557852 7424 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0327 23:31:48.794262 7424 ssh_runner.go:195] Run: sudo systemctl restart docker
I0327 23:32:49.944492 7424 ssh_runner.go:235] Completed: sudo systemctl restart docker: (1m1.1499106s)
I0327 23:32:49.959604 7424 ssh_runner.go:195] Run: sudo journalctl --no-pager -u docker
I0327 23:32:49.996476 7424 out.go:177]
W0327 23:32:49.999280 7424 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 --
Mar 27 23:31:14 addons-120100 systemd[1]: Starting Docker Application Container Engine...
Mar 27 23:31:14 addons-120100 dockerd[662]: time="2024-03-27T23:31:14.088636550Z" level=info msg="Starting up"
Mar 27 23:31:14 addons-120100 dockerd[662]: time="2024-03-27T23:31:14.090269455Z" level=info msg="containerd not running, starting managed containerd"
Mar 27 23:31:14 addons-120100 dockerd[662]: time="2024-03-27T23:31:14.093885867Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=668
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.127814872Z" level=info msg="starting containerd" revision=dcf2847247e18caba8dce86522029642f60fe96b version=v1.7.14
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157058162Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157203063Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157288663Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157325363Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157432863Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157542064Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158037465Z" 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
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158141266Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158165966Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158178166Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158296866Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158684867Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163046681Z" 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
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163162681Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163319482Z" 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
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163416982Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163529982Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163675683Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163713783Z" level=info msg="metadata content store policy set" policy=shared
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238637215Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238717715Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238784115Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238807815Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238827515Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.239062916Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.240789522Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241096522Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241191723Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241261023Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241393323Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241475424Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241541024Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241694324Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241817625Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241889525Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241947625Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242226326Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242751628Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242835428Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242894228Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242954628Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243007328Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243057729Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243106929Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243158629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243209529Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243255629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243273029Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243288729Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243305129Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243324429Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243351829Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243367330Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243380530Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243436030Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243456130Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243469330Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243481830Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243763431Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243799731Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243827131Z" level=info msg="NRI interface is disabled by configuration."
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244092532Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244174332Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244231632Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244278332Z" level=info msg="containerd successfully booted in 0.118103s"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.161332460Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.193211752Z" level=info msg="Loading containers: start."
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.493061223Z" level=info msg="Loading containers: done."
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.526921412Z" level=info msg="Docker daemon" commit=8b79278 containerd-snapshotter=false storage-driver=overlay2 version=26.0.0
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.527139113Z" level=info msg="Daemon has completed initialization"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.653383999Z" level=info msg="API listen on /var/run/docker.sock"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.653536999Z" level=info msg="API listen on [::]:2376"
Mar 27 23:31:15 addons-120100 systemd[1]: Started Docker Application Container Engine.
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.823011379Z" level=info msg="Processing signal 'terminated'"
Mar 27 23:31:48 addons-120100 systemd[1]: Stopping Docker Application Container Engine...
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825298578Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825787078Z" level=info msg="Daemon shutdown complete"
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825885977Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825919177Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Mar 27 23:31:49 addons-120100 systemd[1]: docker.service: Deactivated successfully.
Mar 27 23:31:49 addons-120100 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 23:31:49 addons-120100 systemd[1]: Starting Docker Application Container Engine...
Mar 27 23:31:49 addons-120100 dockerd[1013]: time="2024-03-27T23:31:49.909323488Z" level=info msg="Starting up"
Mar 27 23:32:49 addons-120100 dockerd[1013]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 23:32:49 addons-120100 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 23:32:49 addons-120100 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 23:32:49 addons-120100 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 --
Mar 27 23:31:14 addons-120100 systemd[1]: Starting Docker Application Container Engine...
Mar 27 23:31:14 addons-120100 dockerd[662]: time="2024-03-27T23:31:14.088636550Z" level=info msg="Starting up"
Mar 27 23:31:14 addons-120100 dockerd[662]: time="2024-03-27T23:31:14.090269455Z" level=info msg="containerd not running, starting managed containerd"
Mar 27 23:31:14 addons-120100 dockerd[662]: time="2024-03-27T23:31:14.093885867Z" level=info msg="started new containerd process" address=/var/run/docker/containerd/containerd.sock module=libcontainerd pid=668
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.127814872Z" level=info msg="starting containerd" revision=dcf2847247e18caba8dce86522029642f60fe96b version=v1.7.14
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157058162Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157203063Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157288663Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157325363Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157432863Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.157542064Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158037465Z" 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
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158141266Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158165966Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158178166Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158296866Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.158684867Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163046681Z" 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
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163162681Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163319482Z" 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
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163416982Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163529982Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163675683Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.163713783Z" level=info msg="metadata content store policy set" policy=shared
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238637215Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238717715Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238784115Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238807815Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.238827515Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.239062916Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.240789522Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241096522Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241191723Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241261023Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241393323Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241475424Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241541024Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241694324Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241817625Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241889525Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.241947625Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242226326Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242751628Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242835428Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242894228Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.242954628Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243007328Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243057729Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243106929Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243158629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243209529Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243255629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243273029Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243288729Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243305129Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243324429Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243351829Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243367330Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243380530Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243436030Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243456130Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243469330Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243481830Z" level=info msg="skipping tracing processor initialization (no tracing plugin)" error="no OpenTelemetry endpoint: skip plugin"
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243763431Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243799731Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.243827131Z" level=info msg="NRI interface is disabled by configuration."
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244092532Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244174332Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244231632Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Mar 27 23:31:14 addons-120100 dockerd[668]: time="2024-03-27T23:31:14.244278332Z" level=info msg="containerd successfully booted in 0.118103s"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.161332460Z" level=info msg="[graphdriver] trying configured driver: overlay2"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.193211752Z" level=info msg="Loading containers: start."
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.493061223Z" level=info msg="Loading containers: done."
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.526921412Z" level=info msg="Docker daemon" commit=8b79278 containerd-snapshotter=false storage-driver=overlay2 version=26.0.0
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.527139113Z" level=info msg="Daemon has completed initialization"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.653383999Z" level=info msg="API listen on /var/run/docker.sock"
Mar 27 23:31:15 addons-120100 dockerd[662]: time="2024-03-27T23:31:15.653536999Z" level=info msg="API listen on [::]:2376"
Mar 27 23:31:15 addons-120100 systemd[1]: Started Docker Application Container Engine.
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.823011379Z" level=info msg="Processing signal 'terminated'"
Mar 27 23:31:48 addons-120100 systemd[1]: Stopping Docker Application Container Engine...
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825298578Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825787078Z" level=info msg="Daemon shutdown complete"
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825885977Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Mar 27 23:31:48 addons-120100 dockerd[662]: time="2024-03-27T23:31:48.825919177Z" level=info msg="stopping healthcheck following graceful shutdown" module=libcontainerd
Mar 27 23:31:49 addons-120100 systemd[1]: docker.service: Deactivated successfully.
Mar 27 23:31:49 addons-120100 systemd[1]: Stopped Docker Application Container Engine.
Mar 27 23:31:49 addons-120100 systemd[1]: Starting Docker Application Container Engine...
Mar 27 23:31:49 addons-120100 dockerd[1013]: time="2024-03-27T23:31:49.909323488Z" level=info msg="Starting up"
Mar 27 23:32:49 addons-120100 dockerd[1013]: failed to start daemon: failed to dial "/run/containerd/containerd.sock": failed to dial "/run/containerd/containerd.sock": context deadline exceeded
Mar 27 23:32:49 addons-120100 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 23:32:49 addons-120100 systemd[1]: docker.service: Failed with result 'exit-code'.
Mar 27 23:32:49 addons-120100 systemd[1]: Failed to start Docker Application Container Engine.
-- /stdout --
W0327 23:32:49.999603 7424 out.go:239] *
*
W0327 23:32:50.001732 7424 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
I0327 23:32:50.004794 7424 out.go:177]
** /stderr **
addons_test.go:111: out/minikube-windows-amd64.exe start -p addons-120100 --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 (225.97s)