=== RUN   TestAddons/serial/GCPAuth/PullSecret

addons_test.go:614: (dbg) Run:  kubectl --context addons-403820 create -f testdata/busybox.yaml

addons_test.go:621: (dbg) Run:  kubectl --context addons-403820 create sa gcp-auth-test

addons_test.go:627: (dbg) TestAddons/serial/GCPAuth/PullSecret: waiting 8m0s for pods matching "integration-test=busybox" in namespace "default" ...

helpers_test.go:344: "busybox" [4f24bd3c-2e0c-4bb9-8eb6-2c19aabf6e81] Pending / Ready:ContainersNotReady (containers with unready status: [busybox]) / ContainersReady:ContainersNotReady (containers with unready status: [busybox])

helpers_test.go:329: TestAddons/serial/GCPAuth/PullSecret: WARNING: pod list for "default" "integration-test=busybox" returned: client rate limiter Wait returned an error: context deadline exceeded

addons_test.go:627: ***** TestAddons/serial/GCPAuth/PullSecret: pod "integration-test=busybox" failed to start within 8m0s: context deadline exceeded ****

addons_test.go:627: (dbg) Run:  out/minikube-linux-amd64 status --format={{.APIServer}} -p addons-403820 -n addons-403820

addons_test.go:627: TestAddons/serial/GCPAuth/PullSecret: showing logs for failed pods as of 2024-10-08 17:42:54.278024005 +0000 UTC m=+779.541241130

addons_test.go:627: (dbg) Run:  kubectl --context addons-403820 describe po busybox -n default

addons_test.go:627: (dbg) kubectl --context addons-403820 describe po busybox -n default:

Name:             busybox

Namespace:        default

Priority:         0

Service Account:  default

Node:             addons-403820/192.168.39.88

Start Time:       Tue, 08 Oct 2024 17:34:53 +0000

Labels:           integration-test=busybox

Annotations:      <none>

Status:           Pending

IP:               10.244.0.28

IPs:

IP:  10.244.0.28

Containers:

busybox:

Container ID:  

Image:         gcr.io/k8s-minikube/busybox:1.28.4-glibc

Image ID:      

Port:          <none>

Host Port:     <none>

Command:

sleep

3600

State:          Waiting

Reason:       ImagePullBackOff

Ready:          False

Restart Count:  0

Environment:

GOOGLE_APPLICATION_CREDENTIALS:  /google-app-creds.json

PROJECT_ID:                      this_is_fake

GCP_PROJECT:                     this_is_fake

GCLOUD_PROJECT:                  this_is_fake

GOOGLE_CLOUD_PROJECT:            this_is_fake

CLOUDSDK_CORE_PROJECT:           this_is_fake

Mounts:

/google-app-creds.json from gcp-creds (ro)

/var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-n8wz9 (ro)

Conditions:

Type                        Status

PodReadyToStartContainers   True 

Initialized                 True 

Ready                       False 

ContainersReady             False 

PodScheduled                True 

Volumes:

kube-api-access-n8wz9:

Type:                    Projected (a volume that contains injected data from multiple sources)

TokenExpirationSeconds:  3607

ConfigMapName:           kube-root-ca.crt

ConfigMapOptional:       <nil>

DownwardAPI:             true

gcp-creds:

Type:          HostPath (bare host directory volume)

Path:          /var/lib/minikube/google_application_credentials.json

HostPathType:  File

QoS Class:         BestEffort

Node-Selectors:    <none>

Tolerations:       node.kubernetes.io/not-ready:NoExecute op=Exists for 300s

node.kubernetes.io/unreachable:NoExecute op=Exists for 300s

Events:

Type     Reason          Age                     From               Message

----     ------          ----                    ----               -------

Normal   Scheduled       8m1s                    default-scheduler  Successfully assigned default/busybox to addons-403820

Normal   SandboxChanged  7m59s                   kubelet            Pod sandbox changed, it will be killed and re-created.

Warning  Failed          6m39s (x6 over 7m58s)   kubelet            Error: ImagePullBackOff

Normal   Pulling         6m28s (x4 over 8m)      kubelet            Pulling image "gcr.io/k8s-minikube/busybox:1.28.4-glibc"

Warning  Failed          6m28s (x4 over 7m59s)   kubelet            Failed to pull image "gcr.io/k8s-minikube/busybox:1.28.4-glibc": Error response from daemon: Head "https://gcr.io/v2/k8s-minikube/busybox/manifests/1.28.4-glibc": unauthorized: authentication failed

Warning  Failed          6m28s (x4 over 7m59s)   kubelet            Error: ErrImagePull

Normal   BackOff         2m48s (x22 over 7m58s)  kubelet            Back-off pulling image "gcr.io/k8s-minikube/busybox:1.28.4-glibc"

addons_test.go:627: (dbg) Run:  kubectl --context addons-403820 logs busybox -n default

addons_test.go:627: (dbg) Non-zero exit: kubectl --context addons-403820 logs busybox -n default: exit status 1 (69.933627ms)

** stderr ** 

	Error from server (BadRequest): container "busybox" in pod "busybox" is waiting to start: trying and failing to pull image

** /stderr **

addons_test.go:627: kubectl --context addons-403820 logs busybox -n default: exit status 1

addons_test.go:629: wait: integration-test=busybox within 8m0s: context deadline exceeded

--- FAIL: TestAddons/serial/GCPAuth/PullSecret (480.59s)

=== RUN   TestDownloadOnly/v1.20.0/json-events

aaa_download_only_test.go:81: (dbg) Run:  out/minikube-linux-amd64 start -o=json --download-only -p download-only-527623 --force --alsologtostderr --kubernetes-version=v1.20.0 --container-runtime=docker --driver=kvm2 

aaa_download_only_test.go:81: (dbg) Done: out/minikube-linux-amd64 start -o=json --download-only -p download-only-527623 --force --alsologtostderr --kubernetes-version=v1.20.0 --container-runtime=docker --driver=kvm2 : (25.473755513s)

--- PASS: TestDownloadOnly/v1.20.0/json-events (25.47s)

=== RUN   TestDownloadOnly/v1.20.0/preload-exists

I1008 17:30:20.252841  386147 preload.go:131] Checking if preload exists for k8s version v1.20.0 and runtime docker

I1008 17:30:20.252955  386147 preload.go:146] Found local preload: /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4

--- PASS: TestDownloadOnly/v1.20.0/preload-exists (0.00s)

=== RUN   TestDownloadOnly/v1.20.0/LogsDuration

aaa_download_only_test.go:184: (dbg) Run:  out/minikube-linux-amd64 logs -p download-only-527623

aaa_download_only_test.go:184: (dbg) Non-zero exit: out/minikube-linux-amd64 logs -p download-only-527623: exit status 85 (66.10847ms)

-- stdout --

	==> Audit <==

	|---------|--------------------------------|----------------------|---------|---------|---------------------|----------|

	| Command |              Args              |       Profile        |  User   | Version |     Start Time      | End Time |

	|---------|--------------------------------|----------------------|---------|---------|---------------------|----------|

	| start   | -o=json --download-only        | download-only-527623 | jenkins | v1.34.0 | 08 Oct 24 17:29 UTC |          |

	|         | -p download-only-527623        |                      |         |         |                     |          |

	|         | --force --alsologtostderr      |                      |         |         |                     |          |

	|         | --kubernetes-version=v1.20.0   |                      |         |         |                     |          |

	|         | --container-runtime=docker     |                      |         |         |                     |          |

	|         | --driver=kvm2                  |                      |         |         |                     |          |

	|---------|--------------------------------|----------------------|---------|---------|---------------------|----------|

	==> Last Start <==

	Log file created at: 2024/10/08 17:29:54

	Running on machine: ubuntu-20-agent-6

	Binary: Built with gc go1.23.1 for linux/amd64

	Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg

	I1008 17:29:54.822799  386159 out.go:345] Setting OutFile to fd 1 ...

	I1008 17:29:54.823055  386159 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:29:54.823064  386159 out.go:358] Setting ErrFile to fd 2...

	I1008 17:29:54.823069  386159 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:29:54.823247  386159 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

	W1008 17:29:54.823410  386159 root.go:314] Error reading config file at /home/jenkins/minikube-integration/19774-378864/.minikube/config/config.json: open /home/jenkins/minikube-integration/19774-378864/.minikube/config/config.json: no such file or directory

	I1008 17:29:54.824027  386159 out.go:352] Setting JSON to true

	I1008 17:29:54.825006  386159 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-6","uptime":4342,"bootTime":1728404253,"procs":173,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1069-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}

	I1008 17:29:54.825123  386159 start.go:139] virtualization: kvm guest

	I1008 17:29:54.827477  386159 out.go:97] [download-only-527623] minikube v1.34.0 on Ubuntu 20.04 (kvm/amd64)

	W1008 17:29:54.827591  386159 preload.go:293] Failed to list preload files: open /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball: no such file or directory

	I1008 17:29:54.827643  386159 notify.go:220] Checking for updates...

	I1008 17:29:54.828998  386159 out.go:169] MINIKUBE_LOCATION=19774

	I1008 17:29:54.830477  386159 out.go:169] MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true

	I1008 17:29:54.831691  386159 out.go:169] KUBECONFIG=/home/jenkins/minikube-integration/19774-378864/kubeconfig

	I1008 17:29:54.832999  386159 out.go:169] MINIKUBE_HOME=/home/jenkins/minikube-integration/19774-378864/.minikube

	I1008 17:29:54.834188  386159 out.go:169] MINIKUBE_BIN=out/minikube-linux-amd64

	W1008 17:29:54.836232  386159 out.go:321] minikube skips various validations when --force is supplied; this may lead to unexpected behavior

	I1008 17:29:54.836447  386159 driver.go:394] Setting default libvirt URI to qemu:///system

	I1008 17:29:54.868369  386159 out.go:97] Using the kvm2 driver based on user configuration

	I1008 17:29:54.868406  386159 start.go:297] selected driver: kvm2

	I1008 17:29:54.868413  386159 start.go:901] validating driver "kvm2" against <nil>

	I1008 17:29:54.868765  386159 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}

	I1008 17:29:54.868890  386159 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/minikube-integration/19774-378864/.minikube/bin:/home/jenkins/workspace/KVM_Linux_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

	I1008 17:29:54.884131  386159 install.go:137] /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2 version is 1.34.0

	I1008 17:29:54.884191  386159 start_flags.go:310] no existing cluster config was found, will generate one from the flags 

	I1008 17:29:54.884994  386159 start_flags.go:393] Using suggested 6000MB memory alloc based on sys=32089MB, container=0MB

	I1008 17:29:54.885195  386159 start_flags.go:929] Wait components to verify : map[apiserver:true system_pods:true]

	I1008 17:29:54.885233  386159 cni.go:84] Creating CNI manager for ""

	I1008 17:29:54.885304  386159 cni.go:162] CNI unnecessary in this configuration, recommending no CNI

	I1008 17:29:54.885371  386159 start.go:340] cluster config:

	{Name:download-only-527623 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.45-1728382586-19774@sha256:5d8c4f6d838467365e214e2194dd0153a763e3f78723b5f2a8e06ef7b47409ec Memory:6000 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.20.0 ClusterName:download-only-527623 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local Cont

ainerRuntime:docker CRISocket: NetworkPlugin: 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.20.0 ContainerRuntime:docker 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}

	I1008 17:29:54.885612  386159 iso.go:125] acquiring lock: {Name:mke863826b3bcb2ca3f38704a4a8e298b6daf5a2 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}

	I1008 17:29:54.887719  386159 out.go:97] Downloading VM boot image ...

	I1008 17:29:54.887760  386159 download.go:107] Downloading: https://storage.googleapis.com/minikube-builds/iso/19774/minikube-v1.34.0-1728382514-19774-amd64.iso?checksum=file:https://storage.googleapis.com/minikube-builds/iso/19774/minikube-v1.34.0-1728382514-19774-amd64.iso.sha256 -> /home/jenkins/minikube-integration/19774-378864/.minikube/cache/iso/amd64/minikube-v1.34.0-1728382514-19774-amd64.iso

	I1008 17:30:08.421033  386159 out.go:97] Starting "download-only-527623" primary control-plane node in "download-only-527623" cluster

	I1008 17:30:08.421067  386159 preload.go:131] Checking if preload exists for k8s version v1.20.0 and runtime docker

	I1008 17:30:08.519739  386159 preload.go:118] Found remote preload: https://storage.googleapis.com/minikube-preloaded-volume-tarballs/v18/v1.20.0/preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4

	I1008 17:30:08.519779  386159 cache.go:56] Caching tarball of preloaded images

	I1008 17:30:08.519961  386159 preload.go:131] Checking if preload exists for k8s version v1.20.0 and runtime docker

	I1008 17:30:08.522213  386159 out.go:97] Downloading Kubernetes v1.20.0 preload ...

	I1008 17:30:08.522244  386159 preload.go:236] getting checksum for preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4 ...

	I1008 17:30:08.622037  386159 download.go:107] Downloading: https://storage.googleapis.com/minikube-preloaded-volume-tarballs/v18/v1.20.0/preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4?checksum=md5:9a82241e9b8b4ad2b5cca73108f2c7a3 -> /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4

	I1008 17:30:18.660101  386159 preload.go:247] saving checksum for preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4 ...

	I1008 17:30:18.660202  386159 preload.go:254] verifying checksum of /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.20.0-docker-overlay2-amd64.tar.lz4 ...

	I1008 17:30:19.437265  386159 cache.go:59] Finished verifying existence of preloaded tar for v1.20.0 on docker

	I1008 17:30:19.437673  386159 profile.go:143] Saving config to /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/download-only-527623/config.json ...

	I1008 17:30:19.437705  386159 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/download-only-527623/config.json: {Name:mk63f16e7d0bb03de3bb5ba90ccb6bfdd517e21c Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}

	I1008 17:30:19.437868  386159 preload.go:131] Checking if preload exists for k8s version v1.20.0 and runtime docker

	I1008 17:30:19.438033  386159 download.go:107] Downloading: https://dl.k8s.io/release/v1.20.0/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.20.0/bin/linux/amd64/kubectl.sha256 -> /home/jenkins/minikube-integration/19774-378864/.minikube/cache/linux/amd64/v1.20.0/kubectl

	* The control-plane node download-only-527623 host does not exist

	  To start a cluster, run: "minikube start -p download-only-527623"

-- /stdout --

aaa_download_only_test.go:185: minikube logs failed with error: exit status 85

--- PASS: TestDownloadOnly/v1.20.0/LogsDuration (0.07s)

=== RUN   TestDownloadOnly/v1.20.0/DeleteAll

aaa_download_only_test.go:197: (dbg) Run:  out/minikube-linux-amd64 delete --all

--- PASS: TestDownloadOnly/v1.20.0/DeleteAll (0.15s)

=== RUN   TestDownloadOnly/v1.20.0/DeleteAlwaysSucceeds

aaa_download_only_test.go:208: (dbg) Run:  out/minikube-linux-amd64 delete -p download-only-527623

--- PASS: TestDownloadOnly/v1.20.0/DeleteAlwaysSucceeds (0.14s)

=== RUN   TestDownloadOnly/v1.31.1/json-events

aaa_download_only_test.go:81: (dbg) Run:  out/minikube-linux-amd64 start -o=json --download-only -p download-only-271499 --force --alsologtostderr --kubernetes-version=v1.31.1 --container-runtime=docker --driver=kvm2 

aaa_download_only_test.go:81: (dbg) Done: out/minikube-linux-amd64 start -o=json --download-only -p download-only-271499 --force --alsologtostderr --kubernetes-version=v1.31.1 --container-runtime=docker --driver=kvm2 : (10.655162106s)

--- PASS: TestDownloadOnly/v1.31.1/json-events (10.66s)

=== RUN   TestDownloadOnly/v1.31.1/preload-exists

I1008 17:30:31.257835  386147 preload.go:131] Checking if preload exists for k8s version v1.31.1 and runtime docker

I1008 17:30:31.257885  386147 preload.go:146] Found local preload: /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4

--- PASS: TestDownloadOnly/v1.31.1/preload-exists (0.00s)

=== RUN   TestDownloadOnly/v1.31.1/LogsDuration

aaa_download_only_test.go:184: (dbg) Run:  out/minikube-linux-amd64 logs -p download-only-271499

aaa_download_only_test.go:184: (dbg) Non-zero exit: out/minikube-linux-amd64 logs -p download-only-271499: exit status 85 (66.632967ms)

-- stdout --

	==> Audit <==

	|---------|--------------------------------|----------------------|---------|---------|---------------------|---------------------|

	| Command |              Args              |       Profile        |  User   | Version |     Start Time      |      End Time       |

	|---------|--------------------------------|----------------------|---------|---------|---------------------|---------------------|

	| start   | -o=json --download-only        | download-only-527623 | jenkins | v1.34.0 | 08 Oct 24 17:29 UTC |                     |

	|         | -p download-only-527623        |                      |         |         |                     |                     |

	|         | --force --alsologtostderr      |                      |         |         |                     |                     |

	|         | --kubernetes-version=v1.20.0   |                      |         |         |                     |                     |

	|         | --container-runtime=docker     |                      |         |         |                     |                     |

	|         | --driver=kvm2                  |                      |         |         |                     |                     |

	| delete  | --all                          | minikube             | jenkins | v1.34.0 | 08 Oct 24 17:30 UTC | 08 Oct 24 17:30 UTC |

	| delete  | -p download-only-527623        | download-only-527623 | jenkins | v1.34.0 | 08 Oct 24 17:30 UTC | 08 Oct 24 17:30 UTC |

	| start   | -o=json --download-only        | download-only-271499 | jenkins | v1.34.0 | 08 Oct 24 17:30 UTC |                     |

	|         | -p download-only-271499        |                      |         |         |                     |                     |

	|         | --force --alsologtostderr      |                      |         |         |                     |                     |

	|         | --kubernetes-version=v1.31.1   |                      |         |         |                     |                     |

	|         | --container-runtime=docker     |                      |         |         |                     |                     |

	|         | --driver=kvm2                  |                      |         |         |                     |                     |

	|---------|--------------------------------|----------------------|---------|---------|---------------------|---------------------|

	==> Last Start <==

	Log file created at: 2024/10/08 17:30:20

	Running on machine: ubuntu-20-agent-6

	Binary: Built with gc go1.23.1 for linux/amd64

	Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg

	I1008 17:30:20.647194  386410 out.go:345] Setting OutFile to fd 1 ...

	I1008 17:30:20.647321  386410 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:30:20.647331  386410 out.go:358] Setting ErrFile to fd 2...

	I1008 17:30:20.647337  386410 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:30:20.647541  386410 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

	I1008 17:30:20.648168  386410 out.go:352] Setting JSON to true

	I1008 17:30:20.649202  386410 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-6","uptime":4368,"bootTime":1728404253,"procs":171,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1069-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}

	I1008 17:30:20.649346  386410 start.go:139] virtualization: kvm guest

	I1008 17:30:20.651803  386410 out.go:97] [download-only-271499] minikube v1.34.0 on Ubuntu 20.04 (kvm/amd64)

	I1008 17:30:20.652047  386410 notify.go:220] Checking for updates...

	I1008 17:30:20.653391  386410 out.go:169] MINIKUBE_LOCATION=19774

	I1008 17:30:20.655092  386410 out.go:169] MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true

	I1008 17:30:20.656427  386410 out.go:169] KUBECONFIG=/home/jenkins/minikube-integration/19774-378864/kubeconfig

	I1008 17:30:20.657526  386410 out.go:169] MINIKUBE_HOME=/home/jenkins/minikube-integration/19774-378864/.minikube

	I1008 17:30:20.658551  386410 out.go:169] MINIKUBE_BIN=out/minikube-linux-amd64

	W1008 17:30:20.660860  386410 out.go:321] minikube skips various validations when --force is supplied; this may lead to unexpected behavior

	I1008 17:30:20.661159  386410 driver.go:394] Setting default libvirt URI to qemu:///system

	I1008 17:30:20.693636  386410 out.go:97] Using the kvm2 driver based on user configuration

	I1008 17:30:20.693671  386410 start.go:297] selected driver: kvm2

	I1008 17:30:20.693677  386410 start.go:901] validating driver "kvm2" against <nil>

	I1008 17:30:20.694005  386410 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}

	I1008 17:30:20.694091  386410 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/minikube-integration/19774-378864/.minikube/bin:/home/jenkins/workspace/KVM_Linux_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

	I1008 17:30:20.709571  386410 install.go:137] /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2 version is 1.34.0

	I1008 17:30:20.709628  386410 start_flags.go:310] no existing cluster config was found, will generate one from the flags 

	I1008 17:30:20.710172  386410 start_flags.go:393] Using suggested 6000MB memory alloc based on sys=32089MB, container=0MB

	I1008 17:30:20.710309  386410 start_flags.go:929] Wait components to verify : map[apiserver:true system_pods:true]

	I1008 17:30:20.710336  386410 cni.go:84] Creating CNI manager for ""

	I1008 17:30:20.710398  386410 cni.go:158] "kvm2" driver + "docker" container runtime found on kubernetes v1.24+, recommending bridge

	I1008 17:30:20.710407  386410 start_flags.go:319] Found "bridge CNI" CNI - setting NetworkPlugin=cni

	I1008 17:30:20.710464  386410 start.go:340] cluster config:

	{Name:download-only-271499 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.45-1728382586-19774@sha256:5d8c4f6d838467365e214e2194dd0153a763e3f78723b5f2a8e06ef7b47409ec Memory:6000 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.1 ClusterName:download-only-271499 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local Cont

ainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.31.1 ContainerRuntime:docker 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}

	I1008 17:30:20.710566  386410 iso.go:125] acquiring lock: {Name:mke863826b3bcb2ca3f38704a4a8e298b6daf5a2 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}

	I1008 17:30:20.712270  386410 out.go:97] Starting "download-only-271499" primary control-plane node in "download-only-271499" cluster

	I1008 17:30:20.712295  386410 preload.go:131] Checking if preload exists for k8s version v1.31.1 and runtime docker

	I1008 17:30:20.805571  386410 preload.go:118] Found remote preload: https://storage.googleapis.com/minikube-preloaded-volume-tarballs/v18/v1.31.1/preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4

	I1008 17:30:20.805618  386410 cache.go:56] Caching tarball of preloaded images

	I1008 17:30:20.805790  386410 preload.go:131] Checking if preload exists for k8s version v1.31.1 and runtime docker

	I1008 17:30:20.807423  386410 out.go:97] Downloading Kubernetes v1.31.1 preload ...

	I1008 17:30:20.807451  386410 preload.go:236] getting checksum for preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4 ...

	I1008 17:30:20.904942  386410 download.go:107] Downloading: https://storage.googleapis.com/minikube-preloaded-volume-tarballs/v18/v1.31.1/preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4?checksum=md5:42e9a173dd5f0c45ed1a890dd06aec5a -> /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4

	I1008 17:30:29.810343  386410 preload.go:247] saving checksum for preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4 ...

	I1008 17:30:29.810434  386410 preload.go:254] verifying checksum of /home/jenkins/minikube-integration/19774-378864/.minikube/cache/preloaded-tarball/preloaded-images-k8s-v18-v1.31.1-docker-overlay2-amd64.tar.lz4 ...

	I1008 17:30:30.470079  386410 cache.go:59] Finished verifying existence of preloaded tar for v1.31.1 on docker

	I1008 17:30:30.470463  386410 profile.go:143] Saving config to /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/download-only-271499/config.json ...

	I1008 17:30:30.470502  386410 lock.go:35] WriteFile acquiring /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/download-only-271499/config.json: {Name:mkb8f64aab2729aae5b3c27216713df8fdae5f75 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}

	I1008 17:30:30.470692  386410 preload.go:131] Checking if preload exists for k8s version v1.31.1 and runtime docker

	I1008 17:30:30.470868  386410 download.go:107] Downloading: https://dl.k8s.io/release/v1.31.1/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.31.1/bin/linux/amd64/kubectl.sha256 -> /home/jenkins/minikube-integration/19774-378864/.minikube/cache/linux/amd64/v1.31.1/kubectl

	* The control-plane node download-only-271499 host does not exist

	  To start a cluster, run: "minikube start -p download-only-271499"

-- /stdout --

aaa_download_only_test.go:185: minikube logs failed with error: exit status 85

--- PASS: TestDownloadOnly/v1.31.1/LogsDuration (0.07s)

=== RUN   TestDownloadOnly/v1.31.1/DeleteAll

aaa_download_only_test.go:197: (dbg) Run:  out/minikube-linux-amd64 delete --all

--- PASS: TestDownloadOnly/v1.31.1/DeleteAll (0.14s)

=== RUN   TestDownloadOnly/v1.31.1/DeleteAlwaysSucceeds

aaa_download_only_test.go:208: (dbg) Run:  out/minikube-linux-amd64 delete -p download-only-271499

--- PASS: TestDownloadOnly/v1.31.1/DeleteAlwaysSucceeds (0.13s)

=== RUN   TestBinaryMirror

I1008 17:30:31.859144  386147 binary.go:74] Not caching binary, using https://dl.k8s.io/release/v1.31.1/bin/linux/amd64/kubectl?checksum=file:https://dl.k8s.io/release/v1.31.1/bin/linux/amd64/kubectl.sha256

aaa_download_only_test.go:314: (dbg) Run:  out/minikube-linux-amd64 start --download-only -p binary-mirror-604837 --alsologtostderr --binary-mirror http://127.0.0.1:41541 --driver=kvm2 

helpers_test.go:175: Cleaning up "binary-mirror-604837" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p binary-mirror-604837

--- PASS: TestBinaryMirror (0.62s)

=== RUN   TestOffline

=== PAUSE TestOffline

=== CONT  TestOffline

aab_offline_test.go:55: (dbg) Run:  out/minikube-linux-amd64 start -p offline-docker-797432 --alsologtostderr -v=1 --memory=2048 --wait=true --driver=kvm2 

aab_offline_test.go:55: (dbg) Done: out/minikube-linux-amd64 start -p offline-docker-797432 --alsologtostderr -v=1 --memory=2048 --wait=true --driver=kvm2 : (1m30.82272565s)

helpers_test.go:175: Cleaning up "offline-docker-797432" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p offline-docker-797432

--- PASS: TestOffline (91.65s)

=== RUN   TestAddons/PreSetup/EnablingAddonOnNonExistingCluster

=== PAUSE TestAddons/PreSetup/EnablingAddonOnNonExistingCluster

=== CONT  TestAddons/PreSetup/EnablingAddonOnNonExistingCluster

addons_test.go:934: (dbg) Run:  out/minikube-linux-amd64 addons enable dashboard -p addons-403820

addons_test.go:934: (dbg) Non-zero exit: out/minikube-linux-amd64 addons enable dashboard -p addons-403820: exit status 85 (57.072334ms)

-- stdout --

	* Profile "addons-403820" not found. Run "minikube profile list" to view all profiles.

	  To start a cluster, run: "minikube start -p addons-403820"

-- /stdout --

--- PASS: TestAddons/PreSetup/EnablingAddonOnNonExistingCluster (0.06s)

=== RUN   TestAddons/PreSetup/DisablingAddonOnNonExistingCluster

=== PAUSE TestAddons/PreSetup/DisablingAddonOnNonExistingCluster

=== CONT  TestAddons/PreSetup/DisablingAddonOnNonExistingCluster

addons_test.go:945: (dbg) Run:  out/minikube-linux-amd64 addons disable dashboard -p addons-403820

addons_test.go:945: (dbg) Non-zero exit: out/minikube-linux-amd64 addons disable dashboard -p addons-403820: exit status 85 (56.245764ms)

-- stdout --

	* Profile "addons-403820" not found. Run "minikube profile list" to view all profiles.

	  To start a cluster, run: "minikube start -p addons-403820"

-- /stdout --

--- PASS: TestAddons/PreSetup/DisablingAddonOnNonExistingCluster (0.06s)

=== RUN   TestAddons/Setup

addons_test.go:107: (dbg) Run:  out/minikube-linux-amd64 start -p addons-403820 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=nvidia-device-plugin --addons=yakd --addons=volcano --driver=kvm2  --addons=ingress --addons=ingress-dns --addons=storage-provisioner-rancher

addons_test.go:107: (dbg) Done: out/minikube-linux-amd64 start -p addons-403820 --wait=true --memory=4000 --alsologtostderr --addons=registry --addons=metrics-server --addons=volumesnapshots --addons=csi-hostpath-driver --addons=gcp-auth --addons=cloud-spanner --addons=inspektor-gadget --addons=nvidia-device-plugin --addons=yakd --addons=volcano --driver=kvm2  --addons=ingress --addons=ingress-dns --addons=storage-provisioner-rancher: (3m36.651896598s)

--- PASS: TestAddons/Setup (216.65s)

=== RUN   TestAddons/serial/Volcano

addons_test.go:819: volcano-controller stabilized in 9.944827ms

addons_test.go:811: volcano-admission stabilized in 10.003788ms

addons_test.go:803: volcano-scheduler stabilized in 10.038799ms

addons_test.go:825: (dbg) TestAddons/serial/Volcano: waiting 6m0s for pods matching "app=volcano-scheduler" in namespace "volcano-system" ...

helpers_test.go:344: "volcano-scheduler-6c9778cbdf-rwhr8" [0b37cc8f-ed65-46af-bf40-236ac516c313] Running

addons_test.go:825: (dbg) TestAddons/serial/Volcano: app=volcano-scheduler healthy within 6.00382089s

addons_test.go:829: (dbg) TestAddons/serial/Volcano: waiting 6m0s for pods matching "app=volcano-admission" in namespace "volcano-system" ...

helpers_test.go:344: "volcano-admission-5874dfdd79-2xsm9" [d99a7e67-4f8b-4f89-becd-fae13c1615fc] Running

addons_test.go:829: (dbg) TestAddons/serial/Volcano: app=volcano-admission healthy within 5.004724925s

addons_test.go:833: (dbg) TestAddons/serial/Volcano: waiting 6m0s for pods matching "app=volcano-controller" in namespace "volcano-system" ...

helpers_test.go:344: "volcano-controllers-789ffc5785-5vbjn" [05df35ee-6cfe-45fb-ac83-a75abd9d8909] Running

addons_test.go:833: (dbg) TestAddons/serial/Volcano: app=volcano-controller healthy within 5.00479725s

addons_test.go:838: (dbg) Run:  kubectl --context addons-403820 delete -n volcano-system job volcano-admission-init

addons_test.go:844: (dbg) Run:  kubectl --context addons-403820 create -f testdata/vcjob.yaml

addons_test.go:852: (dbg) Run:  kubectl --context addons-403820 get vcjob -n my-volcano

addons_test.go:870: (dbg) TestAddons/serial/Volcano: waiting 3m0s for pods matching "volcano.sh/job-name=test-job" in namespace "my-volcano" ...

helpers_test.go:344: "test-job-nginx-0" [f44e3461-f6cd-49ba-a182-4946ac23473c] Pending

helpers_test.go:344: "test-job-nginx-0" [f44e3461-f6cd-49ba-a182-4946ac23473c] Pending / Ready:ContainersNotReady (containers with unready status: [nginx]) / ContainersReady:ContainersNotReady (containers with unready status: [nginx])

helpers_test.go:344: "test-job-nginx-0" [f44e3461-f6cd-49ba-a182-4946ac23473c] Running

addons_test.go:870: (dbg) TestAddons/serial/Volcano: volcano.sh/job-name=test-job healthy within 17.004058064s

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable volcano --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable volcano --alsologtostderr -v=1: (11.083528686s)

--- PASS: TestAddons/serial/Volcano (44.52s)

=== RUN   TestAddons/serial/GCPAuth/Namespaces

addons_test.go:569: (dbg) Run:  kubectl --context addons-403820 create ns new-namespace

addons_test.go:583: (dbg) Run:  kubectl --context addons-403820 get secret gcp-auth -n new-namespace

--- PASS: TestAddons/serial/GCPAuth/Namespaces (0.13s)

=== RUN   TestAddons/parallel/Registry

=== PAUSE TestAddons/parallel/Registry

=== CONT  TestAddons/parallel/Registry

addons_test.go:321: registry stabilized in 2.927241ms

addons_test.go:323: (dbg) TestAddons/parallel/Registry: waiting 6m0s for pods matching "actual-registry=true" in namespace "kube-system" ...

helpers_test.go:344: "registry-66c9cd494c-8bd7j" [1a9bee6b-2fce-4f96-b362-7b4321360ecb] Running

addons_test.go:323: (dbg) TestAddons/parallel/Registry: actual-registry=true healthy within 5.178496765s

addons_test.go:326: (dbg) TestAddons/parallel/Registry: waiting 10m0s for pods matching "registry-proxy=true" in namespace "kube-system" ...

helpers_test.go:344: "registry-proxy-pjtpp" [5a9aad22-a76d-42c7-a758-96a1f10de890] Running

addons_test.go:326: (dbg) TestAddons/parallel/Registry: registry-proxy=true healthy within 6.003907749s

addons_test.go:331: (dbg) Run:  kubectl --context addons-403820 delete po -l run=registry-test --now

addons_test.go:336: (dbg) Run:  kubectl --context addons-403820 run --rm registry-test --restart=Never --image=gcr.io/k8s-minikube/busybox -it -- sh -c "wget --spider -S http://registry.kube-system.svc.cluster.local"

addons_test.go:336: (dbg) Done: kubectl --context addons-403820 run --rm registry-test --restart=Never --image=gcr.io/k8s-minikube/busybox -it -- sh -c "wget --spider -S http://registry.kube-system.svc.cluster.local": (5.186415123s)

addons_test.go:350: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 ip

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable registry --alsologtostderr -v=1

--- PASS: TestAddons/parallel/Registry (17.09s)

=== RUN   TestAddons/parallel/Ingress

=== PAUSE TestAddons/parallel/Ingress

=== CONT  TestAddons/parallel/Ingress

addons_test.go:207: (dbg) Run:  kubectl --context addons-403820 wait --for=condition=ready --namespace=ingress-nginx pod --selector=app.kubernetes.io/component=controller --timeout=90s

addons_test.go:232: (dbg) Run:  kubectl --context addons-403820 replace --force -f testdata/nginx-ingress-v1.yaml

addons_test.go:245: (dbg) Run:  kubectl --context addons-403820 replace --force -f testdata/nginx-pod-svc.yaml

addons_test.go:250: (dbg) TestAddons/parallel/Ingress: waiting 8m0s for pods matching "run=nginx" in namespace "default" ...

helpers_test.go:344: "nginx" [b297d70f-d727-48a6-a9b0-46e5c315784a] Pending / Ready:ContainersNotReady (containers with unready status: [nginx]) / ContainersReady:ContainersNotReady (containers with unready status: [nginx])

helpers_test.go:344: "nginx" [b297d70f-d727-48a6-a9b0-46e5c315784a] Running

addons_test.go:250: (dbg) TestAddons/parallel/Ingress: run=nginx healthy within 11.004435353s

I1008 17:43:22.783404  386147 kapi.go:150] Service nginx in namespace default found.

addons_test.go:262: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 ssh "curl -s http://127.0.0.1/ -H 'Host: nginx.example.com'"

addons_test.go:286: (dbg) Run:  kubectl --context addons-403820 replace --force -f testdata/ingress-dns-example-v1.yaml

addons_test.go:291: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 ip

addons_test.go:297: (dbg) Run:  nslookup hello-john.test 192.168.39.88

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable ingress-dns --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable ingress-dns --alsologtostderr -v=1: (1.357151084s)

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable ingress --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable ingress --alsologtostderr -v=1: (7.768981698s)

--- PASS: TestAddons/parallel/Ingress (21.30s)

=== RUN   TestAddons/parallel/InspektorGadget

=== PAUSE TestAddons/parallel/InspektorGadget

=== CONT  TestAddons/parallel/InspektorGadget

addons_test.go:758: (dbg) TestAddons/parallel/InspektorGadget: waiting 8m0s for pods matching "k8s-app=gadget" in namespace "gadget" ...

helpers_test.go:344: "gadget-smjgm" [a5f0b1fb-b9c8-43e1-beb8-28760acfd320] Running

addons_test.go:758: (dbg) TestAddons/parallel/InspektorGadget: k8s-app=gadget healthy within 5.181923687s

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable inspektor-gadget --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable inspektor-gadget --alsologtostderr -v=1: (6.201235004s)

--- PASS: TestAddons/parallel/InspektorGadget (11.38s)

=== RUN   TestAddons/parallel/MetricsServer

=== PAUSE TestAddons/parallel/MetricsServer

=== CONT  TestAddons/parallel/MetricsServer

addons_test.go:394: metrics-server stabilized in 2.91983ms

addons_test.go:396: (dbg) TestAddons/parallel/MetricsServer: waiting 6m0s for pods matching "k8s-app=metrics-server" in namespace "kube-system" ...

helpers_test.go:344: "metrics-server-84c5f94fbc-v68cl" [ba02ae98-3e27-427d-b785-a2276e07bda9] Running

addons_test.go:396: (dbg) TestAddons/parallel/MetricsServer: k8s-app=metrics-server healthy within 5.178287533s

addons_test.go:402: (dbg) Run:  kubectl --context addons-403820 top pods -n kube-system

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable metrics-server --alsologtostderr -v=1

--- PASS: TestAddons/parallel/MetricsServer (6.23s)

=== RUN   TestAddons/parallel/CSI

=== PAUSE TestAddons/parallel/CSI

=== CONT  TestAddons/parallel/CSI

I1008 17:43:16.454991  386147 kapi.go:75] Waiting for pod with label "kubernetes.io/minikube-addons=csi-hostpath-driver" in ns "kube-system" ...

I1008 17:43:16.470848  386147 kapi.go:86] Found 3 Pods for label selector kubernetes.io/minikube-addons=csi-hostpath-driver

I1008 17:43:16.470883  386147 kapi.go:107] duration metric: took 15.927682ms to wait for kubernetes.io/minikube-addons=csi-hostpath-driver ...

addons_test.go:488: csi-hostpath-driver pods stabilized in 15.936572ms

addons_test.go:491: (dbg) Run:  kubectl --context addons-403820 create -f testdata/csi-hostpath-driver/pvc.yaml

addons_test.go:496: (dbg) TestAddons/parallel/CSI: waiting 6m0s for pvc "hpvc" in namespace "default" ...

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

2024/10/08 17:43:21 [DEBUG] GET http://192.168.39.88:5000

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc -o jsonpath={.status.phase} -n default

addons_test.go:501: (dbg) Run:  kubectl --context addons-403820 create -f testdata/csi-hostpath-driver/pv-pod.yaml

addons_test.go:506: (dbg) TestAddons/parallel/CSI: waiting 6m0s for pods matching "app=task-pv-pod" in namespace "default" ...

helpers_test.go:344: "task-pv-pod" [9222be69-ef53-46b2-843e-52d2b3fd1c09] Pending

helpers_test.go:344: "task-pv-pod" [9222be69-ef53-46b2-843e-52d2b3fd1c09] Pending / Ready:ContainersNotReady (containers with unready status: [task-pv-container]) / ContainersReady:ContainersNotReady (containers with unready status: [task-pv-container])

helpers_test.go:344: "task-pv-pod" [9222be69-ef53-46b2-843e-52d2b3fd1c09] Running

addons_test.go:506: (dbg) TestAddons/parallel/CSI: app=task-pv-pod healthy within 8.00452711s

addons_test.go:511: (dbg) Run:  kubectl --context addons-403820 create -f testdata/csi-hostpath-driver/snapshot.yaml

addons_test.go:516: (dbg) TestAddons/parallel/CSI: waiting 6m0s for volume snapshot "new-snapshot-demo" in namespace "default" ...

helpers_test.go:419: (dbg) Run:  kubectl --context addons-403820 get volumesnapshot new-snapshot-demo -o jsonpath={.status.readyToUse} -n default

helpers_test.go:419: (dbg) Run:  kubectl --context addons-403820 get volumesnapshot new-snapshot-demo -o jsonpath={.status.readyToUse} -n default

addons_test.go:521: (dbg) Run:  kubectl --context addons-403820 delete pod task-pv-pod

addons_test.go:527: (dbg) Run:  kubectl --context addons-403820 delete pvc hpvc

addons_test.go:533: (dbg) Run:  kubectl --context addons-403820 create -f testdata/csi-hostpath-driver/pvc-restore.yaml

addons_test.go:538: (dbg) TestAddons/parallel/CSI: waiting 6m0s for pvc "hpvc-restore" in namespace "default" ...

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc hpvc-restore -o jsonpath={.status.phase} -n default

addons_test.go:543: (dbg) Run:  kubectl --context addons-403820 create -f testdata/csi-hostpath-driver/pv-pod-restore.yaml

addons_test.go:548: (dbg) TestAddons/parallel/CSI: waiting 6m0s for pods matching "app=task-pv-pod-restore" in namespace "default" ...

helpers_test.go:344: "task-pv-pod-restore" [66ceb3f5-ff2e-4757-9bc1-b484ebdfb44d] Pending

helpers_test.go:344: "task-pv-pod-restore" [66ceb3f5-ff2e-4757-9bc1-b484ebdfb44d] Pending / Ready:ContainersNotReady (containers with unready status: [task-pv-container]) / ContainersReady:ContainersNotReady (containers with unready status: [task-pv-container])

helpers_test.go:344: "task-pv-pod-restore" [66ceb3f5-ff2e-4757-9bc1-b484ebdfb44d] Running

addons_test.go:548: (dbg) TestAddons/parallel/CSI: app=task-pv-pod-restore healthy within 8.003433023s

addons_test.go:553: (dbg) Run:  kubectl --context addons-403820 delete pod task-pv-pod-restore

addons_test.go:557: (dbg) Run:  kubectl --context addons-403820 delete pvc hpvc-restore

addons_test.go:561: (dbg) Run:  kubectl --context addons-403820 delete volumesnapshot new-snapshot-demo

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable volumesnapshots --alsologtostderr -v=1

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable csi-hostpath-driver --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable csi-hostpath-driver --alsologtostderr -v=1: (6.652863534s)

--- PASS: TestAddons/parallel/CSI (53.19s)

=== RUN   TestAddons/parallel/Headlamp

=== PAUSE TestAddons/parallel/Headlamp

=== CONT  TestAddons/parallel/Headlamp

addons_test.go:743: (dbg) Run:  out/minikube-linux-amd64 addons enable headlamp -p addons-403820 --alsologtostderr -v=1

addons_test.go:748: (dbg) TestAddons/parallel/Headlamp: waiting 8m0s for pods matching "app.kubernetes.io/name=headlamp" in namespace "headlamp" ...

helpers_test.go:344: "headlamp-7b5c95b59d-6zgr6" [a5efb1c9-cba1-46a4-8c27-a381fa0983eb] Pending

helpers_test.go:344: "headlamp-7b5c95b59d-6zgr6" [a5efb1c9-cba1-46a4-8c27-a381fa0983eb] Pending / Ready:ContainersNotReady (containers with unready status: [headlamp]) / ContainersReady:ContainersNotReady (containers with unready status: [headlamp])

helpers_test.go:344: "headlamp-7b5c95b59d-6zgr6" [a5efb1c9-cba1-46a4-8c27-a381fa0983eb] Running

addons_test.go:748: (dbg) TestAddons/parallel/Headlamp: app.kubernetes.io/name=headlamp healthy within 13.004681062s

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable headlamp --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable headlamp --alsologtostderr -v=1: (5.771349034s)

--- PASS: TestAddons/parallel/Headlamp (19.61s)

=== RUN   TestAddons/parallel/CloudSpanner

=== PAUSE TestAddons/parallel/CloudSpanner

=== CONT  TestAddons/parallel/CloudSpanner

addons_test.go:775: (dbg) TestAddons/parallel/CloudSpanner: waiting 6m0s for pods matching "app=cloud-spanner-emulator" in namespace "default" ...

helpers_test.go:344: "cloud-spanner-emulator-5b584cc74-w56w9" [8608cb44-2f09-4655-9cb2-d5c3ee19af50] Running

addons_test.go:775: (dbg) TestAddons/parallel/CloudSpanner: app=cloud-spanner-emulator healthy within 5.004072361s

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable cloud-spanner --alsologtostderr -v=1

--- PASS: TestAddons/parallel/CloudSpanner (5.46s)

=== RUN   TestAddons/parallel/LocalPath

=== PAUSE TestAddons/parallel/LocalPath

=== CONT  TestAddons/parallel/LocalPath

addons_test.go:883: (dbg) Run:  kubectl --context addons-403820 apply -f testdata/storage-provisioner-rancher/pvc.yaml

addons_test.go:889: (dbg) Run:  kubectl --context addons-403820 apply -f testdata/storage-provisioner-rancher/pod.yaml

addons_test.go:893: (dbg) TestAddons/parallel/LocalPath: waiting 5m0s for pvc "test-pvc" in namespace "default" ...

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

helpers_test.go:394: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o jsonpath={.status.phase} -n default

addons_test.go:896: (dbg) TestAddons/parallel/LocalPath: waiting 3m0s for pods matching "run=test-local-path" in namespace "default" ...

helpers_test.go:344: "test-local-path" [fd47d4fe-bfb2-4a71-8151-6684feceba46] Pending / Ready:ContainersNotReady (containers with unready status: [busybox]) / ContainersReady:ContainersNotReady (containers with unready status: [busybox])

helpers_test.go:344: "test-local-path" [fd47d4fe-bfb2-4a71-8151-6684feceba46] Pending / Initialized:PodCompleted / Ready:PodCompleted / ContainersReady:PodCompleted

helpers_test.go:344: "test-local-path" [fd47d4fe-bfb2-4a71-8151-6684feceba46] Succeeded / Initialized:PodCompleted / Ready:PodCompleted / ContainersReady:PodCompleted

addons_test.go:896: (dbg) TestAddons/parallel/LocalPath: run=test-local-path healthy within 5.00346845s

addons_test.go:901: (dbg) Run:  kubectl --context addons-403820 get pvc test-pvc -o=json

addons_test.go:910: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 ssh "cat /opt/local-path-provisioner/pvc-0f14077e-5ca3-44a2-9ee5-6c6829e721bd_default_test-pvc/file1"

addons_test.go:922: (dbg) Run:  kubectl --context addons-403820 delete pod test-local-path

addons_test.go:926: (dbg) Run:  kubectl --context addons-403820 delete pvc test-pvc

addons_test.go:979: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable storage-provisioner-rancher --alsologtostderr -v=1

addons_test.go:979: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable storage-provisioner-rancher --alsologtostderr -v=1: (43.118545922s)

--- PASS: TestAddons/parallel/LocalPath (54.94s)

=== RUN   TestAddons/parallel/NvidiaDevicePlugin

=== PAUSE TestAddons/parallel/NvidiaDevicePlugin

=== CONT  TestAddons/parallel/NvidiaDevicePlugin

addons_test.go:958: (dbg) TestAddons/parallel/NvidiaDevicePlugin: waiting 6m0s for pods matching "name=nvidia-device-plugin-ds" in namespace "kube-system" ...

helpers_test.go:344: "nvidia-device-plugin-daemonset-znsv6" [5a51f906-0154-4740-8496-7edf0af29231] Running

addons_test.go:958: (dbg) TestAddons/parallel/NvidiaDevicePlugin: name=nvidia-device-plugin-ds healthy within 6.005339533s

addons_test.go:961: (dbg) Run:  out/minikube-linux-amd64 addons disable nvidia-device-plugin -p addons-403820

--- PASS: TestAddons/parallel/NvidiaDevicePlugin (6.47s)

=== RUN   TestAddons/parallel/Yakd

=== PAUSE TestAddons/parallel/Yakd

=== CONT  TestAddons/parallel/Yakd

addons_test.go:969: (dbg) TestAddons/parallel/Yakd: waiting 2m0s for pods matching "app.kubernetes.io/name=yakd-dashboard" in namespace "yakd-dashboard" ...

helpers_test.go:344: "yakd-dashboard-67d98fc6b-mbdfh" [6e4d04ad-66b8-4559-ac35-ed0093619aca] Running

addons_test.go:969: (dbg) TestAddons/parallel/Yakd: app.kubernetes.io/name=yakd-dashboard healthy within 5.00633291s

addons_test.go:973: (dbg) Run:  out/minikube-linux-amd64 -p addons-403820 addons disable yakd --alsologtostderr -v=1

addons_test.go:973: (dbg) Done: out/minikube-linux-amd64 -p addons-403820 addons disable yakd --alsologtostderr -v=1: (5.770187342s)

--- PASS: TestAddons/parallel/Yakd (10.78s)

=== RUN   TestAddons/StoppedEnableDisable

addons_test.go:170: (dbg) Run:  out/minikube-linux-amd64 stop -p addons-403820

addons_test.go:170: (dbg) Done: out/minikube-linux-amd64 stop -p addons-403820: (8.289937205s)

addons_test.go:174: (dbg) Run:  out/minikube-linux-amd64 addons enable dashboard -p addons-403820

addons_test.go:178: (dbg) Run:  out/minikube-linux-amd64 addons disable dashboard -p addons-403820

addons_test.go:183: (dbg) Run:  out/minikube-linux-amd64 addons disable gvisor -p addons-403820

--- PASS: TestAddons/StoppedEnableDisable (8.58s)

=== RUN   TestCertOptions

=== PAUSE TestCertOptions

=== CONT  TestCertOptions

cert_options_test.go:49: (dbg) Run:  out/minikube-linux-amd64 start -p cert-options-837859 --memory=2048 --apiserver-ips=127.0.0.1 --apiserver-ips=192.168.15.15 --apiserver-names=localhost --apiserver-names=www.google.com --apiserver-port=8555 --driver=kvm2 

E1008 18:33:50.011356  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

cert_options_test.go:49: (dbg) Done: out/minikube-linux-amd64 start -p cert-options-837859 --memory=2048 --apiserver-ips=127.0.0.1 --apiserver-ips=192.168.15.15 --apiserver-names=localhost --apiserver-names=www.google.com --apiserver-port=8555 --driver=kvm2 : (1m21.517745697s)

cert_options_test.go:60: (dbg) Run:  out/minikube-linux-amd64 -p cert-options-837859 ssh "openssl x509 -text -noout -in /var/lib/minikube/certs/apiserver.crt"

cert_options_test.go:88: (dbg) Run:  kubectl --context cert-options-837859 config view

cert_options_test.go:100: (dbg) Run:  out/minikube-linux-amd64 ssh -p cert-options-837859 -- "sudo cat /etc/kubernetes/admin.conf"

helpers_test.go:175: Cleaning up "cert-options-837859" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p cert-options-837859

helpers_test.go:178: (dbg) Done: out/minikube-linux-amd64 delete -p cert-options-837859: (1.039358128s)

--- PASS: TestCertOptions (83.03s)

=== RUN   TestCertExpiration

=== PAUSE TestCertExpiration

=== CONT  TestCertExpiration

cert_options_test.go:123: (dbg) Run:  out/minikube-linux-amd64 start -p cert-expiration-558372 --memory=2048 --cert-expiration=3m --driver=kvm2 

cert_options_test.go:123: (dbg) Done: out/minikube-linux-amd64 start -p cert-expiration-558372 --memory=2048 --cert-expiration=3m --driver=kvm2 : (1m26.325959452s)

cert_options_test.go:131: (dbg) Run:  out/minikube-linux-amd64 start -p cert-expiration-558372 --memory=2048 --cert-expiration=8760h --driver=kvm2 

cert_options_test.go:131: (dbg) Done: out/minikube-linux-amd64 start -p cert-expiration-558372 --memory=2048 --cert-expiration=8760h --driver=kvm2 : (1m41.360198463s)

helpers_test.go:175: Cleaning up "cert-expiration-558372" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p cert-expiration-558372

helpers_test.go:178: (dbg) Done: out/minikube-linux-amd64 delete -p cert-expiration-558372: (1.260147985s)

--- PASS: TestCertExpiration (368.95s)

=== RUN   TestDockerFlags

=== PAUSE TestDockerFlags

=== CONT  TestDockerFlags

docker_test.go:51: (dbg) Run:  out/minikube-linux-amd64 start -p docker-flags-816930 --cache-images=false --memory=2048 --install-addons=false --wait=false --docker-env=FOO=BAR --docker-env=BAZ=BAT --docker-opt=debug --docker-opt=icc=true --alsologtostderr -v=5 --driver=kvm2 

docker_test.go:51: (dbg) Done: out/minikube-linux-amd64 start -p docker-flags-816930 --cache-images=false --memory=2048 --install-addons=false --wait=false --docker-env=FOO=BAR --docker-env=BAZ=BAT --docker-opt=debug --docker-opt=icc=true --alsologtostderr -v=5 --driver=kvm2 : (1m33.642888709s)

docker_test.go:56: (dbg) Run:  out/minikube-linux-amd64 -p docker-flags-816930 ssh "sudo systemctl show docker --property=Environment --no-pager"

docker_test.go:67: (dbg) Run:  out/minikube-linux-amd64 -p docker-flags-816930 ssh "sudo systemctl show docker --property=ExecStart --no-pager"

helpers_test.go:175: Cleaning up "docker-flags-816930" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p docker-flags-816930

helpers_test.go:178: (dbg) Done: out/minikube-linux-amd64 delete -p docker-flags-816930: (1.024679123s)

--- PASS: TestDockerFlags (95.11s)

=== RUN   TestForceSystemdFlag

=== PAUSE TestForceSystemdFlag

=== CONT  TestForceSystemdFlag

docker_test.go:91: (dbg) Run:  out/minikube-linux-amd64 start -p force-systemd-flag-847197 --memory=2048 --force-systemd --alsologtostderr -v=5 --driver=kvm2 

docker_test.go:91: (dbg) Done: out/minikube-linux-amd64 start -p force-systemd-flag-847197 --memory=2048 --force-systemd --alsologtostderr -v=5 --driver=kvm2 : (1m48.078481628s)

docker_test.go:110: (dbg) Run:  out/minikube-linux-amd64 -p force-systemd-flag-847197 ssh "docker info --format {{.CgroupDriver}}"

helpers_test.go:175: Cleaning up "force-systemd-flag-847197" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p force-systemd-flag-847197

--- PASS: TestForceSystemdFlag (109.30s)

=== RUN   TestForceSystemdEnv

=== PAUSE TestForceSystemdEnv

=== CONT  TestForceSystemdEnv

docker_test.go:155: (dbg) Run:  out/minikube-linux-amd64 start -p force-systemd-env-349427 --memory=2048 --alsologtostderr -v=5 --driver=kvm2 

docker_test.go:155: (dbg) Done: out/minikube-linux-amd64 start -p force-systemd-env-349427 --memory=2048 --alsologtostderr -v=5 --driver=kvm2 : (55.61165952s)

docker_test.go:110: (dbg) Run:  out/minikube-linux-amd64 -p force-systemd-env-349427 ssh "docker info --format {{.CgroupDriver}}"

helpers_test.go:175: Cleaning up "force-systemd-env-349427" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p force-systemd-env-349427

helpers_test.go:178: (dbg) Done: out/minikube-linux-amd64 delete -p force-systemd-env-349427: (1.110060049s)

--- PASS: TestForceSystemdEnv (57.00s)

=== RUN   TestKVMDriverInstallOrUpdate

=== PAUSE TestKVMDriverInstallOrUpdate

=== CONT  TestKVMDriverInstallOrUpdate

I1008 18:32:39.672626  386147 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}

I1008 18:32:39.672820  386147 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/workspace/KVM_Linux_integration/testdata/kvm2-driver-without-version:/home/jenkins/workspace/KVM_Linux_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

W1008 18:32:39.705860  386147 install.go:62] docker-machine-driver-kvm2: exit status 1

W1008 18:32:39.706152  386147 out.go:174] [unset outFile]: * Downloading driver docker-machine-driver-kvm2:

I1008 18:32:39.706230  386147 download.go:107] Downloading: https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64?checksum=file:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64.sha256 -> /tmp/TestKVMDriverInstallOrUpdate1527084848/001/docker-machine-driver-kvm2

I1008 18:32:39.904072  386147 driver.go:46] failed to download arch specific driver: getter: &{Ctx:context.Background Src:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64?checksum=file:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64.sha256 Dst:/tmp/TestKVMDriverInstallOrUpdate1527084848/001/docker-machine-driver-kvm2.download Pwd: Mode:2 Umask:---------- Detectors:[0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80] Decompressors:map[bz2:0xc000015ed0 gz:0xc000015ed8 tar:0xc000015e60 tar.bz2:0xc000015e70 tar.gz:0xc000015e80 tar.xz:0xc000015e90 tar.zst:0xc000015eb0 tbz2:0xc000015e70 tgz:0xc000015e80 txz:0xc000015e90 tzst:0xc000015eb0 xz:0xc000015ee0 zip:0xc000015ef0 zst:0xc000015ee8] Getters:map[file:0xc001934dd0 http:0xc000b86190 https:0xc000b861e0] Dir:false ProgressListener:<nil> Insecure:false DisableSymlinks:false Options:[]}: invalid checksum: Error downloading checksum file: bad response co

de: 404. trying to get the common version

I1008 18:32:39.904123  386147 download.go:107] Downloading: https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2?checksum=file:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2.sha256 -> /tmp/TestKVMDriverInstallOrUpdate1527084848/001/docker-machine-driver-kvm2

I1008 18:32:42.642113  386147 install.go:52] acquiring lock: {Name:mk900956b073697a4aa6c80a27c6bb0742a99a53 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}

I1008 18:32:42.642203  386147 install.go:117] Validating docker-machine-driver-kvm2, PATH=/home/jenkins/workspace/KVM_Linux_integration/testdata/kvm2-driver-older-version:/home/jenkins/workspace/KVM_Linux_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

I1008 18:32:42.675864  386147 install.go:137] /home/jenkins/workspace/KVM_Linux_integration/testdata/kvm2-driver-older-version/docker-machine-driver-kvm2 version is 1.1.1

W1008 18:32:42.675901  386147 install.go:62] docker-machine-driver-kvm2: docker-machine-driver-kvm2 is version 1.1.1, want 1.3.0

W1008 18:32:42.675971  386147 out.go:174] [unset outFile]: * Downloading driver docker-machine-driver-kvm2:

I1008 18:32:42.676026  386147 download.go:107] Downloading: https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64?checksum=file:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64.sha256 -> /tmp/TestKVMDriverInstallOrUpdate1527084848/002/docker-machine-driver-kvm2

I1008 18:32:42.722138  386147 driver.go:46] failed to download arch specific driver: getter: &{Ctx:context.Background Src:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64?checksum=file:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2-amd64.sha256 Dst:/tmp/TestKVMDriverInstallOrUpdate1527084848/002/docker-machine-driver-kvm2.download Pwd: Mode:2 Umask:---------- Detectors:[0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80 0x52f4c80] Decompressors:map[bz2:0xc000015ed0 gz:0xc000015ed8 tar:0xc000015e60 tar.bz2:0xc000015e70 tar.gz:0xc000015e80 tar.xz:0xc000015e90 tar.zst:0xc000015eb0 tbz2:0xc000015e70 tgz:0xc000015e80 txz:0xc000015e90 tzst:0xc000015eb0 xz:0xc000015ee0 zip:0xc000015ef0 zst:0xc000015ee8] Getters:map[file:0xc0018c8840 http:0xc000772280 https:0xc0007722d0] Dir:false ProgressListener:<nil> Insecure:false DisableSymlinks:false Options:[]}: invalid checksum: Error downloading checksum file: bad response co

de: 404. trying to get the common version

I1008 18:32:42.722187  386147 download.go:107] Downloading: https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2?checksum=file:https://github.com/kubernetes/minikube/releases/download/v1.3.0/docker-machine-driver-kvm2.sha256 -> /tmp/TestKVMDriverInstallOrUpdate1527084848/002/docker-machine-driver-kvm2

--- PASS: TestKVMDriverInstallOrUpdate (4.96s)

=== RUN   TestErrorSpam/setup

error_spam_test.go:81: (dbg) Run:  out/minikube-linux-amd64 start -p nospam-997110 -n=1 --memory=2250 --wait=false --log_dir=/tmp/nospam-997110 --driver=kvm2 

error_spam_test.go:81: (dbg) Done: out/minikube-linux-amd64 start -p nospam-997110 -n=1 --memory=2250 --wait=false --log_dir=/tmp/nospam-997110 --driver=kvm2 : (50.412365366s)

--- PASS: TestErrorSpam/setup (50.41s)

=== RUN   TestErrorSpam/start

error_spam_test.go:216: Cleaning up 1 logfile(s) ...

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 start --dry-run

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 start --dry-run

error_spam_test.go:182: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 start --dry-run

--- PASS: TestErrorSpam/start (0.36s)

=== RUN   TestErrorSpam/status

error_spam_test.go:216: Cleaning up 0 logfile(s) ...

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 status

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 status

error_spam_test.go:182: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 status

--- PASS: TestErrorSpam/status (0.79s)

=== RUN   TestErrorSpam/pause

error_spam_test.go:216: Cleaning up 0 logfile(s) ...

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 pause

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 pause

error_spam_test.go:182: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 pause

--- PASS: TestErrorSpam/pause (1.22s)

=== RUN   TestErrorSpam/unpause

error_spam_test.go:216: Cleaning up 0 logfile(s) ...

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 unpause

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 unpause

error_spam_test.go:182: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 unpause

--- PASS: TestErrorSpam/unpause (1.37s)

=== RUN   TestErrorSpam/stop

error_spam_test.go:216: Cleaning up 0 logfile(s) ...

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 stop

error_spam_test.go:159: (dbg) Done: out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 stop: (12.427072146s)

error_spam_test.go:159: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 stop

error_spam_test.go:159: (dbg) Done: out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 stop: (1.951869776s)

error_spam_test.go:182: (dbg) Run:  out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 stop

error_spam_test.go:182: (dbg) Done: out/minikube-linux-amd64 -p nospam-997110 --log_dir /tmp/nospam-997110 stop: (1.951972571s)

--- PASS: TestErrorSpam/stop (16.33s)

=== RUN   TestFunctional/serial/CopySyncFile

functional_test.go:1855: local sync path: /home/jenkins/minikube-integration/19774-378864/.minikube/files/etc/test/nested/copy/386147/hosts

--- PASS: TestFunctional/serial/CopySyncFile (0.00s)

=== RUN   TestFunctional/serial/StartWithProxy

functional_test.go:2234: (dbg) Run:  out/minikube-linux-amd64 start -p functional-270926 --memory=4000 --apiserver-port=8441 --wait=all --driver=kvm2 

functional_test.go:2234: (dbg) Done: out/minikube-linux-amd64 start -p functional-270926 --memory=4000 --apiserver-port=8441 --wait=all --driver=kvm2 : (1m27.32237784s)

--- PASS: TestFunctional/serial/StartWithProxy (87.32s)

=== RUN   TestFunctional/serial/AuditLog

--- PASS: TestFunctional/serial/AuditLog (0.00s)

=== RUN   TestFunctional/serial/SoftStart

I1008 17:47:11.149045  386147 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

functional_test.go:659: (dbg) Run:  out/minikube-linux-amd64 start -p functional-270926 --alsologtostderr -v=8

functional_test.go:659: (dbg) Done: out/minikube-linux-amd64 start -p functional-270926 --alsologtostderr -v=8: (41.666194844s)

functional_test.go:663: soft start took 41.667014416s for "functional-270926" cluster.

I1008 17:47:52.815593  386147 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

--- PASS: TestFunctional/serial/SoftStart (41.67s)

=== RUN   TestFunctional/serial/KubeContext

functional_test.go:681: (dbg) Run:  kubectl config current-context

--- PASS: TestFunctional/serial/KubeContext (0.05s)

=== RUN   TestFunctional/serial/KubectlGetPods

functional_test.go:696: (dbg) Run:  kubectl --context functional-270926 get po -A

--- PASS: TestFunctional/serial/KubectlGetPods (0.07s)

=== RUN   TestFunctional/serial/CacheCmd/cache/add_remote

functional_test.go:1049: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cache add registry.k8s.io/pause:3.1

functional_test.go:1049: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cache add registry.k8s.io/pause:3.3

functional_test.go:1049: (dbg) Done: out/minikube-linux-amd64 -p functional-270926 cache add registry.k8s.io/pause:3.3: (1.071771056s)

functional_test.go:1049: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cache add registry.k8s.io/pause:latest

--- PASS: TestFunctional/serial/CacheCmd/cache/add_remote (2.94s)

=== RUN   TestFunctional/serial/CacheCmd/cache/add_local

functional_test.go:1077: (dbg) Run:  docker build -t minikube-local-cache-test:functional-270926 /tmp/TestFunctionalserialCacheCmdcacheadd_local3443830378/001

functional_test.go:1089: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cache add minikube-local-cache-test:functional-270926

functional_test.go:1089: (dbg) Done: out/minikube-linux-amd64 -p functional-270926 cache add minikube-local-cache-test:functional-270926: (1.057548484s)

functional_test.go:1094: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cache delete minikube-local-cache-test:functional-270926

functional_test.go:1083: (dbg) Run:  docker rmi minikube-local-cache-test:functional-270926

--- PASS: TestFunctional/serial/CacheCmd/cache/add_local (1.37s)

=== RUN   TestFunctional/serial/CacheCmd/cache/CacheDelete

functional_test.go:1102: (dbg) Run:  out/minikube-linux-amd64 cache delete registry.k8s.io/pause:3.3

--- PASS: TestFunctional/serial/CacheCmd/cache/CacheDelete (0.05s)

=== RUN   TestFunctional/serial/CacheCmd/cache/list

functional_test.go:1110: (dbg) Run:  out/minikube-linux-amd64 cache list

--- PASS: TestFunctional/serial/CacheCmd/cache/list (0.05s)

=== RUN   TestFunctional/serial/CacheCmd/cache/verify_cache_inside_node

functional_test.go:1124: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh sudo crictl images

--- PASS: TestFunctional/serial/CacheCmd/cache/verify_cache_inside_node (0.23s)

=== RUN   TestFunctional/serial/CacheCmd/cache/cache_reload

functional_test.go:1147: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh sudo docker rmi registry.k8s.io/pause:latest

functional_test.go:1153: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh sudo crictl inspecti registry.k8s.io/pause:latest

functional_test.go:1153: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh sudo crictl inspecti registry.k8s.io/pause:latest: exit status 1 (219.432019ms)

-- stdout --

	FATA[0000] no such image "registry.k8s.io/pause:latest" present 

-- /stdout --

** stderr ** 

	ssh: Process exited with status 1

** /stderr **

functional_test.go:1158: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cache reload

functional_test.go:1163: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh sudo crictl inspecti registry.k8s.io/pause:latest

--- PASS: TestFunctional/serial/CacheCmd/cache/cache_reload (1.27s)

=== RUN   TestFunctional/serial/CacheCmd/cache/delete

functional_test.go:1172: (dbg) Run:  out/minikube-linux-amd64 cache delete registry.k8s.io/pause:3.1

functional_test.go:1172: (dbg) Run:  out/minikube-linux-amd64 cache delete registry.k8s.io/pause:latest

--- PASS: TestFunctional/serial/CacheCmd/cache/delete (0.10s)

=== RUN   TestFunctional/serial/MinikubeKubectlCmd

functional_test.go:716: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 kubectl -- --context functional-270926 get pods

--- PASS: TestFunctional/serial/MinikubeKubectlCmd (0.11s)

=== RUN   TestFunctional/serial/MinikubeKubectlCmdDirectly

functional_test.go:741: (dbg) Run:  out/kubectl --context functional-270926 get pods

--- PASS: TestFunctional/serial/MinikubeKubectlCmdDirectly (0.11s)

=== RUN   TestFunctional/serial/ExtraConfig

functional_test.go:757: (dbg) Run:  out/minikube-linux-amd64 start -p functional-270926 --extra-config=apiserver.enable-admission-plugins=NamespaceAutoProvision --wait=all

functional_test.go:757: (dbg) Done: out/minikube-linux-amd64 start -p functional-270926 --extra-config=apiserver.enable-admission-plugins=NamespaceAutoProvision --wait=all: (42.49665324s)

functional_test.go:761: restart took 42.496793465s for "functional-270926" cluster.

I1008 17:48:41.661919  386147 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

--- PASS: TestFunctional/serial/ExtraConfig (42.50s)

=== RUN   TestFunctional/serial/ComponentHealth

functional_test.go:810: (dbg) Run:  kubectl --context functional-270926 get po -l tier=control-plane -n kube-system -o=json

functional_test.go:825: etcd phase: Running

functional_test.go:835: etcd status: Ready

functional_test.go:825: kube-apiserver phase: Running

functional_test.go:835: kube-apiserver status: Ready

functional_test.go:825: kube-controller-manager phase: Running

functional_test.go:835: kube-controller-manager status: Ready

functional_test.go:825: kube-scheduler phase: Running

functional_test.go:835: kube-scheduler status: Ready

--- PASS: TestFunctional/serial/ComponentHealth (0.06s)

=== RUN   TestFunctional/serial/LogsCmd

functional_test.go:1236: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 logs

--- PASS: TestFunctional/serial/LogsCmd (0.97s)

=== RUN   TestFunctional/serial/LogsFileCmd

functional_test.go:1250: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 logs --file /tmp/TestFunctionalserialLogsFileCmd3908908638/001/logs.txt

--- PASS: TestFunctional/serial/LogsFileCmd (0.96s)

=== RUN   TestFunctional/serial/InvalidService

functional_test.go:2321: (dbg) Run:  kubectl --context functional-270926 apply -f testdata/invalidsvc.yaml

functional_test.go:2335: (dbg) Run:  out/minikube-linux-amd64 service invalid-svc -p functional-270926

functional_test.go:2335: (dbg) Non-zero exit: out/minikube-linux-amd64 service invalid-svc -p functional-270926: exit status 115 (292.264745ms)

-- stdout --

	|-----------|-------------|-------------|-----------------------------|

	| NAMESPACE |    NAME     | TARGET PORT |             URL             |

	|-----------|-------------|-------------|-----------------------------|

	| default   | invalid-svc |          80 | http://192.168.39.153:31218 |

	|-----------|-------------|-------------|-----------------------------|

-- /stdout --

** stderr ** 

	X Exiting due to SVC_UNREACHABLE: service not available: no running pod for service invalid-svc found

	* 

	╭─────────────────────────────────────────────────────────────────────────────────────────────╮

	│                                                                                             │

	│    * If the above advice does not help, please let us know:                                 │

	│      https://github.com/kubernetes/minikube/issues/new/choose                               │

	│                                                                                             │

	│    * Please run `minikube logs --file=logs.txt` and attach logs.txt to the GitHub issue.    │

	│    * Please also attach the following file to the GitHub issue:                             │

	│    * - /tmp/minikube_service_96b204199e3191fa1740d4430b018a3c8028d52d_0.log                 │

	│                                                                                             │

	╰─────────────────────────────────────────────────────────────────────────────────────────────╯

** /stderr **

functional_test.go:2327: (dbg) Run:  kubectl --context functional-270926 delete -f testdata/invalidsvc.yaml

functional_test.go:2327: (dbg) Done: kubectl --context functional-270926 delete -f testdata/invalidsvc.yaml: (1.876235641s)

--- PASS: TestFunctional/serial/InvalidService (5.37s)

=== RUN   TestFunctional/parallel/ConfigCmd

=== PAUSE TestFunctional/parallel/ConfigCmd

=== CONT  TestFunctional/parallel/ConfigCmd

functional_test.go:1199: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 config unset cpus

functional_test.go:1199: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 config get cpus

functional_test.go:1199: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 config get cpus: exit status 14 (48.934233ms)

** stderr ** 

	Error: specified key could not be found in config

** /stderr **

functional_test.go:1199: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 config set cpus 2

functional_test.go:1199: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 config get cpus

functional_test.go:1199: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 config unset cpus

functional_test.go:1199: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 config get cpus

functional_test.go:1199: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 config get cpus: exit status 14 (50.56303ms)

** stderr ** 

	Error: specified key could not be found in config

** /stderr **

--- PASS: TestFunctional/parallel/ConfigCmd (0.33s)

=== RUN   TestFunctional/parallel/DashboardCmd

=== PAUSE TestFunctional/parallel/DashboardCmd

=== CONT  TestFunctional/parallel/DashboardCmd

functional_test.go:905: (dbg) daemon: [out/minikube-linux-amd64 dashboard --url --port 36195 -p functional-270926 --alsologtostderr -v=1]

functional_test.go:910: (dbg) stopping [out/minikube-linux-amd64 dashboard --url --port 36195 -p functional-270926 --alsologtostderr -v=1] ...

helpers_test.go:508: unable to kill pid 395310: os: process already finished

--- PASS: TestFunctional/parallel/DashboardCmd (15.44s)

=== RUN   TestFunctional/parallel/DryRun

=== PAUSE TestFunctional/parallel/DryRun

=== CONT  TestFunctional/parallel/DryRun

functional_test.go:974: (dbg) Run:  out/minikube-linux-amd64 start -p functional-270926 --dry-run --memory 250MB --alsologtostderr --driver=kvm2 

functional_test.go:974: (dbg) Non-zero exit: out/minikube-linux-amd64 start -p functional-270926 --dry-run --memory 250MB --alsologtostderr --driver=kvm2 : exit status 23 (156.994761ms)

-- stdout --

	* [functional-270926] minikube v1.34.0 on Ubuntu 20.04 (kvm/amd64)

	  - MINIKUBE_LOCATION=19774

	  - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true

	  - KUBECONFIG=/home/jenkins/minikube-integration/19774-378864/kubeconfig

	  - MINIKUBE_HOME=/home/jenkins/minikube-integration/19774-378864/.minikube

	  - MINIKUBE_BIN=out/minikube-linux-amd64

	  - MINIKUBE_FORCE_SYSTEMD=

	* Using the kvm2 driver based on existing profile

-- /stdout --

** stderr ** 

	I1008 17:48:52.859235  395089 out.go:345] Setting OutFile to fd 1 ...

	I1008 17:48:52.859372  395089 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:48:52.859383  395089 out.go:358] Setting ErrFile to fd 2...

	I1008 17:48:52.859389  395089 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:48:52.859586  395089 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

	I1008 17:48:52.860152  395089 out.go:352] Setting JSON to false

	I1008 17:48:52.861194  395089 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-6","uptime":5480,"bootTime":1728404253,"procs":245,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1069-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}

	I1008 17:48:52.861306  395089 start.go:139] virtualization: kvm guest

	I1008 17:48:52.862760  395089 out.go:177] * [functional-270926] minikube v1.34.0 on Ubuntu 20.04 (kvm/amd64)

	I1008 17:48:52.864223  395089 notify.go:220] Checking for updates...

	I1008 17:48:52.864236  395089 out.go:177]   - MINIKUBE_LOCATION=19774

	I1008 17:48:52.865419  395089 out.go:177]   - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true

	I1008 17:48:52.866573  395089 out.go:177]   - KUBECONFIG=/home/jenkins/minikube-integration/19774-378864/kubeconfig

	I1008 17:48:52.868169  395089 out.go:177]   - MINIKUBE_HOME=/home/jenkins/minikube-integration/19774-378864/.minikube

	I1008 17:48:52.869397  395089 out.go:177]   - MINIKUBE_BIN=out/minikube-linux-amd64

	I1008 17:48:52.870560  395089 out.go:177]   - MINIKUBE_FORCE_SYSTEMD=

	I1008 17:48:52.872359  395089 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

	I1008 17:48:52.872930  395089 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:48:52.872986  395089 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:48:52.889598  395089 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:39527

	I1008 17:48:52.890178  395089 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:48:52.891024  395089 main.go:141] libmachine: Using API Version  1

	I1008 17:48:52.891040  395089 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:48:52.891417  395089 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:48:52.891814  395089 main.go:141] libmachine: (functional-270926) Calling .DriverName

	I1008 17:48:52.892140  395089 driver.go:394] Setting default libvirt URI to qemu:///system

	I1008 17:48:52.892516  395089 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:48:52.892556  395089 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:48:52.910282  395089 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:39909

	I1008 17:48:52.910711  395089 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:48:52.911193  395089 main.go:141] libmachine: Using API Version  1

	I1008 17:48:52.911227  395089 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:48:52.911620  395089 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:48:52.911820  395089 main.go:141] libmachine: (functional-270926) Calling .DriverName

	I1008 17:48:52.945601  395089 out.go:177] * Using the kvm2 driver based on existing profile

	I1008 17:48:52.946947  395089 start.go:297] selected driver: kvm2

	I1008 17:48:52.946970  395089 start.go:901] validating driver "kvm2" against &{Name:functional-270926 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19774/minikube-v1.34.0-1728382514-19774-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.45-1728382586-19774@sha256:5d8c4f6d838467365e214e2194dd0153a763e3f78723b5f2a8e06ef7b47409ec Memory:4000 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:8441 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{Kuber

netesVersion:v1.31.1 ClusterName:functional-270926 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[{Component:apiserver Key:enable-admission-plugins Value:NamespaceAutoProvision}] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:192.168.39.153 Port:8441 KubernetesVersion:v1.31.1 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[default-storageclass:true storage-provisioner:true] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0

s 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}

	I1008 17:48:52.947132  395089 start.go:912] status for kvm2: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}

	I1008 17:48:52.949170  395089 out.go:201] 

	W1008 17:48:52.950399  395089 out.go:270] X Exiting due to RSRC_INSUFFICIENT_REQ_MEMORY: Requested memory allocation 250MiB is less than the usable minimum of 1800MB

	X Exiting due to RSRC_INSUFFICIENT_REQ_MEMORY: Requested memory allocation 250MiB is less than the usable minimum of 1800MB

	I1008 17:48:52.951579  395089 out.go:201] 

** /stderr **

functional_test.go:991: (dbg) Run:  out/minikube-linux-amd64 start -p functional-270926 --dry-run --alsologtostderr -v=1 --driver=kvm2 

--- PASS: TestFunctional/parallel/DryRun (0.31s)

=== RUN   TestFunctional/parallel/InternationalLanguage

=== PAUSE TestFunctional/parallel/InternationalLanguage

=== CONT  TestFunctional/parallel/InternationalLanguage

functional_test.go:1020: (dbg) Run:  out/minikube-linux-amd64 start -p functional-270926 --dry-run --memory 250MB --alsologtostderr --driver=kvm2 

functional_test.go:1020: (dbg) Non-zero exit: out/minikube-linux-amd64 start -p functional-270926 --dry-run --memory 250MB --alsologtostderr --driver=kvm2 : exit status 23 (330.427256ms)

-- stdout --

	* [functional-270926] minikube v1.34.0 sur Ubuntu 20.04 (kvm/amd64)

	  - MINIKUBE_LOCATION=19774

	  - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true

	  - KUBECONFIG=/home/jenkins/minikube-integration/19774-378864/kubeconfig

	  - MINIKUBE_HOME=/home/jenkins/minikube-integration/19774-378864/.minikube

	  - MINIKUBE_BIN=out/minikube-linux-amd64

	  - MINIKUBE_FORCE_SYSTEMD=

	* Utilisation du pilote kvm2 basé sur le profil existant

-- /stdout --

** stderr ** 

	I1008 17:48:52.523320  395062 out.go:345] Setting OutFile to fd 1 ...

	I1008 17:48:52.525299  395062 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:48:52.525322  395062 out.go:358] Setting ErrFile to fd 2...

	I1008 17:48:52.525330  395062 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:48:52.525718  395062 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

	I1008 17:48:52.526408  395062 out.go:352] Setting JSON to false

	I1008 17:48:52.527764  395062 start.go:129] hostinfo: {"hostname":"ubuntu-20-agent-6","uptime":5479,"bootTime":1728404253,"procs":245,"os":"linux","platform":"ubuntu","platformFamily":"debian","platformVersion":"20.04","kernelVersion":"5.15.0-1069-gcp","kernelArch":"x86_64","virtualizationSystem":"kvm","virtualizationRole":"guest","hostId":"591c9f12-2938-3743-e2bf-c56a050d43d1"}

	I1008 17:48:52.527899  395062 start.go:139] virtualization: kvm guest

	I1008 17:48:52.530236  395062 out.go:177] * [functional-270926] minikube v1.34.0 sur Ubuntu 20.04 (kvm/amd64)

	I1008 17:48:52.531889  395062 notify.go:220] Checking for updates...

	I1008 17:48:52.531920  395062 out.go:177]   - MINIKUBE_LOCATION=19774

	I1008 17:48:52.533633  395062 out.go:177]   - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true

	I1008 17:48:52.535238  395062 out.go:177]   - KUBECONFIG=/home/jenkins/minikube-integration/19774-378864/kubeconfig

	I1008 17:48:52.536614  395062 out.go:177]   - MINIKUBE_HOME=/home/jenkins/minikube-integration/19774-378864/.minikube

	I1008 17:48:52.538148  395062 out.go:177]   - MINIKUBE_BIN=out/minikube-linux-amd64

	I1008 17:48:52.539509  395062 out.go:177]   - MINIKUBE_FORCE_SYSTEMD=

	I1008 17:48:52.541148  395062 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

	I1008 17:48:52.541647  395062 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:48:52.541710  395062 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:48:52.558676  395062 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:43715

	I1008 17:48:52.571600  395062 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:48:52.572562  395062 main.go:141] libmachine: Using API Version  1

	I1008 17:48:52.572595  395062 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:48:52.573084  395062 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:48:52.573336  395062 main.go:141] libmachine: (functional-270926) Calling .DriverName

	I1008 17:48:52.573670  395062 driver.go:394] Setting default libvirt URI to qemu:///system

	I1008 17:48:52.574031  395062 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:48:52.574082  395062 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:48:52.593764  395062 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:44521

	I1008 17:48:52.594378  395062 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:48:52.594952  395062 main.go:141] libmachine: Using API Version  1

	I1008 17:48:52.594990  395062 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:48:52.595313  395062 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:48:52.595491  395062 main.go:141] libmachine: (functional-270926) Calling .DriverName

	I1008 17:48:52.674460  395062 out.go:177] * Utilisation du pilote kvm2 basé sur le profil existant

	I1008 17:48:52.675613  395062 start.go:297] selected driver: kvm2

	I1008 17:48:52.675631  395062 start.go:901] validating driver "kvm2" against &{Name:functional-270926 KeepContext:false EmbedCerts:false MinikubeISO:https://storage.googleapis.com/minikube-builds/iso/19774/minikube-v1.34.0-1728382514-19774-amd64.iso KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.45-1728382586-19774@sha256:5d8c4f6d838467365e214e2194dd0153a763e3f78723b5f2a8e06ef7b47409ec Memory:4000 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:8441 DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot:/nfsshares UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:true HostOnlyNicType:virtio NatNicType:virtio SSHIPAddress: SSHUser:root SSHKey: SSHPort:22 KubernetesConfig:{Kuber

netesVersion:v1.31.1 ClusterName:functional-270926 Namespace:default APIServerHAVIP: APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: RegistryAliases: ExtraOptions:[{Component:apiserver Key:enable-admission-plugins Value:NamespaceAutoProvision}] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:} Nodes:[{Name: IP:192.168.39.153 Port:8441 KubernetesVersion:v1.31.1 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[default-storageclass:true storage-provisioner:true] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0

s 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}

	I1008 17:48:52.675775  395062 start.go:912] status for kvm2: {Installed:true Healthy:true Running:true NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}

	I1008 17:48:52.697884  395062 out.go:201] 

	W1008 17:48:52.791050  395062 out.go:270] X Fermeture en raison de RSRC_INSUFFICIENT_REQ_MEMORY : L'allocation de mémoire demandée 250 Mio est inférieure au minimum utilisable de 1800 Mo

	X Fermeture en raison de RSRC_INSUFFICIENT_REQ_MEMORY : L'allocation de mémoire demandée 250 Mio est inférieure au minimum utilisable de 1800 Mo

	I1008 17:48:52.792146  395062 out.go:201] 

** /stderr **

--- PASS: TestFunctional/parallel/InternationalLanguage (0.33s)

=== RUN   TestFunctional/parallel/StatusCmd

=== PAUSE TestFunctional/parallel/StatusCmd

=== CONT  TestFunctional/parallel/StatusCmd

functional_test.go:854: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 status

functional_test.go:860: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 status -f host:{{.Host}},kublet:{{.Kubelet}},apiserver:{{.APIServer}},kubeconfig:{{.Kubeconfig}}

functional_test.go:872: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 status -o json

--- PASS: TestFunctional/parallel/StatusCmd (1.08s)

=== RUN   TestFunctional/parallel/ServiceCmdConnect

=== PAUSE TestFunctional/parallel/ServiceCmdConnect

=== CONT  TestFunctional/parallel/ServiceCmdConnect

functional_test.go:1629: (dbg) Run:  kubectl --context functional-270926 create deployment hello-node-connect --image=registry.k8s.io/echoserver:1.8

functional_test.go:1635: (dbg) Run:  kubectl --context functional-270926 expose deployment hello-node-connect --type=NodePort --port=8080

functional_test.go:1640: (dbg) TestFunctional/parallel/ServiceCmdConnect: waiting 10m0s for pods matching "app=hello-node-connect" in namespace "default" ...

helpers_test.go:344: "hello-node-connect-67bdd5bbb4-z8qz4" [1d29fe10-6676-4409-90e9-47c8573533d4] Pending / Ready:ContainersNotReady (containers with unready status: [echoserver]) / ContainersReady:ContainersNotReady (containers with unready status: [echoserver])

2024/10/08 17:49:08 [DEBUG] GET http://127.0.0.1:36195/api/v1/namespaces/kubernetes-dashboard/services/http:kubernetes-dashboard:/proxy/

helpers_test.go:344: "hello-node-connect-67bdd5bbb4-z8qz4" [1d29fe10-6676-4409-90e9-47c8573533d4] Running

functional_test.go:1640: (dbg) TestFunctional/parallel/ServiceCmdConnect: app=hello-node-connect healthy within 7.004851891s

functional_test.go:1649: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 service hello-node-connect --url

functional_test.go:1655: found endpoint for hello-node-connect: http://192.168.39.153:31796

functional_test.go:1675: http://192.168.39.153:31796: success! body:

Hostname: hello-node-connect-67bdd5bbb4-z8qz4

Pod Information:

	-no pod information available-

Server values:

	server_version=nginx: 1.13.3 - lua: 10008

Request Information:

	client_address=10.244.0.1

	method=GET

	real path=/

	query=

	request_version=1.1

	request_uri=http://192.168.39.153:8080/

Request Headers:

	accept-encoding=gzip

	host=192.168.39.153:31796

	user-agent=Go-http-client/1.1

Request Body:

	-no body in request-

--- PASS: TestFunctional/parallel/ServiceCmdConnect (7.75s)

=== RUN   TestFunctional/parallel/AddonsCmd

=== PAUSE TestFunctional/parallel/AddonsCmd

=== CONT  TestFunctional/parallel/AddonsCmd

functional_test.go:1690: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 addons list

functional_test.go:1702: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 addons list -o json

--- PASS: TestFunctional/parallel/AddonsCmd (0.13s)

=== RUN   TestFunctional/parallel/PersistentVolumeClaim

=== PAUSE TestFunctional/parallel/PersistentVolumeClaim

=== CONT  TestFunctional/parallel/PersistentVolumeClaim

functional_test_pvc_test.go:44: (dbg) TestFunctional/parallel/PersistentVolumeClaim: waiting 4m0s for pods matching "integration-test=storage-provisioner" in namespace "kube-system" ...

helpers_test.go:344: "storage-provisioner" [566cea99-c8c1-48cd-82eb-fb00c2e0ce15] Running

functional_test_pvc_test.go:44: (dbg) TestFunctional/parallel/PersistentVolumeClaim: integration-test=storage-provisioner healthy within 5.004230386s

functional_test_pvc_test.go:49: (dbg) Run:  kubectl --context functional-270926 get storageclass -o=json

functional_test_pvc_test.go:69: (dbg) Run:  kubectl --context functional-270926 apply -f testdata/storage-provisioner/pvc.yaml

functional_test_pvc_test.go:76: (dbg) Run:  kubectl --context functional-270926 get pvc myclaim -o=json

I1008 17:49:11.323009  386147 retry.go:31] will retry after 1.838092117s: testpvc phase = "Pending", want "Bound" (msg={TypeMeta:{Kind:PersistentVolumeClaim APIVersion:v1} ObjectMeta:{Name:myclaim GenerateName: Namespace:default SelfLink: UID:2c41a611-57cf-47f3-965b-e7fbdeb88f91 ResourceVersion:855 Generation:0 CreationTimestamp:2024-10-08 17:49:11 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[] Annotations:map[kubectl.kubernetes.io/last-applied-configuration:{"apiVersion":"v1","kind":"PersistentVolumeClaim","metadata":{"annotations":{},"name":"myclaim","namespace":"default"},"spec":{"accessModes":["ReadWriteOnce"],"resources":{"requests":{"storage":"500Mi"}},"volumeMode":"Filesystem"}}

pv.kubernetes.io/bind-completed:yes pv.kubernetes.io/bound-by-controller:yes volume.beta.kubernetes.io/storage-provisioner:k8s.io/minikube-hostpath volume.kubernetes.io/storage-provisioner:k8s.io/minikube-hostpath] OwnerReferences:[] Finalizers:[kubernetes.io/pvc-protection] ManagedFields:[]} Spec:{AccessModes:[ReadWriteOnce] Selector:nil Resources:{Limits:map[] Requests:map[storage:{i:{value:524288000 scale:0} d:{Dec:<nil>} s:500Mi Format:BinarySI}]} VolumeName:pvc-2c41a611-57cf-47f3-965b-e7fbdeb88f91 StorageClassName:0xc0017e9460 VolumeMode:0xc0017e9470 DataSource:nil DataSourceRef:nil VolumeAttributesClassName:<nil>} Status:{Phase:Pending AccessModes:[] Capacity:map[] Conditions:[] AllocatedResources:map[] AllocatedResourceStatuses:map[] CurrentVolumeAttributesClassName:<nil> ModifyVolumeStatus:nil}})

E1008 17:49:11.757583  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

functional_test_pvc_test.go:76: (dbg) Run:  kubectl --context functional-270926 get pvc myclaim -o=json

functional_test_pvc_test.go:125: (dbg) Run:  kubectl --context functional-270926 apply -f testdata/storage-provisioner/pod.yaml

functional_test_pvc_test.go:130: (dbg) TestFunctional/parallel/PersistentVolumeClaim: waiting 3m0s for pods matching "test=storage-provisioner" in namespace "default" ...

helpers_test.go:344: "sp-pod" [f3bd8346-c607-4067-b026-651a13c0ad1d] Pending

helpers_test.go:344: "sp-pod" [f3bd8346-c607-4067-b026-651a13c0ad1d] Pending / Ready:ContainersNotReady (containers with unready status: [myfrontend]) / ContainersReady:ContainersNotReady (containers with unready status: [myfrontend])

helpers_test.go:344: "sp-pod" [f3bd8346-c607-4067-b026-651a13c0ad1d] Running

functional_test_pvc_test.go:130: (dbg) TestFunctional/parallel/PersistentVolumeClaim: test=storage-provisioner healthy within 24.003346627s

functional_test_pvc_test.go:100: (dbg) Run:  kubectl --context functional-270926 exec sp-pod -- touch /tmp/mount/foo

functional_test_pvc_test.go:106: (dbg) Run:  kubectl --context functional-270926 delete -f testdata/storage-provisioner/pod.yaml

functional_test_pvc_test.go:125: (dbg) Run:  kubectl --context functional-270926 apply -f testdata/storage-provisioner/pod.yaml

functional_test_pvc_test.go:130: (dbg) TestFunctional/parallel/PersistentVolumeClaim: waiting 3m0s for pods matching "test=storage-provisioner" in namespace "default" ...

helpers_test.go:344: "sp-pod" [715b05a2-6a08-4ea2-8c68-c0bc7b111b4b] Pending

helpers_test.go:344: "sp-pod" [715b05a2-6a08-4ea2-8c68-c0bc7b111b4b] Pending / Ready:ContainersNotReady (containers with unready status: [myfrontend]) / ContainersReady:ContainersNotReady (containers with unready status: [myfrontend])

helpers_test.go:344: "sp-pod" [715b05a2-6a08-4ea2-8c68-c0bc7b111b4b] Running

functional_test_pvc_test.go:130: (dbg) TestFunctional/parallel/PersistentVolumeClaim: test=storage-provisioner healthy within 8.004409776s

functional_test_pvc_test.go:114: (dbg) Run:  kubectl --context functional-270926 exec sp-pod -- ls /tmp/mount

--- PASS: TestFunctional/parallel/PersistentVolumeClaim (40.32s)

=== RUN   TestFunctional/parallel/SSHCmd

=== PAUSE TestFunctional/parallel/SSHCmd

=== CONT  TestFunctional/parallel/SSHCmd

functional_test.go:1725: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "echo hello"

functional_test.go:1742: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "cat /etc/hostname"

--- PASS: TestFunctional/parallel/SSHCmd (0.44s)

=== RUN   TestFunctional/parallel/CpCmd

=== PAUSE TestFunctional/parallel/CpCmd

=== CONT  TestFunctional/parallel/CpCmd

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cp testdata/cp-test.txt /home/docker/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh -n functional-270926 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cp functional-270926:/home/docker/cp-test.txt /tmp/TestFunctionalparallelCpCmd4048460101/001/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh -n functional-270926 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 cp testdata/cp-test.txt /tmp/does/not/exist/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh -n functional-270926 "sudo cat /tmp/does/not/exist/cp-test.txt"

--- PASS: TestFunctional/parallel/CpCmd (1.47s)

=== RUN   TestFunctional/parallel/MySQL

=== PAUSE TestFunctional/parallel/MySQL

=== CONT  TestFunctional/parallel/MySQL

functional_test.go:1793: (dbg) Run:  kubectl --context functional-270926 replace --force -f testdata/mysql.yaml

functional_test.go:1799: (dbg) TestFunctional/parallel/MySQL: waiting 10m0s for pods matching "app=mysql" in namespace "default" ...

helpers_test.go:344: "mysql-6cdb49bbb-h2ng7" [0473bafe-11b1-43a4-8820-fd06be6235de] Pending / Ready:ContainersNotReady (containers with unready status: [mysql]) / ContainersReady:ContainersNotReady (containers with unready status: [mysql])

helpers_test.go:344: "mysql-6cdb49bbb-h2ng7" [0473bafe-11b1-43a4-8820-fd06be6235de] Running

functional_test.go:1799: (dbg) TestFunctional/parallel/MySQL: app=mysql healthy within 32.005346532s

functional_test.go:1807: (dbg) Run:  kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;"

E1008 17:49:29.683233  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

functional_test.go:1807: (dbg) Non-zero exit: kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;": exit status 1 (258.422588ms)

** stderr ** 

	mysql: [Warning] Using a password on the command line interface can be insecure.

	ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: YES)

	command terminated with exit code 1

** /stderr **

I1008 17:49:29.803222  386147 retry.go:31] will retry after 637.737078ms: exit status 1

functional_test.go:1807: (dbg) Run:  kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;"

functional_test.go:1807: (dbg) Non-zero exit: kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;": exit status 1 (174.608588ms)

** stderr ** 

	mysql: [Warning] Using a password on the command line interface can be insecure.

	ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: YES)

	command terminated with exit code 1

** /stderr **

I1008 17:49:30.616088  386147 retry.go:31] will retry after 1.665954305s: exit status 1

functional_test.go:1807: (dbg) Run:  kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;"

functional_test.go:1807: (dbg) Non-zero exit: kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;": exit status 1 (146.065329ms)

** stderr ** 

	mysql: [Warning] Using a password on the command line interface can be insecure.

	ERROR 2002 (HY000): Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2)

	command terminated with exit code 1

** /stderr **

I1008 17:49:32.428469  386147 retry.go:31] will retry after 3.072027514s: exit status 1

functional_test.go:1807: (dbg) Run:  kubectl --context functional-270926 exec mysql-6cdb49bbb-h2ng7 -- mysql -ppassword -e "show databases;"

--- PASS: TestFunctional/parallel/MySQL (38.32s)

=== RUN   TestFunctional/parallel/FileSync

=== PAUSE TestFunctional/parallel/FileSync

=== CONT  TestFunctional/parallel/FileSync

functional_test.go:1929: Checking for existence of /etc/test/nested/copy/386147/hosts within VM

functional_test.go:1931: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /etc/test/nested/copy/386147/hosts"

functional_test.go:1936: file sync test content: Test file for checking file sync process

--- PASS: TestFunctional/parallel/FileSync (0.22s)

=== RUN   TestFunctional/parallel/CertSync

=== PAUSE TestFunctional/parallel/CertSync

=== CONT  TestFunctional/parallel/CertSync

functional_test.go:1972: Checking for existence of /etc/ssl/certs/386147.pem within VM

functional_test.go:1973: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /etc/ssl/certs/386147.pem"

functional_test.go:1972: Checking for existence of /usr/share/ca-certificates/386147.pem within VM

functional_test.go:1973: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /usr/share/ca-certificates/386147.pem"

functional_test.go:1972: Checking for existence of /etc/ssl/certs/51391683.0 within VM

functional_test.go:1973: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /etc/ssl/certs/51391683.0"

functional_test.go:1999: Checking for existence of /etc/ssl/certs/3861472.pem within VM

functional_test.go:2000: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /etc/ssl/certs/3861472.pem"

functional_test.go:1999: Checking for existence of /usr/share/ca-certificates/3861472.pem within VM

functional_test.go:2000: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /usr/share/ca-certificates/3861472.pem"

functional_test.go:1999: Checking for existence of /etc/ssl/certs/3ec20f2e.0 within VM

functional_test.go:2000: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo cat /etc/ssl/certs/3ec20f2e.0"

--- PASS: TestFunctional/parallel/CertSync (1.36s)

=== RUN   TestFunctional/parallel/NodeLabels

=== PAUSE TestFunctional/parallel/NodeLabels

=== CONT  TestFunctional/parallel/NodeLabels

functional_test.go:219: (dbg) Run:  kubectl --context functional-270926 get nodes --output=go-template "--template='{{range $k, $v := (index .items 0).metadata.labels}}{{$k}} {{end}}'"

--- PASS: TestFunctional/parallel/NodeLabels (0.06s)

=== RUN   TestFunctional/parallel/NonActiveRuntimeDisabled

=== PAUSE TestFunctional/parallel/NonActiveRuntimeDisabled

=== CONT  TestFunctional/parallel/NonActiveRuntimeDisabled

functional_test.go:2027: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo systemctl is-active crio"

functional_test.go:2027: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh "sudo systemctl is-active crio": exit status 1 (222.271013ms)

-- stdout --

	inactive

-- /stdout --

** stderr ** 

	ssh: Process exited with status 3

** /stderr **

--- PASS: TestFunctional/parallel/NonActiveRuntimeDisabled (0.22s)

=== RUN   TestFunctional/parallel/License

=== PAUSE TestFunctional/parallel/License

=== CONT  TestFunctional/parallel/License

functional_test.go:2288: (dbg) Run:  out/minikube-linux-amd64 license

--- PASS: TestFunctional/parallel/License (0.57s)

=== RUN   TestFunctional/parallel/Version/short

=== PAUSE TestFunctional/parallel/Version/short

=== CONT  TestFunctional/parallel/Version/short

functional_test.go:2256: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 version --short

--- PASS: TestFunctional/parallel/Version/short (0.05s)

=== RUN   TestFunctional/parallel/Version/components

=== PAUSE TestFunctional/parallel/Version/components

=== CONT  TestFunctional/parallel/Version/components

functional_test.go:2270: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 version -o=json --components

--- PASS: TestFunctional/parallel/Version/components (0.43s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageListShort

=== PAUSE TestFunctional/parallel/ImageCommands/ImageListShort

=== CONT  TestFunctional/parallel/ImageCommands/ImageListShort

functional_test.go:261: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls --format short --alsologtostderr

E1008 17:49:09.350145  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:49:09.511629  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

functional_test.go:266: (dbg) Stdout: out/minikube-linux-amd64 -p functional-270926 image ls --format short --alsologtostderr:

registry.k8s.io/pause:latest

registry.k8s.io/pause:3.3

registry.k8s.io/pause:3.10

registry.k8s.io/pause:3.1

registry.k8s.io/kube-scheduler:v1.31.1

registry.k8s.io/kube-proxy:v1.31.1

registry.k8s.io/kube-controller-manager:v1.31.1

registry.k8s.io/kube-apiserver:v1.31.1

registry.k8s.io/etcd:3.5.15-0

registry.k8s.io/echoserver:1.8

registry.k8s.io/coredns/coredns:v1.11.3

gcr.io/k8s-minikube/storage-provisioner:v5

gcr.io/k8s-minikube/busybox:1.28.4-glibc

docker.io/library/minikube-local-cache-test:functional-270926

docker.io/kubernetesui/metrics-scraper:<none>

docker.io/kubernetesui/dashboard:<none>

docker.io/kicbase/echo-server:functional-270926

functional_test.go:269: (dbg) Stderr: out/minikube-linux-amd64 -p functional-270926 image ls --format short --alsologtostderr:

I1008 17:49:09.356150  396541 out.go:345] Setting OutFile to fd 1 ...

I1008 17:49:09.356272  396541 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:09.356281  396541 out.go:358] Setting ErrFile to fd 2...

I1008 17:49:09.356286  396541 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:09.356486  396541 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

I1008 17:49:09.357102  396541 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:09.357203  396541 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:09.357582  396541 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:09.357632  396541 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:09.373259  396541 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:44325

I1008 17:49:09.373876  396541 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:09.374556  396541 main.go:141] libmachine: Using API Version  1

I1008 17:49:09.374584  396541 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:09.374959  396541 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:09.375164  396541 main.go:141] libmachine: (functional-270926) Calling .GetState

I1008 17:49:09.377212  396541 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:09.377271  396541 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:09.392816  396541 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:40443

I1008 17:49:09.393332  396541 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:09.393912  396541 main.go:141] libmachine: Using API Version  1

I1008 17:49:09.393941  396541 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:09.394342  396541 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:09.394496  396541 main.go:141] libmachine: (functional-270926) Calling .DriverName

I1008 17:49:09.394727  396541 ssh_runner.go:195] Run: systemctl --version

I1008 17:49:09.394750  396541 main.go:141] libmachine: (functional-270926) Calling .GetSSHHostname

I1008 17:49:09.397276  396541 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:09.397746  396541 main.go:141] libmachine: (functional-270926) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:36:a2:c5", ip: ""} in network mk-functional-270926: {Iface:virbr1 ExpiryTime:2024-10-08 18:45:57 +0000 UTC Type:0 Mac:52:54:00:36:a2:c5 Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:functional-270926 Clientid:01:52:54:00:36:a2:c5}

I1008 17:49:09.397771  396541 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined IP address 192.168.39.153 and MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:09.397924  396541 main.go:141] libmachine: (functional-270926) Calling .GetSSHPort

I1008 17:49:09.398079  396541 main.go:141] libmachine: (functional-270926) Calling .GetSSHKeyPath

I1008 17:49:09.398208  396541 main.go:141] libmachine: (functional-270926) Calling .GetSSHUsername

I1008 17:49:09.398334  396541 sshutil.go:53] new ssh client: &{IP:192.168.39.153 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/functional-270926/id_rsa Username:docker}

I1008 17:49:09.486257  396541 ssh_runner.go:195] Run: docker images --no-trunc --format "{{json .}}"

I1008 17:49:09.524267  396541 main.go:141] libmachine: Making call to close driver server

I1008 17:49:09.524288  396541 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:09.524577  396541 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:09.524597  396541 main.go:141] libmachine: (functional-270926) DBG | Closing plugin on server side

I1008 17:49:09.524606  396541 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:09.524628  396541 main.go:141] libmachine: Making call to close driver server

I1008 17:49:09.524634  396541 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:09.524858  396541 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:09.524873  396541 main.go:141] libmachine: Making call to close connection to plugin binary

--- PASS: TestFunctional/parallel/ImageCommands/ImageListShort (0.22s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageListTable

=== PAUSE TestFunctional/parallel/ImageCommands/ImageListTable

=== CONT  TestFunctional/parallel/ImageCommands/ImageListTable

functional_test.go:261: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls --format table --alsologtostderr

E1008 17:49:14.319046  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

functional_test.go:266: (dbg) Stdout: out/minikube-linux-amd64 -p functional-270926 image ls --format table --alsologtostderr:

|---------------------------------------------|-------------------|---------------|--------|

|                    Image                    |        Tag        |   Image ID    |  Size  |

|---------------------------------------------|-------------------|---------------|--------|

| localhost/my-image                          | functional-270926 | 5afa2c9663b0f | 1.24MB |

| docker.io/library/minikube-local-cache-test | functional-270926 | 8039bf7d48d6b | 30B    |

| registry.k8s.io/kube-apiserver              | v1.31.1           | 6bab7719df100 | 94.2MB |

| registry.k8s.io/kube-controller-manager     | v1.31.1           | 175ffd71cce3d | 88.4MB |

| registry.k8s.io/kube-proxy                  | v1.31.1           | 60c005f310ff3 | 91.5MB |

| registry.k8s.io/pause                       | 3.1               | da86e6ba6ca19 | 742kB  |

| registry.k8s.io/coredns/coredns             | v1.11.3           | c69fa2e9cbf5f | 61.8MB |

| registry.k8s.io/etcd                        | 3.5.15-0          | 2e96e5913fc06 | 148MB  |

| docker.io/kicbase/echo-server               | functional-270926 | 9056ab77afb8e | 4.94MB |

| registry.k8s.io/echoserver                  | 1.8               | 82e4c8a736a4f | 95.4MB |

| docker.io/kubernetesui/dashboard            | <none>            | 07655ddf2eebe | 246MB  |

| docker.io/kubernetesui/metrics-scraper      | <none>            | 115053965e86b | 43.8MB |

| registry.k8s.io/kube-scheduler              | v1.31.1           | 9aa1fad941575 | 67.4MB |

| registry.k8s.io/pause                       | 3.10              | 873ed75102791 | 736kB  |

| gcr.io/k8s-minikube/storage-provisioner     | v5                | 6e38f40d628db | 31.5MB |

| registry.k8s.io/pause                       | 3.3               | 0184c1613d929 | 683kB  |

| gcr.io/k8s-minikube/busybox                 | 1.28.4-glibc      | 56cc512116c8f | 4.4MB  |

| registry.k8s.io/pause                       | latest            | 350b164e7ae1d | 240kB  |

|---------------------------------------------|-------------------|---------------|--------|

functional_test.go:269: (dbg) Stderr: out/minikube-linux-amd64 -p functional-270926 image ls --format table --alsologtostderr:

I1008 17:49:14.312705  396767 out.go:345] Setting OutFile to fd 1 ...

I1008 17:49:14.312837  396767 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:14.312848  396767 out.go:358] Setting ErrFile to fd 2...

I1008 17:49:14.312855  396767 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:14.313110  396767 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

I1008 17:49:14.314030  396767 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:14.314177  396767 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:14.314722  396767 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:14.314783  396767 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:14.331626  396767 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:43773

I1008 17:49:14.332253  396767 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:14.332869  396767 main.go:141] libmachine: Using API Version  1

I1008 17:49:14.332894  396767 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:14.333368  396767 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:14.333644  396767 main.go:141] libmachine: (functional-270926) Calling .GetState

I1008 17:49:14.335601  396767 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:14.335651  396767 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:14.352231  396767 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:41047

I1008 17:49:14.352712  396767 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:14.353373  396767 main.go:141] libmachine: Using API Version  1

I1008 17:49:14.353412  396767 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:14.353752  396767 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:14.353925  396767 main.go:141] libmachine: (functional-270926) Calling .DriverName

I1008 17:49:14.354129  396767 ssh_runner.go:195] Run: systemctl --version

I1008 17:49:14.354161  396767 main.go:141] libmachine: (functional-270926) Calling .GetSSHHostname

I1008 17:49:14.357313  396767 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:14.357794  396767 main.go:141] libmachine: (functional-270926) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:36:a2:c5", ip: ""} in network mk-functional-270926: {Iface:virbr1 ExpiryTime:2024-10-08 18:45:57 +0000 UTC Type:0 Mac:52:54:00:36:a2:c5 Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:functional-270926 Clientid:01:52:54:00:36:a2:c5}

I1008 17:49:14.357820  396767 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined IP address 192.168.39.153 and MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:14.358023  396767 main.go:141] libmachine: (functional-270926) Calling .GetSSHPort

I1008 17:49:14.358218  396767 main.go:141] libmachine: (functional-270926) Calling .GetSSHKeyPath

I1008 17:49:14.358402  396767 main.go:141] libmachine: (functional-270926) Calling .GetSSHUsername

I1008 17:49:14.358561  396767 sshutil.go:53] new ssh client: &{IP:192.168.39.153 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/functional-270926/id_rsa Username:docker}

I1008 17:49:14.450331  396767 ssh_runner.go:195] Run: docker images --no-trunc --format "{{json .}}"

I1008 17:49:14.503652  396767 main.go:141] libmachine: Making call to close driver server

I1008 17:49:14.503676  396767 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:14.504020  396767 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:14.504063  396767 main.go:141] libmachine: (functional-270926) DBG | Closing plugin on server side

I1008 17:49:14.504081  396767 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:14.504101  396767 main.go:141] libmachine: Making call to close driver server

I1008 17:49:14.504113  396767 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:14.504353  396767 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:14.504373  396767 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:14.504402  396767 main.go:141] libmachine: (functional-270926) DBG | Closing plugin on server side

--- PASS: TestFunctional/parallel/ImageCommands/ImageListTable (0.25s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageListJson

=== PAUSE TestFunctional/parallel/ImageCommands/ImageListJson

=== CONT  TestFunctional/parallel/ImageCommands/ImageListJson

functional_test.go:261: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls --format json --alsologtostderr

functional_test.go:266: (dbg) Stdout: out/minikube-linux-amd64 -p functional-270926 image ls --format json --alsologtostderr:

[{"id":"6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562","repoDigests":[],"repoTags":["gcr.io/k8s-minikube/storage-provisioner:v5"],"size":"31500000"},{"id":"6bab7719df1001fdcc7e39f1decfa1f73b7f3af2757a91c5bafa1aaea29d1aee","repoDigests":[],"repoTags":["registry.k8s.io/kube-apiserver:v1.31.1"],"size":"94200000"},{"id":"9aa1fad941575eed91ab13d44f3e4cb5b1ff4e09cbbe954ea63002289416a13b","repoDigests":[],"repoTags":["registry.k8s.io/kube-scheduler:v1.31.1"],"size":"67400000"},{"id":"60c005f310ff3ad6d131805170f07d2946095307063eaaa5eedcaf06a0a89561","repoDigests":[],"repoTags":["registry.k8s.io/kube-proxy:v1.31.1"],"size":"91500000"},{"id":"0184c1613d92931126feb4c548e5da11015513b9e4c104e7305ee8b53b50a9da","repoDigests":[],"repoTags":["registry.k8s.io/pause:3.3"],"size":"683000"},{"id":"da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e","repoDigests":[],"repoTags":["registry.k8s.io/pause:3.1"],"size":"742000"},{"id":"82e4c8a736a4fcf22b5ef9f6a4ff6207064c7187d7694bf97bd561605a53

8410","repoDigests":[],"repoTags":["registry.k8s.io/echoserver:1.8"],"size":"95400000"},{"id":"175ffd71cce3d90bae95904b55260db941b10007a4e5471a19f3135b30aa9cd1","repoDigests":[],"repoTags":["registry.k8s.io/kube-controller-manager:v1.31.1"],"size":"88400000"},{"id":"2e96e5913fc06e3d26915af3d0f2ca5048cc4b6327e661e80da792cbf8d8d9d4","repoDigests":[],"repoTags":["registry.k8s.io/etcd:3.5.15-0"],"size":"148000000"},{"id":"9056ab77afb8e18e04303f11000a9d31b3f16b74c59475b899ae1b342d328d30","repoDigests":[],"repoTags":["docker.io/kicbase/echo-server:functional-270926"],"size":"4940000"},{"id":"56cc512116c8f894f11ce1995460aef1ee0972d48bc2a3bdb1faaac7c020289c","repoDigests":[],"repoTags":["gcr.io/k8s-minikube/busybox:1.28.4-glibc"],"size":"4400000"},{"id":"350b164e7ae1dcddeffadd65c76226c9b6dc5553f5179153fb0e36b78f2a5e06","repoDigests":[],"repoTags":["registry.k8s.io/pause:latest"],"size":"240000"},{"id":"8039bf7d48d6bac3fafd612c3c34a5743ea78cf649cd589772d18d45642893cb","repoDigests":[],"repoTags":["docker.io/library/mi

nikube-local-cache-test:functional-270926"],"size":"30"},{"id":"c69fa2e9cbf5f42dc48af631e956d3f95724c13f91596bc567591790e5e36db6","repoDigests":[],"repoTags":["registry.k8s.io/coredns/coredns:v1.11.3"],"size":"61800000"},{"id":"873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136","repoDigests":[],"repoTags":["registry.k8s.io/pause:3.10"],"size":"736000"},{"id":"07655ddf2eebe5d250f7a72c25f638b27126805d61779741b4e62e69ba080558","repoDigests":[],"repoTags":["docker.io/kubernetesui/dashboard:\u003cnone\u003e"],"size":"246000000"},{"id":"115053965e86b2df4d78af78d7951b8644839d20a03820c6df59a261103315f7","repoDigests":[],"repoTags":["docker.io/kubernetesui/metrics-scraper:\u003cnone\u003e"],"size":"43800000"}]

functional_test.go:269: (dbg) Stderr: out/minikube-linux-amd64 -p functional-270926 image ls --format json --alsologtostderr:

I1008 17:49:14.075298  396744 out.go:345] Setting OutFile to fd 1 ...

I1008 17:49:14.075462  396744 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:14.075473  396744 out.go:358] Setting ErrFile to fd 2...

I1008 17:49:14.075479  396744 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:14.075675  396744 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

I1008 17:49:14.076353  396744 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:14.076491  396744 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:14.076858  396744 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:14.076921  396744 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:14.092096  396744 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:38913

I1008 17:49:14.092646  396744 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:14.093217  396744 main.go:141] libmachine: Using API Version  1

I1008 17:49:14.093242  396744 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:14.093727  396744 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:14.093966  396744 main.go:141] libmachine: (functional-270926) Calling .GetState

I1008 17:49:14.095900  396744 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:14.095945  396744 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:14.111323  396744 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:45865

I1008 17:49:14.111842  396744 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:14.112363  396744 main.go:141] libmachine: Using API Version  1

I1008 17:49:14.112387  396744 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:14.112756  396744 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:14.112928  396744 main.go:141] libmachine: (functional-270926) Calling .DriverName

I1008 17:49:14.113127  396744 ssh_runner.go:195] Run: systemctl --version

I1008 17:49:14.113151  396744 main.go:141] libmachine: (functional-270926) Calling .GetSSHHostname

I1008 17:49:14.115977  396744 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:14.116434  396744 main.go:141] libmachine: (functional-270926) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:36:a2:c5", ip: ""} in network mk-functional-270926: {Iface:virbr1 ExpiryTime:2024-10-08 18:45:57 +0000 UTC Type:0 Mac:52:54:00:36:a2:c5 Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:functional-270926 Clientid:01:52:54:00:36:a2:c5}

I1008 17:49:14.116458  396744 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined IP address 192.168.39.153 and MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:14.116617  396744 main.go:141] libmachine: (functional-270926) Calling .GetSSHPort

I1008 17:49:14.116936  396744 main.go:141] libmachine: (functional-270926) Calling .GetSSHKeyPath

I1008 17:49:14.117126  396744 main.go:141] libmachine: (functional-270926) Calling .GetSSHUsername

I1008 17:49:14.117283  396744 sshutil.go:53] new ssh client: &{IP:192.168.39.153 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/functional-270926/id_rsa Username:docker}

I1008 17:49:14.211854  396744 ssh_runner.go:195] Run: docker images --no-trunc --format "{{json .}}"

I1008 17:49:14.255283  396744 main.go:141] libmachine: Making call to close driver server

I1008 17:49:14.255304  396744 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:14.255603  396744 main.go:141] libmachine: (functional-270926) DBG | Closing plugin on server side

I1008 17:49:14.255620  396744 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:14.255637  396744 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:14.255654  396744 main.go:141] libmachine: Making call to close driver server

I1008 17:49:14.255676  396744 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:14.255932  396744 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:14.255951  396744 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:14.255952  396744 main.go:141] libmachine: (functional-270926) DBG | Closing plugin on server side

--- PASS: TestFunctional/parallel/ImageCommands/ImageListJson (0.24s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageListYaml

=== PAUSE TestFunctional/parallel/ImageCommands/ImageListYaml

=== CONT  TestFunctional/parallel/ImageCommands/ImageListYaml

functional_test.go:261: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls --format yaml --alsologtostderr

functional_test.go:266: (dbg) Stdout: out/minikube-linux-amd64 -p functional-270926 image ls --format yaml --alsologtostderr:

- id: 0184c1613d92931126feb4c548e5da11015513b9e4c104e7305ee8b53b50a9da

repoDigests: []

repoTags:

- registry.k8s.io/pause:3.3

size: "683000"

- id: 350b164e7ae1dcddeffadd65c76226c9b6dc5553f5179153fb0e36b78f2a5e06

repoDigests: []

repoTags:

- registry.k8s.io/pause:latest

size: "240000"

- id: 6bab7719df1001fdcc7e39f1decfa1f73b7f3af2757a91c5bafa1aaea29d1aee

repoDigests: []

repoTags:

- registry.k8s.io/kube-apiserver:v1.31.1

size: "94200000"

- id: 9056ab77afb8e18e04303f11000a9d31b3f16b74c59475b899ae1b342d328d30

repoDigests: []

repoTags:

- docker.io/kicbase/echo-server:functional-270926

size: "4940000"

- id: 115053965e86b2df4d78af78d7951b8644839d20a03820c6df59a261103315f7

repoDigests: []

repoTags:

- docker.io/kubernetesui/metrics-scraper:<none>

size: "43800000"

- id: 873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136

repoDigests: []

repoTags:

- registry.k8s.io/pause:3.10

size: "736000"

- id: 56cc512116c8f894f11ce1995460aef1ee0972d48bc2a3bdb1faaac7c020289c

repoDigests: []

repoTags:

- gcr.io/k8s-minikube/busybox:1.28.4-glibc

size: "4400000"

- id: 8039bf7d48d6bac3fafd612c3c34a5743ea78cf649cd589772d18d45642893cb

repoDigests: []

repoTags:

- docker.io/library/minikube-local-cache-test:functional-270926

size: "30"

- id: c69fa2e9cbf5f42dc48af631e956d3f95724c13f91596bc567591790e5e36db6

repoDigests: []

repoTags:

- registry.k8s.io/coredns/coredns:v1.11.3

size: "61800000"

- id: 2e96e5913fc06e3d26915af3d0f2ca5048cc4b6327e661e80da792cbf8d8d9d4

repoDigests: []

repoTags:

- registry.k8s.io/etcd:3.5.15-0

size: "148000000"

- id: 175ffd71cce3d90bae95904b55260db941b10007a4e5471a19f3135b30aa9cd1

repoDigests: []

repoTags:

- registry.k8s.io/kube-controller-manager:v1.31.1

size: "88400000"

- id: 6e38f40d628db3002f5617342c8872c935de530d867d0f709a2fbda1a302a562

repoDigests: []

repoTags:

- gcr.io/k8s-minikube/storage-provisioner:v5

size: "31500000"

- id: da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e

repoDigests: []

repoTags:

- registry.k8s.io/pause:3.1

size: "742000"

- id: 82e4c8a736a4fcf22b5ef9f6a4ff6207064c7187d7694bf97bd561605a538410

repoDigests: []

repoTags:

- registry.k8s.io/echoserver:1.8

size: "95400000"

- id: 9aa1fad941575eed91ab13d44f3e4cb5b1ff4e09cbbe954ea63002289416a13b

repoDigests: []

repoTags:

- registry.k8s.io/kube-scheduler:v1.31.1

size: "67400000"

- id: 60c005f310ff3ad6d131805170f07d2946095307063eaaa5eedcaf06a0a89561

repoDigests: []

repoTags:

- registry.k8s.io/kube-proxy:v1.31.1

size: "91500000"

- id: 07655ddf2eebe5d250f7a72c25f638b27126805d61779741b4e62e69ba080558

repoDigests: []

repoTags:

- docker.io/kubernetesui/dashboard:<none>

size: "246000000"

functional_test.go:269: (dbg) Stderr: out/minikube-linux-amd64 -p functional-270926 image ls --format yaml --alsologtostderr:

I1008 17:49:09.582417  396565 out.go:345] Setting OutFile to fd 1 ...

I1008 17:49:09.582576  396565 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:09.582590  396565 out.go:358] Setting ErrFile to fd 2...

I1008 17:49:09.582596  396565 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:09.582872  396565 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

I1008 17:49:09.583775  396565 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:09.583934  396565 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:09.584541  396565 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:09.584604  396565 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:09.600146  396565 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:35537

I1008 17:49:09.600685  396565 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:09.601297  396565 main.go:141] libmachine: Using API Version  1

I1008 17:49:09.601323  396565 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:09.601717  396565 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:09.602005  396565 main.go:141] libmachine: (functional-270926) Calling .GetState

I1008 17:49:09.604276  396565 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:09.604331  396565 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:09.621302  396565 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:44327

I1008 17:49:09.621857  396565 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:09.622414  396565 main.go:141] libmachine: Using API Version  1

I1008 17:49:09.622447  396565 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:09.622818  396565 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:09.623032  396565 main.go:141] libmachine: (functional-270926) Calling .DriverName

I1008 17:49:09.623237  396565 ssh_runner.go:195] Run: systemctl --version

I1008 17:49:09.623267  396565 main.go:141] libmachine: (functional-270926) Calling .GetSSHHostname

I1008 17:49:09.626347  396565 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:09.626778  396565 main.go:141] libmachine: (functional-270926) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:36:a2:c5", ip: ""} in network mk-functional-270926: {Iface:virbr1 ExpiryTime:2024-10-08 18:45:57 +0000 UTC Type:0 Mac:52:54:00:36:a2:c5 Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:functional-270926 Clientid:01:52:54:00:36:a2:c5}

I1008 17:49:09.626805  396565 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined IP address 192.168.39.153 and MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:09.626949  396565 main.go:141] libmachine: (functional-270926) Calling .GetSSHPort

I1008 17:49:09.627128  396565 main.go:141] libmachine: (functional-270926) Calling .GetSSHKeyPath

I1008 17:49:09.627275  396565 main.go:141] libmachine: (functional-270926) Calling .GetSSHUsername

I1008 17:49:09.627439  396565 sshutil.go:53] new ssh client: &{IP:192.168.39.153 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/functional-270926/id_rsa Username:docker}

I1008 17:49:09.718351  396565 ssh_runner.go:195] Run: docker images --no-trunc --format "{{json .}}"

I1008 17:49:09.741790  396565 main.go:141] libmachine: Making call to close driver server

I1008 17:49:09.741808  396565 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:09.742164  396565 main.go:141] libmachine: (functional-270926) DBG | Closing plugin on server side

I1008 17:49:09.742173  396565 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:09.742203  396565 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:09.742219  396565 main.go:141] libmachine: Making call to close driver server

I1008 17:49:09.742230  396565 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:09.742455  396565 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:09.742469  396565 main.go:141] libmachine: Making call to close connection to plugin binary

--- PASS: TestFunctional/parallel/ImageCommands/ImageListYaml (0.22s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageBuild

=== PAUSE TestFunctional/parallel/ImageCommands/ImageBuild

=== CONT  TestFunctional/parallel/ImageCommands/ImageBuild

functional_test.go:308: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh pgrep buildkitd

E1008 17:49:09.833897  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

functional_test.go:308: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh pgrep buildkitd: exit status 1 (208.329694ms)

** stderr ** 

	ssh: Process exited with status 1

** /stderr **

functional_test.go:315: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image build -t localhost/my-image:functional-270926 testdata/build --alsologtostderr

E1008 17:49:10.475512  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

functional_test.go:315: (dbg) Done: out/minikube-linux-amd64 -p functional-270926 image build -t localhost/my-image:functional-270926 testdata/build --alsologtostderr: (4.617075964s)

functional_test.go:323: (dbg) Stderr: out/minikube-linux-amd64 -p functional-270926 image build -t localhost/my-image:functional-270926 testdata/build --alsologtostderr:

I1008 17:49:10.007568  396618 out.go:345] Setting OutFile to fd 1 ...

I1008 17:49:10.008153  396618 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:10.008170  396618 out.go:358] Setting ErrFile to fd 2...

I1008 17:49:10.008178  396618 out.go:392] TERM=,COLORTERM=, which probably does not support color

I1008 17:49:10.008627  396618 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

I1008 17:49:10.009885  396618 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:10.010489  396618 config.go:182] Loaded profile config "functional-270926": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

I1008 17:49:10.010850  396618 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:10.010914  396618 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:10.027532  396618 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:34665

I1008 17:49:10.028140  396618 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:10.028733  396618 main.go:141] libmachine: Using API Version  1

I1008 17:49:10.028770  396618 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:10.029230  396618 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:10.029483  396618 main.go:141] libmachine: (functional-270926) Calling .GetState

I1008 17:49:10.031643  396618 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

I1008 17:49:10.031688  396618 main.go:141] libmachine: Launching plugin server for driver kvm2

I1008 17:49:10.048382  396618 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:45643

I1008 17:49:10.048889  396618 main.go:141] libmachine: () Calling .GetVersion

I1008 17:49:10.049427  396618 main.go:141] libmachine: Using API Version  1

I1008 17:49:10.049453  396618 main.go:141] libmachine: () Calling .SetConfigRaw

I1008 17:49:10.049787  396618 main.go:141] libmachine: () Calling .GetMachineName

I1008 17:49:10.049985  396618 main.go:141] libmachine: (functional-270926) Calling .DriverName

I1008 17:49:10.050189  396618 ssh_runner.go:195] Run: systemctl --version

I1008 17:49:10.050214  396618 main.go:141] libmachine: (functional-270926) Calling .GetSSHHostname

I1008 17:49:10.053052  396618 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:10.053678  396618 main.go:141] libmachine: (functional-270926) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:36:a2:c5", ip: ""} in network mk-functional-270926: {Iface:virbr1 ExpiryTime:2024-10-08 18:45:57 +0000 UTC Type:0 Mac:52:54:00:36:a2:c5 Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:functional-270926 Clientid:01:52:54:00:36:a2:c5}

I1008 17:49:10.053709  396618 main.go:141] libmachine: (functional-270926) DBG | domain functional-270926 has defined IP address 192.168.39.153 and MAC address 52:54:00:36:a2:c5 in network mk-functional-270926

I1008 17:49:10.053902  396618 main.go:141] libmachine: (functional-270926) Calling .GetSSHPort

I1008 17:49:10.054074  396618 main.go:141] libmachine: (functional-270926) Calling .GetSSHKeyPath

I1008 17:49:10.054244  396618 main.go:141] libmachine: (functional-270926) Calling .GetSSHUsername

I1008 17:49:10.054388  396618 sshutil.go:53] new ssh client: &{IP:192.168.39.153 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/functional-270926/id_rsa Username:docker}

I1008 17:49:10.143627  396618 build_images.go:161] Building image from path: /tmp/build.2312623997.tar

I1008 17:49:10.143715  396618 ssh_runner.go:195] Run: sudo mkdir -p /var/lib/minikube/build

I1008 17:49:10.164824  396618 ssh_runner.go:195] Run: stat -c "%s %y" /var/lib/minikube/build/build.2312623997.tar

I1008 17:49:10.169815  396618 ssh_runner.go:352] existence check for /var/lib/minikube/build/build.2312623997.tar: stat -c "%s %y" /var/lib/minikube/build/build.2312623997.tar: Process exited with status 1

stdout:

stderr:

stat: cannot statx '/var/lib/minikube/build/build.2312623997.tar': No such file or directory

I1008 17:49:10.169854  396618 ssh_runner.go:362] scp /tmp/build.2312623997.tar --> /var/lib/minikube/build/build.2312623997.tar (3072 bytes)

I1008 17:49:10.199237  396618 ssh_runner.go:195] Run: sudo mkdir -p /var/lib/minikube/build/build.2312623997

I1008 17:49:10.218370  396618 ssh_runner.go:195] Run: sudo tar -C /var/lib/minikube/build/build.2312623997 -xf /var/lib/minikube/build/build.2312623997.tar

I1008 17:49:10.231918  396618 docker.go:360] Building image: /var/lib/minikube/build/build.2312623997

I1008 17:49:10.231998  396618 ssh_runner.go:195] Run: docker build -t localhost/my-image:functional-270926 /var/lib/minikube/build/build.2312623997

#0 building with "default" instance using docker driver

#1 [internal] load build definition from Dockerfile

#1 transferring dockerfile: 97B done

#1 DONE 0.1s

#2 [internal] load metadata for gcr.io/k8s-minikube/busybox:latest

#2 DONE 2.2s

#3 [internal] load .dockerignore

#3 transferring context: 2B done

#3 DONE 0.0s

#4 [internal] load build context

#4 transferring context: 62B done

#4 DONE 0.1s

#5 [1/3] FROM gcr.io/k8s-minikube/busybox:latest@sha256:ca5ae90100d50772da31f3b5016209e25ad61972404e2ccd83d44f10dee7e79b

#5 resolve gcr.io/k8s-minikube/busybox:latest@sha256:ca5ae90100d50772da31f3b5016209e25ad61972404e2ccd83d44f10dee7e79b 0.0s done

#5 sha256:ca5ae90100d50772da31f3b5016209e25ad61972404e2ccd83d44f10dee7e79b 770B / 770B done

#5 sha256:62ffc2ed7554e4c6d360bce40bbcf196573dd27c4ce080641a2c59867e732dee 527B / 527B done

#5 sha256:beae173ccac6ad749f76713cf4440fe3d21d1043fe616dfbe30775815d1d0f6a 1.46kB / 1.46kB done

#5 sha256:5cc84ad355aaa64f46ea9c7bbcc319a9d808ab15088a27209c9e70ef86e5a2aa 0B / 772.79kB 0.1s

#5 sha256:5cc84ad355aaa64f46ea9c7bbcc319a9d808ab15088a27209c9e70ef86e5a2aa 772.79kB / 772.79kB 0.6s done

#5 extracting sha256:5cc84ad355aaa64f46ea9c7bbcc319a9d808ab15088a27209c9e70ef86e5a2aa 0.1s done

#5 DONE 0.8s

#6 [2/3] RUN true

#6 DONE 0.5s

#7 [3/3] ADD content.txt /

#7 DONE 0.1s

#8 exporting to image

#8 exporting layers 0.1s done

#8 writing image sha256:5afa2c9663b0fcb196b21974a64f421f925a67b68e98d0c246aa9cfdfa12eb82 done

#8 naming to localhost/my-image:functional-270926 done

#8 DONE 0.1s

I1008 17:49:14.539263  396618 ssh_runner.go:235] Completed: docker build -t localhost/my-image:functional-270926 /var/lib/minikube/build/build.2312623997: (4.307235864s)

I1008 17:49:14.539375  396618 ssh_runner.go:195] Run: sudo rm -rf /var/lib/minikube/build/build.2312623997

I1008 17:49:14.555069  396618 ssh_runner.go:195] Run: sudo rm -f /var/lib/minikube/build/build.2312623997.tar

I1008 17:49:14.567903  396618 build_images.go:217] Built localhost/my-image:functional-270926 from /tmp/build.2312623997.tar

I1008 17:49:14.567941  396618 build_images.go:133] succeeded building to: functional-270926

I1008 17:49:14.567945  396618 build_images.go:134] failed building to: 

I1008 17:49:14.567970  396618 main.go:141] libmachine: Making call to close driver server

I1008 17:49:14.567979  396618 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:14.568334  396618 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:14.568353  396618 main.go:141] libmachine: Making call to close connection to plugin binary

I1008 17:49:14.568363  396618 main.go:141] libmachine: Making call to close driver server

I1008 17:49:14.568370  396618 main.go:141] libmachine: (functional-270926) Calling .Close

I1008 17:49:14.568632  396618 main.go:141] libmachine: Successfully made call to close driver server

I1008 17:49:14.568646  396618 main.go:141] libmachine: Making call to close connection to plugin binary

functional_test.go:451: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls

E1008 17:49:19.440864  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

--- PASS: TestFunctional/parallel/ImageCommands/ImageBuild (5.05s)

=== RUN   TestFunctional/parallel/ImageCommands/Setup

functional_test.go:342: (dbg) Run:  docker pull kicbase/echo-server:1.0

functional_test.go:342: (dbg) Done: docker pull kicbase/echo-server:1.0: (1.967386129s)

functional_test.go:347: (dbg) Run:  docker tag kicbase/echo-server:1.0 kicbase/echo-server:functional-270926

--- PASS: TestFunctional/parallel/ImageCommands/Setup (2.00s)

=== RUN   TestFunctional/parallel/DockerEnv/bash

functional_test.go:499: (dbg) Run:  /bin/bash -c "eval $(out/minikube-linux-amd64 -p functional-270926 docker-env) && out/minikube-linux-amd64 status -p functional-270926"

functional_test.go:522: (dbg) Run:  /bin/bash -c "eval $(out/minikube-linux-amd64 -p functional-270926 docker-env) && docker images"

--- PASS: TestFunctional/parallel/DockerEnv/bash (0.86s)

=== RUN   TestFunctional/parallel/UpdateContextCmd/no_changes

=== PAUSE TestFunctional/parallel/UpdateContextCmd/no_changes

=== CONT  TestFunctional/parallel/UpdateContextCmd/no_changes

functional_test.go:2119: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 update-context --alsologtostderr -v=2

E1008 17:49:09.226351  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:49:09.268281  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

--- PASS: TestFunctional/parallel/UpdateContextCmd/no_changes (0.10s)

=== RUN   TestFunctional/parallel/UpdateContextCmd/no_minikube_cluster

=== PAUSE TestFunctional/parallel/UpdateContextCmd/no_minikube_cluster

=== CONT  TestFunctional/parallel/UpdateContextCmd/no_minikube_cluster

functional_test.go:2119: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 update-context --alsologtostderr -v=2

--- PASS: TestFunctional/parallel/UpdateContextCmd/no_minikube_cluster (0.10s)

=== RUN   TestFunctional/parallel/UpdateContextCmd/no_clusters

=== PAUSE TestFunctional/parallel/UpdateContextCmd/no_clusters

=== CONT  TestFunctional/parallel/UpdateContextCmd/no_clusters

functional_test.go:2119: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 update-context --alsologtostderr -v=2

E1008 17:49:09.186030  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:49:09.192401  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:49:09.204070  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

--- PASS: TestFunctional/parallel/UpdateContextCmd/no_clusters (0.09s)

=== RUN   TestFunctional/parallel/ServiceCmd/DeployApp

functional_test.go:1439: (dbg) Run:  kubectl --context functional-270926 create deployment hello-node --image=registry.k8s.io/echoserver:1.8

functional_test.go:1445: (dbg) Run:  kubectl --context functional-270926 expose deployment hello-node --type=NodePort --port=8080

functional_test.go:1450: (dbg) TestFunctional/parallel/ServiceCmd/DeployApp: waiting 10m0s for pods matching "app=hello-node" in namespace "default" ...

helpers_test.go:344: "hello-node-6b9f76b5c7-bfhpw" [8c649920-6bdc-4e7f-92ae-f64661294b20] Pending / Ready:ContainersNotReady (containers with unready status: [echoserver]) / ContainersReady:ContainersNotReady (containers with unready status: [echoserver])

helpers_test.go:344: "hello-node-6b9f76b5c7-bfhpw" [8c649920-6bdc-4e7f-92ae-f64661294b20] Running

functional_test.go:1450: (dbg) TestFunctional/parallel/ServiceCmd/DeployApp: app=hello-node healthy within 13.00525222s

--- PASS: TestFunctional/parallel/ServiceCmd/DeployApp (13.19s)

=== RUN   TestFunctional/parallel/ProfileCmd/profile_not_create

functional_test.go:1270: (dbg) Run:  out/minikube-linux-amd64 profile lis

functional_test.go:1275: (dbg) Run:  out/minikube-linux-amd64 profile list --output json

--- PASS: TestFunctional/parallel/ProfileCmd/profile_not_create (0.40s)

=== RUN   TestFunctional/parallel/ProfileCmd/profile_list

functional_test.go:1310: (dbg) Run:  out/minikube-linux-amd64 profile list

functional_test.go:1315: Took "329.302859ms" to run "out/minikube-linux-amd64 profile list"

functional_test.go:1324: (dbg) Run:  out/minikube-linux-amd64 profile list -l

functional_test.go:1329: Took "67.43856ms" to run "out/minikube-linux-amd64 profile list -l"

--- PASS: TestFunctional/parallel/ProfileCmd/profile_list (0.40s)

=== RUN   TestFunctional/parallel/MountCmd/any-port

functional_test_mount_test.go:73: (dbg) daemon: [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdany-port501513947/001:/mount-9p --alsologtostderr -v=1]

functional_test_mount_test.go:107: wrote "test-1728409730725258816" to /tmp/TestFunctionalparallelMountCmdany-port501513947/001/created-by-test

functional_test_mount_test.go:107: wrote "test-1728409730725258816" to /tmp/TestFunctionalparallelMountCmdany-port501513947/001/created-by-test-removed-by-pod

functional_test_mount_test.go:107: wrote "test-1728409730725258816" to /tmp/TestFunctionalparallelMountCmdany-port501513947/001/test-1728409730725258816

functional_test_mount_test.go:115: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T /mount-9p | grep 9p"

functional_test_mount_test.go:115: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T /mount-9p | grep 9p": exit status 1 (218.011627ms)

** stderr ** 

	ssh: Process exited with status 1

** /stderr **

I1008 17:48:50.943574  386147 retry.go:31] will retry after 364.530489ms: exit status 1

functional_test_mount_test.go:115: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T /mount-9p | grep 9p"

functional_test_mount_test.go:129: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh -- ls -la /mount-9p

functional_test_mount_test.go:133: guest mount directory contents

total 2

-rw-r--r-- 1 docker docker 24 Oct  8 17:48 created-by-test

-rw-r--r-- 1 docker docker 24 Oct  8 17:48 created-by-test-removed-by-pod

-rw-r--r-- 1 docker docker 24 Oct  8 17:48 test-1728409730725258816

functional_test_mount_test.go:137: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh cat /mount-9p/test-1728409730725258816

functional_test_mount_test.go:148: (dbg) Run:  kubectl --context functional-270926 replace --force -f testdata/busybox-mount-test.yaml

functional_test_mount_test.go:153: (dbg) TestFunctional/parallel/MountCmd/any-port: waiting 4m0s for pods matching "integration-test=busybox-mount" in namespace "default" ...

helpers_test.go:344: "busybox-mount" [c53bdabc-863c-46a7-b81c-7d536597487d] Pending

helpers_test.go:344: "busybox-mount" [c53bdabc-863c-46a7-b81c-7d536597487d] Pending / Ready:ContainersNotReady (containers with unready status: [mount-munger]) / ContainersReady:ContainersNotReady (containers with unready status: [mount-munger])

helpers_test.go:344: "busybox-mount" [c53bdabc-863c-46a7-b81c-7d536597487d] Pending / Initialized:PodCompleted / Ready:PodCompleted / ContainersReady:PodCompleted

helpers_test.go:344: "busybox-mount" [c53bdabc-863c-46a7-b81c-7d536597487d] Succeeded / Initialized:PodCompleted / Ready:PodCompleted / ContainersReady:PodCompleted

functional_test_mount_test.go:153: (dbg) TestFunctional/parallel/MountCmd/any-port: integration-test=busybox-mount healthy within 8.003636851s

functional_test_mount_test.go:169: (dbg) Run:  kubectl --context functional-270926 logs busybox-mount

functional_test_mount_test.go:181: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh stat /mount-9p/created-by-test

functional_test_mount_test.go:181: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh stat /mount-9p/created-by-pod

functional_test_mount_test.go:90: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo umount -f /mount-9p"

functional_test_mount_test.go:94: (dbg) stopping [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdany-port501513947/001:/mount-9p --alsologtostderr -v=1] ...

--- PASS: TestFunctional/parallel/MountCmd/any-port (10.53s)

=== RUN   TestFunctional/parallel/ProfileCmd/profile_json_output

functional_test.go:1361: (dbg) Run:  out/minikube-linux-amd64 profile list -o json

functional_test.go:1366: Took "347.542481ms" to run "out/minikube-linux-amd64 profile list -o json"

functional_test.go:1374: (dbg) Run:  out/minikube-linux-amd64 profile list -o json --light

functional_test.go:1379: Took "63.281319ms" to run "out/minikube-linux-amd64 profile list -o json --light"

--- PASS: TestFunctional/parallel/ProfileCmd/profile_json_output (0.41s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageLoadDaemon

functional_test.go:355: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image load --daemon kicbase/echo-server:functional-270926 --alsologtostderr

functional_test.go:355: (dbg) Done: out/minikube-linux-amd64 -p functional-270926 image load --daemon kicbase/echo-server:functional-270926 --alsologtostderr: (1.106044778s)

functional_test.go:451: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls

--- PASS: TestFunctional/parallel/ImageCommands/ImageLoadDaemon (1.38s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageReloadDaemon

functional_test.go:365: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image load --daemon kicbase/echo-server:functional-270926 --alsologtostderr

functional_test.go:451: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls

--- PASS: TestFunctional/parallel/ImageCommands/ImageReloadDaemon (0.89s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageTagAndLoadDaemon

functional_test.go:235: (dbg) Run:  docker pull kicbase/echo-server:latest

functional_test.go:240: (dbg) Run:  docker tag kicbase/echo-server:latest kicbase/echo-server:functional-270926

functional_test.go:245: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image load --daemon kicbase/echo-server:functional-270926 --alsologtostderr

functional_test.go:451: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls

--- PASS: TestFunctional/parallel/ImageCommands/ImageTagAndLoadDaemon (1.70s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageSaveToFile

functional_test.go:380: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image save kicbase/echo-server:functional-270926 /home/jenkins/workspace/KVM_Linux_integration/echo-server-save.tar --alsologtostderr

--- PASS: TestFunctional/parallel/ImageCommands/ImageSaveToFile (0.39s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageRemove

functional_test.go:392: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image rm kicbase/echo-server:functional-270926 --alsologtostderr

functional_test.go:451: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls

--- PASS: TestFunctional/parallel/ImageCommands/ImageRemove (0.40s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageLoadFromFile

functional_test.go:409: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image load /home/jenkins/workspace/KVM_Linux_integration/echo-server-save.tar --alsologtostderr

functional_test.go:451: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image ls

--- PASS: TestFunctional/parallel/ImageCommands/ImageLoadFromFile (0.72s)

=== RUN   TestFunctional/parallel/ImageCommands/ImageSaveDaemon

functional_test.go:419: (dbg) Run:  docker rmi kicbase/echo-server:functional-270926

functional_test.go:424: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 image save --daemon kicbase/echo-server:functional-270926 --alsologtostderr

functional_test.go:432: (dbg) Run:  docker image inspect kicbase/echo-server:functional-270926

--- PASS: TestFunctional/parallel/ImageCommands/ImageSaveDaemon (0.35s)

=== RUN   TestFunctional/parallel/MountCmd/specific-port

functional_test_mount_test.go:213: (dbg) daemon: [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdspecific-port970934005/001:/mount-9p --alsologtostderr -v=1 --port 46464]

functional_test_mount_test.go:243: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T /mount-9p | grep 9p"

functional_test_mount_test.go:243: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T /mount-9p | grep 9p": exit status 1 (231.233297ms)

** stderr ** 

	ssh: Process exited with status 1

** /stderr **

I1008 17:49:01.485425  386147 retry.go:31] will retry after 480.714238ms: exit status 1

functional_test_mount_test.go:243: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T /mount-9p | grep 9p"

functional_test_mount_test.go:257: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh -- ls -la /mount-9p

functional_test_mount_test.go:261: guest mount directory contents

total 0

functional_test_mount_test.go:263: (dbg) stopping [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdspecific-port970934005/001:/mount-9p --alsologtostderr -v=1 --port 46464] ...

functional_test_mount_test.go:264: reading mount text

functional_test_mount_test.go:278: done reading mount text

functional_test_mount_test.go:230: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "sudo umount -f /mount-9p"

functional_test_mount_test.go:230: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh "sudo umount -f /mount-9p": exit status 1 (208.219306ms)

-- stdout --

	umount: /mount-9p: not mounted.

-- /stdout --

** stderr ** 

	ssh: Process exited with status 32

** /stderr **

functional_test_mount_test.go:232: "out/minikube-linux-amd64 -p functional-270926 ssh \"sudo umount -f /mount-9p\"": exit status 1

functional_test_mount_test.go:234: (dbg) stopping [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdspecific-port970934005/001:/mount-9p --alsologtostderr -v=1 --port 46464] ...

--- PASS: TestFunctional/parallel/MountCmd/specific-port (1.74s)

=== RUN   TestFunctional/parallel/MountCmd/VerifyCleanup

functional_test_mount_test.go:298: (dbg) daemon: [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdVerifyCleanup2451227214/001:/mount1 --alsologtostderr -v=1]

functional_test_mount_test.go:298: (dbg) daemon: [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdVerifyCleanup2451227214/001:/mount2 --alsologtostderr -v=1]

functional_test_mount_test.go:298: (dbg) daemon: [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdVerifyCleanup2451227214/001:/mount3 --alsologtostderr -v=1]

functional_test_mount_test.go:325: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T" /mount1

functional_test_mount_test.go:325: (dbg) Non-zero exit: out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T" /mount1: exit status 1 (339.318615ms)

** stderr ** 

	ssh: Process exited with status 1

** /stderr **

I1008 17:49:03.336982  386147 retry.go:31] will retry after 583.257836ms: exit status 1

functional_test_mount_test.go:325: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T" /mount1

functional_test_mount_test.go:325: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T" /mount2

functional_test_mount_test.go:325: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 ssh "findmnt -T" /mount3

functional_test_mount_test.go:370: (dbg) Run:  out/minikube-linux-amd64 mount -p functional-270926 --kill=true

functional_test_mount_test.go:313: (dbg) stopping [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdVerifyCleanup2451227214/001:/mount1 --alsologtostderr -v=1] ...

helpers_test.go:490: unable to find parent, assuming dead: process does not exist

functional_test_mount_test.go:313: (dbg) stopping [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdVerifyCleanup2451227214/001:/mount2 --alsologtostderr -v=1] ...

helpers_test.go:490: unable to find parent, assuming dead: process does not exist

functional_test_mount_test.go:313: (dbg) stopping [out/minikube-linux-amd64 mount -p functional-270926 /tmp/TestFunctionalparallelMountCmdVerifyCleanup2451227214/001:/mount3 --alsologtostderr -v=1] ...

helpers_test.go:490: unable to find parent, assuming dead: process does not exist

--- PASS: TestFunctional/parallel/MountCmd/VerifyCleanup (1.61s)

=== RUN   TestFunctional/parallel/ServiceCmd/List

functional_test.go:1459: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 service list

functional_test.go:1459: (dbg) Done: out/minikube-linux-amd64 -p functional-270926 service list: (1.233596774s)

--- PASS: TestFunctional/parallel/ServiceCmd/List (1.23s)

=== RUN   TestFunctional/parallel/ServiceCmd/JSONOutput

functional_test.go:1489: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 service list -o json

functional_test.go:1494: Took "873.204466ms" to run "out/minikube-linux-amd64 -p functional-270926 service list -o json"

--- PASS: TestFunctional/parallel/ServiceCmd/JSONOutput (0.87s)

=== RUN   TestFunctional/parallel/ServiceCmd/HTTPS

functional_test.go:1509: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 service --namespace=default --https --url hello-node

functional_test.go:1522: found endpoint: https://192.168.39.153:31423

--- PASS: TestFunctional/parallel/ServiceCmd/HTTPS (0.32s)

=== RUN   TestFunctional/parallel/ServiceCmd/Format

functional_test.go:1540: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 service hello-node --url --format={{.IP}}

--- PASS: TestFunctional/parallel/ServiceCmd/Format (0.33s)

=== RUN   TestFunctional/parallel/ServiceCmd/URL

functional_test.go:1559: (dbg) Run:  out/minikube-linux-amd64 -p functional-270926 service hello-node --url

functional_test.go:1565: found endpoint for hello-node: http://192.168.39.153:31423

--- PASS: TestFunctional/parallel/ServiceCmd/URL (0.30s)

=== RUN   TestFunctional/delete_echo-server_images

functional_test.go:190: (dbg) Run:  docker rmi -f kicbase/echo-server:1.0

functional_test.go:190: (dbg) Run:  docker rmi -f kicbase/echo-server:functional-270926

--- PASS: TestFunctional/delete_echo-server_images (0.04s)

=== RUN   TestFunctional/delete_my-image_image

functional_test.go:198: (dbg) Run:  docker rmi -f localhost/my-image:functional-270926

--- PASS: TestFunctional/delete_my-image_image (0.02s)

=== RUN   TestFunctional/delete_minikube_cached_images

functional_test.go:206: (dbg) Run:  docker rmi -f minikube-local-cache-test:functional-270926

--- PASS: TestFunctional/delete_minikube_cached_images (0.02s)

=== RUN   TestGvisorAddon

=== PAUSE TestGvisorAddon

=== CONT  TestGvisorAddon

gvisor_addon_test.go:52: (dbg) Run:  out/minikube-linux-amd64 start -p gvisor-649944 --memory=2200 --container-runtime=containerd --docker-opt containerd=/var/run/containerd/containerd.sock --driver=kvm2 

gvisor_addon_test.go:52: (dbg) Done: out/minikube-linux-amd64 start -p gvisor-649944 --memory=2200 --container-runtime=containerd --docker-opt containerd=/var/run/containerd/containerd.sock --driver=kvm2 : (1m21.081858651s)

gvisor_addon_test.go:58: (dbg) Run:  out/minikube-linux-amd64 -p gvisor-649944 cache add gcr.io/k8s-minikube/gvisor-addon:2

E1008 18:34:09.187470  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

gvisor_addon_test.go:58: (dbg) Done: out/minikube-linux-amd64 -p gvisor-649944 cache add gcr.io/k8s-minikube/gvisor-addon:2: (21.55117713s)

gvisor_addon_test.go:63: (dbg) Run:  out/minikube-linux-amd64 -p gvisor-649944 addons enable gvisor

gvisor_addon_test.go:63: (dbg) Done: out/minikube-linux-amd64 -p gvisor-649944 addons enable gvisor: (5.706289251s)

gvisor_addon_test.go:68: (dbg) TestGvisorAddon: waiting 4m0s for pods matching "kubernetes.io/minikube-addons=gvisor" in namespace "kube-system" ...

helpers_test.go:344: "gvisor" [35a2de9e-42d5-4a35-a733-a68560e702c1] Running

gvisor_addon_test.go:68: (dbg) TestGvisorAddon: kubernetes.io/minikube-addons=gvisor healthy within 6.004175154s

gvisor_addon_test.go:73: (dbg) Run:  kubectl --context gvisor-649944 replace --force -f testdata/nginx-gvisor.yaml

gvisor_addon_test.go:78: (dbg) TestGvisorAddon: waiting 4m0s for pods matching "run=nginx,runtime=gvisor" in namespace "default" ...

helpers_test.go:344: "nginx-gvisor" [92e46a06-dfd8-4eee-b434-f24c87eb68a7] Pending

helpers_test.go:344: "nginx-gvisor" [92e46a06-dfd8-4eee-b434-f24c87eb68a7] Pending / Ready:ContainersNotReady (containers with unready status: [nginx]) / ContainersReady:ContainersNotReady (containers with unready status: [nginx])

helpers_test.go:344: "nginx-gvisor" [92e46a06-dfd8-4eee-b434-f24c87eb68a7] Running

gvisor_addon_test.go:78: (dbg) TestGvisorAddon: run=nginx,runtime=gvisor healthy within 26.004694456s

gvisor_addon_test.go:83: (dbg) Run:  out/minikube-linux-amd64 stop -p gvisor-649944

gvisor_addon_test.go:83: (dbg) Done: out/minikube-linux-amd64 stop -p gvisor-649944: (6.794480412s)

gvisor_addon_test.go:88: (dbg) Run:  out/minikube-linux-amd64 start -p gvisor-649944 --memory=2200 --container-runtime=containerd --docker-opt containerd=/var/run/containerd/containerd.sock --driver=kvm2 

gvisor_addon_test.go:88: (dbg) Done: out/minikube-linux-amd64 start -p gvisor-649944 --memory=2200 --container-runtime=containerd --docker-opt containerd=/var/run/containerd/containerd.sock --driver=kvm2 : (53.84029389s)

gvisor_addon_test.go:92: (dbg) TestGvisorAddon: waiting 4m0s for pods matching "kubernetes.io/minikube-addons=gvisor" in namespace "kube-system" ...

helpers_test.go:344: "gvisor" [35a2de9e-42d5-4a35-a733-a68560e702c1] Running

gvisor_addon_test.go:92: (dbg) TestGvisorAddon: kubernetes.io/minikube-addons=gvisor healthy within 6.003116337s

gvisor_addon_test.go:95: (dbg) TestGvisorAddon: waiting 4m0s for pods matching "run=nginx,runtime=gvisor" in namespace "default" ...

helpers_test.go:344: "nginx-gvisor" [92e46a06-dfd8-4eee-b434-f24c87eb68a7] Running / Ready:ContainersNotReady (containers with unready status: [nginx]) / ContainersReady:ContainersNotReady (containers with unready status: [nginx])

gvisor_addon_test.go:95: (dbg) TestGvisorAddon: run=nginx,runtime=gvisor healthy within 5.004519222s

helpers_test.go:175: Cleaning up "gvisor-649944" profile ...

helpers_test.go:178: (dbg) Run:  out/minikube-linux-amd64 delete -p gvisor-649944

helpers_test.go:178: (dbg) Done: out/minikube-linux-amd64 delete -p gvisor-649944: (1.086582676s)

--- PASS: TestGvisorAddon (213.25s)

=== RUN   TestMultiControlPlane/serial/StartCluster

ha_test.go:101: (dbg) Run:  out/minikube-linux-amd64 start -p ha-876017 --wait=true --memory=2200 --ha -v=7 --alsologtostderr --driver=kvm2 

E1008 17:49:50.164590  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:50:31.126862  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:51:53.048569  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

ha_test.go:101: (dbg) Done: out/minikube-linux-amd64 start -p ha-876017 --wait=true --memory=2200 --ha -v=7 --alsologtostderr --driver=kvm2 : (3m34.613822528s)

ha_test.go:107: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 status -v=7 --alsologtostderr

--- PASS: TestMultiControlPlane/serial/StartCluster (215.29s)

=== RUN   TestMultiControlPlane/serial/DeployApp

ha_test.go:128: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- apply -f ./testdata/ha/ha-pod-dns-test.yaml

ha_test.go:133: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- rollout status deployment/busybox

ha_test.go:133: (dbg) Done: out/minikube-linux-amd64 kubectl -p ha-876017 -- rollout status deployment/busybox: (3.719591374s)

ha_test.go:140: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- get pods -o jsonpath='{.items[*].status.podIP}'

ha_test.go:163: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- get pods -o jsonpath='{.items[*].metadata.name}'

ha_test.go:171: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-99t6v -- nslookup kubernetes.io

ha_test.go:171: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-cbnxb -- nslookup kubernetes.io

ha_test.go:171: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-kjz8b -- nslookup kubernetes.io

ha_test.go:181: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-99t6v -- nslookup kubernetes.default

ha_test.go:181: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-cbnxb -- nslookup kubernetes.default

ha_test.go:181: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-kjz8b -- nslookup kubernetes.default

ha_test.go:189: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-99t6v -- nslookup kubernetes.default.svc.cluster.local

ha_test.go:189: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-cbnxb -- nslookup kubernetes.default.svc.cluster.local

ha_test.go:189: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-kjz8b -- nslookup kubernetes.default.svc.cluster.local

--- PASS: TestMultiControlPlane/serial/DeployApp (5.96s)

=== RUN   TestMultiControlPlane/serial/PingHostFromPods

ha_test.go:199: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- get pods -o jsonpath='{.items[*].metadata.name}'

ha_test.go:207: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-99t6v -- sh -c "nslookup host.minikube.internal | awk 'NR==5' | cut -d' ' -f3"

ha_test.go:218: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-99t6v -- sh -c "ping -c 1 192.168.39.1"

ha_test.go:207: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-cbnxb -- sh -c "nslookup host.minikube.internal | awk 'NR==5' | cut -d' ' -f3"

ha_test.go:218: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-cbnxb -- sh -c "ping -c 1 192.168.39.1"

ha_test.go:207: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-kjz8b -- sh -c "nslookup host.minikube.internal | awk 'NR==5' | cut -d' ' -f3"

ha_test.go:218: (dbg) Run:  out/minikube-linux-amd64 kubectl -p ha-876017 -- exec busybox-7dff88458-kjz8b -- sh -c "ping -c 1 192.168.39.1"

--- PASS: TestMultiControlPlane/serial/PingHostFromPods (1.22s)

=== RUN   TestMultiControlPlane/serial/AddWorkerNode

ha_test.go:228: (dbg) Run:  out/minikube-linux-amd64 node add -p ha-876017 -v=7 --alsologtostderr

E1008 17:53:50.011562  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.017975  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.029400  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.050816  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.092268  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.173781  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.335214  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:50.656628  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:51.298695  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:52.580162  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:53:55.142548  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:54:00.264125  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:54:09.186105  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:54:10.506391  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:54:30.988140  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

ha_test.go:228: (dbg) Done: out/minikube-linux-amd64 node add -p ha-876017 -v=7 --alsologtostderr: (1m1.365360044s)

ha_test.go:234: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 status -v=7 --alsologtostderr

--- PASS: TestMultiControlPlane/serial/AddWorkerNode (62.21s)

=== RUN   TestMultiControlPlane/serial/NodeLabels

ha_test.go:255: (dbg) Run:  kubectl --context ha-876017 get nodes -o "jsonpath=[{range .items[*]}{.metadata.labels},{end}]"

--- PASS: TestMultiControlPlane/serial/NodeLabels (0.07s)

=== RUN   TestMultiControlPlane/serial/HAppyAfterClusterStart

ha_test.go:281: (dbg) Run:  out/minikube-linux-amd64 profile list --output json

--- PASS: TestMultiControlPlane/serial/HAppyAfterClusterStart (0.87s)

=== RUN   TestMultiControlPlane/serial/CopyFile

ha_test.go:328: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 status --output json -v=7 --alsologtostderr

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp testdata/cp-test.txt ha-876017:/home/docker/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017:/home/docker/cp-test.txt /tmp/TestMultiControlPlaneserialCopyFile2860984875/001/cp-test_ha-876017.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017:/home/docker/cp-test.txt ha-876017-m02:/home/docker/cp-test_ha-876017_ha-876017-m02.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test_ha-876017_ha-876017-m02.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017:/home/docker/cp-test.txt ha-876017-m03:/home/docker/cp-test_ha-876017_ha-876017-m03.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test_ha-876017_ha-876017-m03.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017:/home/docker/cp-test.txt ha-876017-m04:/home/docker/cp-test_ha-876017_ha-876017-m04.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test.txt"

E1008 17:54:36.890537  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test_ha-876017_ha-876017-m04.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp testdata/cp-test.txt ha-876017-m02:/home/docker/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m02:/home/docker/cp-test.txt /tmp/TestMultiControlPlaneserialCopyFile2860984875/001/cp-test_ha-876017-m02.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m02:/home/docker/cp-test.txt ha-876017:/home/docker/cp-test_ha-876017-m02_ha-876017.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test_ha-876017-m02_ha-876017.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m02:/home/docker/cp-test.txt ha-876017-m03:/home/docker/cp-test_ha-876017-m02_ha-876017-m03.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test_ha-876017-m02_ha-876017-m03.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m02:/home/docker/cp-test.txt ha-876017-m04:/home/docker/cp-test_ha-876017-m02_ha-876017-m04.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test_ha-876017-m02_ha-876017-m04.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp testdata/cp-test.txt ha-876017-m03:/home/docker/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m03:/home/docker/cp-test.txt /tmp/TestMultiControlPlaneserialCopyFile2860984875/001/cp-test_ha-876017-m03.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m03:/home/docker/cp-test.txt ha-876017:/home/docker/cp-test_ha-876017-m03_ha-876017.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test_ha-876017-m03_ha-876017.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m03:/home/docker/cp-test.txt ha-876017-m02:/home/docker/cp-test_ha-876017-m03_ha-876017-m02.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test_ha-876017-m03_ha-876017-m02.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m03:/home/docker/cp-test.txt ha-876017-m04:/home/docker/cp-test_ha-876017-m03_ha-876017-m04.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test_ha-876017-m03_ha-876017-m04.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp testdata/cp-test.txt ha-876017-m04:/home/docker/cp-test.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m04:/home/docker/cp-test.txt /tmp/TestMultiControlPlaneserialCopyFile2860984875/001/cp-test_ha-876017-m04.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m04:/home/docker/cp-test.txt ha-876017:/home/docker/cp-test_ha-876017-m04_ha-876017.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017 "sudo cat /home/docker/cp-test_ha-876017-m04_ha-876017.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m04:/home/docker/cp-test.txt ha-876017-m02:/home/docker/cp-test_ha-876017-m04_ha-876017-m02.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m02 "sudo cat /home/docker/cp-test_ha-876017-m04_ha-876017-m02.txt"

helpers_test.go:556: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 cp ha-876017-m04:/home/docker/cp-test.txt ha-876017-m03:/home/docker/cp-test_ha-876017-m04_ha-876017-m03.txt

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m04 "sudo cat /home/docker/cp-test.txt"

helpers_test.go:534: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 ssh -n ha-876017-m03 "sudo cat /home/docker/cp-test_ha-876017-m04_ha-876017-m03.txt"

--- PASS: TestMultiControlPlane/serial/CopyFile (13.12s)

=== RUN   TestMultiControlPlane/serial/StopSecondaryNode

ha_test.go:365: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 node stop m02 -v=7 --alsologtostderr

ha_test.go:365: (dbg) Done: out/minikube-linux-amd64 -p ha-876017 node stop m02 -v=7 --alsologtostderr: (12.602092988s)

ha_test.go:371: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 status -v=7 --alsologtostderr

ha_test.go:371: (dbg) Non-zero exit: out/minikube-linux-amd64 -p ha-876017 status -v=7 --alsologtostderr: exit status 7 (643.141746ms)

-- stdout --

	ha-876017

	type: Control Plane

	host: Running

	kubelet: Running

	apiserver: Running

	kubeconfig: Configured

	ha-876017-m02

	type: Control Plane

	host: Stopped

	kubelet: Stopped

	apiserver: Stopped

	kubeconfig: Stopped

	ha-876017-m03

	type: Control Plane

	host: Running

	kubelet: Running

	apiserver: Running

	kubeconfig: Configured

	ha-876017-m04

	type: Worker

	host: Running

	kubelet: Running

-- /stdout --

** stderr ** 

	I1008 17:54:59.007498  401332 out.go:345] Setting OutFile to fd 1 ...

	I1008 17:54:59.007652  401332 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:54:59.007665  401332 out.go:358] Setting ErrFile to fd 2...

	I1008 17:54:59.007671  401332 out.go:392] TERM=,COLORTERM=, which probably does not support color

	I1008 17:54:59.007865  401332 root.go:338] Updating PATH: /home/jenkins/minikube-integration/19774-378864/.minikube/bin

	I1008 17:54:59.008073  401332 out.go:352] Setting JSON to false

	I1008 17:54:59.008102  401332 mustload.go:65] Loading cluster: ha-876017

	I1008 17:54:59.008254  401332 notify.go:220] Checking for updates...

	I1008 17:54:59.008613  401332 config.go:182] Loaded profile config "ha-876017": Driver=kvm2, ContainerRuntime=docker, KubernetesVersion=v1.31.1

	I1008 17:54:59.008640  401332 status.go:174] checking status of ha-876017 ...

	I1008 17:54:59.009091  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.009153  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.029401  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:40549

	I1008 17:54:59.029874  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.030577  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.030619  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.030980  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.031157  401332 main.go:141] libmachine: (ha-876017) Calling .GetState

	I1008 17:54:59.032755  401332 status.go:371] ha-876017 host status = "Running" (err=<nil>)

	I1008 17:54:59.032771  401332 host.go:66] Checking if "ha-876017" exists ...

	I1008 17:54:59.033080  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.033119  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.048760  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:43387

	I1008 17:54:59.049262  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.049767  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.049791  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.050105  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.050301  401332 main.go:141] libmachine: (ha-876017) Calling .GetIP

	I1008 17:54:59.053397  401332 main.go:141] libmachine: (ha-876017) DBG | domain ha-876017 has defined MAC address 52:54:00:32:88:eb in network mk-ha-876017

	I1008 17:54:59.054005  401332 main.go:141] libmachine: (ha-876017) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:32:88:eb", ip: ""} in network mk-ha-876017: {Iface:virbr1 ExpiryTime:2024-10-08 18:50:01 +0000 UTC Type:0 Mac:52:54:00:32:88:eb Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:ha-876017 Clientid:01:52:54:00:32:88:eb}

	I1008 17:54:59.054037  401332 main.go:141] libmachine: (ha-876017) DBG | domain ha-876017 has defined IP address 192.168.39.153 and MAC address 52:54:00:32:88:eb in network mk-ha-876017

	I1008 17:54:59.054143  401332 host.go:66] Checking if "ha-876017" exists ...

	I1008 17:54:59.054440  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.054478  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.071686  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:38677

	I1008 17:54:59.072252  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.072823  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.072854  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.073221  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.073439  401332 main.go:141] libmachine: (ha-876017) Calling .DriverName

	I1008 17:54:59.073620  401332 ssh_runner.go:195] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"

	I1008 17:54:59.073649  401332 main.go:141] libmachine: (ha-876017) Calling .GetSSHHostname

	I1008 17:54:59.076970  401332 main.go:141] libmachine: (ha-876017) DBG | domain ha-876017 has defined MAC address 52:54:00:32:88:eb in network mk-ha-876017

	I1008 17:54:59.077176  401332 main.go:141] libmachine: (ha-876017) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:32:88:eb", ip: ""} in network mk-ha-876017: {Iface:virbr1 ExpiryTime:2024-10-08 18:50:01 +0000 UTC Type:0 Mac:52:54:00:32:88:eb Iaid: IPaddr:192.168.39.153 Prefix:24 Hostname:ha-876017 Clientid:01:52:54:00:32:88:eb}

	I1008 17:54:59.077195  401332 main.go:141] libmachine: (ha-876017) DBG | domain ha-876017 has defined IP address 192.168.39.153 and MAC address 52:54:00:32:88:eb in network mk-ha-876017

	I1008 17:54:59.077390  401332 main.go:141] libmachine: (ha-876017) Calling .GetSSHPort

	I1008 17:54:59.077571  401332 main.go:141] libmachine: (ha-876017) Calling .GetSSHKeyPath

	I1008 17:54:59.077736  401332 main.go:141] libmachine: (ha-876017) Calling .GetSSHUsername

	I1008 17:54:59.077955  401332 sshutil.go:53] new ssh client: &{IP:192.168.39.153 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/ha-876017/id_rsa Username:docker}

	I1008 17:54:59.156625  401332 ssh_runner.go:195] Run: systemctl --version

	I1008 17:54:59.163755  401332 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service kubelet

	I1008 17:54:59.179404  401332 kubeconfig.go:125] found "ha-876017" server: "https://192.168.39.254:8443"

	I1008 17:54:59.179446  401332 api_server.go:166] Checking apiserver status ...

	I1008 17:54:59.179492  401332 ssh_runner.go:195] Run: sudo pgrep -xnf kube-apiserver.*minikube.*

	I1008 17:54:59.194011  401332 ssh_runner.go:195] Run: sudo egrep ^[0-9]+:freezer: /proc/1979/cgroup

	W1008 17:54:59.203794  401332 api_server.go:177] unable to find freezer cgroup: sudo egrep ^[0-9]+:freezer: /proc/1979/cgroup: Process exited with status 1

	stdout:

	stderr:

	I1008 17:54:59.203862  401332 ssh_runner.go:195] Run: ls

	I1008 17:54:59.208582  401332 api_server.go:253] Checking apiserver healthz at https://192.168.39.254:8443/healthz ...

	I1008 17:54:59.212861  401332 api_server.go:279] https://192.168.39.254:8443/healthz returned 200:

	ok

	I1008 17:54:59.212895  401332 status.go:463] ha-876017 apiserver status = Running (err=<nil>)

	I1008 17:54:59.212927  401332 status.go:176] ha-876017 status: &{Name:ha-876017 Host:Running Kubelet:Running APIServer:Running Kubeconfig:Configured Worker:false TimeToStop: DockerEnv: PodManEnv:}

	I1008 17:54:59.212952  401332 status.go:174] checking status of ha-876017-m02 ...

	I1008 17:54:59.213306  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.213348  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.228967  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:41531

	I1008 17:54:59.229506  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.229971  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.229992  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.230406  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.230581  401332 main.go:141] libmachine: (ha-876017-m02) Calling .GetState

	I1008 17:54:59.232906  401332 status.go:371] ha-876017-m02 host status = "Stopped" (err=<nil>)

	I1008 17:54:59.232928  401332 status.go:384] host is not running, skipping remaining checks

	I1008 17:54:59.232936  401332 status.go:176] ha-876017-m02 status: &{Name:ha-876017-m02 Host:Stopped Kubelet:Stopped APIServer:Stopped Kubeconfig:Stopped Worker:false TimeToStop: DockerEnv: PodManEnv:}

	I1008 17:54:59.232959  401332 status.go:174] checking status of ha-876017-m03 ...

	I1008 17:54:59.233353  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.233398  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.249524  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:41821

	I1008 17:54:59.250113  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.250659  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.250686  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.251057  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.251318  401332 main.go:141] libmachine: (ha-876017-m03) Calling .GetState

	I1008 17:54:59.253318  401332 status.go:371] ha-876017-m03 host status = "Running" (err=<nil>)

	I1008 17:54:59.253338  401332 host.go:66] Checking if "ha-876017-m03" exists ...

	I1008 17:54:59.253720  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.253768  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.269325  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:33549

	I1008 17:54:59.269824  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.270373  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.270399  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.270727  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.270923  401332 main.go:141] libmachine: (ha-876017-m03) Calling .GetIP

	I1008 17:54:59.273784  401332 main.go:141] libmachine: (ha-876017-m03) DBG | domain ha-876017-m03 has defined MAC address 52:54:00:77:5a:07 in network mk-ha-876017

	I1008 17:54:59.274162  401332 main.go:141] libmachine: (ha-876017-m03) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:77:5a:07", ip: ""} in network mk-ha-876017: {Iface:virbr1 ExpiryTime:2024-10-08 18:52:11 +0000 UTC Type:0 Mac:52:54:00:77:5a:07 Iaid: IPaddr:192.168.39.8 Prefix:24 Hostname:ha-876017-m03 Clientid:01:52:54:00:77:5a:07}

	I1008 17:54:59.274198  401332 main.go:141] libmachine: (ha-876017-m03) DBG | domain ha-876017-m03 has defined IP address 192.168.39.8 and MAC address 52:54:00:77:5a:07 in network mk-ha-876017

	I1008 17:54:59.274332  401332 host.go:66] Checking if "ha-876017-m03" exists ...

	I1008 17:54:59.274648  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.274698  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.290488  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:38319

	I1008 17:54:59.291008  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.291605  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.291639  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.291964  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.292209  401332 main.go:141] libmachine: (ha-876017-m03) Calling .DriverName

	I1008 17:54:59.292490  401332 ssh_runner.go:195] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"

	I1008 17:54:59.292519  401332 main.go:141] libmachine: (ha-876017-m03) Calling .GetSSHHostname

	I1008 17:54:59.295577  401332 main.go:141] libmachine: (ha-876017-m03) DBG | domain ha-876017-m03 has defined MAC address 52:54:00:77:5a:07 in network mk-ha-876017

	I1008 17:54:59.296131  401332 main.go:141] libmachine: (ha-876017-m03) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:77:5a:07", ip: ""} in network mk-ha-876017: {Iface:virbr1 ExpiryTime:2024-10-08 18:52:11 +0000 UTC Type:0 Mac:52:54:00:77:5a:07 Iaid: IPaddr:192.168.39.8 Prefix:24 Hostname:ha-876017-m03 Clientid:01:52:54:00:77:5a:07}

	I1008 17:54:59.296154  401332 main.go:141] libmachine: (ha-876017-m03) DBG | domain ha-876017-m03 has defined IP address 192.168.39.8 and MAC address 52:54:00:77:5a:07 in network mk-ha-876017

	I1008 17:54:59.296372  401332 main.go:141] libmachine: (ha-876017-m03) Calling .GetSSHPort

	I1008 17:54:59.296562  401332 main.go:141] libmachine: (ha-876017-m03) Calling .GetSSHKeyPath

	I1008 17:54:59.296727  401332 main.go:141] libmachine: (ha-876017-m03) Calling .GetSSHUsername

	I1008 17:54:59.296856  401332 sshutil.go:53] new ssh client: &{IP:192.168.39.8 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/ha-876017-m03/id_rsa Username:docker}

	I1008 17:54:59.379920  401332 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service kubelet

	I1008 17:54:59.402992  401332 kubeconfig.go:125] found "ha-876017" server: "https://192.168.39.254:8443"

	I1008 17:54:59.403037  401332 api_server.go:166] Checking apiserver status ...

	I1008 17:54:59.403083  401332 ssh_runner.go:195] Run: sudo pgrep -xnf kube-apiserver.*minikube.*

	I1008 17:54:59.419694  401332 ssh_runner.go:195] Run: sudo egrep ^[0-9]+:freezer: /proc/1875/cgroup

	W1008 17:54:59.429111  401332 api_server.go:177] unable to find freezer cgroup: sudo egrep ^[0-9]+:freezer: /proc/1875/cgroup: Process exited with status 1

	stdout:

	stderr:

	I1008 17:54:59.429179  401332 ssh_runner.go:195] Run: ls

	I1008 17:54:59.433796  401332 api_server.go:253] Checking apiserver healthz at https://192.168.39.254:8443/healthz ...

	I1008 17:54:59.438789  401332 api_server.go:279] https://192.168.39.254:8443/healthz returned 200:

	ok

	I1008 17:54:59.438816  401332 status.go:463] ha-876017-m03 apiserver status = Running (err=<nil>)

	I1008 17:54:59.438824  401332 status.go:176] ha-876017-m03 status: &{Name:ha-876017-m03 Host:Running Kubelet:Running APIServer:Running Kubeconfig:Configured Worker:false TimeToStop: DockerEnv: PodManEnv:}

	I1008 17:54:59.438840  401332 status.go:174] checking status of ha-876017-m04 ...

	I1008 17:54:59.439149  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.439182  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.455021  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:35491

	I1008 17:54:59.455531  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.456039  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.456061  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.456426  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.456603  401332 main.go:141] libmachine: (ha-876017-m04) Calling .GetState

	I1008 17:54:59.458162  401332 status.go:371] ha-876017-m04 host status = "Running" (err=<nil>)

	I1008 17:54:59.458178  401332 host.go:66] Checking if "ha-876017-m04" exists ...

	I1008 17:54:59.458472  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.458515  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.473989  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:41047

	I1008 17:54:59.474524  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.475084  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.475108  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.475439  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.475651  401332 main.go:141] libmachine: (ha-876017-m04) Calling .GetIP

	I1008 17:54:59.478746  401332 main.go:141] libmachine: (ha-876017-m04) DBG | domain ha-876017-m04 has defined MAC address 52:54:00:99:36:18 in network mk-ha-876017

	I1008 17:54:59.479178  401332 main.go:141] libmachine: (ha-876017-m04) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:99:36:18", ip: ""} in network mk-ha-876017: {Iface:virbr1 ExpiryTime:2024-10-08 18:53:44 +0000 UTC Type:0 Mac:52:54:00:99:36:18 Iaid: IPaddr:192.168.39.79 Prefix:24 Hostname:ha-876017-m04 Clientid:01:52:54:00:99:36:18}

	I1008 17:54:59.479224  401332 main.go:141] libmachine: (ha-876017-m04) DBG | domain ha-876017-m04 has defined IP address 192.168.39.79 and MAC address 52:54:00:99:36:18 in network mk-ha-876017

	I1008 17:54:59.479329  401332 host.go:66] Checking if "ha-876017-m04" exists ...

	I1008 17:54:59.479694  401332 main.go:141] libmachine: Found binary path at /home/jenkins/workspace/KVM_Linux_integration/out/docker-machine-driver-kvm2

	I1008 17:54:59.479741  401332 main.go:141] libmachine: Launching plugin server for driver kvm2

	I1008 17:54:59.494800  401332 main.go:141] libmachine: Plugin server listening at address 127.0.0.1:43945

	I1008 17:54:59.495198  401332 main.go:141] libmachine: () Calling .GetVersion

	I1008 17:54:59.495704  401332 main.go:141] libmachine: Using API Version  1

	I1008 17:54:59.495727  401332 main.go:141] libmachine: () Calling .SetConfigRaw

	I1008 17:54:59.496115  401332 main.go:141] libmachine: () Calling .GetMachineName

	I1008 17:54:59.496348  401332 main.go:141] libmachine: (ha-876017-m04) Calling .DriverName

	I1008 17:54:59.496566  401332 ssh_runner.go:195] Run: sh -c "df -h /var | awk 'NR==2{print $5}'"

	I1008 17:54:59.496590  401332 main.go:141] libmachine: (ha-876017-m04) Calling .GetSSHHostname

	I1008 17:54:59.499101  401332 main.go:141] libmachine: (ha-876017-m04) DBG | domain ha-876017-m04 has defined MAC address 52:54:00:99:36:18 in network mk-ha-876017

	I1008 17:54:59.499505  401332 main.go:141] libmachine: (ha-876017-m04) DBG | found host DHCP lease matching {name: "", mac: "52:54:00:99:36:18", ip: ""} in network mk-ha-876017: {Iface:virbr1 ExpiryTime:2024-10-08 18:53:44 +0000 UTC Type:0 Mac:52:54:00:99:36:18 Iaid: IPaddr:192.168.39.79 Prefix:24 Hostname:ha-876017-m04 Clientid:01:52:54:00:99:36:18}

	I1008 17:54:59.499526  401332 main.go:141] libmachine: (ha-876017-m04) DBG | domain ha-876017-m04 has defined IP address 192.168.39.79 and MAC address 52:54:00:99:36:18 in network mk-ha-876017

	I1008 17:54:59.499739  401332 main.go:141] libmachine: (ha-876017-m04) Calling .GetSSHPort

	I1008 17:54:59.499891  401332 main.go:141] libmachine: (ha-876017-m04) Calling .GetSSHKeyPath

	I1008 17:54:59.500038  401332 main.go:141] libmachine: (ha-876017-m04) Calling .GetSSHUsername

	I1008 17:54:59.500172  401332 sshutil.go:53] new ssh client: &{IP:192.168.39.79 Port:22 SSHKeyPath:/home/jenkins/minikube-integration/19774-378864/.minikube/machines/ha-876017-m04/id_rsa Username:docker}

	I1008 17:54:59.583024  401332 ssh_runner.go:195] Run: sudo systemctl is-active --quiet service kubelet

	I1008 17:54:59.597580  401332 status.go:176] ha-876017-m04 status: &{Name:ha-876017-m04 Host:Running Kubelet:Running APIServer:Irrelevant Kubeconfig:Irrelevant Worker:true TimeToStop: DockerEnv: PodManEnv:}

** /stderr **

--- PASS: TestMultiControlPlane/serial/StopSecondaryNode (13.25s)

=== RUN   TestMultiControlPlane/serial/DegradedAfterControlPlaneNodeStop

ha_test.go:392: (dbg) Run:  out/minikube-linux-amd64 profile list --output json

--- PASS: TestMultiControlPlane/serial/DegradedAfterControlPlaneNodeStop (0.64s)

=== RUN   TestMultiControlPlane/serial/RestartSecondaryNode

ha_test.go:422: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 node start m02 -v=7 --alsologtostderr

E1008 17:55:11.951036  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

ha_test.go:422: (dbg) Done: out/minikube-linux-amd64 -p ha-876017 node start m02 -v=7 --alsologtostderr: (38.564381977s)

ha_test.go:430: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 status -v=7 --alsologtostderr

ha_test.go:450: (dbg) Run:  kubectl get nodes

--- PASS: TestMultiControlPlane/serial/RestartSecondaryNode (39.48s)

=== RUN   TestMultiControlPlane/serial/HAppyAfterSecondaryNodeRestart

ha_test.go:281: (dbg) Run:  out/minikube-linux-amd64 profile list --output json

--- PASS: TestMultiControlPlane/serial/HAppyAfterSecondaryNodeRestart (0.84s)

=== RUN   TestMultiControlPlane/serial/RestartClusterKeepsNodes

ha_test.go:458: (dbg) Run:  out/minikube-linux-amd64 node list -p ha-876017 -v=7 --alsologtostderr

ha_test.go:464: (dbg) Run:  out/minikube-linux-amd64 stop -p ha-876017 -v=7 --alsologtostderr

ha_test.go:464: (dbg) Done: out/minikube-linux-amd64 stop -p ha-876017 -v=7 --alsologtostderr: (40.759112575s)

ha_test.go:469: (dbg) Run:  out/minikube-linux-amd64 start -p ha-876017 --wait=true -v=7 --alsologtostderr

E1008 17:56:33.872737  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:58:50.011663  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:59:09.186750  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/addons-403820/client.crt: no such file or directory" logger="UnhandledError"

E1008 17:59:17.714757  386147 cert_rotation.go:171] "Unhandled Error" err="key failed with : open /home/jenkins/minikube-integration/19774-378864/.minikube/profiles/functional-270926/client.crt: no such file or directory" logger="UnhandledError"

ha_test.go:469: (dbg) Done: out/minikube-linux-amd64 start -p ha-876017 --wait=true -v=7 --alsologtostderr: (3m32.564434895s)

ha_test.go:474: (dbg) Run:  out/minikube-linux-amd64 node list -p ha-876017

--- PASS: TestMultiControlPlane/serial/RestartClusterKeepsNodes (253.43s)

=== RUN   TestMultiControlPlane/serial/DeleteSecondaryNode

ha_test.go:489: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 node delete m03 -v=7 --alsologtostderr

ha_test.go:489: (dbg) Done: out/minikube-linux-amd64 -p ha-876017 node delete m03 -v=7 --alsologtostderr: (6.389865493s)

ha_test.go:495: (dbg) Run:  out/minikube-linux-amd64 -p ha-876017 status -v=7 --alsologtostderr

ha_test.go:513: (dbg) Run:  kubectl get nodes

ha_test.go:521: (dbg) Run:  kubectl get nodes -o "go-template='{{range .items}}{{range .status.conditions}}{{if eq .type "Ready"}} {{.status}}{{"\n"}}{{end}}{{end}}{{end}}'"

--- PASS: TestMultiControlPlane/serial/DeleteSecondaryNode (7.14s)