=== RUN TestScheduledStopUnix
scheduled_stop_test.go:128: (dbg) Run: out/minikube-linux-amd64 start -p scheduled-stop-869526 --memory=2048 --driver=kvm2 --container-runtime=containerd
scheduled_stop_test.go:128: (dbg) Done: out/minikube-linux-amd64 start -p scheduled-stop-869526 --memory=2048 --driver=kvm2 --container-runtime=containerd: (43.599254755s)
scheduled_stop_test.go:137: (dbg) Run: out/minikube-linux-amd64 stop -p scheduled-stop-869526 --schedule 5m
scheduled_stop_test.go:191: (dbg) Run: out/minikube-linux-amd64 status --format={{.TimeToStop}} -p scheduled-stop-869526 -n scheduled-stop-869526
scheduled_stop_test.go:169: signal error was: <nil>
scheduled_stop_test.go:137: (dbg) Run: out/minikube-linux-amd64 stop -p scheduled-stop-869526 --schedule 15s
scheduled_stop_test.go:169: signal error was: <nil>
scheduled_stop_test.go:98: process 46107 running but should have been killed on reschedule of stop
panic.go:626: *** TestScheduledStopUnix FAILED at 2024-08-19 11:35:58.49620897 +0000 UTC m=+3645.837724257
helpers_test.go:222: -----------------------post-mortem--------------------------------
helpers_test.go:239: (dbg) Run: out/minikube-linux-amd64 status --format={{.Host}} -p scheduled-stop-869526 -n scheduled-stop-869526
helpers_test.go:244: <<< TestScheduledStopUnix FAILED: start of post-mortem logs <<<
helpers_test.go:245: ======> post-mortem[TestScheduledStopUnix]: minikube logs <======
helpers_test.go:247: (dbg) Run: out/minikube-linux-amd64 -p scheduled-stop-869526 logs -n 25
helpers_test.go:252: TestScheduledStopUnix logs:
-- stdout --
==> Audit <==
|---------|--------------------------------|-----------------------|---------|---------|---------------------|---------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|--------------------------------|-----------------------|---------|---------|---------------------|---------------------|
| stop | -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:18 UTC | 19 Aug 24 11:21 UTC |
| start | -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:21 UTC | 19 Aug 24 11:24 UTC |
| | --wait=true -v=8 | | | | | |
| | --alsologtostderr | | | | | |
| node | list -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:24 UTC | |
| node | multinode-282953 node delete | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:24 UTC | 19 Aug 24 11:24 UTC |
| | m03 | | | | | |
| stop | multinode-282953 stop | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:24 UTC | 19 Aug 24 11:27 UTC |
| start | -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:27 UTC | 19 Aug 24 11:28 UTC |
| | --wait=true -v=8 | | | | | |
| | --alsologtostderr | | | | | |
| | --driver=kvm2 | | | | | |
| | --container-runtime=containerd | | | | | |
| node | list -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:28 UTC | |
| start | -p multinode-282953-m02 | multinode-282953-m02 | jenkins | v1.33.1 | 19 Aug 24 11:28 UTC | |
| | --driver=kvm2 | | | | | |
| | --container-runtime=containerd | | | | | |
| start | -p multinode-282953-m03 | multinode-282953-m03 | jenkins | v1.33.1 | 19 Aug 24 11:28 UTC | 19 Aug 24 11:29 UTC |
| | --driver=kvm2 | | | | | |
| | --container-runtime=containerd | | | | | |
| node | add -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:29 UTC | |
| delete | -p multinode-282953-m03 | multinode-282953-m03 | jenkins | v1.33.1 | 19 Aug 24 11:29 UTC | 19 Aug 24 11:29 UTC |
| delete | -p multinode-282953 | multinode-282953 | jenkins | v1.33.1 | 19 Aug 24 11:29 UTC | 19 Aug 24 11:29 UTC |
| start | -p test-preload-893124 | test-preload-893124 | jenkins | v1.33.1 | 19 Aug 24 11:29 UTC | 19 Aug 24 11:32 UTC |
| | --memory=2200 | | | | | |
| | --alsologtostderr --wait=true | | | | | |
| | --preload=false --driver=kvm2 | | | | | |
| | --container-runtime=containerd | | | | | |
| | --kubernetes-version=v1.24.4 | | | | | |
| image | test-preload-893124 image pull | test-preload-893124 | jenkins | v1.33.1 | 19 Aug 24 11:32 UTC | 19 Aug 24 11:32 UTC |
| | gcr.io/k8s-minikube/busybox | | | | | |
| stop | -p test-preload-893124 | test-preload-893124 | jenkins | v1.33.1 | 19 Aug 24 11:32 UTC | 19 Aug 24 11:34 UTC |
| start | -p test-preload-893124 | test-preload-893124 | jenkins | v1.33.1 | 19 Aug 24 11:34 UTC | 19 Aug 24 11:35 UTC |
| | --memory=2200 | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| | --wait=true --driver=kvm2 | | | | | |
| | --container-runtime=containerd | | | | | |
| image | test-preload-893124 image list | test-preload-893124 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | 19 Aug 24 11:35 UTC |
| delete | -p test-preload-893124 | test-preload-893124 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | 19 Aug 24 11:35 UTC |
| start | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | 19 Aug 24 11:35 UTC |
| | --memory=2048 --driver=kvm2 | | | | | |
| | --container-runtime=containerd | | | | | |
| stop | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | |
| | --schedule 5m | | | | | |
| stop | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | |
| | --schedule 5m | | | | | |
| stop | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | |
| | --schedule 5m | | | | | |
| stop | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | |
| | --schedule 15s | | | | | |
| stop | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | |
| | --schedule 15s | | | | | |
| stop | -p scheduled-stop-869526 | scheduled-stop-869526 | jenkins | v1.33.1 | 19 Aug 24 11:35 UTC | |
| | --schedule 15s | | | | | |
|---------|--------------------------------|-----------------------|---------|---------|---------------------|---------------------|
==> Last Start <==
Log file created at: 2024/08/19 11:35:14
Running on machine: ubuntu-20-agent-11
Binary: Built with gc go1.22.5 for linux/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I0819 11:35:14.607523 45737 out.go:345] Setting OutFile to fd 1 ...
I0819 11:35:14.607777 45737 out.go:392] TERM=,COLORTERM=, which probably does not support color
I0819 11:35:14.607781 45737 out.go:358] Setting ErrFile to fd 2...
I0819 11:35:14.607784 45737 out.go:392] TERM=,COLORTERM=, which probably does not support color
I0819 11:35:14.607971 45737 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19476-7059/.minikube/bin
I0819 11:35:14.608497 45737 out.go:352] Setting JSON to false
I0819 11:35:14.609369 45737 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-11","uptime":4610,"bootTime":1724062705,"procs":178,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1066-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}
I0819 11:35:14.609419 45737 start.go:139] virtualization: kvm guest
I0819 11:35:14.611622 45737 out.go:177] * [scheduled-stop-869526] minikube v1.33.1 on Ubuntu 20.04 (kvm/amd64)
I0819 11:35:14.612923 45737 out.go:177] - MINIKUBE_LOCATION=19476
I0819 11:35:14.612923 45737 notify.go:220] Checking for updates...
I0819 11:35:14.614279 45737 out.go:177] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0819 11:35:14.615504 45737 out.go:177] - KUBECONFIG=/home/jenkins/minikube-integration/19476-7059/kubeconfig
I0819 11:35:14.616710 45737 out.go:177] - MINIKUBE_HOME=/home/jenkins/minikube-integration/19476-7059/.minikube
I0819 11:35:14.618013 45737 out.go:177] - MINIKUBE_BIN=out/minikube-linux-amd64
I0819 11:35:14.619304 45737 out.go:177] - MINIKUBE_FORCE_SYSTEMD=
I0819 11:35:14.620700 45737 driver.go:392] Setting default libvirt URI to qemu:///system
I0819 11:35:14.655013 45737 out.go:177] * Using the kvm2 driver based on user configuration
I0819 11:35:14.656186 45737 start.go:297] selected driver: kvm2
I0819 11:35:14.656199 45737 start.go:901] validating driver "kvm2" against <nil>
I0819 11:35:14.656208 45737 start.go:912] status for kvm2: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0819 11:35:14.656906 45737 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0819 11:35:14.656975 45737 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/minikube-integration/19476-7059/.minikube/bin:/home/jenkins/workspace/KVM_Linux_containerd_integration/out/:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games:/usr/local/go/bin:/home/jenkins/go/bin:/usr/local/bin/:/usr/local/go/bin/:/home/jenkins/go/bin
I0819 11:35:14.671251 45737 install.go:137] /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2 version is 1.33.1
I0819 11:35:14.671289 45737 start_flags.go:310] no existing cluster config was found, will generate one from the flags
I0819 11:35:14.671506 45737 start_flags.go:929] Wait components to verify : map[apiserver:true system_pods:true]
I0819 11:35:14.671556 45737 cni.go:84] Creating CNI manager for ""
I0819 11:35:14.671564 45737 cni.go:146] "kvm2" driver + "containerd" runtime found, recommending bridge
I0819 11:35:14.671573 45737 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni
I0819 11:35:14.671639 45737 start.go:340] cluster config:
{Name:scheduled-stop-869526 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723740748-19452@sha256:2211a6931895d2d502e957e9667096db10734a96767d670cb4dbffdd37397b0d Memory:2048 CPUs:2 DiskSize:20000 Driver:kvm2 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.31.0 ClusterName:scheduled-stop-869526 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local Co
ntainerRuntime:containerd 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.31.0 ContainerRuntime:containerd ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/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}
I0819 11:35:14.671749 45737 iso.go:125] acquiring lock: {Name:mk8743ff7672c999cda88ae6e832d9c714d7959c Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0819 11:35:14.674468 45737 out.go:177] * Starting "scheduled-stop-869526" primary control-plane node in "scheduled-stop-869526" cluster
I0819 11:35:14.675657 45737 preload.go:131] Checking if preload exists for k8s version v1.31.0 and runtime containerd
I0819 11:35:14.675685 45737 preload.go:146] Found local preload: /home/jenkins/minikube-integration/19476-7059/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.0-containerd-overlay2-amd64.tar.lz4
I0819 11:35:14.675690 45737 cache.go:56] Caching tarball of preloaded images
I0819 11:35:14.675756 45737 preload.go:172] Found /home/jenkins/minikube-integration/19476-7059/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.0-containerd-overlay2-amd64.tar.lz4 in cache, skipping download
I0819 11:35:14.675762 45737 cache.go:59] Finished verifying existence of preloaded tar for v1.31.0 on containerd
I0819 11:35:14.676019 45737 profile.go:143] Saving config to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/config.json ...
I0819 11:35:14.676030 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/config.json: {Name:mk27c875e1837f12ebd38fb290d77bba14c0baa6 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:14.676151 45737 start.go:360] acquireMachinesLock for scheduled-stop-869526: {Name:mk5ba9d518c6842628e013ae05bf3e28d8afe296 Clock:{} Delay:500ms Timeout:13m0s Cancel:<nil>}
I0819 11:35:14.676183 45737 start.go:364] duration metric: took 15.269µs to acquireMachinesLock for "scheduled-stop-869526"
I0819 11:35:14.676195 45737 start.go:93] Provisioning new machine with config: &{Name:scheduled-stop-869526 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19452/minikube-v1.33.1-1723740674-19452-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723740748-19452@sha256:2211a6931895d2d502e957e9667096db10734a96767d670cb4dbffdd37397b0d Memory:2048 CPUs:2 DiskSize:20000 Driver:kvm2 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 KubernetesConfi
g:{KubernetesVersion:v1.31.0 ClusterName:scheduled-stop-869526 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd 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.31.0 ContainerRuntime:containerd ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: Disa
bleOptimizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} &{Name: IP: Port:8443 KubernetesVersion:v1.31.0 ContainerRuntime:containerd ControlPlane:true Worker:true}
I0819 11:35:14.676243 45737 start.go:125] createHost starting for "" (driver="kvm2")
I0819 11:35:14.678794 45737 out.go:235] * Creating kvm2 VM (CPUs=2, Memory=2048MB, Disk=20000MB) ...
I0819 11:35:14.678933 45737 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0819 11:35:14.678961 45737 main.go:141] libmachine: Launching plugin server for driver kvm2
I0819 11:35:14.693230 45737 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:44155
I0819 11:35:14.693582 45737 main.go:141] libmachine: () Calling .GetVersion
I0819 11:35:14.694132 45737 main.go:141] libmachine: Using API Version 1
I0819 11:35:14.694147 45737 main.go:141] libmachine: () Calling .SetConfigRaw
I0819 11:35:14.694502 45737 main.go:141] libmachine: () Calling .GetMachineName
I0819 11:35:14.694714 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetMachineName
I0819 11:35:14.694859 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:14.695038 45737 start.go:159] libmachine.API.Create for "scheduled-stop-869526" (driver="kvm2")
I0819 11:35:14.695057 45737 client.go:168] LocalClient.Create starting
I0819 11:35:14.695082 45737 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca.pem
I0819 11:35:14.695111 45737 main.go:141] libmachine: Decoding PEM data...
I0819 11:35:14.695123 45737 main.go:141] libmachine: Parsing certificate...
I0819 11:35:14.695177 45737 main.go:141] libmachine: Reading certificate data from /home/jenkins/minikube-integration/19476-7059/.minikube/certs/cert.pem
I0819 11:35:14.695191 45737 main.go:141] libmachine: Decoding PEM data...
I0819 11:35:14.695201 45737 main.go:141] libmachine: Parsing certificate...
I0819 11:35:14.695212 45737 main.go:141] libmachine: Running pre-create checks...
I0819 11:35:14.695222 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .PreCreateCheck
I0819 11:35:14.695594 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetConfigRaw
I0819 11:35:14.696058 45737 main.go:141] libmachine: Creating machine...
I0819 11:35:14.696065 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .Create
I0819 11:35:14.696225 45737 main.go:141] libmachine: (scheduled-stop-869526) Creating KVM machine...
I0819 11:35:14.697569 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found existing default KVM network
I0819 11:35:14.698346 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:14.698199 45760 network.go:206] using free private subnet 192.168.39.0/24: &{IP:192.168.39.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.39.0/24 Gateway:192.168.39.1 ClientMin:192.168.39.2 ClientMax:192.168.39.254 Broadcast:192.168.39.255 IsPrivate:true Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:} reservation:0xc000015aa0}
I0819 11:35:14.698362 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | created network xml:
I0819 11:35:14.698374 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | <network>
I0819 11:35:14.698382 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | <name>mk-scheduled-stop-869526</name>
I0819 11:35:14.698393 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | <dns enable='no'/>
I0819 11:35:14.698397 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG |
I0819 11:35:14.698402 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | <ip address='192.168.39.1' netmask='255.255.255.0'>
I0819 11:35:14.698406 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | <dhcp>
I0819 11:35:14.698411 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | <range start='192.168.39.2' end='192.168.39.253'/>
I0819 11:35:14.698414 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | </dhcp>
I0819 11:35:14.698424 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | </ip>
I0819 11:35:14.698427 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG |
I0819 11:35:14.698451 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | </network>
I0819 11:35:14.698465 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG |
I0819 11:35:14.703543 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | trying to create private KVM network mk-scheduled-stop-869526 192.168.39.0/24...
I0819 11:35:14.767995 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | private KVM network mk-scheduled-stop-869526 192.168.39.0/24 created
I0819 11:35:14.768017 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:14.767982 45760 common.go:145] Making disk image using store path: /home/jenkins/minikube-integration/19476-7059/.minikube
I0819 11:35:14.768033 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting up store path in /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526 ...
I0819 11:35:14.768050 45737 main.go:141] libmachine: (scheduled-stop-869526) Building disk image from file:///home/jenkins/minikube-integration/19476-7059/.minikube/cache/iso/amd64/minikube-v1.33.1-1723740674-19452-amd64.iso
I0819 11:35:14.768149 45737 main.go:141] libmachine: (scheduled-stop-869526) Downloading /home/jenkins/minikube-integration/19476-7059/.minikube/cache/boot2docker.iso from file:///home/jenkins/minikube-integration/19476-7059/.minikube/cache/iso/amd64/minikube-v1.33.1-1723740674-19452-amd64.iso...
I0819 11:35:15.001165 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:15.000988 45760 common.go:152] Creating ssh key: /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa...
I0819 11:35:15.369658 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:15.369530 45760 common.go:158] Creating raw disk image: /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/scheduled-stop-869526.rawdisk...
I0819 11:35:15.369670 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Writing magic tar header
I0819 11:35:15.369694 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Writing SSH key tar header
I0819 11:35:15.369713 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:15.369644 45760 common.go:172] Fixing permissions on /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526 ...
I0819 11:35:15.369723 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526
I0819 11:35:15.369736 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting executable bit set on /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526 (perms=drwx------)
I0819 11:35:15.369744 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19476-7059/.minikube/machines
I0819 11:35:15.369763 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19476-7059/.minikube
I0819 11:35:15.369772 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home/jenkins/minikube-integration/19476-7059
I0819 11:35:15.369782 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home/jenkins/minikube-integration
I0819 11:35:15.369789 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home/jenkins
I0819 11:35:15.369799 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting executable bit set on /home/jenkins/minikube-integration/19476-7059/.minikube/machines (perms=drwxr-xr-x)
I0819 11:35:15.369807 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting executable bit set on /home/jenkins/minikube-integration/19476-7059/.minikube (perms=drwxr-xr-x)
I0819 11:35:15.369812 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting executable bit set on /home/jenkins/minikube-integration/19476-7059 (perms=drwxrwxr-x)
I0819 11:35:15.369818 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting executable bit set on /home/jenkins/minikube-integration (perms=drwxrwxr-x)
I0819 11:35:15.369822 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Checking permissions on dir: /home
I0819 11:35:15.369827 45737 main.go:141] libmachine: (scheduled-stop-869526) Setting executable bit set on /home/jenkins (perms=drwxr-xr-x)
I0819 11:35:15.369834 45737 main.go:141] libmachine: (scheduled-stop-869526) Creating domain...
I0819 11:35:15.369864 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Skipping /home - not owner
I0819 11:35:15.371042 45737 main.go:141] libmachine: (scheduled-stop-869526) define libvirt domain using xml:
I0819 11:35:15.371055 45737 main.go:141] libmachine: (scheduled-stop-869526) <domain type='kvm'>
I0819 11:35:15.371061 45737 main.go:141] libmachine: (scheduled-stop-869526) <name>scheduled-stop-869526</name>
I0819 11:35:15.371065 45737 main.go:141] libmachine: (scheduled-stop-869526) <memory unit='MiB'>2048</memory>
I0819 11:35:15.371069 45737 main.go:141] libmachine: (scheduled-stop-869526) <vcpu>2</vcpu>
I0819 11:35:15.371073 45737 main.go:141] libmachine: (scheduled-stop-869526) <features>
I0819 11:35:15.371077 45737 main.go:141] libmachine: (scheduled-stop-869526) <acpi/>
I0819 11:35:15.371081 45737 main.go:141] libmachine: (scheduled-stop-869526) <apic/>
I0819 11:35:15.371085 45737 main.go:141] libmachine: (scheduled-stop-869526) <pae/>
I0819 11:35:15.371097 45737 main.go:141] libmachine: (scheduled-stop-869526)
I0819 11:35:15.371101 45737 main.go:141] libmachine: (scheduled-stop-869526) </features>
I0819 11:35:15.371104 45737 main.go:141] libmachine: (scheduled-stop-869526) <cpu mode='host-passthrough'>
I0819 11:35:15.371108 45737 main.go:141] libmachine: (scheduled-stop-869526)
I0819 11:35:15.371111 45737 main.go:141] libmachine: (scheduled-stop-869526) </cpu>
I0819 11:35:15.371114 45737 main.go:141] libmachine: (scheduled-stop-869526) <os>
I0819 11:35:15.371118 45737 main.go:141] libmachine: (scheduled-stop-869526) <type>hvm</type>
I0819 11:35:15.371129 45737 main.go:141] libmachine: (scheduled-stop-869526) <boot dev='cdrom'/>
I0819 11:35:15.371133 45737 main.go:141] libmachine: (scheduled-stop-869526) <boot dev='hd'/>
I0819 11:35:15.371138 45737 main.go:141] libmachine: (scheduled-stop-869526) <bootmenu enable='no'/>
I0819 11:35:15.371141 45737 main.go:141] libmachine: (scheduled-stop-869526) </os>
I0819 11:35:15.371145 45737 main.go:141] libmachine: (scheduled-stop-869526) <devices>
I0819 11:35:15.371148 45737 main.go:141] libmachine: (scheduled-stop-869526) <disk type='file' device='cdrom'>
I0819 11:35:15.371156 45737 main.go:141] libmachine: (scheduled-stop-869526) <source file='/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/boot2docker.iso'/>
I0819 11:35:15.371160 45737 main.go:141] libmachine: (scheduled-stop-869526) <target dev='hdc' bus='scsi'/>
I0819 11:35:15.371164 45737 main.go:141] libmachine: (scheduled-stop-869526) <readonly/>
I0819 11:35:15.371167 45737 main.go:141] libmachine: (scheduled-stop-869526) </disk>
I0819 11:35:15.371172 45737 main.go:141] libmachine: (scheduled-stop-869526) <disk type='file' device='disk'>
I0819 11:35:15.371178 45737 main.go:141] libmachine: (scheduled-stop-869526) <driver name='qemu' type='raw' cache='default' io='threads' />
I0819 11:35:15.371186 45737 main.go:141] libmachine: (scheduled-stop-869526) <source file='/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/scheduled-stop-869526.rawdisk'/>
I0819 11:35:15.371189 45737 main.go:141] libmachine: (scheduled-stop-869526) <target dev='hda' bus='virtio'/>
I0819 11:35:15.371193 45737 main.go:141] libmachine: (scheduled-stop-869526) </disk>
I0819 11:35:15.371200 45737 main.go:141] libmachine: (scheduled-stop-869526) <interface type='network'>
I0819 11:35:15.371206 45737 main.go:141] libmachine: (scheduled-stop-869526) <source network='mk-scheduled-stop-869526'/>
I0819 11:35:15.371212 45737 main.go:141] libmachine: (scheduled-stop-869526) <model type='virtio'/>
I0819 11:35:15.371223 45737 main.go:141] libmachine: (scheduled-stop-869526) </interface>
I0819 11:35:15.371227 45737 main.go:141] libmachine: (scheduled-stop-869526) <interface type='network'>
I0819 11:35:15.371232 45737 main.go:141] libmachine: (scheduled-stop-869526) <source network='default'/>
I0819 11:35:15.371235 45737 main.go:141] libmachine: (scheduled-stop-869526) <model type='virtio'/>
I0819 11:35:15.371239 45737 main.go:141] libmachine: (scheduled-stop-869526) </interface>
I0819 11:35:15.371242 45737 main.go:141] libmachine: (scheduled-stop-869526) <serial type='pty'>
I0819 11:35:15.371247 45737 main.go:141] libmachine: (scheduled-stop-869526) <target port='0'/>
I0819 11:35:15.371250 45737 main.go:141] libmachine: (scheduled-stop-869526) </serial>
I0819 11:35:15.371254 45737 main.go:141] libmachine: (scheduled-stop-869526) <console type='pty'>
I0819 11:35:15.371258 45737 main.go:141] libmachine: (scheduled-stop-869526) <target type='serial' port='0'/>
I0819 11:35:15.371262 45737 main.go:141] libmachine: (scheduled-stop-869526) </console>
I0819 11:35:15.371265 45737 main.go:141] libmachine: (scheduled-stop-869526) <rng model='virtio'>
I0819 11:35:15.371289 45737 main.go:141] libmachine: (scheduled-stop-869526) <backend model='random'>/dev/random</backend>
I0819 11:35:15.371297 45737 main.go:141] libmachine: (scheduled-stop-869526) </rng>
I0819 11:35:15.371301 45737 main.go:141] libmachine: (scheduled-stop-869526)
I0819 11:35:15.371305 45737 main.go:141] libmachine: (scheduled-stop-869526)
I0819 11:35:15.371309 45737 main.go:141] libmachine: (scheduled-stop-869526) </devices>
I0819 11:35:15.371312 45737 main.go:141] libmachine: (scheduled-stop-869526) </domain>
I0819 11:35:15.371319 45737 main.go:141] libmachine: (scheduled-stop-869526)
I0819 11:35:15.375652 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:95:06:18 in network default
I0819 11:35:15.376231 45737 main.go:141] libmachine: (scheduled-stop-869526) Ensuring networks are active...
I0819 11:35:15.376242 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:15.376892 45737 main.go:141] libmachine: (scheduled-stop-869526) Ensuring network default is active
I0819 11:35:15.377255 45737 main.go:141] libmachine: (scheduled-stop-869526) Ensuring network mk-scheduled-stop-869526 is active
I0819 11:35:15.377749 45737 main.go:141] libmachine: (scheduled-stop-869526) Getting domain xml...
I0819 11:35:15.378685 45737 main.go:141] libmachine: (scheduled-stop-869526) Creating domain...
I0819 11:35:16.569135 45737 main.go:141] libmachine: (scheduled-stop-869526) Waiting to get IP...
I0819 11:35:16.569799 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:16.570171 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:16.570216 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:16.570155 45760 retry.go:31] will retry after 215.162584ms: waiting for machine to come up
I0819 11:35:16.786479 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:16.786937 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:16.786960 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:16.786902 45760 retry.go:31] will retry after 234.732075ms: waiting for machine to come up
I0819 11:35:17.023360 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:17.023698 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:17.023709 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:17.023657 45760 retry.go:31] will retry after 458.546482ms: waiting for machine to come up
I0819 11:35:17.484037 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:17.484409 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:17.484425 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:17.484374 45760 retry.go:31] will retry after 388.335396ms: waiting for machine to come up
I0819 11:35:17.873923 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:17.874321 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:17.874340 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:17.874289 45760 retry.go:31] will retry after 464.935473ms: waiting for machine to come up
I0819 11:35:18.340857 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:18.341325 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:18.341342 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:18.341296 45760 retry.go:31] will retry after 925.054086ms: waiting for machine to come up
I0819 11:35:19.268550 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:19.268953 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:19.268980 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:19.268918 45760 retry.go:31] will retry after 876.235887ms: waiting for machine to come up
I0819 11:35:20.146643 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:20.147048 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:20.147063 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:20.147010 45760 retry.go:31] will retry after 1.258006946s: waiting for machine to come up
I0819 11:35:21.406444 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:21.406932 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:21.406950 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:21.406873 45760 retry.go:31] will retry after 1.515439273s: waiting for machine to come up
I0819 11:35:22.924310 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:22.924701 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:22.924732 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:22.924665 45760 retry.go:31] will retry after 2.208221211s: waiting for machine to come up
I0819 11:35:25.134879 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:25.135220 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:25.135250 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:25.135185 45760 retry.go:31] will retry after 2.343606253s: waiting for machine to come up
I0819 11:35:27.481748 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:27.482238 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:27.482258 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:27.482191 45760 retry.go:31] will retry after 2.654106574s: waiting for machine to come up
I0819 11:35:30.137393 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:30.137752 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:30.137772 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:30.137718 45760 retry.go:31] will retry after 3.922728223s: waiting for machine to come up
I0819 11:35:34.064679 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:34.065048 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find current IP address of domain scheduled-stop-869526 in network mk-scheduled-stop-869526
I0819 11:35:34.065059 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | I0819 11:35:34.065001 45760 retry.go:31] will retry after 3.594689774s: waiting for machine to come up
I0819 11:35:37.663710 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.664120 45737 main.go:141] libmachine: (scheduled-stop-869526) Found IP for machine: 192.168.39.25
I0819 11:35:37.664127 45737 main.go:141] libmachine: (scheduled-stop-869526) Reserving static IP address...
I0819 11:35:37.664134 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has current primary IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.664456 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | unable to find host DHCP lease matching {name: "scheduled-stop-869526", mac: "52:54:00:12:df:26", ip: "192.168.39.25"} in network mk-scheduled-stop-869526
I0819 11:35:37.736032 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Getting to WaitForSSH function...
I0819 11:35:37.736055 45737 main.go:141] libmachine: (scheduled-stop-869526) Reserved static IP address: 192.168.39.25
I0819 11:35:37.736067 45737 main.go:141] libmachine: (scheduled-stop-869526) Waiting for SSH to be available...
I0819 11:35:37.738500 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.738847 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:minikube Clientid:01:52:54:00:12:df:26}
I0819 11:35:37.738866 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.739014 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Using SSH client type: external
I0819 11:35:37.739041 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Using SSH private key: /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa (-rw-------)
I0819 11:35:37.739071 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | &{[-F /dev/null -o ConnectionAttempts=3 -o ConnectTimeout=10 -o ControlMaster=no -o ControlPath=none -o LogLevel=quiet -o PasswordAuthentication=no -o ServerAliveInterval=60 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null docker@192.168.39.25 -o IdentitiesOnly=yes -i /home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa -p 22] /usr/bin/ssh <nil>}
I0819 11:35:37.739081 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | About to run SSH command:
I0819 11:35:37.739092 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | exit 0
I0819 11:35:37.865982 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | SSH cmd err, output: <nil>:
I0819 11:35:37.866218 45737 main.go:141] libmachine: (scheduled-stop-869526) KVM machine creation complete!
I0819 11:35:37.866542 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetConfigRaw
I0819 11:35:37.867096 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:37.867260 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:37.867378 45737 main.go:141] libmachine: Waiting for machine to be running, this may take a few minutes...
I0819 11:35:37.867401 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetState
I0819 11:35:37.868660 45737 main.go:141] libmachine: Detecting operating system of created instance...
I0819 11:35:37.868677 45737 main.go:141] libmachine: Waiting for SSH to be available...
I0819 11:35:37.868683 45737 main.go:141] libmachine: Getting to WaitForSSH function...
I0819 11:35:37.868691 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:37.870692 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.871035 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:37.871055 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.871132 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:37.871294 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:37.871434 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:37.871586 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:37.871745 45737 main.go:141] libmachine: Using SSH client type: native
I0819 11:35:37.871932 45737 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.25 22 <nil> <nil>}
I0819 11:35:37.871937 45737 main.go:141] libmachine: About to run SSH command:
exit 0
I0819 11:35:37.985244 45737 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0819 11:35:37.985260 45737 main.go:141] libmachine: Detecting the provisioner...
I0819 11:35:37.985268 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:37.987738 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.988050 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:37.988074 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:37.988182 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:37.988367 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:37.988489 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:37.988633 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:37.988784 45737 main.go:141] libmachine: Using SSH client type: native
I0819 11:35:37.988977 45737 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.25 22 <nil> <nil>}
I0819 11:35:37.988986 45737 main.go:141] libmachine: About to run SSH command:
cat /etc/os-release
I0819 11:35:38.102322 45737 main.go:141] libmachine: SSH cmd err, output: <nil>: NAME=Buildroot
VERSION=2023.02.9-dirty
ID=buildroot
VERSION_ID=2023.02.9
PRETTY_NAME="Buildroot 2023.02.9"
I0819 11:35:38.102398 45737 main.go:141] libmachine: found compatible host: buildroot
I0819 11:35:38.102403 45737 main.go:141] libmachine: Provisioning with buildroot...
I0819 11:35:38.102409 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetMachineName
I0819 11:35:38.102636 45737 buildroot.go:166] provisioning hostname "scheduled-stop-869526"
I0819 11:35:38.102652 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetMachineName
I0819 11:35:38.102822 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:38.105357 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.105631 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.105654 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.105824 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:38.105983 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.106101 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.106196 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:38.106333 45737 main.go:141] libmachine: Using SSH client type: native
I0819 11:35:38.106518 45737 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.25 22 <nil> <nil>}
I0819 11:35:38.106526 45737 main.go:141] libmachine: About to run SSH command:
sudo hostname scheduled-stop-869526 && echo "scheduled-stop-869526" | sudo tee /etc/hostname
I0819 11:35:38.232314 45737 main.go:141] libmachine: SSH cmd err, output: <nil>: scheduled-stop-869526
I0819 11:35:38.232328 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:38.234961 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.235341 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.235388 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.235509 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:38.235679 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.235797 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.235897 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:38.236016 45737 main.go:141] libmachine: Using SSH client type: native
I0819 11:35:38.236170 45737 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.25 22 <nil> <nil>}
I0819 11:35:38.236180 45737 main.go:141] libmachine: About to run SSH command:
if ! grep -xq '.*\sscheduled-stop-869526' /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 scheduled-stop-869526/g' /etc/hosts;
else
echo '127.0.1.1 scheduled-stop-869526' | sudo tee -a /etc/hosts;
fi
fi
I0819 11:35:38.354579 45737 main.go:141] libmachine: SSH cmd err, output: <nil>:
I0819 11:35:38.354594 45737 buildroot.go:172] set auth options {CertDir:/home/jenkins/minikube-integration/19476-7059/.minikube CaCertPath:/home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca.pem CaPrivateKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca-key.pem CaCertRemotePath:/etc/docker/ca.pem ServerCertPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/server.pem ServerKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/server-key.pem ClientKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/certs/key.pem ServerCertRemotePath:/etc/docker/server.pem ServerKeyRemotePath:/etc/docker/server-key.pem ClientCertPath:/home/jenkins/minikube-integration/19476-7059/.minikube/certs/cert.pem ServerCertSANs:[] StorePath:/home/jenkins/minikube-integration/19476-7059/.minikube}
I0819 11:35:38.354617 45737 buildroot.go:174] setting up certificates
I0819 11:35:38.354624 45737 provision.go:84] configureAuth start
I0819 11:35:38.354631 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetMachineName
I0819 11:35:38.354899 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetIP
I0819 11:35:38.357233 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.357491 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.357504 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.357638 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:38.359852 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.360188 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.360215 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.360320 45737 provision.go:143] copyHostCerts
I0819 11:35:38.360387 45737 exec_runner.go:144] found /home/jenkins/minikube-integration/19476-7059/.minikube/ca.pem, removing ...
I0819 11:35:38.360402 45737 exec_runner.go:203] rm: /home/jenkins/minikube-integration/19476-7059/.minikube/ca.pem
I0819 11:35:38.360480 45737 exec_runner.go:151] cp: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca.pem --> /home/jenkins/minikube-integration/19476-7059/.minikube/ca.pem (1078 bytes)
I0819 11:35:38.360597 45737 exec_runner.go:144] found /home/jenkins/minikube-integration/19476-7059/.minikube/cert.pem, removing ...
I0819 11:35:38.360603 45737 exec_runner.go:203] rm: /home/jenkins/minikube-integration/19476-7059/.minikube/cert.pem
I0819 11:35:38.360633 45737 exec_runner.go:151] cp: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/cert.pem --> /home/jenkins/minikube-integration/19476-7059/.minikube/cert.pem (1123 bytes)
I0819 11:35:38.360718 45737 exec_runner.go:144] found /home/jenkins/minikube-integration/19476-7059/.minikube/key.pem, removing ...
I0819 11:35:38.360722 45737 exec_runner.go:203] rm: /home/jenkins/minikube-integration/19476-7059/.minikube/key.pem
I0819 11:35:38.360757 45737 exec_runner.go:151] cp: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/key.pem --> /home/jenkins/minikube-integration/19476-7059/.minikube/key.pem (1679 bytes)
I0819 11:35:38.360829 45737 provision.go:117] generating server cert: /home/jenkins/minikube-integration/19476-7059/.minikube/machines/server.pem ca-key=/home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca.pem private-key=/home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca-key.pem org=jenkins.scheduled-stop-869526 san=[127.0.0.1 192.168.39.25 localhost minikube scheduled-stop-869526]
I0819 11:35:38.614454 45737 provision.go:177] copyRemoteCerts
I0819 11:35:38.614504 45737 ssh_runner.go:195] Run: sudo mkdir -p /etc/docker /etc/docker /etc/docker
I0819 11:35:38.614529 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:38.617112 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.617370 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.617390 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.617508 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:38.617683 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.617795 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:38.617928 45737 sshutil.go:53] new ssh client: &{IP:192.168.39.25 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa Username:docker}
I0819 11:35:38.704795 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca.pem --> /etc/docker/ca.pem (1078 bytes)
I0819 11:35:38.732050 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/machines/server.pem --> /etc/docker/server.pem (1233 bytes)
I0819 11:35:38.759160 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/machines/server-key.pem --> /etc/docker/server-key.pem (1675 bytes)
I0819 11:35:38.786746 45737 provision.go:87] duration metric: took 432.113356ms to configureAuth
I0819 11:35:38.786764 45737 buildroot.go:189] setting minikube options for container-runtime
I0819 11:35:38.786913 45737 config.go:182] Loaded profile config "scheduled-stop-869526": Driver=kvm2, ContainerRuntime=containerd, KubernetesVersion=v1.31.0
I0819 11:35:38.786927 45737 main.go:141] libmachine: Checking connection to Docker...
I0819 11:35:38.786935 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetURL
I0819 11:35:38.788059 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Using libvirt version 6000000
I0819 11:35:38.790300 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.790622 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.790644 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.790769 45737 main.go:141] libmachine: Docker is up and running!
I0819 11:35:38.790776 45737 main.go:141] libmachine: Reticulating splines...
I0819 11:35:38.790781 45737 client.go:171] duration metric: took 24.095719216s to LocalClient.Create
I0819 11:35:38.790802 45737 start.go:167] duration metric: took 24.095766093s to libmachine.API.Create "scheduled-stop-869526"
I0819 11:35:38.790808 45737 start.go:293] postStartSetup for "scheduled-stop-869526" (driver="kvm2")
I0819 11:35:38.790815 45737 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]
I0819 11:35:38.790829 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:38.791064 45737 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
I0819 11:35:38.791076 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:38.793011 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.793276 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.793291 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.793488 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:38.793692 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.793829 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:38.793955 45737 sshutil.go:53] new ssh client: &{IP:192.168.39.25 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa Username:docker}
I0819 11:35:38.880235 45737 ssh_runner.go:195] Run: cat /etc/os-release
I0819 11:35:38.884518 45737 info.go:137] Remote host: Buildroot 2023.02.9
I0819 11:35:38.884532 45737 filesync.go:126] Scanning /home/jenkins/minikube-integration/19476-7059/.minikube/addons for local assets ...
I0819 11:35:38.884590 45737 filesync.go:126] Scanning /home/jenkins/minikube-integration/19476-7059/.minikube/files for local assets ...
I0819 11:35:38.884653 45737 filesync.go:149] local asset: /home/jenkins/minikube-integration/19476-7059/.minikube/files/etc/ssl/certs/142282.pem -> 142282.pem in /etc/ssl/certs
I0819 11:35:38.884739 45737 ssh_runner.go:195] Run: sudo mkdir -p /etc/ssl/certs
I0819 11:35:38.893817 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/files/etc/ssl/certs/142282.pem --> /etc/ssl/certs/142282.pem (1708 bytes)
I0819 11:35:38.916959 45737 start.go:296] duration metric: took 126.141237ms for postStartSetup
I0819 11:35:38.916992 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetConfigRaw
I0819 11:35:38.917515 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetIP
I0819 11:35:38.919972 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.920286 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.920302 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.920525 45737 profile.go:143] Saving config to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/config.json ...
I0819 11:35:38.920701 45737 start.go:128] duration metric: took 24.244449957s to createHost
I0819 11:35:38.920720 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:38.922820 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.923091 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:38.923106 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:38.923225 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:38.923388 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.923518 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:38.923620 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:38.923783 45737 main.go:141] libmachine: Using SSH client type: native
I0819 11:35:38.923970 45737 main.go:141] libmachine: &{{{<nil> 0 [] [] []} docker [0x82f9c0] 0x832720 <nil> [] 0s} 192.168.39.25 22 <nil> <nil>}
I0819 11:35:38.923978 45737 main.go:141] libmachine: About to run SSH command:
date +%s.%N
I0819 11:35:39.034525 45737 main.go:141] libmachine: SSH cmd err, output: <nil>: 1724067338.992150040
I0819 11:35:39.034536 45737 fix.go:216] guest clock: 1724067338.992150040
I0819 11:35:39.034542 45737 fix.go:229] Guest: 2024-08-19 11:35:38.99215004 +0000 UTC Remote: 2024-08-19 11:35:38.920707018 +0000 UTC m=+24.348850918 (delta=71.443022ms)
I0819 11:35:39.034558 45737 fix.go:200] guest clock delta is within tolerance: 71.443022ms
I0819 11:35:39.034561 45737 start.go:83] releasing machines lock for "scheduled-stop-869526", held for 24.358373703s
I0819 11:35:39.034576 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:39.034853 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetIP
I0819 11:35:39.037306 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:39.037617 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:39.037637 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:39.037812 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:39.038353 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:39.038502 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:39.038583 45737 ssh_runner.go:195] Run: curl -sS -m 2 https://registry.k8s.io/
I0819 11:35:39.038628 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:39.038663 45737 ssh_runner.go:195] Run: cat /version.json
I0819 11:35:39.038677 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:39.040974 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:39.041162 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:39.041226 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:39.041244 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:39.041404 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:39.041574 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:39.041688 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:39.041697 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:39.041703 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:39.041866 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:39.041856 45737 sshutil.go:53] new ssh client: &{IP:192.168.39.25 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa Username:docker}
I0819 11:35:39.042031 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:39.042199 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:39.042311 45737 sshutil.go:53] new ssh client: &{IP:192.168.39.25 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa Username:docker}
I0819 11:35:39.145064 45737 ssh_runner.go:195] Run: systemctl --version
I0819 11:35:39.151237 45737 ssh_runner.go:195] Run: sh -c "stat /etc/cni/net.d/*loopback.conf*"
W0819 11:35:39.157179 45737 cni.go:209] loopback cni configuration skipped: "/etc/cni/net.d/*loopback.conf*" not found
I0819 11:35:39.157323 45737 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" ;
I0819 11:35:39.174033 45737 cni.go:262] disabled [/etc/cni/net.d/87-podman-bridge.conflist] bridge cni config(s)
I0819 11:35:39.174046 45737 start.go:495] detecting cgroup driver to use...
I0819 11:35:39.174114 45737 ssh_runner.go:195] Run: sudo systemctl stop -f crio
I0819 11:35:39.205551 45737 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service crio
I0819 11:35:39.218947 45737 docker.go:217] disabling cri-docker service (if available) ...
I0819 11:35:39.218997 45737 ssh_runner.go:195] Run: sudo systemctl stop -f cri-docker.socket
I0819 11:35:39.232359 45737 ssh_runner.go:195] Run: sudo systemctl stop -f cri-docker.service
I0819 11:35:39.245848 45737 ssh_runner.go:195] Run: sudo systemctl disable cri-docker.socket
I0819 11:35:39.357000 45737 ssh_runner.go:195] Run: sudo systemctl mask cri-docker.service
I0819 11:35:39.512587 45737 docker.go:233] disabling docker service ...
I0819 11:35:39.512679 45737 ssh_runner.go:195] Run: sudo systemctl stop -f docker.socket
I0819 11:35:39.529349 45737 ssh_runner.go:195] Run: sudo systemctl stop -f docker.service
I0819 11:35:39.541748 45737 ssh_runner.go:195] Run: sudo systemctl disable docker.socket
I0819 11:35:39.665525 45737 ssh_runner.go:195] Run: sudo systemctl mask docker.service
I0819 11:35:39.781618 45737 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service docker
I0819 11:35:39.796502 45737 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"
I0819 11:35:39.814991 45737 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)sandbox_image = .*$|\1sandbox_image = "registry.k8s.io/pause:3.10"|' /etc/containerd/config.toml"
I0819 11:35:39.826230 45737 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"
I0819 11:35:39.837043 45737 containerd.go:146] configuring containerd to use "cgroupfs" as cgroup driver...
I0819 11:35:39.837081 45737 ssh_runner.go:195] Run: sh -c "sudo sed -i -r 's|^( *)SystemdCgroup = .*$|\1SystemdCgroup = false|g' /etc/containerd/config.toml"
I0819 11:35:39.847695 45737 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"
I0819 11:35:39.858397 45737 ssh_runner.go:195] Run: sh -c "sudo sed -i '/systemd_cgroup/d' /etc/containerd/config.toml"
I0819 11:35:39.869068 45737 ssh_runner.go:195] Run: sh -c "sudo sed -i 's|"io.containerd.runc.v1"|"io.containerd.runc.v2"|g' /etc/containerd/config.toml"
I0819 11:35:39.879569 45737 ssh_runner.go:195] Run: sh -c "sudo rm -rf /etc/cni/net.mk"
I0819 11:35:39.890122 45737 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"
I0819 11:35:39.900504 45737 ssh_runner.go:195] Run: sh -c "sudo sed -i '/^ *enable_unprivileged_ports = .*/d' /etc/containerd/config.toml"
I0819 11:35:39.910971 45737 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"
I0819 11:35:39.921566 45737 ssh_runner.go:195] Run: sudo sysctl net.bridge.bridge-nf-call-iptables
I0819 11:35:39.931095 45737 crio.go:166] couldn't verify netfilter by "sudo sysctl net.bridge.bridge-nf-call-iptables" which might be okay. error: sudo sysctl net.bridge.bridge-nf-call-iptables: Process exited with status 255
stdout:
stderr:
sysctl: cannot stat /proc/sys/net/bridge/bridge-nf-call-iptables: No such file or directory
I0819 11:35:39.931145 45737 ssh_runner.go:195] Run: sudo modprobe br_netfilter
I0819 11:35:39.943857 45737 ssh_runner.go:195] Run: sudo sh -c "echo 1 > /proc/sys/net/ipv4/ip_forward"
I0819 11:35:39.953692 45737 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0819 11:35:40.078773 45737 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0819 11:35:40.107629 45737 start.go:542] Will wait 60s for socket path /run/containerd/containerd.sock
I0819 11:35:40.107695 45737 ssh_runner.go:195] Run: stat /run/containerd/containerd.sock
I0819 11:35:40.112190 45737 retry.go:31] will retry after 1.103962529s: stat /run/containerd/containerd.sock: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/run/containerd/containerd.sock': No such file or directory
I0819 11:35:41.216476 45737 ssh_runner.go:195] Run: stat /run/containerd/containerd.sock
I0819 11:35:41.221989 45737 start.go:563] Will wait 60s for crictl version
I0819 11:35:41.222039 45737 ssh_runner.go:195] Run: which crictl
I0819 11:35:41.225901 45737 ssh_runner.go:195] Run: sudo /usr/bin/crictl version
I0819 11:35:41.262226 45737 start.go:579] Version: 0.1.0
RuntimeName: containerd
RuntimeVersion: v1.7.20
RuntimeApiVersion: v1
I0819 11:35:41.262276 45737 ssh_runner.go:195] Run: containerd --version
I0819 11:35:41.288875 45737 ssh_runner.go:195] Run: containerd --version
I0819 11:35:41.315290 45737 out.go:177] * Preparing Kubernetes v1.31.0 on containerd 1.7.20 ...
I0819 11:35:41.316433 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetIP
I0819 11:35:41.319075 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:41.319440 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:41.319456 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:41.319695 45737 ssh_runner.go:195] Run: grep 192.168.39.1 host.minikube.internal$ /etc/hosts
I0819 11:35:41.323893 45737 ssh_runner.go:195] Run: /bin/bash -c "{ grep -v $'\thost.minikube.internal$' "/etc/hosts"; echo "192.168.39.1 host.minikube.internal"; } > /tmp/h.$$; sudo cp /tmp/h.$$ "/etc/hosts""
I0819 11:35:41.336568 45737 kubeadm.go:883] updating cluster {Name:scheduled-stop-869526 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19452/minikube-v1.33.1-1723740674-19452-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723740748-19452@sha256:2211a6931895d2d502e957e9667096db10734a96767d670cb4dbffdd37397b0d Memory:2048 CPUs:2 DiskSize:20000 Driver:kvm2 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:{KubernetesVersio
n:v1.31.0 ClusterName:scheduled-stop-869526 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:192.168.39.25 Port:8443 KubernetesVersion:v1.31.0 ContainerRuntime:containerd ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOpt
imizations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s} ...
I0819 11:35:41.336649 45737 preload.go:131] Checking if preload exists for k8s version v1.31.0 and runtime containerd
I0819 11:35:41.336687 45737 ssh_runner.go:195] Run: sudo crictl images --output json
I0819 11:35:41.369268 45737 containerd.go:623] couldn't find preloaded image for "registry.k8s.io/kube-apiserver:v1.31.0". assuming images are not preloaded.
I0819 11:35:41.369333 45737 ssh_runner.go:195] Run: which lz4
I0819 11:35:41.373385 45737 ssh_runner.go:195] Run: stat -c "%s %y" /preloaded.tar.lz4
I0819 11:35:41.377515 45737 ssh_runner.go:352] existence check for /preloaded.tar.lz4: stat -c "%s %y" /preloaded.tar.lz4: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/preloaded.tar.lz4': No such file or directory
I0819 11:35:41.377537 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.0-containerd-overlay2-amd64.tar.lz4 --> /preloaded.tar.lz4 (388596648 bytes)
I0819 11:35:42.677482 45737 containerd.go:563] duration metric: took 1.30412827s to copy over tarball
I0819 11:35:42.677547 45737 ssh_runner.go:195] Run: sudo tar --xattrs --xattrs-include security.capability -I lz4 -C /var -xf /preloaded.tar.lz4
I0819 11:35:44.717024 45737 ssh_runner.go:235] Completed: sudo tar --xattrs --xattrs-include security.capability -I lz4 -C /var -xf /preloaded.tar.lz4: (2.039454557s)
I0819 11:35:44.717039 45737 containerd.go:570] duration metric: took 2.039537442s to extract the tarball
I0819 11:35:44.717045 45737 ssh_runner.go:146] rm: /preloaded.tar.lz4
I0819 11:35:44.754022 45737 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0819 11:35:44.859410 45737 ssh_runner.go:195] Run: sudo systemctl restart containerd
I0819 11:35:44.887035 45737 ssh_runner.go:195] Run: sudo crictl images --output json
I0819 11:35:44.923117 45737 retry.go:31] will retry after 319.310621ms: sudo crictl images --output json: Process exited with status 1
stdout:
stderr:
time="2024-08-19T11:35:44Z" level=fatal msg="validate service connection: validate CRI v1 image API for endpoint \"unix:///run/containerd/containerd.sock\": rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial unix /run/containerd/containerd.sock: connect: no such file or directory\""
I0819 11:35:45.242650 45737 ssh_runner.go:195] Run: sudo crictl images --output json
I0819 11:35:45.276185 45737 containerd.go:627] all images are preloaded for containerd runtime.
I0819 11:35:45.276197 45737 cache_images.go:84] Images are preloaded, skipping loading
I0819 11:35:45.276203 45737 kubeadm.go:934] updating node { 192.168.39.25 8443 v1.31.0 containerd true true} ...
I0819 11:35:45.276310 45737 kubeadm.go:946] kubelet [Unit]
Wants=containerd.service
[Service]
ExecStart=
ExecStart=/var/lib/minikube/binaries/v1.31.0/kubelet --bootstrap-kubeconfig=/etc/kubernetes/bootstrap-kubelet.conf --config=/var/lib/kubelet/config.yaml --hostname-override=scheduled-stop-869526 --kubeconfig=/etc/kubernetes/kubelet.conf --node-ip=192.168.39.25
[Install]
config:
{KubernetesVersion:v1.31.0 ClusterName:scheduled-stop-869526 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:}
I0819 11:35:45.276365 45737 ssh_runner.go:195] Run: sudo crictl info
I0819 11:35:45.309083 45737 cni.go:84] Creating CNI manager for ""
I0819 11:35:45.309091 45737 cni.go:146] "kvm2" driver + "containerd" runtime found, recommending bridge
I0819 11:35:45.309099 45737 kubeadm.go:84] Using pod CIDR: 10.244.0.0/16
I0819 11:35:45.309117 45737 kubeadm.go:181] kubeadm options: {CertDir:/var/lib/minikube/certs ServiceCIDR:10.96.0.0/12 PodSubnet:10.244.0.0/16 AdvertiseAddress:192.168.39.25 APIServerPort:8443 KubernetesVersion:v1.31.0 EtcdDataDir:/var/lib/minikube/etcd EtcdExtraArgs:map[] ClusterName:scheduled-stop-869526 NodeName:scheduled-stop-869526 DNSDomain:cluster.local CRISocket:/run/containerd/containerd.sock ImageRepository: ComponentOptions:[{Component:apiServer ExtraArgs:map[enable-admission-plugins:NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota] Pairs:map[certSANs:["127.0.0.1", "localhost", "192.168.39.25"]]} {Component:controllerManager ExtraArgs:map[allocate-node-cidrs:true leader-elect:false] Pairs:map[]} {Component:scheduler ExtraArgs:map[leader-elect:false] Pairs:map[]}] FeatureArgs:map[] NodeIP:192.168.39.25 CgroupDriver:cgroupfs ClientCAFile:/var/lib/minikube/certs/ca.crt
StaticPodPath:/etc/kubernetes/manifests ControlPlaneAddress:control-plane.minikube.internal KubeProxyOptions:map[] ResolvConfSearchRegression:false KubeletConfigOpts:map[containerRuntimeEndpoint:unix:///run/containerd/containerd.sock hairpinMode:hairpin-veth runtimeRequestTimeout:15m] PrependCriSocketUnix:true}
I0819 11:35:45.309218 45737 kubeadm.go:187] kubeadm config:
apiVersion: kubeadm.k8s.io/v1beta3
kind: InitConfiguration
localAPIEndpoint:
advertiseAddress: 192.168.39.25
bindPort: 8443
bootstrapTokens:
- groups:
- system:bootstrappers:kubeadm:default-node-token
ttl: 24h0m0s
usages:
- signing
- authentication
nodeRegistration:
criSocket: unix:///run/containerd/containerd.sock
name: "scheduled-stop-869526"
kubeletExtraArgs:
node-ip: 192.168.39.25
taints: []
---
apiVersion: kubeadm.k8s.io/v1beta3
kind: ClusterConfiguration
apiServer:
certSANs: ["127.0.0.1", "localhost", "192.168.39.25"]
extraArgs:
enable-admission-plugins: "NamespaceLifecycle,LimitRanger,ServiceAccount,DefaultStorageClass,DefaultTolerationSeconds,NodeRestriction,MutatingAdmissionWebhook,ValidatingAdmissionWebhook,ResourceQuota"
controllerManager:
extraArgs:
allocate-node-cidrs: "true"
leader-elect: "false"
scheduler:
extraArgs:
leader-elect: "false"
certificatesDir: /var/lib/minikube/certs
clusterName: mk
controlPlaneEndpoint: control-plane.minikube.internal:8443
etcd:
local:
dataDir: /var/lib/minikube/etcd
extraArgs:
proxy-refresh-interval: "70000"
kubernetesVersion: v1.31.0
networking:
dnsDomain: cluster.local
podSubnet: "10.244.0.0/16"
serviceSubnet: 10.96.0.0/12
---
apiVersion: kubelet.config.k8s.io/v1beta1
kind: KubeletConfiguration
authentication:
x509:
clientCAFile: /var/lib/minikube/certs/ca.crt
cgroupDriver: cgroupfs
containerRuntimeEndpoint: unix:///run/containerd/containerd.sock
hairpinMode: hairpin-veth
runtimeRequestTimeout: 15m
clusterDomain: "cluster.local"
# disable disk resource management by default
imageGCHighThresholdPercent: 100
evictionHard:
nodefs.available: "0%"
nodefs.inodesFree: "0%"
imagefs.available: "0%"
failSwapOn: false
staticPodPath: /etc/kubernetes/manifests
---
apiVersion: kubeproxy.config.k8s.io/v1alpha1
kind: KubeProxyConfiguration
clusterCIDR: "10.244.0.0/16"
metricsBindAddress: 0.0.0.0:10249
conntrack:
maxPerCore: 0
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_established"
tcpEstablishedTimeout: 0s
# Skip setting "net.netfilter.nf_conntrack_tcp_timeout_close"
tcpCloseWaitTimeout: 0s
I0819 11:35:45.309265 45737 ssh_runner.go:195] Run: sudo ls /var/lib/minikube/binaries/v1.31.0
I0819 11:35:45.319339 45737 binaries.go:44] Found k8s binaries, skipping transfer
I0819 11:35:45.319389 45737 ssh_runner.go:195] Run: sudo mkdir -p /etc/systemd/system/kubelet.service.d /lib/systemd/system /var/tmp/minikube
I0819 11:35:45.328628 45737 ssh_runner.go:362] scp memory --> /etc/systemd/system/kubelet.service.d/10-kubeadm.conf (326 bytes)
I0819 11:35:45.345682 45737 ssh_runner.go:362] scp memory --> /lib/systemd/system/kubelet.service (352 bytes)
I0819 11:35:45.362365 45737 ssh_runner.go:362] scp memory --> /var/tmp/minikube/kubeadm.yaml.new (2178 bytes)
I0819 11:35:45.379253 45737 ssh_runner.go:195] Run: grep 192.168.39.25 control-plane.minikube.internal$ /etc/hosts
I0819 11:35:45.383160 45737 ssh_runner.go:195] Run: /bin/bash -c "{ grep -v $'\tcontrol-plane.minikube.internal$' "/etc/hosts"; echo "192.168.39.25 control-plane.minikube.internal"; } > /tmp/h.$$; sudo cp /tmp/h.$$ "/etc/hosts""
I0819 11:35:45.395041 45737 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0819 11:35:45.504137 45737 ssh_runner.go:195] Run: sudo systemctl start kubelet
I0819 11:35:45.522488 45737 certs.go:68] Setting up /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526 for IP: 192.168.39.25
I0819 11:35:45.522501 45737 certs.go:194] generating shared ca certs ...
I0819 11:35:45.522517 45737 certs.go:226] acquiring lock for ca certs: {Name:mkbb77ae400b9f85aa0a1768d3b8be4d81294910 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:45.522703 45737 certs.go:235] skipping valid "minikubeCA" ca cert: /home/jenkins/minikube-integration/19476-7059/.minikube/ca.key
I0819 11:35:45.522758 45737 certs.go:235] skipping valid "proxyClientCA" ca cert: /home/jenkins/minikube-integration/19476-7059/.minikube/proxy-client-ca.key
I0819 11:35:45.522774 45737 certs.go:256] generating profile certs ...
I0819 11:35:45.522842 45737 certs.go:363] generating signed profile cert for "minikube-user": /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/client.key
I0819 11:35:45.522853 45737 crypto.go:68] Generating cert /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/client.crt with IP's: []
I0819 11:35:45.743994 45737 crypto.go:156] Writing cert to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/client.crt ...
I0819 11:35:45.744009 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/client.crt: {Name:mk6d8cc1cbe928d8c873c4f276ac7c9eb683ff92 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:45.744170 45737 crypto.go:164] Writing key to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/client.key ...
I0819 11:35:45.744179 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/client.key: {Name:mk7bbfd2ba977d6fde966c767c447862933e2455 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:45.744255 45737 certs.go:363] generating signed profile cert for "minikube": /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.key.bd477086
I0819 11:35:45.744265 45737 crypto.go:68] Generating cert /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.crt.bd477086 with IP's: [10.96.0.1 127.0.0.1 10.0.0.1 192.168.39.25]
I0819 11:35:45.892922 45737 crypto.go:156] Writing cert to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.crt.bd477086 ...
I0819 11:35:45.892937 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.crt.bd477086: {Name:mkde5a6e1c276198151ec8b6ed05e6eafb2e06fe Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:45.893096 45737 crypto.go:164] Writing key to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.key.bd477086 ...
I0819 11:35:45.893103 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.key.bd477086: {Name:mk4bc5820d7182991bc2c1d3d29dde98294b2df5 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:45.893182 45737 certs.go:381] copying /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.crt.bd477086 -> /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.crt
I0819 11:35:45.893266 45737 certs.go:385] copying /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.key.bd477086 -> /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.key
I0819 11:35:45.893319 45737 certs.go:363] generating signed profile cert for "aggregator": /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.key
I0819 11:35:45.893330 45737 crypto.go:68] Generating cert /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.crt with IP's: []
I0819 11:35:46.200740 45737 crypto.go:156] Writing cert to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.crt ...
I0819 11:35:46.200755 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.crt: {Name:mka4416dc8300346b733067540c78599b90cc32e Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:46.200920 45737 crypto.go:164] Writing key to /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.key ...
I0819 11:35:46.200928 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.key: {Name:mk10228ddbb530b08eb1a34df3f30eecd8364361 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:46.201102 45737 certs.go:484] found cert: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/14228.pem (1338 bytes)
W0819 11:35:46.201133 45737 certs.go:480] ignoring /home/jenkins/minikube-integration/19476-7059/.minikube/certs/14228_empty.pem, impossibly tiny 0 bytes
I0819 11:35:46.201139 45737 certs.go:484] found cert: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca-key.pem (1679 bytes)
I0819 11:35:46.201159 45737 certs.go:484] found cert: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/ca.pem (1078 bytes)
I0819 11:35:46.201178 45737 certs.go:484] found cert: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/cert.pem (1123 bytes)
I0819 11:35:46.201195 45737 certs.go:484] found cert: /home/jenkins/minikube-integration/19476-7059/.minikube/certs/key.pem (1679 bytes)
I0819 11:35:46.201226 45737 certs.go:484] found cert: /home/jenkins/minikube-integration/19476-7059/.minikube/files/etc/ssl/certs/142282.pem (1708 bytes)
I0819 11:35:46.201735 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/ca.crt --> /var/lib/minikube/certs/ca.crt (1111 bytes)
I0819 11:35:46.227933 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/ca.key --> /var/lib/minikube/certs/ca.key (1679 bytes)
I0819 11:35:46.252016 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/proxy-client-ca.crt --> /var/lib/minikube/certs/proxy-client-ca.crt (1119 bytes)
I0819 11:35:46.280052 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/proxy-client-ca.key --> /var/lib/minikube/certs/proxy-client-ca.key (1675 bytes)
I0819 11:35:46.308528 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.crt --> /var/lib/minikube/certs/apiserver.crt (1432 bytes)
I0819 11:35:46.337001 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/apiserver.key --> /var/lib/minikube/certs/apiserver.key (1679 bytes)
I0819 11:35:46.360954 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.crt --> /var/lib/minikube/certs/proxy-client.crt (1147 bytes)
I0819 11:35:46.384741 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/profiles/scheduled-stop-869526/proxy-client.key --> /var/lib/minikube/certs/proxy-client.key (1679 bytes)
I0819 11:35:46.408330 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/certs/14228.pem --> /usr/share/ca-certificates/14228.pem (1338 bytes)
I0819 11:35:46.432325 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/files/etc/ssl/certs/142282.pem --> /usr/share/ca-certificates/142282.pem (1708 bytes)
I0819 11:35:46.456144 45737 ssh_runner.go:362] scp /home/jenkins/minikube-integration/19476-7059/.minikube/ca.crt --> /usr/share/ca-certificates/minikubeCA.pem (1111 bytes)
I0819 11:35:46.480078 45737 ssh_runner.go:362] scp memory --> /var/lib/minikube/kubeconfig (738 bytes)
I0819 11:35:46.496962 45737 ssh_runner.go:195] Run: openssl version
I0819 11:35:46.503006 45737 ssh_runner.go:195] Run: sudo /bin/bash -c "test -s /usr/share/ca-certificates/minikubeCA.pem && ln -fs /usr/share/ca-certificates/minikubeCA.pem /etc/ssl/certs/minikubeCA.pem"
I0819 11:35:46.514310 45737 ssh_runner.go:195] Run: ls -la /usr/share/ca-certificates/minikubeCA.pem
I0819 11:35:46.519245 45737 certs.go:528] hashing: -rw-r--r-- 1 root root 1111 Aug 19 10:36 /usr/share/ca-certificates/minikubeCA.pem
I0819 11:35:46.519289 45737 ssh_runner.go:195] Run: openssl x509 -hash -noout -in /usr/share/ca-certificates/minikubeCA.pem
I0819 11:35:46.525050 45737 ssh_runner.go:195] Run: sudo /bin/bash -c "test -L /etc/ssl/certs/b5213941.0 || ln -fs /etc/ssl/certs/minikubeCA.pem /etc/ssl/certs/b5213941.0"
I0819 11:35:46.535843 45737 ssh_runner.go:195] Run: sudo /bin/bash -c "test -s /usr/share/ca-certificates/14228.pem && ln -fs /usr/share/ca-certificates/14228.pem /etc/ssl/certs/14228.pem"
I0819 11:35:46.546559 45737 ssh_runner.go:195] Run: ls -la /usr/share/ca-certificates/14228.pem
I0819 11:35:46.551143 45737 certs.go:528] hashing: -rw-r--r-- 1 root root 1338 Aug 19 10:43 /usr/share/ca-certificates/14228.pem
I0819 11:35:46.551178 45737 ssh_runner.go:195] Run: openssl x509 -hash -noout -in /usr/share/ca-certificates/14228.pem
I0819 11:35:46.556726 45737 ssh_runner.go:195] Run: sudo /bin/bash -c "test -L /etc/ssl/certs/51391683.0 || ln -fs /etc/ssl/certs/14228.pem /etc/ssl/certs/51391683.0"
I0819 11:35:46.567272 45737 ssh_runner.go:195] Run: sudo /bin/bash -c "test -s /usr/share/ca-certificates/142282.pem && ln -fs /usr/share/ca-certificates/142282.pem /etc/ssl/certs/142282.pem"
I0819 11:35:46.577862 45737 ssh_runner.go:195] Run: ls -la /usr/share/ca-certificates/142282.pem
I0819 11:35:46.582433 45737 certs.go:528] hashing: -rw-r--r-- 1 root root 1708 Aug 19 10:43 /usr/share/ca-certificates/142282.pem
I0819 11:35:46.582470 45737 ssh_runner.go:195] Run: openssl x509 -hash -noout -in /usr/share/ca-certificates/142282.pem
I0819 11:35:46.587934 45737 ssh_runner.go:195] Run: sudo /bin/bash -c "test -L /etc/ssl/certs/3ec20f2e.0 || ln -fs /etc/ssl/certs/142282.pem /etc/ssl/certs/3ec20f2e.0"
I0819 11:35:46.598446 45737 ssh_runner.go:195] Run: stat /var/lib/minikube/certs/apiserver-kubelet-client.crt
I0819 11:35:46.602568 45737 certs.go:399] 'apiserver-kubelet-client' cert doesn't exist, likely first start: stat /var/lib/minikube/certs/apiserver-kubelet-client.crt: Process exited with status 1
stdout:
stderr:
stat: cannot statx '/var/lib/minikube/certs/apiserver-kubelet-client.crt': No such file or directory
I0819 11:35:46.602616 45737 kubeadm.go:392] StartCluster: {Name:scheduled-stop-869526 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19452/minikube-v1.33.1-1723740674-19452-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.44-1723740748-19452@sha256:2211a6931895d2d502e957e9667096db10734a96767d670cb4dbffdd37397b0d Memory:2048 CPUs:2 DiskSize:20000 Driver:kvm2 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:v
1.31.0 ClusterName:scheduled-stop-869526 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:containerd CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:192.168.39.25 Port:8443 KubernetesVersion:v1.31.0 ContainerRuntime:containerd ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:/home/jenkins:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimi
zations:false DisableMetrics:false CustomQemuFirmwarePath: SocketVMnetClientPath: SocketVMnetPath: StaticIP: SSHAuthSock: SSHAgentPID:0 GPUs: AutoPauseInterval:1m0s}
I0819 11:35:46.602692 45737 cri.go:54] listing CRI containers in root /run/containerd/runc/k8s.io: {State:paused Name: Namespaces:[kube-system]}
I0819 11:35:46.602737 45737 ssh_runner.go:195] Run: sudo -s eval "crictl ps -a --quiet --label io.kubernetes.pod.namespace=kube-system"
I0819 11:35:46.640082 45737 cri.go:89] found id: ""
I0819 11:35:46.640143 45737 ssh_runner.go:195] Run: sudo ls /var/lib/kubelet/kubeadm-flags.env /var/lib/kubelet/config.yaml /var/lib/minikube/etcd
I0819 11:35:46.650506 45737 ssh_runner.go:195] Run: sudo cp /var/tmp/minikube/kubeadm.yaml.new /var/tmp/minikube/kubeadm.yaml
I0819 11:35:46.660087 45737 ssh_runner.go:195] Run: sudo ls -la /etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf
I0819 11:35:46.669443 45737 kubeadm.go:155] config check failed, skipping stale config cleanup: sudo ls -la /etc/kubernetes/admin.conf /etc/kubernetes/kubelet.conf /etc/kubernetes/controller-manager.conf /etc/kubernetes/scheduler.conf: Process exited with status 2
stdout:
stderr:
ls: cannot access '/etc/kubernetes/admin.conf': No such file or directory
ls: cannot access '/etc/kubernetes/kubelet.conf': No such file or directory
ls: cannot access '/etc/kubernetes/controller-manager.conf': No such file or directory
ls: cannot access '/etc/kubernetes/scheduler.conf': No such file or directory
I0819 11:35:46.669451 45737 kubeadm.go:157] found existing configuration files:
I0819 11:35:46.669487 45737 ssh_runner.go:195] Run: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/admin.conf
I0819 11:35:46.678737 45737 kubeadm.go:163] "https://control-plane.minikube.internal:8443" may not be in /etc/kubernetes/admin.conf - will remove: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/admin.conf: Process exited with status 2
stdout:
stderr:
grep: /etc/kubernetes/admin.conf: No such file or directory
I0819 11:35:46.678779 45737 ssh_runner.go:195] Run: sudo rm -f /etc/kubernetes/admin.conf
I0819 11:35:46.688507 45737 ssh_runner.go:195] Run: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/kubelet.conf
I0819 11:35:46.697461 45737 kubeadm.go:163] "https://control-plane.minikube.internal:8443" may not be in /etc/kubernetes/kubelet.conf - will remove: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/kubelet.conf: Process exited with status 2
stdout:
stderr:
grep: /etc/kubernetes/kubelet.conf: No such file or directory
I0819 11:35:46.697502 45737 ssh_runner.go:195] Run: sudo rm -f /etc/kubernetes/kubelet.conf
I0819 11:35:46.706849 45737 ssh_runner.go:195] Run: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/controller-manager.conf
I0819 11:35:46.715795 45737 kubeadm.go:163] "https://control-plane.minikube.internal:8443" may not be in /etc/kubernetes/controller-manager.conf - will remove: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/controller-manager.conf: Process exited with status 2
stdout:
stderr:
grep: /etc/kubernetes/controller-manager.conf: No such file or directory
I0819 11:35:46.715834 45737 ssh_runner.go:195] Run: sudo rm -f /etc/kubernetes/controller-manager.conf
I0819 11:35:46.725144 45737 ssh_runner.go:195] Run: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/scheduler.conf
I0819 11:35:46.734117 45737 kubeadm.go:163] "https://control-plane.minikube.internal:8443" may not be in /etc/kubernetes/scheduler.conf - will remove: sudo grep https://control-plane.minikube.internal:8443 /etc/kubernetes/scheduler.conf: Process exited with status 2
stdout:
stderr:
grep: /etc/kubernetes/scheduler.conf: No such file or directory
I0819 11:35:46.734162 45737 ssh_runner.go:195] Run: sudo rm -f /etc/kubernetes/scheduler.conf
I0819 11:35:46.743369 45737 ssh_runner.go:286] Start: /bin/bash -c "sudo env PATH="/var/lib/minikube/binaries/v1.31.0:$PATH" kubeadm init --config /var/tmp/minikube/kubeadm.yaml --ignore-preflight-errors=DirAvailable--etc-kubernetes-manifests,DirAvailable--var-lib-minikube,DirAvailable--var-lib-minikube-etcd,FileAvailable--etc-kubernetes-manifests-kube-scheduler.yaml,FileAvailable--etc-kubernetes-manifests-kube-apiserver.yaml,FileAvailable--etc-kubernetes-manifests-kube-controller-manager.yaml,FileAvailable--etc-kubernetes-manifests-etcd.yaml,Port-10250,Swap,NumCPU,Mem"
I0819 11:35:46.848769 45737 kubeadm.go:310] W0819 11:35:46.805392 765 common.go:101] your configuration file uses a deprecated API spec: "kubeadm.k8s.io/v1beta3" (kind: "ClusterConfiguration"). Please use 'kubeadm config migrate --old-config old.yaml --new-config new.yaml', which will write the new, similar spec using a newer API version.
I0819 11:35:46.849441 45737 kubeadm.go:310] W0819 11:35:46.806254 765 common.go:101] your configuration file uses a deprecated API spec: "kubeadm.k8s.io/v1beta3" (kind: "InitConfiguration"). Please use 'kubeadm config migrate --old-config old.yaml --new-config new.yaml', which will write the new, similar spec using a newer API version.
I0819 11:35:46.962062 45737 kubeadm.go:310] [WARNING Service-Kubelet]: kubelet service is not enabled, please run 'systemctl enable kubelet.service'
I0819 11:35:56.643361 45737 kubeadm.go:310] [init] Using Kubernetes version: v1.31.0
I0819 11:35:56.643430 45737 kubeadm.go:310] [preflight] Running pre-flight checks
I0819 11:35:56.643520 45737 kubeadm.go:310] [preflight] Pulling images required for setting up a Kubernetes cluster
I0819 11:35:56.643612 45737 kubeadm.go:310] [preflight] This might take a minute or two, depending on the speed of your internet connection
I0819 11:35:56.643685 45737 kubeadm.go:310] [preflight] You can also perform this action beforehand using 'kubeadm config images pull'
I0819 11:35:56.643734 45737 kubeadm.go:310] [certs] Using certificateDir folder "/var/lib/minikube/certs"
I0819 11:35:56.645038 45737 out.go:235] - Generating certificates and keys ...
I0819 11:35:56.645097 45737 kubeadm.go:310] [certs] Using existing ca certificate authority
I0819 11:35:56.645146 45737 kubeadm.go:310] [certs] Using existing apiserver certificate and key on disk
I0819 11:35:56.645216 45737 kubeadm.go:310] [certs] Generating "apiserver-kubelet-client" certificate and key
I0819 11:35:56.645272 45737 kubeadm.go:310] [certs] Generating "front-proxy-ca" certificate and key
I0819 11:35:56.645319 45737 kubeadm.go:310] [certs] Generating "front-proxy-client" certificate and key
I0819 11:35:56.645358 45737 kubeadm.go:310] [certs] Generating "etcd/ca" certificate and key
I0819 11:35:56.645400 45737 kubeadm.go:310] [certs] Generating "etcd/server" certificate and key
I0819 11:35:56.645559 45737 kubeadm.go:310] [certs] etcd/server serving cert is signed for DNS names [localhost scheduled-stop-869526] and IPs [192.168.39.25 127.0.0.1 ::1]
I0819 11:35:56.645632 45737 kubeadm.go:310] [certs] Generating "etcd/peer" certificate and key
I0819 11:35:56.645786 45737 kubeadm.go:310] [certs] etcd/peer serving cert is signed for DNS names [localhost scheduled-stop-869526] and IPs [192.168.39.25 127.0.0.1 ::1]
I0819 11:35:56.645891 45737 kubeadm.go:310] [certs] Generating "etcd/healthcheck-client" certificate and key
I0819 11:35:56.645962 45737 kubeadm.go:310] [certs] Generating "apiserver-etcd-client" certificate and key
I0819 11:35:56.645999 45737 kubeadm.go:310] [certs] Generating "sa" key and public key
I0819 11:35:56.646042 45737 kubeadm.go:310] [kubeconfig] Using kubeconfig folder "/etc/kubernetes"
I0819 11:35:56.646088 45737 kubeadm.go:310] [kubeconfig] Writing "admin.conf" kubeconfig file
I0819 11:35:56.646133 45737 kubeadm.go:310] [kubeconfig] Writing "super-admin.conf" kubeconfig file
I0819 11:35:56.646174 45737 kubeadm.go:310] [kubeconfig] Writing "kubelet.conf" kubeconfig file
I0819 11:35:56.646234 45737 kubeadm.go:310] [kubeconfig] Writing "controller-manager.conf" kubeconfig file
I0819 11:35:56.646282 45737 kubeadm.go:310] [kubeconfig] Writing "scheduler.conf" kubeconfig file
I0819 11:35:56.646350 45737 kubeadm.go:310] [etcd] Creating static Pod manifest for local etcd in "/etc/kubernetes/manifests"
I0819 11:35:56.646402 45737 kubeadm.go:310] [control-plane] Using manifest folder "/etc/kubernetes/manifests"
I0819 11:35:56.648464 45737 out.go:235] - Booting up control plane ...
I0819 11:35:56.648541 45737 kubeadm.go:310] [control-plane] Creating static Pod manifest for "kube-apiserver"
I0819 11:35:56.648600 45737 kubeadm.go:310] [control-plane] Creating static Pod manifest for "kube-controller-manager"
I0819 11:35:56.648667 45737 kubeadm.go:310] [control-plane] Creating static Pod manifest for "kube-scheduler"
I0819 11:35:56.648753 45737 kubeadm.go:310] [kubelet-start] Writing kubelet environment file with flags to file "/var/lib/kubelet/kubeadm-flags.env"
I0819 11:35:56.648841 45737 kubeadm.go:310] [kubelet-start] Writing kubelet configuration to file "/var/lib/kubelet/config.yaml"
I0819 11:35:56.648901 45737 kubeadm.go:310] [kubelet-start] Starting the kubelet
I0819 11:35:56.649025 45737 kubeadm.go:310] [wait-control-plane] Waiting for the kubelet to boot up the control plane as static Pods from directory "/etc/kubernetes/manifests"
I0819 11:35:56.649117 45737 kubeadm.go:310] [kubelet-check] Waiting for a healthy kubelet at http://127.0.0.1:10248/healthz. This can take up to 4m0s
I0819 11:35:56.649165 45737 kubeadm.go:310] [kubelet-check] The kubelet is healthy after 503.373854ms
I0819 11:35:56.649228 45737 kubeadm.go:310] [api-check] Waiting for a healthy API server. This can take up to 4m0s
I0819 11:35:56.649272 45737 kubeadm.go:310] [api-check] The API server is healthy after 5.001997466s
I0819 11:35:56.649358 45737 kubeadm.go:310] [upload-config] Storing the configuration used in ConfigMap "kubeadm-config" in the "kube-system" Namespace
I0819 11:35:56.649462 45737 kubeadm.go:310] [kubelet] Creating a ConfigMap "kubelet-config" in namespace kube-system with the configuration for the kubelets in the cluster
I0819 11:35:56.649515 45737 kubeadm.go:310] [upload-certs] Skipping phase. Please see --upload-certs
I0819 11:35:56.649664 45737 kubeadm.go:310] [mark-control-plane] Marking the node scheduled-stop-869526 as control-plane by adding the labels: [node-role.kubernetes.io/control-plane node.kubernetes.io/exclude-from-external-load-balancers]
I0819 11:35:56.649720 45737 kubeadm.go:310] [bootstrap-token] Using token: tcgrqv.qwllo0fvay37dnix
I0819 11:35:56.650780 45737 out.go:235] - Configuring RBAC rules ...
I0819 11:35:56.650859 45737 kubeadm.go:310] [bootstrap-token] Configuring bootstrap tokens, cluster-info ConfigMap, RBAC Roles
I0819 11:35:56.650924 45737 kubeadm.go:310] [bootstrap-token] Configured RBAC rules to allow Node Bootstrap tokens to get nodes
I0819 11:35:56.651038 45737 kubeadm.go:310] [bootstrap-token] Configured RBAC rules to allow Node Bootstrap tokens to post CSRs in order for nodes to get long term certificate credentials
I0819 11:35:56.651194 45737 kubeadm.go:310] [bootstrap-token] Configured RBAC rules to allow the csrapprover controller automatically approve CSRs from a Node Bootstrap Token
I0819 11:35:56.651351 45737 kubeadm.go:310] [bootstrap-token] Configured RBAC rules to allow certificate rotation for all node client certificates in the cluster
I0819 11:35:56.651458 45737 kubeadm.go:310] [bootstrap-token] Creating the "cluster-info" ConfigMap in the "kube-public" namespace
I0819 11:35:56.651598 45737 kubeadm.go:310] [kubelet-finalize] Updating "/etc/kubernetes/kubelet.conf" to point to a rotatable kubelet client certificate and key
I0819 11:35:56.651653 45737 kubeadm.go:310] [addons] Applied essential addon: CoreDNS
I0819 11:35:56.651716 45737 kubeadm.go:310] [addons] Applied essential addon: kube-proxy
I0819 11:35:56.651720 45737 kubeadm.go:310]
I0819 11:35:56.651800 45737 kubeadm.go:310] Your Kubernetes control-plane has initialized successfully!
I0819 11:35:56.651804 45737 kubeadm.go:310]
I0819 11:35:56.651908 45737 kubeadm.go:310] To start using your cluster, you need to run the following as a regular user:
I0819 11:35:56.651913 45737 kubeadm.go:310]
I0819 11:35:56.651932 45737 kubeadm.go:310] mkdir -p $HOME/.kube
I0819 11:35:56.651979 45737 kubeadm.go:310] sudo cp -i /etc/kubernetes/admin.conf $HOME/.kube/config
I0819 11:35:56.652023 45737 kubeadm.go:310] sudo chown $(id -u):$(id -g) $HOME/.kube/config
I0819 11:35:56.652025 45737 kubeadm.go:310]
I0819 11:35:56.652067 45737 kubeadm.go:310] Alternatively, if you are the root user, you can run:
I0819 11:35:56.652070 45737 kubeadm.go:310]
I0819 11:35:56.652106 45737 kubeadm.go:310] export KUBECONFIG=/etc/kubernetes/admin.conf
I0819 11:35:56.652112 45737 kubeadm.go:310]
I0819 11:35:56.652158 45737 kubeadm.go:310] You should now deploy a pod network to the cluster.
I0819 11:35:56.652221 45737 kubeadm.go:310] Run "kubectl apply -f [podnetwork].yaml" with one of the options listed at:
I0819 11:35:56.652288 45737 kubeadm.go:310] https://kubernetes.io/docs/concepts/cluster-administration/addons/
I0819 11:35:56.652292 45737 kubeadm.go:310]
I0819 11:35:56.652409 45737 kubeadm.go:310] You can now join any number of control-plane nodes by copying certificate authorities
I0819 11:35:56.652486 45737 kubeadm.go:310] and service account keys on each node and then running the following as root:
I0819 11:35:56.652490 45737 kubeadm.go:310]
I0819 11:35:56.652555 45737 kubeadm.go:310] kubeadm join control-plane.minikube.internal:8443 --token tcgrqv.qwllo0fvay37dnix \
I0819 11:35:56.652640 45737 kubeadm.go:310] --discovery-token-ca-cert-hash sha256:2645dbbb93100827a71fb830a4581fd00795548587f7ea97ced327ab380c5450 \
I0819 11:35:56.652656 45737 kubeadm.go:310] --control-plane
I0819 11:35:56.652659 45737 kubeadm.go:310]
I0819 11:35:56.652725 45737 kubeadm.go:310] Then you can join any number of worker nodes by running the following on each as root:
I0819 11:35:56.652727 45737 kubeadm.go:310]
I0819 11:35:56.652791 45737 kubeadm.go:310] kubeadm join control-plane.minikube.internal:8443 --token tcgrqv.qwllo0fvay37dnix \
I0819 11:35:56.652899 45737 kubeadm.go:310] --discovery-token-ca-cert-hash sha256:2645dbbb93100827a71fb830a4581fd00795548587f7ea97ced327ab380c5450
I0819 11:35:56.652907 45737 cni.go:84] Creating CNI manager for ""
I0819 11:35:56.652912 45737 cni.go:146] "kvm2" driver + "containerd" runtime found, recommending bridge
I0819 11:35:56.654309 45737 out.go:177] * Configuring bridge CNI (Container Networking Interface) ...
I0819 11:35:56.655524 45737 ssh_runner.go:195] Run: sudo mkdir -p /etc/cni/net.d
I0819 11:35:56.666011 45737 ssh_runner.go:362] scp memory --> /etc/cni/net.d/1-k8s.conflist (496 bytes)
I0819 11:35:56.690580 45737 ssh_runner.go:195] Run: /bin/bash -c "cat /proc/$(pgrep kube-apiserver)/oom_adj"
I0819 11:35:56.690637 45737 ssh_runner.go:195] Run: sudo /var/lib/minikube/binaries/v1.31.0/kubectl create clusterrolebinding minikube-rbac --clusterrole=cluster-admin --serviceaccount=kube-system:default --kubeconfig=/var/lib/minikube/kubeconfig
I0819 11:35:56.690653 45737 ssh_runner.go:195] Run: sudo /var/lib/minikube/binaries/v1.31.0/kubectl --kubeconfig=/var/lib/minikube/kubeconfig label --overwrite nodes scheduled-stop-869526 minikube.k8s.io/updated_at=2024_08_19T11_35_56_0700 minikube.k8s.io/version=v1.33.1 minikube.k8s.io/commit=7871dd89d2a8218fd3bbcc542b116f963c0d9934 minikube.k8s.io/name=scheduled-stop-869526 minikube.k8s.io/primary=true
I0819 11:35:56.728170 45737 ops.go:34] apiserver oom_adj: -16
I0819 11:35:56.913913 45737 kubeadm.go:1113] duration metric: took 223.319765ms to wait for elevateKubeSystemPrivileges
I0819 11:35:56.913981 45737 kubeadm.go:394] duration metric: took 10.311362821s to StartCluster
I0819 11:35:56.914002 45737 settings.go:142] acquiring lock: {Name:mke84a2aab3f09d0fd690695ca18124e6dfc4cca Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:56.914070 45737 settings.go:150] Updating kubeconfig: /home/jenkins/minikube-integration/19476-7059/kubeconfig
I0819 11:35:56.914680 45737 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19476-7059/kubeconfig: {Name:mk1b288e71922c9fb44690f8ca4a22a60667abde Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0819 11:35:56.914874 45737 ssh_runner.go:195] Run: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.31.0/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml"
I0819 11:35:56.914898 45737 start.go:235] Will wait 6m0s for node &{Name: IP:192.168.39.25 Port:8443 KubernetesVersion:v1.31.0 ContainerRuntime:containerd ControlPlane:true Worker:true}
I0819 11:35:56.914953 45737 addons.go:507] enable addons start: toEnable=map[ambassador:false auto-pause:false cloud-spanner:false csi-hostpath-driver:false dashboard:false default-storageclass:true efk:false freshpod:false gcp-auth:false gvisor:false headlamp:false helm-tiller:false inaccel:false ingress:false ingress-dns:false inspektor-gadget:false istio:false istio-provisioner:false kong:false kubeflow:false kubevirt:false logviewer:false metallb:false metrics-server:false nvidia-device-plugin:false nvidia-driver-installer:false nvidia-gpu-device-plugin:false olm:false pod-security-policy:false portainer:false registry:false registry-aliases:false registry-creds:false storage-provisioner:true storage-provisioner-gluster:false storage-provisioner-rancher:false volcano:false volumesnapshots:false yakd:false]
I0819 11:35:56.915009 45737 addons.go:69] Setting storage-provisioner=true in profile "scheduled-stop-869526"
I0819 11:35:56.915033 45737 addons.go:234] Setting addon storage-provisioner=true in "scheduled-stop-869526"
I0819 11:35:56.915028 45737 addons.go:69] Setting default-storageclass=true in profile "scheduled-stop-869526"
I0819 11:35:56.915057 45737 host.go:66] Checking if "scheduled-stop-869526" exists ...
I0819 11:35:56.915082 45737 addons_storage_classes.go:33] enableOrDisableStorageClasses default-storageclass=true on "scheduled-stop-869526"
I0819 11:35:56.915107 45737 config.go:182] Loaded profile config "scheduled-stop-869526": Driver=kvm2, ContainerRuntime=containerd, KubernetesVersion=v1.31.0
I0819 11:35:56.915425 45737 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0819 11:35:56.915428 45737 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0819 11:35:56.915444 45737 main.go:141] libmachine: Launching plugin server for driver kvm2
I0819 11:35:56.915447 45737 main.go:141] libmachine: Launching plugin server for driver kvm2
I0819 11:35:56.916616 45737 out.go:177] * Verifying Kubernetes components...
I0819 11:35:56.918098 45737 ssh_runner.go:195] Run: sudo systemctl daemon-reload
I0819 11:35:56.930629 45737 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:39151
I0819 11:35:56.931062 45737 main.go:141] libmachine: () Calling .GetVersion
I0819 11:35:56.931504 45737 main.go:141] libmachine: Using API Version 1
I0819 11:35:56.931520 45737 main.go:141] libmachine: () Calling .SetConfigRaw
I0819 11:35:56.931842 45737 main.go:141] libmachine: () Calling .GetMachineName
I0819 11:35:56.932040 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetState
I0819 11:35:56.934001 45737 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:39047
I0819 11:35:56.934436 45737 main.go:141] libmachine: () Calling .GetVersion
I0819 11:35:56.934795 45737 addons.go:234] Setting addon default-storageclass=true in "scheduled-stop-869526"
I0819 11:35:56.934818 45737 host.go:66] Checking if "scheduled-stop-869526" exists ...
I0819 11:35:56.934939 45737 main.go:141] libmachine: Using API Version 1
I0819 11:35:56.934947 45737 main.go:141] libmachine: () Calling .SetConfigRaw
I0819 11:35:56.935160 45737 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0819 11:35:56.935182 45737 main.go:141] libmachine: Launching plugin server for driver kvm2
I0819 11:35:56.935280 45737 main.go:141] libmachine: () Calling .GetMachineName
I0819 11:35:56.935723 45737 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0819 11:35:56.935739 45737 main.go:141] libmachine: Launching plugin server for driver kvm2
I0819 11:35:56.949595 45737 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:39045
I0819 11:35:56.949890 45737 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:38751
I0819 11:35:56.950048 45737 main.go:141] libmachine: () Calling .GetVersion
I0819 11:35:56.950416 45737 main.go:141] libmachine: () Calling .GetVersion
I0819 11:35:56.950500 45737 main.go:141] libmachine: Using API Version 1
I0819 11:35:56.950511 45737 main.go:141] libmachine: () Calling .SetConfigRaw
I0819 11:35:56.950870 45737 main.go:141] libmachine: Using API Version 1
I0819 11:35:56.950881 45737 main.go:141] libmachine: () Calling .SetConfigRaw
I0819 11:35:56.950919 45737 main.go:141] libmachine: () Calling .GetMachineName
I0819 11:35:56.951123 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetState
I0819 11:35:56.951218 45737 main.go:141] libmachine: () Calling .GetMachineName
I0819 11:35:56.951618 45737 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_containerd_integration/out/docker-machine-driver-kvm2
I0819 11:35:56.951643 45737 main.go:141] libmachine: Launching plugin server for driver kvm2
I0819 11:35:56.953185 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:56.955072 45737 out.go:177] - Using image gcr.io/k8s-minikube/storage-provisioner:v5
I0819 11:35:56.956388 45737 addons.go:431] installing /etc/kubernetes/addons/storage-provisioner.yaml
I0819 11:35:56.956395 45737 ssh_runner.go:362] scp memory --> /etc/kubernetes/addons/storage-provisioner.yaml (2676 bytes)
I0819 11:35:56.956408 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:56.959605 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:56.960022 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:56.960036 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:56.960243 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:56.960418 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:56.960580 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:56.960733 45737 sshutil.go:53] new ssh client: &{IP:192.168.39.25 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa Username:docker}
I0819 11:35:56.968921 45737 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:44789
I0819 11:35:56.969413 45737 main.go:141] libmachine: () Calling .GetVersion
I0819 11:35:56.969947 45737 main.go:141] libmachine: Using API Version 1
I0819 11:35:56.969970 45737 main.go:141] libmachine: () Calling .SetConfigRaw
I0819 11:35:56.970401 45737 main.go:141] libmachine: () Calling .GetMachineName
I0819 11:35:56.970613 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetState
I0819 11:35:56.972450 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .DriverName
I0819 11:35:56.972639 45737 addons.go:431] installing /etc/kubernetes/addons/storageclass.yaml
I0819 11:35:56.972648 45737 ssh_runner.go:362] scp storageclass/storageclass.yaml --> /etc/kubernetes/addons/storageclass.yaml (271 bytes)
I0819 11:35:56.972665 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHHostname
I0819 11:35:56.975912 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:56.976383 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:12:df:26", ip: ""} in network mk-scheduled-stop-869526: {Iface:virbr1 ExpiryTime:2024-08-19 12:35:29 +0000 UTC Type:0 Mac:52:54:00:12:df:26 Iaid: IPaddr:192.168.39.25 Prefix:24 Hostname:scheduled-stop-869526 Clientid:01:52:54:00:12:df:26}
I0819 11:35:56.976401 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | domain scheduled-stop-869526 has defined IP address 192.168.39.25 and MAC address 52:54:00:12:df:26 in network mk-scheduled-stop-869526
I0819 11:35:56.976522 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHPort
I0819 11:35:56.976680 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHKeyPath
I0819 11:35:56.976822 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .GetSSHUsername
I0819 11:35:56.976923 45737 sshutil.go:53] new ssh client: &{IP:192.168.39.25 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19476-7059/.minikube/machines/scheduled-stop-869526/id_rsa Username:docker}
I0819 11:35:57.188096 45737 ssh_runner.go:195] Run: sudo systemctl start kubelet
I0819 11:35:57.188163 45737 ssh_runner.go:195] Run: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.31.0/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml | sed -e '/^ forward . \/etc\/resolv.conf.*/i \ hosts {\n 192.168.39.1 host.minikube.internal\n fallthrough\n }' -e '/^ errors *$/i \ log' | sudo /var/lib/minikube/binaries/v1.31.0/kubectl --kubeconfig=/var/lib/minikube/kubeconfig replace -f -"
I0819 11:35:57.222621 45737 api_server.go:52] waiting for apiserver process to appear ...
I0819 11:35:57.222684 45737 ssh_runner.go:195] Run: sudo pgrep -xnf kube-apiserver.*minikube.*
I0819 11:35:57.302561 45737 ssh_runner.go:195] Run: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.31.0/kubectl apply -f /etc/kubernetes/addons/storage-provisioner.yaml
I0819 11:35:57.440626 45737 ssh_runner.go:195] Run: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.31.0/kubectl apply -f /etc/kubernetes/addons/storageclass.yaml
I0819 11:35:57.603724 45737 start.go:971] {"host.minikube.internal": 192.168.39.1} host record injected into CoreDNS's ConfigMap
I0819 11:35:57.603764 45737 api_server.go:72] duration metric: took 688.840349ms to wait for apiserver process to appear ...
I0819 11:35:57.603778 45737 api_server.go:88] waiting for apiserver healthz status ...
I0819 11:35:57.603795 45737 api_server.go:253] Checking apiserver healthz at https://192.168.39.25:8443/healthz ...
I0819 11:35:57.608354 45737 api_server.go:279] https://192.168.39.25:8443/healthz returned 200:
ok
I0819 11:35:57.613169 45737 api_server.go:141] control plane version: v1.31.0
I0819 11:35:57.613185 45737 api_server.go:131] duration metric: took 9.40011ms to wait for apiserver health ...
I0819 11:35:57.613192 45737 system_pods.go:43] waiting for kube-system pods to appear ...
I0819 11:35:57.626653 45737 system_pods.go:59] 4 kube-system pods found
I0819 11:35:57.626673 45737 system_pods.go:61] "etcd-scheduled-stop-869526" [d03d8a2c-0937-40fc-8907-450270151761] Running / Ready:ContainersNotReady (containers with unready status: [etcd]) / ContainersReady:ContainersNotReady (containers with unready status: [etcd])
I0819 11:35:57.626681 45737 system_pods.go:61] "kube-apiserver-scheduled-stop-869526" [0f84f484-1016-4ce4-b496-12cb7519d0e9] Running / Ready:ContainersNotReady (containers with unready status: [kube-apiserver]) / ContainersReady:ContainersNotReady (containers with unready status: [kube-apiserver])
I0819 11:35:57.626687 45737 system_pods.go:61] "kube-controller-manager-scheduled-stop-869526" [f6525403-c1db-4e19-95c6-5ed39f3d06ea] Running / Ready:ContainersNotReady (containers with unready status: [kube-controller-manager]) / ContainersReady:ContainersNotReady (containers with unready status: [kube-controller-manager])
I0819 11:35:57.626693 45737 system_pods.go:61] "kube-scheduler-scheduled-stop-869526" [4e016be7-f01b-40cf-9c11-ce4d0f585690] Running / Ready:ContainersNotReady (containers with unready status: [kube-scheduler]) / ContainersReady:ContainersNotReady (containers with unready status: [kube-scheduler])
I0819 11:35:57.626699 45737 system_pods.go:74] duration metric: took 13.502067ms to wait for pod list to return data ...
I0819 11:35:57.626709 45737 kubeadm.go:582] duration metric: took 711.789013ms to wait for: map[apiserver:true system_pods:true]
I0819 11:35:57.626722 45737 node_conditions.go:102] verifying NodePressure condition ...
I0819 11:35:57.630154 45737 node_conditions.go:122] node storage ephemeral capacity is 17734596Ki
I0819 11:35:57.630166 45737 node_conditions.go:123] node cpu capacity is 2
I0819 11:35:57.630174 45737 node_conditions.go:105] duration metric: took 3.449232ms to run NodePressure ...
I0819 11:35:57.630186 45737 start.go:241] waiting for startup goroutines ...
I0819 11:35:57.840999 45737 main.go:141] libmachine: Making call to close driver server
I0819 11:35:57.841013 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .Close
I0819 11:35:57.841052 45737 main.go:141] libmachine: Making call to close driver server
I0819 11:35:57.841064 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .Close
I0819 11:35:57.841351 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Closing plugin on server side
I0819 11:35:57.841379 45737 main.go:141] libmachine: Successfully made call to close driver server
I0819 11:35:57.841391 45737 main.go:141] libmachine: Making call to close connection to plugin binary
I0819 11:35:57.841398 45737 main.go:141] libmachine: Making call to close driver server
I0819 11:35:57.841405 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .Close
I0819 11:35:57.841406 45737 main.go:141] libmachine: Successfully made call to close driver server
I0819 11:35:57.841413 45737 main.go:141] libmachine: Making call to close connection to plugin binary
I0819 11:35:57.841419 45737 main.go:141] libmachine: Making call to close driver server
I0819 11:35:57.841416 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Closing plugin on server side
I0819 11:35:57.841424 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .Close
I0819 11:35:57.841600 45737 main.go:141] libmachine: Successfully made call to close driver server
I0819 11:35:57.841608 45737 main.go:141] libmachine: Making call to close connection to plugin binary
I0819 11:35:57.841710 45737 main.go:141] libmachine: Successfully made call to close driver server
I0819 11:35:57.841711 45737 main.go:141] libmachine: (scheduled-stop-869526) DBG | Closing plugin on server side
I0819 11:35:57.841718 45737 main.go:141] libmachine: Making call to close connection to plugin binary
I0819 11:35:57.848494 45737 main.go:141] libmachine: Making call to close driver server
I0819 11:35:57.848505 45737 main.go:141] libmachine: (scheduled-stop-869526) Calling .Close
I0819 11:35:57.848805 45737 main.go:141] libmachine: Successfully made call to close driver server
I0819 11:35:57.848815 45737 main.go:141] libmachine: Making call to close connection to plugin binary
I0819 11:35:57.850614 45737 out.go:177] * Enabled addons: storage-provisioner, default-storageclass
I0819 11:35:57.851688 45737 addons.go:510] duration metric: took 936.732559ms for enable addons: enabled=[storage-provisioner default-storageclass]
I0819 11:35:58.107776 45737 kapi.go:214] "coredns" deployment in "kube-system" namespace and "scheduled-stop-869526" context rescaled to 1 replicas
I0819 11:35:58.107802 45737 start.go:246] waiting for cluster config update ...
I0819 11:35:58.107810 45737 start.go:255] writing updated cluster config ...
I0819 11:35:58.108054 45737 ssh_runner.go:195] Run: rm -f paused
I0819 11:35:58.155573 45737 start.go:600] kubectl: 1.31.0, cluster: 1.31.0 (minor skew: 0)
I0819 11:35:58.157195 45737 out.go:177] * Done! kubectl is now configured to use "scheduled-stop-869526" cluster and "default" namespace by default
==> container status <==
CONTAINER IMAGE CREATED STATE NAME ATTEMPT POD ID POD
1a637cf28ebd5 1766f54c897f0 8 seconds ago Running kube-scheduler 0 73d4fe1fbd696 kube-scheduler-scheduled-stop-869526
826c94ca13ee1 2e96e5913fc06 8 seconds ago Running etcd 0 b64432be9e34b etcd-scheduled-stop-869526
8e55583994b75 604f5db92eaa8 8 seconds ago Running kube-apiserver 0 218f471fd5ba3 kube-apiserver-scheduled-stop-869526
6fabc625c41a5 045733566833c 8 seconds ago Running kube-controller-manager 0 a61e22074893b kube-controller-manager-scheduled-stop-869526
==> containerd <==
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.524532606Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.532022051Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.534878142Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.534952591Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.535033748Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.568091995Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-controller-manager-scheduled-stop-869526,Uid:8e557e37ffae06f2348f4717b04bc426,Namespace:kube-system,Attempt:0,} returns sandbox id \"a61e22074893b954054d4d8c1aa8ca32f7fe0e07ff6c4858d1a4190e784d1bbb\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.571839160Z" level=info msg="CreateContainer within sandbox \"a61e22074893b954054d4d8c1aa8ca32f7fe0e07ff6c4858d1a4190e784d1bbb\" for container &ContainerMetadata{Name:kube-controller-manager,Attempt:0,}"
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.627856108Z" level=info msg="CreateContainer within sandbox \"a61e22074893b954054d4d8c1aa8ca32f7fe0e07ff6c4858d1a4190e784d1bbb\" for &ContainerMetadata{Name:kube-controller-manager,Attempt:0,} returns container id \"6fabc625c41a5c1853160f460359bc68cd51fb2aab4fba08880b64b97cbb6e45\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.628916454Z" level=info msg="StartContainer for \"6fabc625c41a5c1853160f460359bc68cd51fb2aab4fba08880b64b97cbb6e45\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.666641269Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-apiserver-scheduled-stop-869526,Uid:48142e991b6f9e8b827dd5c382ce31e3,Namespace:kube-system,Attempt:0,} returns sandbox id \"218f471fd5ba3c74dfacf4acd0979de04a0115124001b588c85bd987188798b8\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.670520717Z" level=info msg="CreateContainer within sandbox \"218f471fd5ba3c74dfacf4acd0979de04a0115124001b588c85bd987188798b8\" for container &ContainerMetadata{Name:kube-apiserver,Attempt:0,}"
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.677958505Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:etcd-scheduled-stop-869526,Uid:6214ff9aee667fcd93fc4c7104c61e2a,Namespace:kube-system,Attempt:0,} returns sandbox id \"b64432be9e34b41e499c6e1b8fd9a53ba1c73f83b06fb1a6466e9bae2d90c022\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.682228812Z" level=info msg="CreateContainer within sandbox \"b64432be9e34b41e499c6e1b8fd9a53ba1c73f83b06fb1a6466e9bae2d90c022\" for container &ContainerMetadata{Name:etcd,Attempt:0,}"
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.701347737Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-scheduler-scheduled-stop-869526,Uid:5724da33765fc5909b99f07647298b45,Namespace:kube-system,Attempt:0,} returns sandbox id \"73d4fe1fbd696b733d86529f7b706f1394ea81598db05a3ad30876523171be27\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.715564356Z" level=info msg="CreateContainer within sandbox \"218f471fd5ba3c74dfacf4acd0979de04a0115124001b588c85bd987188798b8\" for &ContainerMetadata{Name:kube-apiserver,Attempt:0,} returns container id \"8e55583994b7562fd65570ff128345a8e756a073bad298f4841088699f883a46\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.718493692Z" level=info msg="StartContainer for \"8e55583994b7562fd65570ff128345a8e756a073bad298f4841088699f883a46\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.723180895Z" level=info msg="CreateContainer within sandbox \"73d4fe1fbd696b733d86529f7b706f1394ea81598db05a3ad30876523171be27\" for container &ContainerMetadata{Name:kube-scheduler,Attempt:0,}"
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.744699248Z" level=info msg="CreateContainer within sandbox \"b64432be9e34b41e499c6e1b8fd9a53ba1c73f83b06fb1a6466e9bae2d90c022\" for &ContainerMetadata{Name:etcd,Attempt:0,} returns container id \"826c94ca13ee18443a8cd2c2f9c57c0e1b316acf3511672450ab75dfa69d6bbf\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.745544726Z" level=info msg="StartContainer for \"826c94ca13ee18443a8cd2c2f9c57c0e1b316acf3511672450ab75dfa69d6bbf\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.765093897Z" level=info msg="CreateContainer within sandbox \"73d4fe1fbd696b733d86529f7b706f1394ea81598db05a3ad30876523171be27\" for &ContainerMetadata{Name:kube-scheduler,Attempt:0,} returns container id \"1a637cf28ebd55c5a347c6997166aa108195f2341c5cdbf8520f0540814c86ab\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.765897723Z" level=info msg="StartContainer for \"1a637cf28ebd55c5a347c6997166aa108195f2341c5cdbf8520f0540814c86ab\""
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.800095647Z" level=info msg="StartContainer for \"6fabc625c41a5c1853160f460359bc68cd51fb2aab4fba08880b64b97cbb6e45\" returns successfully"
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.895597499Z" level=info msg="StartContainer for \"826c94ca13ee18443a8cd2c2f9c57c0e1b316acf3511672450ab75dfa69d6bbf\" returns successfully"
Aug 19 11:35:50 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:50.941320192Z" level=info msg="StartContainer for \"8e55583994b7562fd65570ff128345a8e756a073bad298f4841088699f883a46\" returns successfully"
Aug 19 11:35:51 scheduled-stop-869526 containerd[641]: time="2024-08-19T11:35:51.027981451Z" level=info msg="StartContainer for \"1a637cf28ebd55c5a347c6997166aa108195f2341c5cdbf8520f0540814c86ab\" returns successfully"
==> describe nodes <==
Name: scheduled-stop-869526
Roles: control-plane
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=scheduled-stop-869526
kubernetes.io/os=linux
minikube.k8s.io/commit=7871dd89d2a8218fd3bbcc542b116f963c0d9934
minikube.k8s.io/name=scheduled-stop-869526
minikube.k8s.io/primary=true
minikube.k8s.io/updated_at=2024_08_19T11_35_56_0700
minikube.k8s.io/version=v1.33.1
node-role.kubernetes.io/control-plane=
node.kubernetes.io/exclude-from-external-load-balancers=
Annotations: kubeadm.alpha.kubernetes.io/cri-socket: unix:///run/containerd/containerd.sock
volumes.kubernetes.io/controller-managed-attach-detach: true
CreationTimestamp: Mon, 19 Aug 2024 11:35:53 +0000
Taints: node.kubernetes.io/not-ready:NoSchedule
Unschedulable: false
Lease:
HolderIdentity: scheduled-stop-869526
AcquireTime: <unset>
RenewTime: Mon, 19 Aug 2024 11:35:55 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Mon, 19 Aug 2024 11:35:56 +0000 Mon, 19 Aug 2024 11:35:51 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Mon, 19 Aug 2024 11:35:56 +0000 Mon, 19 Aug 2024 11:35:51 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Mon, 19 Aug 2024 11:35:56 +0000 Mon, 19 Aug 2024 11:35:51 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready True Mon, 19 Aug 2024 11:35:56 +0000 Mon, 19 Aug 2024 11:35:56 +0000 KubeletReady kubelet is posting ready status
Addresses:
InternalIP: 192.168.39.25
Hostname: scheduled-stop-869526
Capacity:
cpu: 2
ephemeral-storage: 17734596Ki
hugepages-2Mi: 0
memory: 2015704Ki
pods: 110
Allocatable:
cpu: 2
ephemeral-storage: 17734596Ki
hugepages-2Mi: 0
memory: 2015704Ki
pods: 110
System Info:
Machine ID: 31bdd61437044a9295aaf5a33a37c486
System UUID: 31bdd614-3704-4a92-95aa-f5a33a37c486
Boot ID: 6f0d8a79-86ad-4b93-aad2-feb8fd258951
Kernel Version: 5.10.207
OS Image: Buildroot 2023.02.9
Operating System: linux
Architecture: amd64
Container Runtime Version: containerd://1.7.20
Kubelet Version: v1.31.0
Kube-Proxy Version:
Non-terminated Pods: (4 in total)
Namespace Name CPU Requests CPU Limits Memory Requests Memory Limits Age
--------- ---- ------------ ---------- --------------- ------------- ---
kube-system etcd-scheduled-stop-869526 100m (5%) 0 (0%) 100Mi (5%) 0 (0%) 5s
kube-system kube-apiserver-scheduled-stop-869526 250m (12%) 0 (0%) 0 (0%) 0 (0%) 3s
kube-system kube-controller-manager-scheduled-stop-869526 200m (10%) 0 (0%) 0 (0%) 0 (0%) 3s
kube-system kube-scheduler-scheduled-stop-869526 100m (5%) 0 (0%) 0 (0%) 0 (0%) 5s
Allocated resources:
(Total limits may be over 100 percent, i.e., overcommitted.)
Resource Requests Limits
-------- -------- ------
cpu 650m (32%) 0 (0%)
memory 100Mi (5%) 0 (0%)
ephemeral-storage 0 (0%) 0 (0%)
hugepages-2Mi 0 (0%) 0 (0%)
Events:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal Starting 10s kubelet Starting kubelet.
Normal NodeHasSufficientMemory 9s (x8 over 10s) kubelet Node scheduled-stop-869526 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 9s (x8 over 10s) kubelet Node scheduled-stop-869526 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 9s (x7 over 10s) kubelet Node scheduled-stop-869526 status is now: NodeHasSufficientPID
Normal NodeAllocatableEnforced 9s kubelet Updated Node Allocatable limit across pods
Normal Starting 4s kubelet Starting kubelet.
Normal NodeAllocatableEnforced 4s kubelet Updated Node Allocatable limit across pods
Normal NodeHasSufficientMemory 3s kubelet Node scheduled-stop-869526 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 3s kubelet Node scheduled-stop-869526 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 3s kubelet Node scheduled-stop-869526 status is now: NodeHasSufficientPID
Normal NodeReady 3s kubelet Node scheduled-stop-869526 status is now: NodeReady
==> dmesg <==
[Aug19 11:35] You have booted with nomodeset. This means your GPU drivers are DISABLED
[ +0.000001] Any video related functionality will be severely degraded, and you may not even be able to suspend the system properly
[ +0.000000] Unless you actually understand what nomodeset does, you should reboot without enabling it
[ +0.049045] Spectre V2 : WARNING: Unprivileged eBPF is enabled with eIBRS on, data leaks possible via Spectre v2 BHB attacks!
[ +0.039027] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ +4.771115] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ +2.512815] systemd-fstab-generator[116]: Ignoring "noauto" option for root device
[ +4.642765] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ +0.000007] NFSD: unable to find recovery directory /var/lib/nfs/v4recovery
[ +0.000001] NFSD: Unable to initialize client recovery tracking! (-2)
[ +8.638082] systemd-fstab-generator[505]: Ignoring "noauto" option for root device
[ +0.064817] kauditd_printk_skb: 1 callbacks suppressed
[ +0.059490] systemd-fstab-generator[517]: Ignoring "noauto" option for root device
[ +0.191273] systemd-fstab-generator[532]: Ignoring "noauto" option for root device
[ +0.118192] systemd-fstab-generator[544]: Ignoring "noauto" option for root device
[ +0.285970] systemd-fstab-generator[575]: Ignoring "noauto" option for root device
[ +4.785757] systemd-fstab-generator[633]: Ignoring "noauto" option for root device
[ +0.061045] kauditd_printk_skb: 158 callbacks suppressed
[ +0.590757] systemd-fstab-generator[680]: Ignoring "noauto" option for root device
[ +4.191672] systemd-fstab-generator[816]: Ignoring "noauto" option for root device
[ +0.375115] kauditd_printk_skb: 74 callbacks suppressed
[ +5.687047] systemd-fstab-generator[1178]: Ignoring "noauto" option for root device
[ +0.080987] kauditd_printk_skb: 41 callbacks suppressed
[ +1.220211] systemd-fstab-generator[1240]: Ignoring "noauto" option for root device
==> etcd [826c94ca13ee18443a8cd2c2f9c57c0e1b316acf3511672450ab75dfa69d6bbf] <==
{"level":"info","ts":"2024-08-19T11:35:51.047330Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/minikube/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2024-08-19T11:35:51.044204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 switched to configuration voters=(5095510705843290384)"}
{"level":"info","ts":"2024-08-19T11:35:51.047906Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5f955826d71045b","local-member-id":"46b6e3fd62fd4110","added-peer-id":"46b6e3fd62fd4110","added-peer-peer-urls":["https://192.168.39.25:2380"]}
{"level":"info","ts":"2024-08-19T11:35:51.044261Z","caller":"embed/etcd.go:599","msg":"serving peer traffic","address":"192.168.39.25:2380"}
{"level":"info","ts":"2024-08-19T11:35:51.048143Z","caller":"embed/etcd.go:571","msg":"cmux::serve","address":"192.168.39.25:2380"}
{"level":"info","ts":"2024-08-19T11:35:51.179956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 is starting a new election at term 1"}
{"level":"info","ts":"2024-08-19T11:35:51.180236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 became pre-candidate at term 1"}
{"level":"info","ts":"2024-08-19T11:35:51.180267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 received MsgPreVoteResp from 46b6e3fd62fd4110 at term 1"}
{"level":"info","ts":"2024-08-19T11:35:51.180359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 became candidate at term 2"}
{"level":"info","ts":"2024-08-19T11:35:51.180378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 received MsgVoteResp from 46b6e3fd62fd4110 at term 2"}
{"level":"info","ts":"2024-08-19T11:35:51.180481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"46b6e3fd62fd4110 became leader at term 2"}
{"level":"info","ts":"2024-08-19T11:35:51.180585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 46b6e3fd62fd4110 elected leader 46b6e3fd62fd4110 at term 2"}
{"level":"info","ts":"2024-08-19T11:35:51.182215Z","caller":"etcdserver/server.go:2118","msg":"published local member to cluster through raft","local-member-id":"46b6e3fd62fd4110","local-member-attributes":"{Name:scheduled-stop-869526 ClientURLs:[https://192.168.39.25:2379]}","request-path":"/0/members/46b6e3fd62fd4110/attributes","cluster-id":"f5f955826d71045b","publish-timeout":"7s"}
{"level":"info","ts":"2024-08-19T11:35:51.182602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2024-08-19T11:35:51.183019Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2024-08-19T11:35:51.183116Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2024-08-19T11:35:51.182602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2024-08-19T11:35:51.182684Z","caller":"etcdserver/server.go:2629","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2024-08-19T11:35:51.185860Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
{"level":"info","ts":"2024-08-19T11:35:51.188999Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
{"level":"info","ts":"2024-08-19T11:35:51.191034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2024-08-19T11:35:51.195892Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5f955826d71045b","local-member-id":"46b6e3fd62fd4110","cluster-version":"3.5"}
{"level":"info","ts":"2024-08-19T11:35:51.196135Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2024-08-19T11:35:51.196196Z","caller":"etcdserver/server.go:2653","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2024-08-19T11:35:51.196287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"192.168.39.25:2379"}
==> kernel <==
11:35:59 up 0 min, 0 users, load average: 0.54, 0.13, 0.04
Linux scheduled-stop-869526 5.10.207 #1 SMP Thu Aug 15 21:30:57 UTC 2024 x86_64 GNU/Linux
PRETTY_NAME="Buildroot 2023.02.9"
==> kube-apiserver [8e55583994b7562fd65570ff128345a8e756a073bad298f4841088699f883a46] <==
I0819 11:35:53.153802 1 shared_informer.go:320] Caches are synced for crd-autoregister
I0819 11:35:53.153968 1 aggregator.go:171] initial CRD sync complete...
I0819 11:35:53.154010 1 autoregister_controller.go:144] Starting autoregister controller
I0819 11:35:53.154028 1 cache.go:32] Waiting for caches to sync for autoregister controller
I0819 11:35:53.154045 1 cache.go:39] Caches are synced for autoregister controller
I0819 11:35:53.168649 1 shared_informer.go:320] Caches are synced for node_authorizer
E0819 11:35:53.177392 1 controller.go:145] "Failed to ensure lease exists, will retry" err="namespaces \"kube-system\" not found" interval="200ms"
E0819 11:35:53.178815 1 controller.go:148] "Unhandled Error" err="while syncing ConfigMap \"kube-system/kube-apiserver-legacy-service-account-token-tracking\", err: namespaces \"kube-system\" not found" logger="UnhandledError"
I0819 11:35:53.192250 1 shared_informer.go:320] Caches are synced for *generic.policySource[*k8s.io/api/admissionregistration/v1.ValidatingAdmissionPolicy,*k8s.io/api/admissionregistration/v1.ValidatingAdmissionPolicyBinding,k8s.io/apiserver/pkg/admission/plugin/policy/validating.Validator]
I0819 11:35:53.192336 1 policy_source.go:224] refreshing policies
I0819 11:35:53.225439 1 controller.go:615] quota admission added evaluator for: namespaces
I0819 11:35:53.383095 1 controller.go:615] quota admission added evaluator for: leases.coordination.k8s.io
I0819 11:35:54.028927 1 storage_scheduling.go:95] created PriorityClass system-node-critical with value 2000001000
I0819 11:35:54.037092 1 storage_scheduling.go:95] created PriorityClass system-cluster-critical with value 2000000000
I0819 11:35:54.037125 1 storage_scheduling.go:111] all system priority classes are created successfully or already exist.
I0819 11:35:54.603607 1 controller.go:615] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I0819 11:35:54.644631 1 controller.go:615] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
I0819 11:35:54.735591 1 alloc.go:330] "allocated clusterIPs" service="default/kubernetes" clusterIPs={"IPv4":"10.96.0.1"}
W0819 11:35:54.744585 1 lease.go:265] Resetting endpoints for master service "kubernetes" to [192.168.39.25]
I0819 11:35:54.746208 1 controller.go:615] quota admission added evaluator for: endpoints
I0819 11:35:54.755323 1 controller.go:615] quota admission added evaluator for: endpointslices.discovery.k8s.io
I0819 11:35:55.088557 1 controller.go:615] quota admission added evaluator for: serviceaccounts
I0819 11:35:56.010920 1 controller.go:615] quota admission added evaluator for: deployments.apps
I0819 11:35:56.037689 1 alloc.go:330] "allocated clusterIPs" service="kube-system/kube-dns" clusterIPs={"IPv4":"10.96.0.10"}
I0819 11:35:56.056095 1 controller.go:615] quota admission added evaluator for: daemonsets.apps
==> kube-controller-manager [6fabc625c41a5c1853160f460359bc68cd51fb2aab4fba08880b64b97cbb6e45] <==
I0819 11:35:58.936694 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-kubelet-client
I0819 11:35:58.936705 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/minikube/certs/ca.crt::/var/lib/minikube/certs/ca.key"
I0819 11:35:58.936839 1 controllermanager.go:797] "Started controller" controller="certificatesigningrequest-signing-controller"
I0819 11:35:58.936913 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-signing-controller" name="csrsigning-kube-apiserver-client"
I0819 11:35:58.936928 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-kube-apiserver-client
I0819 11:35:58.936967 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-signing-controller" name="csrsigning-legacy-unknown"
I0819 11:35:58.936972 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-legacy-unknown
I0819 11:35:58.936983 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/minikube/certs/ca.crt::/var/lib/minikube/certs/ca.key"
I0819 11:35:58.936990 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/minikube/certs/ca.crt::/var/lib/minikube/certs/ca.key"
I0819 11:35:59.088137 1 controllermanager.go:797] "Started controller" controller="bootstrap-signer-controller"
I0819 11:35:59.088157 1 controllermanager.go:749] "Controller is disabled by a feature gate" controller="resourceclaim-controller" requiredFeatureGates=["DynamicResourceAllocation"]
I0819 11:35:59.088218 1 shared_informer.go:313] Waiting for caches to sync for bootstrap_signer
I0819 11:35:59.136218 1 controllermanager.go:797] "Started controller" controller="certificatesigningrequest-cleaner-controller"
I0819 11:35:59.136270 1 cleaner.go:83] "Starting CSR cleaner controller" logger="certificatesigningrequest-cleaner-controller"
I0819 11:35:59.288845 1 controllermanager.go:797] "Started controller" controller="daemonset-controller"
I0819 11:35:59.288999 1 daemon_controller.go:294] "Starting daemon sets controller" logger="daemonset-controller"
I0819 11:35:59.289013 1 shared_informer.go:313] Waiting for caches to sync for daemon sets
I0819 11:35:59.438394 1 controllermanager.go:797] "Started controller" controller="job-controller"
I0819 11:35:59.438415 1 core.go:298] "Warning: configure-cloud-routes is set, but no cloud provider specified. Will not configure cloud provider routes." logger="node-route-controller"
I0819 11:35:59.438422 1 controllermanager.go:775] "Warning: skipping controller" controller="node-route-controller"
I0819 11:35:59.438489 1 job_controller.go:226] "Starting job controller" logger="job-controller"
I0819 11:35:59.438497 1 shared_informer.go:313] Waiting for caches to sync for job
I0819 11:35:59.588014 1 controllermanager.go:797] "Started controller" controller="persistentvolume-attach-detach-controller"
I0819 11:35:59.588122 1 attach_detach_controller.go:338] "Starting attach detach controller" logger="persistentvolume-attach-detach-controller"
I0819 11:35:59.588134 1 shared_informer.go:313] Waiting for caches to sync for attach detach
==> kube-scheduler [1a637cf28ebd55c5a347c6997166aa108195f2341c5cdbf8520f0540814c86ab] <==
W0819 11:35:53.143080 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
E0819 11:35:53.144356 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.PersistentVolumeClaim: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User \"system:kube-scheduler\" cannot list resource \"persistentvolumeclaims\" in API group \"\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.015996 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
E0819 11:35:54.016088 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Namespace: failed to list *v1.Namespace: namespaces is forbidden: User \"system:kube-scheduler\" cannot list resource \"namespaces\" in API group \"\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.048186 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User "system:kube-scheduler" cannot list resource "replicasets" in API group "apps" at the cluster scope
E0819 11:35:54.048236 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.ReplicaSet: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User \"system:kube-scheduler\" cannot list resource \"replicasets\" in API group \"apps\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.061391 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E0819 11:35:54.061450 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.PodDisruptionBudget: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User \"system:kube-scheduler\" cannot list resource \"poddisruptionbudgets\" in API group \"policy\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.245830 1 reflector.go:561] runtime/asm_amd64.s:1695: failed to list *v1.ConfigMap: configmaps "extension-apiserver-authentication" is forbidden: User "system:kube-scheduler" cannot list resource "configmaps" in API group "" in the namespace "kube-system"
E0819 11:35:54.245878 1 reflector.go:158] "Unhandled Error" err="runtime/asm_amd64.s:1695: Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps \"extension-apiserver-authentication\" is forbidden: User \"system:kube-scheduler\" cannot list resource \"configmaps\" in API group \"\" in the namespace \"kube-system\"" logger="UnhandledError"
W0819 11:35:54.249417 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
E0819 11:35:54.249462 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:kube-scheduler\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.276240 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
E0819 11:35:54.276344 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.PersistentVolume: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User \"system:kube-scheduler\" cannot list resource \"persistentvolumes\" in API group \"\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.348848 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIStorageCapacity: csistoragecapacities.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csistoragecapacities" in API group "storage.k8s.io" at the cluster scope
E0819 11:35:54.348904 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIStorageCapacity: failed to list *v1.CSIStorageCapacity: csistoragecapacities.storage.k8s.io is forbidden: User \"system:kube-scheduler\" cannot list resource \"csistoragecapacities\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.365115 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
E0819 11:35:54.365171 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:kube-scheduler\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.371052 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Pod: pods is forbidden: User "system:kube-scheduler" cannot list resource "pods" in API group "" at the cluster scope
E0819 11:35:54.371097 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Pod: failed to list *v1.Pod: pods is forbidden: User \"system:kube-scheduler\" cannot list resource \"pods\" in API group \"\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.411701 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
E0819 11:35:54.411848 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes is forbidden: User \"system:kube-scheduler\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError"
W0819 11:35:54.412299 1 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E0819 11:35:54.412321 1 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User \"system:kube-scheduler\" cannot list resource \"statefulsets\" in API group \"apps\" at the cluster scope" logger="UnhandledError"
I0819 11:35:57.434211 1 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
==> kubelet <==
Aug 19 11:35:55 scheduled-stop-869526 kubelet[1185]: I0819 11:35:55.991185 1185 plugin_manager.go:118] "Starting Kubelet Plugin Manager"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: E0819 11:35:56.045855 1185 kubelet.go:1915] "Failed creating a mirror pod for" err="pods \"etcd-scheduled-stop-869526\" already exists" pod="kube-system/etcd-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: E0819 11:35:56.047658 1185 kubelet.go:1915] "Failed creating a mirror pod for" err="pods \"kube-scheduler-scheduled-stop-869526\" already exists" pod="kube-system/kube-scheduler-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.093832 1185 kubelet_node_status.go:72] "Attempting to register node" node="scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.109186 1185 kubelet_node_status.go:111] "Node was previously registered" node="scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.109320 1185 kubelet_node_status.go:75] "Successfully registered node" node="scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.201636 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flexvolume-dir\" (UniqueName: \"kubernetes.io/host-path/8e557e37ffae06f2348f4717b04bc426-flexvolume-dir\") pod \"kube-controller-manager-scheduled-stop-869526\" (UID: \"8e557e37ffae06f2348f4717b04bc426\") " pod="kube-system/kube-controller-manager-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.201863 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/8e557e37ffae06f2348f4717b04bc426-kubeconfig\") pod \"kube-controller-manager-scheduled-stop-869526\" (UID: \"8e557e37ffae06f2348f4717b04bc426\") " pod="kube-system/kube-controller-manager-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.201966 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/8e557e37ffae06f2348f4717b04bc426-usr-share-ca-certificates\") pod \"kube-controller-manager-scheduled-stop-869526\" (UID: \"8e557e37ffae06f2348f4717b04bc426\") " pod="kube-system/kube-controller-manager-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202030 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"etcd-data\" (UniqueName: \"kubernetes.io/host-path/6214ff9aee667fcd93fc4c7104c61e2a-etcd-data\") pod \"etcd-scheduled-stop-869526\" (UID: \"6214ff9aee667fcd93fc4c7104c61e2a\") " pod="kube-system/etcd-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202075 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/48142e991b6f9e8b827dd5c382ce31e3-k8s-certs\") pod \"kube-apiserver-scheduled-stop-869526\" (UID: \"48142e991b6f9e8b827dd5c382ce31e3\") " pod="kube-system/kube-apiserver-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202212 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/48142e991b6f9e8b827dd5c382ce31e3-usr-share-ca-certificates\") pod \"kube-apiserver-scheduled-stop-869526\" (UID: \"48142e991b6f9e8b827dd5c382ce31e3\") " pod="kube-system/kube-apiserver-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202307 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/8e557e37ffae06f2348f4717b04bc426-ca-certs\") pod \"kube-controller-manager-scheduled-stop-869526\" (UID: \"8e557e37ffae06f2348f4717b04bc426\") " pod="kube-system/kube-controller-manager-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202387 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/8e557e37ffae06f2348f4717b04bc426-k8s-certs\") pod \"kube-controller-manager-scheduled-stop-869526\" (UID: \"8e557e37ffae06f2348f4717b04bc426\") " pod="kube-system/kube-controller-manager-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202436 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/5724da33765fc5909b99f07647298b45-kubeconfig\") pod \"kube-scheduler-scheduled-stop-869526\" (UID: \"5724da33765fc5909b99f07647298b45\") " pod="kube-system/kube-scheduler-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202482 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"etcd-certs\" (UniqueName: \"kubernetes.io/host-path/6214ff9aee667fcd93fc4c7104c61e2a-etcd-certs\") pod \"etcd-scheduled-stop-869526\" (UID: \"6214ff9aee667fcd93fc4c7104c61e2a\") " pod="kube-system/etcd-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.202530 1185 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/48142e991b6f9e8b827dd5c382ce31e3-ca-certs\") pod \"kube-apiserver-scheduled-stop-869526\" (UID: \"48142e991b6f9e8b827dd5c382ce31e3\") " pod="kube-system/kube-apiserver-scheduled-stop-869526"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.741226 1185 kubelet_node_status.go:488] "Fast updating node status as it just became ready"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.881205 1185 apiserver.go:52] "Watching apiserver"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: I0819 11:35:56.900349 1185 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world"
Aug 19 11:35:56 scheduled-stop-869526 kubelet[1185]: E0819 11:35:56.970488 1185 kubelet.go:1915] "Failed creating a mirror pod for" err="pods \"kube-apiserver-scheduled-stop-869526\" already exists" pod="kube-system/kube-apiserver-scheduled-stop-869526"
Aug 19 11:35:57 scheduled-stop-869526 kubelet[1185]: I0819 11:35:57.012968 1185 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-controller-manager-scheduled-stop-869526" podStartSLOduration=1.012925779 podStartE2EDuration="1.012925779s" podCreationTimestamp="2024-08-19 11:35:56 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2024-08-19 11:35:56.997871773 +0000 UTC m=+1.202716946" watchObservedRunningTime="2024-08-19 11:35:57.012925779 +0000 UTC m=+1.217770949"
Aug 19 11:35:57 scheduled-stop-869526 kubelet[1185]: I0819 11:35:57.024732 1185 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-scheduler-scheduled-stop-869526" podStartSLOduration=3.024715098 podStartE2EDuration="3.024715098s" podCreationTimestamp="2024-08-19 11:35:54 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2024-08-19 11:35:57.013229509 +0000 UTC m=+1.218074686" watchObservedRunningTime="2024-08-19 11:35:57.024715098 +0000 UTC m=+1.229560268"
Aug 19 11:35:57 scheduled-stop-869526 kubelet[1185]: I0819 11:35:57.091926 1185 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/etcd-scheduled-stop-869526" podStartSLOduration=3.091907147 podStartE2EDuration="3.091907147s" podCreationTimestamp="2024-08-19 11:35:54 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2024-08-19 11:35:57.025823116 +0000 UTC m=+1.230668293" watchObservedRunningTime="2024-08-19 11:35:57.091907147 +0000 UTC m=+1.296752315"
Aug 19 11:35:57 scheduled-stop-869526 kubelet[1185]: I0819 11:35:57.092360 1185 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-apiserver-scheduled-stop-869526" podStartSLOduration=1.092351482 podStartE2EDuration="1.092351482s" podCreationTimestamp="2024-08-19 11:35:56 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2024-08-19 11:35:57.092237419 +0000 UTC m=+1.297082584" watchObservedRunningTime="2024-08-19 11:35:57.092351482 +0000 UTC m=+1.297196660"
-- /stdout --
helpers_test.go:254: (dbg) Run: out/minikube-linux-amd64 status --format={{.APIServer}} -p scheduled-stop-869526 -n scheduled-stop-869526
helpers_test.go:261: (dbg) Run: kubectl --context scheduled-stop-869526 get po -o=jsonpath={.items[*].metadata.name} -A --field-selector=status.phase!=Running
helpers_test.go:272: non-running pods: storage-provisioner
helpers_test.go:274: ======> post-mortem[TestScheduledStopUnix]: describe non-running pods <======
helpers_test.go:277: (dbg) Run: kubectl --context scheduled-stop-869526 describe pod storage-provisioner
helpers_test.go:277: (dbg) Non-zero exit: kubectl --context scheduled-stop-869526 describe pod storage-provisioner: exit status 1 (64.836245ms)
** stderr **
Error from server (NotFound): pods "storage-provisioner" not found
** /stderr **
helpers_test.go:279: kubectl --context scheduled-stop-869526 describe pod storage-provisioner: exit status 1
helpers_test.go:175: Cleaning up "scheduled-stop-869526" profile ...
helpers_test.go:178: (dbg) Run: out/minikube-linux-amd64 delete -p scheduled-stop-869526
--- FAIL: TestScheduledStopUnix (46.41s)