=== RUN TestCertExpiration
=== PAUSE TestCertExpiration
=== CONT TestCertExpiration
cert_options_test.go:124: (dbg) Run: out/minikube-windows-amd64.exe start -p cert-expiration-20220329190729-1328 --memory=2048 --cert-expiration=3m --driver=docker
=== CONT TestCertExpiration
cert_options_test.go:124: (dbg) Non-zero exit: out/minikube-windows-amd64.exe start -p cert-expiration-20220329190729-1328 --memory=2048 --cert-expiration=3m --driver=docker: exit status 80 (5m51.1605615s)
-- stdout --
* [cert-expiration-20220329190729-1328] minikube v1.25.2 on Microsoft Windows 10 Enterprise N 10.0.19042 Build 19042
- KUBECONFIG=C:\Users\jenkins.minikube8\minikube-integration\kubeconfig
- MINIKUBE_HOME=C:\Users\jenkins.minikube8\minikube-integration\.minikube
- MINIKUBE_LOCATION=13730
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the docker driver based on user configuration
* Starting control plane node cert-expiration-20220329190729-1328 in cluster cert-expiration-20220329190729-1328
* Pulling base image ...
* Creating docker container (CPUs=2, Memory=2048MB) ...
* docker "cert-expiration-20220329190729-1328" container is missing, will recreate.
* Creating docker container (CPUs=2, Memory=2048MB) ...
-- /stdout --
** stderr **
! StartHost failed, but will try again: creating host: create: creating: create kic node: create container: docker run -d -t --privileged --security-opt seccomp=unconfined --tmpfs /tmp --tmpfs /run -v /lib/modules:/lib/modules:ro --hostname cert-expiration-20220329190729-1328 --name cert-expiration-20220329190729-1328 --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=cert-expiration-20220329190729-1328 --label role.minikube.sigs.k8s.io= --label mode.minikube.sigs.k8s.io=cert-expiration-20220329190729-1328 --network cert-expiration-20220329190729-1328 --ip 192.168.76.2 --volume cert-expiration-20220329190729-1328:/var --security-opt apparmor=unconfined --memory=2048mb --memory-swap=2048mb --cpus=2 -e container=docker --expose 8443 --publish=127.0.0.1::8443 --publish=127.0.0.1::22 --publish=127.0.0.1::2376 --publish=127.0.0.1::5000 --publish=127.0.0.1::32443 gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdc
a30a55218347b5: exit status 125
stdout:
f852d3d4685e416c80fef9fe46816a85ddfa6939a7687f4b8c51bc42e1dfb9ee
stderr:
docker: Error response from daemon: network cert-expiration-20220329190729-1328 not found.
* Failed to start docker container. Running "minikube delete -p cert-expiration-20220329190729-1328" may fix it: recreate: creating host: create: creating: create kic node: create container: docker run -d -t --privileged --security-opt seccomp=unconfined --tmpfs /tmp --tmpfs /run -v /lib/modules:/lib/modules:ro --hostname cert-expiration-20220329190729-1328 --name cert-expiration-20220329190729-1328 --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=cert-expiration-20220329190729-1328 --label role.minikube.sigs.k8s.io= --label mode.minikube.sigs.k8s.io=cert-expiration-20220329190729-1328 --network cert-expiration-20220329190729-1328 --ip 192.168.76.2 --volume cert-expiration-20220329190729-1328:/var --security-opt apparmor=unconfined --memory=2048mb --memory-swap=2048mb --cpus=2 -e container=docker --expose 8443 --publish=127.0.0.1::8443 --publish=127.0.0.1::22 --publish=127.0.0.1::2376 --publish=127.0.0.1::5000 --publish=127.0.0.1::32443 gcr.io/k8s-minikube/kicbase-builds
:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5: exit status 125
stdout:
4a1456d4488a91a68fecbbd527435dca4cffdca6936a11d943d46d4b1fc64c80
stderr:
docker: Error response from daemon: network cert-expiration-20220329190729-1328 not found.
X Exiting due to GUEST_PROVISION: Failed to start host: recreate: creating host: create: creating: create kic node: create container: docker run -d -t --privileged --security-opt seccomp=unconfined --tmpfs /tmp --tmpfs /run -v /lib/modules:/lib/modules:ro --hostname cert-expiration-20220329190729-1328 --name cert-expiration-20220329190729-1328 --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=cert-expiration-20220329190729-1328 --label role.minikube.sigs.k8s.io= --label mode.minikube.sigs.k8s.io=cert-expiration-20220329190729-1328 --network cert-expiration-20220329190729-1328 --ip 192.168.76.2 --volume cert-expiration-20220329190729-1328:/var --security-opt apparmor=unconfined --memory=2048mb --memory-swap=2048mb --cpus=2 -e container=docker --expose 8443 --publish=127.0.0.1::8443 --publish=127.0.0.1::22 --publish=127.0.0.1::2376 --publish=127.0.0.1::5000 --publish=127.0.0.1::32443 gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d3
03827e05ce4421985fe9bd7bdca30a55218347b5: exit status 125
stdout:
4a1456d4488a91a68fecbbd527435dca4cffdca6936a11d943d46d4b1fc64c80
stderr:
docker: Error response from daemon: network cert-expiration-20220329190729-1328 not 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. │
│ │
╰─────────────────────────────────────────────────────────────────────────────────────────────╯
** /stderr **
cert_options_test.go:126: failed to start minikube with args: "out/minikube-windows-amd64.exe start -p cert-expiration-20220329190729-1328 --memory=2048 --cert-expiration=3m --driver=docker" : exit status 80
E0329 19:13:22.771403 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\ingress-addon-legacy-20220329181027-1328\client.crt: The system cannot find the path specified.
=== CONT TestCertExpiration
cert_options_test.go:132: (dbg) Run: out/minikube-windows-amd64.exe start -p cert-expiration-20220329190729-1328 --memory=2048 --cert-expiration=8760h --driver=docker
=== CONT TestCertExpiration
cert_options_test.go:132: (dbg) Done: out/minikube-windows-amd64.exe start -p cert-expiration-20220329190729-1328 --memory=2048 --cert-expiration=8760h --driver=docker: (5m48.1231918s)
cert_options_test.go:137: minikube start output did not warn about expired certs:
-- stdout --
* [cert-expiration-20220329190729-1328] minikube v1.25.2 on Microsoft Windows 10 Enterprise N 10.0.19042 Build 19042
- KUBECONFIG=C:\Users\jenkins.minikube8\minikube-integration\kubeconfig
- MINIKUBE_HOME=C:\Users\jenkins.minikube8\minikube-integration\.minikube
- MINIKUBE_LOCATION=13730
- MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
* Using the docker driver based on existing profile
* Starting control plane node cert-expiration-20220329190729-1328 in cluster cert-expiration-20220329190729-1328
* Pulling base image ...
* docker "cert-expiration-20220329190729-1328" container is missing, will recreate.
* Creating docker container (CPUs=2, Memory=2048MB) ...
* Preparing Kubernetes v1.23.5 on Docker 20.10.13 ...
- Generating certificates and keys ...
- Booting up control plane ...
- Configuring RBAC rules ...
* Verifying Kubernetes components...
- Using image gcr.io/k8s-minikube/storage-provisioner:v5
* Enabled addons: storage-provisioner, default-storageclass
- Want kubectl v1.23.5? Try 'minikube kubectl -- get pods -A'
* Done! kubectl is now configured to use "cert-expiration-20220329190729-1328" cluster and "default" namespace by default
-- /stdout --
** stderr **
! C:\ProgramData\chocolatey\bin\kubectl.exe is version 1.18.2, which may have incompatibilites with Kubernetes 1.23.5.
** /stderr **
cert_options_test.go:139: *** TestCertExpiration FAILED at 2022-03-29 19:22:09.1410266 +0000 GMT m=+7666.732640501
helpers_test.go:223: -----------------------post-mortem--------------------------------
helpers_test.go:231: ======> post-mortem[TestCertExpiration]: docker inspect <======
helpers_test.go:232: (dbg) Run: docker inspect cert-expiration-20220329190729-1328
helpers_test.go:236: (dbg) docker inspect cert-expiration-20220329190729-1328:
-- stdout --
[
{
"Id": "6e9adcaedb6a39fb21795fd28098bfb8c8c46666e5199f4d17caf3f8726b8602",
"Created": "2022-03-29T19:20:50.5533447Z",
"Path": "/usr/local/bin/entrypoint",
"Args": [
"/sbin/init"
],
"State": {
"Status": "running",
"Running": true,
"Paused": false,
"Restarting": false,
"OOMKilled": false,
"Dead": false,
"Pid": 218487,
"ExitCode": 0,
"Error": "",
"StartedAt": "2022-03-29T19:20:52.7820033Z",
"FinishedAt": "0001-01-01T00:00:00Z"
},
"Image": "sha256:44d43b69f3d5ba7f801dca891b535f23f9839671e82277938ec7dc42a22c50d6",
"ResolvConfPath": "/var/lib/docker/containers/6e9adcaedb6a39fb21795fd28098bfb8c8c46666e5199f4d17caf3f8726b8602/resolv.conf",
"HostnamePath": "/var/lib/docker/containers/6e9adcaedb6a39fb21795fd28098bfb8c8c46666e5199f4d17caf3f8726b8602/hostname",
"HostsPath": "/var/lib/docker/containers/6e9adcaedb6a39fb21795fd28098bfb8c8c46666e5199f4d17caf3f8726b8602/hosts",
"LogPath": "/var/lib/docker/containers/6e9adcaedb6a39fb21795fd28098bfb8c8c46666e5199f4d17caf3f8726b8602/6e9adcaedb6a39fb21795fd28098bfb8c8c46666e5199f4d17caf3f8726b8602-json.log",
"Name": "/cert-expiration-20220329190729-1328",
"RestartCount": 0,
"Driver": "overlay2",
"Platform": "linux",
"MountLabel": "",
"ProcessLabel": "",
"AppArmorProfile": "",
"ExecIDs": null,
"HostConfig": {
"Binds": [
"/lib/modules:/lib/modules:ro",
"cert-expiration-20220329190729-1328:/var"
],
"ContainerIDFile": "",
"LogConfig": {
"Type": "json-file",
"Config": {}
},
"NetworkMode": "cert-expiration-20220329190729-1328",
"PortBindings": {
"22/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "0"
}
],
"2376/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "0"
}
],
"32443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "0"
}
],
"5000/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "0"
}
],
"8443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "0"
}
]
},
"RestartPolicy": {
"Name": "no",
"MaximumRetryCount": 0
},
"AutoRemove": false,
"VolumeDriver": "",
"VolumesFrom": null,
"CapAdd": null,
"CapDrop": null,
"CgroupnsMode": "host",
"Dns": [],
"DnsOptions": [],
"DnsSearch": [],
"ExtraHosts": null,
"GroupAdd": null,
"IpcMode": "private",
"Cgroup": "",
"Links": null,
"OomScoreAdj": 0,
"PidMode": "",
"Privileged": true,
"PublishAllPorts": false,
"ReadonlyRootfs": false,
"SecurityOpt": [
"seccomp=unconfined",
"apparmor=unconfined",
"label=disable"
],
"Tmpfs": {
"/run": "",
"/tmp": ""
},
"UTSMode": "",
"UsernsMode": "",
"ShmSize": 67108864,
"Runtime": "runc",
"ConsoleSize": [
0,
0
],
"Isolation": "",
"CpuShares": 0,
"Memory": 2147483648,
"NanoCpus": 2000000000,
"CgroupParent": "",
"BlkioWeight": 0,
"BlkioWeightDevice": [],
"BlkioDeviceReadBps": null,
"BlkioDeviceWriteBps": null,
"BlkioDeviceReadIOps": null,
"BlkioDeviceWriteIOps": null,
"CpuPeriod": 0,
"CpuQuota": 0,
"CpuRealtimePeriod": 0,
"CpuRealtimeRuntime": 0,
"CpusetCpus": "",
"CpusetMems": "",
"Devices": [],
"DeviceCgroupRules": null,
"DeviceRequests": null,
"KernelMemory": 0,
"KernelMemoryTCP": 0,
"MemoryReservation": 0,
"MemorySwap": 2147483648,
"MemorySwappiness": null,
"OomKillDisable": false,
"PidsLimit": null,
"Ulimits": null,
"CpuCount": 0,
"CpuPercent": 0,
"IOMaximumIOps": 0,
"IOMaximumBandwidth": 0,
"MaskedPaths": null,
"ReadonlyPaths": null
},
"GraphDriver": {
"Data": {
"LowerDir": "/var/lib/docker/overlay2/d682dc39f10667f866547efb27293200814d92789ac1f10c6b2c860834299301-init/diff:/var/lib/docker/overlay2/4eae5e38ad3553f9f0fde74ad732117b98cb0e1af550ecd7ce386997eede943f/diff:/var/lib/docker/overlay2/6789b74c71a0164bd481c99dc53318989abbcdc33b160f5d04f44aee12c80671/diff:/var/lib/docker/overlay2/91c6ac2f9a1035ebae76daccc83a3cafe5d26b2bd6b60ad54a6e29588a7003f8/diff:/var/lib/docker/overlay2/a916d7329da723d8397bfda8e20f2beb9156ceece20236242a811e43984bbfeb/diff:/var/lib/docker/overlay2/b046f566fd53b4f2f6d2c347c752b47f6c1a64316baeaa8c0fda825346ef7aba/diff:/var/lib/docker/overlay2/13a76ad56283b88db0508d09cc281c66801cee04cdbdd8f00827788d5231a025/diff:/var/lib/docker/overlay2/8e95b9ffc444e9f6b52db61f07f0a93bb3feb51b5d9dab6b7df487fef8d277f6/diff:/var/lib/docker/overlay2/bf807f6bedece6f8033221974e6b2ffdf94a6f9320d4f09337ed51b411f8f999/diff:/var/lib/docker/overlay2/d8184ca2707eba09a4f6bd90cad4795ce0f226f863f2d84723287ad76f1158d8/diff:/var/lib/docker/overlay2/390685
8e1746cab95814956b950325758e0765c0a6597b3d9062a4c36ab409be/diff:/var/lib/docker/overlay2/128db97cb7dee3d09e506aaaf97a45b5a647d8eb90782f5dd444aec15ff525da/diff:/var/lib/docker/overlay2/713bbf0f0ba84035f3a06b59c058ccfe9e7639f2ecb9d3db244e1adec7b6c46b/diff:/var/lib/docker/overlay2/6a820465cd423660c71cbb6741a47e4619efcf0010ac49bd49146501b9ac4925/diff:/var/lib/docker/overlay2/20c66385f330043e2c50b8193a59172de08776bbabdca289cb51c1b5f17e9b98/diff:/var/lib/docker/overlay2/7b2439fa81d8ff403bd5767752380391449aeba92453e1846fd36cfce9e6de61/diff:/var/lib/docker/overlay2/ee227ab74915b1419cfbc67f2b14b08cf564b4a38a39b157de2c65250a9172bf/diff:/var/lib/docker/overlay2/0b92e2531a28b01133cc2ab65802b03c04ef0213e850ac8558c9c4071fd018dd/diff:/var/lib/docker/overlay2/3de4968e9a773e45d79b096d23038e48758528adce69f14e7ff3a93bbd3192d7/diff:/var/lib/docker/overlay2/92eb87a3831ecebb34eb1e0ea7a71af9883f8426f35387845769f5fe75f04a52/diff:/var/lib/docker/overlay2/82a4c6fc3869bde23593a8490af76e406ad5a27ef1c30a38b481944390f7466e/diff:/var/lib/d
ocker/overlay2/6c957b5c04708287c2261d895a0f4563f25cc766eb21913c4ceb36f27a04914e/diff:/var/lib/docker/overlay2/21df3fb223398ef06fb62c4617e3487f0ac955e4f38ee3d2d72c9da488d436c7/diff:/var/lib/docker/overlay2/ddaf18203a4027208ea592b9716939849af0aa5d2cac57d2b0c36382e078f483/diff:/var/lib/docker/overlay2/9a82b4c496462c1bf59ccb096f886e61674d92540023b7fed618682584358cbf/diff:/var/lib/docker/overlay2/62a8d9c5758a93af517541ab9d841f9415f55ca5503844371b7e35d47838dbb0/diff:/var/lib/docker/overlay2/c17d3885b54e341402c392175e2ab4ff1ab038acafe82a8090b1725613597f95/diff:/var/lib/docker/overlay2/d1401e4d6e04dded3c7d0335e32d0eb6cf2d7c19d21da53b836d591dddac8961/diff:/var/lib/docker/overlay2/7c4934c7f4f9cce1a35b340eebbc473f9bb33153f61f1c0454bffd0b2ae5a37e/diff:/var/lib/docker/overlay2/02d6bd07f6dbb7198d2c42fe26ff2efbabb9a889dfa0b79fd05e06a021bc81b4/diff:/var/lib/docker/overlay2/137f83b86485992317df9126e714cd331df51131ac4990d1040cf54cace6506e/diff:/var/lib/docker/overlay2/75d1117a1f5f001df3981193d1251ab8426eb4c100c9c1bbb946f0c2e0e
1d73c/diff:/var/lib/docker/overlay2/b20542be533b230be3dee06af0364759a81f26397d9371a7052efdac48fc1a3e/diff:/var/lib/docker/overlay2/b6103a89043f339bfc18a195b11f4a57f6042806725aac9d6b8db0e2af4fe01e/diff:/var/lib/docker/overlay2/69041f5eef389b325dd43fa81731c884299e2cb880a57ba904b8752c12446236/diff:/var/lib/docker/overlay2/8bc9de0232e5ba86f129e746c52a7f53836827a1a9cfc8e0c731d81af17b92a4/diff:/var/lib/docker/overlay2/5494bafa4607149ff46b2ed95fd9c86139339508d3c27bf32346963a41ae95f1/diff:/var/lib/docker/overlay2/daaadc749b2e3fb99bb23ec4d0a908e70deef3f9caff12f7b3fa29a57086e13a/diff:/var/lib/docker/overlay2/35b939c7fd0daf3717995c2aff595f96a741b48ae2da6b523aeda782ea3922e9/diff:/var/lib/docker/overlay2/b5a01cc1c410e803d28949ef6f35b55ac04473d89beb188d9d4866287b7cbbee/diff:/var/lib/docker/overlay2/c26c0af38634a15c6619c42bd2e5ec804bab550ff8078c084ba220030d8f4b93/diff:/var/lib/docker/overlay2/c12adb9eba87b6903ac0b2e16234b6a4f11a66d10d30d5379b19963433b76506/diff:/var/lib/docker/overlay2/537ea8129185a2faaaafa08ee553e15fe2cee0
4e80dab99066f779573324b53c/diff:/var/lib/docker/overlay2/ba74848f80f8d422a61241b3778f2395a32e73958e6a6dfddf5724bd0367dc67/diff:/var/lib/docker/overlay2/be8013e1c023e08543e181408137e02941d2b05181428b80bf154108c0cf48a5/diff:/var/lib/docker/overlay2/895568f040b89c0f90e7f4e41a1a77ca025acd0a0e0682a242f830a2e9c4ede7/diff",
"MergedDir": "/var/lib/docker/overlay2/d682dc39f10667f866547efb27293200814d92789ac1f10c6b2c860834299301/merged",
"UpperDir": "/var/lib/docker/overlay2/d682dc39f10667f866547efb27293200814d92789ac1f10c6b2c860834299301/diff",
"WorkDir": "/var/lib/docker/overlay2/d682dc39f10667f866547efb27293200814d92789ac1f10c6b2c860834299301/work"
},
"Name": "overlay2"
},
"Mounts": [
{
"Type": "bind",
"Source": "/lib/modules",
"Destination": "/lib/modules",
"Mode": "ro",
"RW": false,
"Propagation": "rprivate"
},
{
"Type": "volume",
"Name": "cert-expiration-20220329190729-1328",
"Source": "/var/lib/docker/volumes/cert-expiration-20220329190729-1328/_data",
"Destination": "/var",
"Driver": "local",
"Mode": "z",
"RW": true,
"Propagation": ""
}
],
"Config": {
"Hostname": "cert-expiration-20220329190729-1328",
"Domainname": "",
"User": "root",
"AttachStdin": false,
"AttachStdout": false,
"AttachStderr": false,
"ExposedPorts": {
"22/tcp": {},
"2376/tcp": {},
"32443/tcp": {},
"5000/tcp": {},
"8443/tcp": {}
},
"Tty": true,
"OpenStdin": false,
"StdinOnce": false,
"Env": [
"container=docker",
"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"
],
"Cmd": null,
"Image": "gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5",
"Volumes": null,
"WorkingDir": "",
"Entrypoint": [
"/usr/local/bin/entrypoint",
"/sbin/init"
],
"OnBuild": null,
"Labels": {
"created_by.minikube.sigs.k8s.io": "true",
"mode.minikube.sigs.k8s.io": "cert-expiration-20220329190729-1328",
"name.minikube.sigs.k8s.io": "cert-expiration-20220329190729-1328",
"role.minikube.sigs.k8s.io": ""
},
"StopSignal": "SIGRTMIN+3"
},
"NetworkSettings": {
"Bridge": "",
"SandboxID": "881fc35d8713ef06d8454a6774fe6ea5e6316b4487e623e6d777eb7f06378575",
"HairpinMode": false,
"LinkLocalIPv6Address": "",
"LinkLocalIPv6PrefixLen": 0,
"Ports": {
"22/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57522"
}
],
"2376/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57523"
}
],
"32443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57524"
}
],
"5000/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57520"
}
],
"8443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57521"
}
]
},
"SandboxKey": "/var/run/docker/netns/881fc35d8713",
"SecondaryIPAddresses": null,
"SecondaryIPv6Addresses": null,
"EndpointID": "",
"Gateway": "",
"GlobalIPv6Address": "",
"GlobalIPv6PrefixLen": 0,
"IPAddress": "",
"IPPrefixLen": 0,
"IPv6Gateway": "",
"MacAddress": "",
"Networks": {
"cert-expiration-20220329190729-1328": {
"IPAMConfig": {
"IPv4Address": "192.168.76.2"
},
"Links": null,
"Aliases": [
"6e9adcaedb6a",
"cert-expiration-20220329190729-1328"
],
"NetworkID": "5a5c47b9507ff52c37db4a0187a438c47e8b33899445a2db5b23a9981bb7d3a8",
"EndpointID": "363e1480dc92faa95f2cd7123c0fd89c3c03ae42a1caa1709068d7a72f0ba73d",
"Gateway": "192.168.76.1",
"IPAddress": "192.168.76.2",
"IPPrefixLen": 24,
"IPv6Gateway": "",
"GlobalIPv6Address": "",
"GlobalIPv6PrefixLen": 0,
"MacAddress": "02:42:c0:a8:4c:02",
"DriverOpts": null
}
}
}
}
]
-- /stdout --
helpers_test.go:240: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.Host}} -p cert-expiration-20220329190729-1328 -n cert-expiration-20220329190729-1328
helpers_test.go:240: (dbg) Done: out/minikube-windows-amd64.exe status --format={{.Host}} -p cert-expiration-20220329190729-1328 -n cert-expiration-20220329190729-1328: (4.5487794s)
helpers_test.go:245: <<< TestCertExpiration FAILED: start of post-mortem logs <<<
helpers_test.go:246: ======> post-mortem[TestCertExpiration]: minikube logs <======
helpers_test.go:248: (dbg) Run: out/minikube-windows-amd64.exe -p cert-expiration-20220329190729-1328 logs -n 25
helpers_test.go:248: (dbg) Done: out/minikube-windows-amd64.exe -p cert-expiration-20220329190729-1328 logs -n 25: (8.8495524s)
helpers_test.go:253: TestCertExpiration logs:
-- stdout --
*
* ==> Audit <==
* |---------|----------------------------------------|----------------------------------------|-------------------|---------|-------------------------------|-------------------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|----------------------------------------|----------------------------------------|-------------------|---------|-------------------------------|-------------------------------|
| stop | -p | kubernetes-upgrade-20220329190043-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:07:53 GMT | Tue, 29 Mar 2022 19:08:21 GMT |
| | kubernetes-upgrade-20220329190043-1328 | | | | | |
| start | -p | kubernetes-upgrade-20220329190043-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:08:23 GMT | Tue, 29 Mar 2022 19:09:53 GMT |
| | kubernetes-upgrade-20220329190043-1328 | | | | | |
| | --memory=2200 | | | | | |
| | --kubernetes-version=v1.23.6-rc.0 | | | | | |
| | --alsologtostderr -v=1 --driver=docker | | | | | |
| start | -p | docker-flags-20220329190750-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:07:50 GMT | Tue, 29 Mar 2022 19:10:06 GMT |
| | docker-flags-20220329190750-1328 | | | | | |
| | --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=docker | | | | | |
| -p | docker-flags-20220329190750-1328 | docker-flags-20220329190750-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:10:07 GMT | Tue, 29 Mar 2022 19:10:10 GMT |
| | ssh sudo systemctl show | | | | | |
| | docker --property=Environment | | | | | |
| | --no-pager | | | | | |
| -p | docker-flags-20220329190750-1328 | docker-flags-20220329190750-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:10:11 GMT | Tue, 29 Mar 2022 19:10:14 GMT |
| | ssh sudo systemctl show docker | | | | | |
| | --property=ExecStart --no-pager | | | | | |
| start | -p | kubernetes-upgrade-20220329190043-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:09:54 GMT | Tue, 29 Mar 2022 19:10:16 GMT |
| | kubernetes-upgrade-20220329190043-1328 | | | | | |
| | --memory=2200 | | | | | |
| | --kubernetes-version=v1.23.6-rc.0 | | | | | |
| | --alsologtostderr -v=1 --driver=docker | | | | | |
| delete | -p | docker-flags-20220329190750-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:10:15 GMT | Tue, 29 Mar 2022 19:10:32 GMT |
| | docker-flags-20220329190750-1328 | | | | | |
| delete | -p | kubernetes-upgrade-20220329190043-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:10:17 GMT | Tue, 29 Mar 2022 19:10:33 GMT |
| | kubernetes-upgrade-20220329190043-1328 | | | | | |
| start | -p | cert-options-20220329191032-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:10:32 GMT | Tue, 29 Mar 2022 19:12:23 GMT |
| | cert-options-20220329191032-1328 | | | | | |
| | --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=docker | | | | | |
| | --apiserver-name=localhost | | | | | |
| -p | cert-options-20220329191032-1328 | cert-options-20220329191032-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:12:23 GMT | Tue, 29 Mar 2022 19:12:28 GMT |
| | ssh openssl x509 -text -noout -in | | | | | |
| | /var/lib/minikube/certs/apiserver.crt | | | | | |
| ssh | -p | cert-options-20220329191032-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:12:29 GMT | Tue, 29 Mar 2022 19:12:33 GMT |
| | cert-options-20220329191032-1328 | | | | | |
| | -- sudo cat | | | | | |
| | /etc/kubernetes/admin.conf | | | | | |
| start | -p auto-20220329190226-1328 | auto-20220329190226-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:10:33 GMT | Tue, 29 Mar 2022 19:13:11 GMT |
| | --memory=2048 | | | | | |
| | --alsologtostderr | | | | | |
| | --wait=true --wait-timeout=5m | | | | | |
| | --driver=docker | | | | | |
| ssh | -p auto-20220329190226-1328 | auto-20220329190226-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:13:11 GMT | Tue, 29 Mar 2022 19:13:15 GMT |
| | pgrep -a kubelet | | | | | |
| delete | -p | cert-options-20220329191032-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:12:33 GMT | Tue, 29 Mar 2022 19:13:20 GMT |
| | cert-options-20220329191032-1328 | | | | | |
| delete | -p auto-20220329190226-1328 | auto-20220329190226-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:13:43 GMT | Tue, 29 Mar 2022 19:14:15 GMT |
| start | -p | force-systemd-env-20220329190726-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:07:26 GMT | Tue, 29 Mar 2022 19:14:33 GMT |
| | force-systemd-env-20220329190726-1328 | | | | | |
| | --memory=2048 --alsologtostderr -v=5 | | | | | |
| | --driver=docker | | | | | |
| -p | force-systemd-env-20220329190726-1328 | force-systemd-env-20220329190726-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:14:33 GMT | Tue, 29 Mar 2022 19:14:37 GMT |
| | ssh docker info --format | | | | | |
| | {{.CgroupDriver}} | | | | | |
| delete | -p | force-systemd-env-20220329190726-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:14:37 GMT | Tue, 29 Mar 2022 19:15:09 GMT |
| | force-systemd-env-20220329190726-1328 | | | | | |
| start | -p | custom-weave-20220329190230-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:15:09 GMT | Tue, 29 Mar 2022 19:17:26 GMT |
| | custom-weave-20220329190230-1328 | | | | | |
| | --memory=2048 --alsologtostderr | | | | | |
| | --wait=true --wait-timeout=5m | | | | | |
| | --cni=testdata\weavenet.yaml | | | | | |
| | --driver=docker | | | | | |
| ssh | -p | custom-weave-20220329190230-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:17:26 GMT | Tue, 29 Mar 2022 19:17:30 GMT |
| | custom-weave-20220329190230-1328 | | | | | |
| | pgrep -a kubelet | | | | | |
| delete | -p | custom-weave-20220329190230-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:17:52 GMT | Tue, 29 Mar 2022 19:18:04 GMT |
| | custom-weave-20220329190230-1328 | | | | | |
| start | -p false-20220329190230-1328 | false-20220329190230-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:18:04 GMT | Tue, 29 Mar 2022 19:20:57 GMT |
| | --memory=2048 | | | | | |
| | --alsologtostderr --wait=true | | | | | |
| | --wait-timeout=5m --cni=false | | | | | |
| | --driver=docker | | | | | |
| ssh | -p false-20220329190230-1328 | false-20220329190230-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:20:57 GMT | Tue, 29 Mar 2022 19:21:02 GMT |
| | pgrep -a kubelet | | | | | |
| delete | -p false-20220329190230-1328 | false-20220329190230-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:21:33 GMT | Tue, 29 Mar 2022 19:21:55 GMT |
| start | -p | cert-expiration-20220329190729-1328 | minikube8\jenkins | v1.25.2 | Tue, 29 Mar 2022 19:16:21 GMT | Tue, 29 Mar 2022 19:22:09 GMT |
| | cert-expiration-20220329190729-1328 | | | | | |
| | --memory=2048 | | | | | |
| | --cert-expiration=8760h | | | | | |
| | --driver=docker | | | | | |
|---------|----------------------------------------|----------------------------------------|-------------------|---------|-------------------------------|-------------------------------|
*
* ==> Last Start <==
* Log file created at: 2022/03/29 19:21:55
Running on machine: minikube8
Binary: Built with gc go1.17.7 for windows/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I0329 19:21:55.571459 3060 out.go:297] Setting OutFile to fd 1852 ...
I0329 19:21:55.639464 3060 out.go:344] TERM=,COLORTERM=, which probably does not support color
I0329 19:21:55.639464 3060 out.go:310] Setting ErrFile to fd 1908...
I0329 19:21:55.639464 3060 out.go:344] TERM=,COLORTERM=, which probably does not support color
I0329 19:21:55.654455 3060 out.go:304] Setting JSON to false
I0329 19:21:55.656455 3060 start.go:114] hostinfo: {"hostname":"minikube8","uptime":8912,"bootTime":1648572803,"procs":153,"os":"windows","platform":"Microsoft Windows 10 Enterprise N","platformFamily":"Standalone Workstation","platformVersion":"10.0.19042 Build 19042","kernelVersion":"10.0.19042 Build 19042","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"907a2b8c-8800-4f4e-912a-028cf331db55"}
W0329 19:21:55.656455 3060 start.go:122] gopshost.Virtualization returned error: not implemented yet
I0329 19:21:55.666473 3060 out.go:176] * [kindnet-20220329190230-1328] minikube v1.25.2 on Microsoft Windows 10 Enterprise N 10.0.19042 Build 19042
I0329 19:21:55.666473 3060 notify.go:193] Checking for updates...
I0329 19:21:55.678467 3060 out.go:176] - KUBECONFIG=C:\Users\jenkins.minikube8\minikube-integration\kubeconfig
I0329 19:21:55.686467 3060 out.go:176] - MINIKUBE_HOME=C:\Users\jenkins.minikube8\minikube-integration\.minikube
I0329 19:21:55.690471 3060 out.go:176] - MINIKUBE_LOCATION=13730
I0329 19:21:53.882864 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:21:56.045501 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:21:55.693498 3060 out.go:176] - MINIKUBE_SUPPRESS_DOCKER_PERFORMANCE=true
I0329 19:21:55.695484 3060 config.go:176] Loaded profile config "calico-20220329190230-1328": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.23.5
I0329 19:21:55.695484 3060 config.go:176] Loaded profile config "cert-expiration-20220329190729-1328": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.23.5
I0329 19:21:55.696486 3060 config.go:176] Loaded profile config "cilium-20220329190230-1328": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.23.5
I0329 19:21:55.696486 3060 driver.go:346] Setting default libvirt URI to qemu:///system
I0329 19:21:58.022478 3060 docker.go:137] docker version: linux-20.10.13
I0329 19:21:58.030485 3060 cli_runner.go:133] Run: docker system info --format "{{json .}}"
I0329 19:21:58.783447 3060 info.go:263] docker info: {ID:EWJC:D32H:QDOV:Q37U:7NCG:FSEF:BHRI:5KZE:BNL5:7NRS:WK2R:WXHN Containers:3 ContainersRunning:3 ContainersPaused:0 ContainersStopped:0 Images:4 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true] [userxattr false]] SystemStatus:<nil> Plugins:{Volume:[local] Network:[bridge host ipvlan macvlan null overlay] Authorization:<nil> Log:[awslogs fluentd gcplogs gelf journald json-file local logentries splunk syslog]} MemoryLimit:true SwapLimit:true KernelMemory:true KernelMemoryTCP:true CPUCfsPeriod:true CPUCfsQuota:true CPUShares:true CPUSet:true PidsLimit:true IPv4Forwarding:true BridgeNfIptables:true BridgeNfIP6Tables:true Debug:true NFd:83 OomKillDisable:true NGoroutines:60 SystemTime:2022-03-29 19:21:58.4022559 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:2 KernelVersion:5.10.102.1-microsoft-standard-WSL2 OperatingSystem:Docker Desktop OSType:linux Architecture:x86_
64 IndexServerAddress:https://index.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:16 MemTotal:53902323712 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy:http.docker.internal:3128 HTTPSProxy:http.docker.internal:3128 NoProxy:hubproxy.docker.internal Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.13 ClusterStore: ClusterAdvertise: Runtimes:{Runc:{Path:runc}} DefaultRuntime:runc Swarm:{NodeID: NodeAddr: LocalNodeState:inactive ControlAvailable:false Error: RemoteManagers:<nil>} LiveRestoreEnabled:false Isolation: InitBinary:docker-init ContainerdCommit:{ID:2a1d4dbdb2a1030dc5b01e96fb110a9d9f150ecc Expected:2a1d4dbdb2a1030dc5b01e96fb110a9d9f150ecc} RuncCommit:{ID:v1.0.3-0-gf46b6ba Expected:v1.0.3-0-gf46b6ba} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,
profile=default] ProductLicense: Warnings:[WARNING: No blkio throttle.read_bps_device support WARNING: No blkio throttle.write_bps_device support WARNING: No blkio throttle.read_iops_device support WARNING: No blkio throttle.write_iops_device support] ServerErrors:[] ClientInfo:{Debug:false Plugins:[map[Name:buildx Path:C:\Program Files\Docker\cli-plugins\docker-buildx.exe SchemaVersion:0.1.0 ShortDescription:Docker Buildx Vendor:Docker Inc. Version:v0.8.0] map[Name:compose Path:C:\Program Files\Docker\cli-plugins\docker-compose.exe SchemaVersion:0.1.0 ShortDescription:Docker Compose Vendor:Docker Inc. Version:v2.3.3] map[Name:scan Path:C:\Program Files\Docker\cli-plugins\docker-scan.exe SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.17.0]] Warnings:<nil>}}
I0329 19:21:58.789286 3060 out.go:176] * Using the docker driver based on user configuration
I0329 19:21:58.789286 3060 start.go:283] selected driver: docker
I0329 19:21:58.789286 3060 start.go:800] validating driver "docker" against <nil>
I0329 19:21:58.789286 3060 start.go:811] status for docker: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc: Version:}
I0329 19:21:58.923237 3060 cli_runner.go:133] Run: docker system info --format "{{json .}}"
I0329 19:21:59.760623 3060 info.go:263] docker info: {ID:EWJC:D32H:QDOV:Q37U:7NCG:FSEF:BHRI:5KZE:BNL5:7NRS:WK2R:WXHN Containers:3 ContainersRunning:3 ContainersPaused:0 ContainersStopped:0 Images:4 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true] [userxattr false]] SystemStatus:<nil> Plugins:{Volume:[local] Network:[bridge host ipvlan macvlan null overlay] Authorization:<nil> Log:[awslogs fluentd gcplogs gelf journald json-file local logentries splunk syslog]} MemoryLimit:true SwapLimit:true KernelMemory:true KernelMemoryTCP:true CPUCfsPeriod:true CPUCfsQuota:true CPUShares:true CPUSet:true PidsLimit:true IPv4Forwarding:true BridgeNfIptables:true BridgeNfIP6Tables:true Debug:true NFd:84 OomKillDisable:true NGoroutines:61 SystemTime:2022-03-29 19:21:59.3543198 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:2 KernelVersion:5.10.102.1-microsoft-standard-WSL2 OperatingSystem:Docker Desktop OSType:linux Architecture:x86_
64 IndexServerAddress:https://index.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:16 MemTotal:53902323712 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy:http.docker.internal:3128 HTTPSProxy:http.docker.internal:3128 NoProxy:hubproxy.docker.internal Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.13 ClusterStore: ClusterAdvertise: Runtimes:{Runc:{Path:runc}} DefaultRuntime:runc Swarm:{NodeID: NodeAddr: LocalNodeState:inactive ControlAvailable:false Error: RemoteManagers:<nil>} LiveRestoreEnabled:false Isolation: InitBinary:docker-init ContainerdCommit:{ID:2a1d4dbdb2a1030dc5b01e96fb110a9d9f150ecc Expected:2a1d4dbdb2a1030dc5b01e96fb110a9d9f150ecc} RuncCommit:{ID:v1.0.3-0-gf46b6ba Expected:v1.0.3-0-gf46b6ba} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,
profile=default] ProductLicense: Warnings:[WARNING: No blkio throttle.read_bps_device support WARNING: No blkio throttle.write_bps_device support WARNING: No blkio throttle.read_iops_device support WARNING: No blkio throttle.write_iops_device support] ServerErrors:[] ClientInfo:{Debug:false Plugins:[map[Name:buildx Path:C:\Program Files\Docker\cli-plugins\docker-buildx.exe SchemaVersion:0.1.0 ShortDescription:Docker Buildx Vendor:Docker Inc. Version:v0.8.0] map[Name:compose Path:C:\Program Files\Docker\cli-plugins\docker-compose.exe SchemaVersion:0.1.0 ShortDescription:Docker Compose Vendor:Docker Inc. Version:v2.3.3] map[Name:scan Path:C:\Program Files\Docker\cli-plugins\docker-scan.exe SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.17.0]] Warnings:<nil>}}
I0329 19:21:59.760623 3060 start_flags.go:292] no existing cluster config was found, will generate one from the flags
I0329 19:21:59.761550 3060 start_flags.go:837] Waiting for all components: map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true]
I0329 19:21:59.761550 3060 cni.go:93] Creating CNI manager for "kindnet"
I0329 19:21:59.761550 3060 cni.go:217] auto-setting extra-config to "kubelet.cni-conf-dir=/etc/cni/net.mk"
I0329 19:21:59.761550 3060 cni.go:222] extra-config set to "kubelet.cni-conf-dir=/etc/cni/net.mk"
I0329 19:21:59.761550 3060 start_flags.go:301] Found "CNI" CNI - setting NetworkPlugin=cni
I0329 19:21:59.761550 3060 start_flags.go:306] config:
{Name:kindnet-20220329190230-1328 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 Memory:2048 CPUs:2 DiskSize:20000 VMDriver: Driver:docker 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 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.23.5 ClusterName:kindnet-20220329190230-1328 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRunt
ime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[{Component:kubelet Key:cni-conf-dir Value:/etc/cni/net.mk}] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:kindnet NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:5m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube8:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0 MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false}
I0329 19:21:59.767551 3060 out.go:176] * Starting control plane node kindnet-20220329190230-1328 in cluster kindnet-20220329190230-1328
I0329 19:21:59.767551 3060 cache.go:120] Beginning downloading kic base image for docker with docker
I0329 19:21:59.777555 3060 out.go:176] * Pulling base image ...
I0329 19:21:59.777555 3060 preload.go:132] Checking if preload exists for k8s version v1.23.5 and runtime docker
I0329 19:21:59.777555 3060 image.go:75] Checking for gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 in local docker daemon
I0329 19:21:59.778595 3060 preload.go:148] Found local preload: C:\Users\jenkins.minikube8\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v17-v1.23.5-docker-overlay2-amd64.tar.lz4
I0329 19:21:59.778595 3060 cache.go:57] Caching tarball of preloaded images
I0329 19:21:59.778595 3060 preload.go:174] Found C:\Users\jenkins.minikube8\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v17-v1.23.5-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0329 19:21:59.778595 3060 cache.go:60] Finished verifying existence of preloaded tar for v1.23.5 on docker
I0329 19:21:59.779558 3060 profile.go:148] Saving config to C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\kindnet-20220329190230-1328\config.json ...
I0329 19:21:59.779558 3060 lock.go:35] WriteFile acquiring C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\kindnet-20220329190230-1328\config.json: {Name:mk6dcdefc191c30bb34c1c8319cc8490444e173c Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0329 19:22:00.328900 3060 image.go:79] Found gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 in local docker daemon, skipping pull
I0329 19:22:00.328900 3060 cache.go:142] gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 exists in daemon, skipping load
I0329 19:22:00.328900 3060 cache.go:208] Successfully downloaded all kic artifacts
I0329 19:22:00.328900 3060 start.go:348] acquiring machines lock for kindnet-20220329190230-1328: {Name:mk93919b231bfab46578efb1f64d7a60b9cbb338 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0329 19:22:00.328900 3060 start.go:352] acquired machines lock for "kindnet-20220329190230-1328" in 0s
I0329 19:22:00.328900 3060 start.go:90] Provisioning new machine with config: &{Name:kindnet-20220329190230-1328 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 Memory:2048 CPUs:2 DiskSize:20000 VMDriver: Driver:docker 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 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.23.5 ClusterName:kindnet-20220329190230-1328 Namespace:default APIServerName:
minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin:cni FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[{Component:kubelet Key:cni-conf-dir Value:/etc/cni/net.mk}] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI:kindnet NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.23.5 ContainerRuntime:docker ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true apps_running:true default_sa:true extra:true kubelet:true node_ready:true system_pods:true] StartHostTimeout:5m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: Subnet: MultiNodeRequested:false ExtraDisks:0 CertExpiration:26280h0m0s Mount:false MountString:C:\Users\jenkins.minikube8:/minikube-host Mount9PVersion:9p2000.L MountGID:docker MountIP: MountMSize:262144 MountOptions:[] MountPort:0
MountType:9p MountUID:docker BinaryMirror: DisableOptimizations:false DisableMetrics:false} &{Name: IP: Port:8443 KubernetesVersion:v1.23.5 ContainerRuntime:docker ControlPlane:true Worker:true}
I0329 19:22:00.330945 3060 start.go:127] createHost starting for "" (driver="docker")
I0329 19:21:56.168143 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
I0329 19:21:58.308282 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:00.317919 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:00.336920 3060 out.go:203] * Creating docker container (CPUs=2, Memory=2048MB) ...
I0329 19:22:00.336920 3060 start.go:161] libmachine.API.Create for "kindnet-20220329190230-1328" (driver="docker")
I0329 19:22:00.336920 3060 client.go:168] LocalClient.Create starting
I0329 19:22:00.337911 3060 main.go:130] libmachine: Reading certificate data from C:\Users\jenkins.minikube8\minikube-integration\.minikube\certs\ca.pem
I0329 19:22:00.337911 3060 main.go:130] libmachine: Decoding PEM data...
I0329 19:22:00.337911 3060 main.go:130] libmachine: Parsing certificate...
I0329 19:22:00.337911 3060 main.go:130] libmachine: Reading certificate data from C:\Users\jenkins.minikube8\minikube-integration\.minikube\certs\cert.pem
I0329 19:22:00.337911 3060 main.go:130] libmachine: Decoding PEM data...
I0329 19:22:00.337911 3060 main.go:130] libmachine: Parsing certificate...
I0329 19:22:00.347908 3060 cli_runner.go:133] Run: docker network inspect kindnet-20220329190230-1328 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
I0329 19:21:58.073149 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:00.525490 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:02.500057 7144 out.go:203] - Generating certificates and keys ...
I0329 19:22:02.507057 7144 out.go:203] - Booting up control plane ...
I0329 19:22:02.819857 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:05.325327 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
W0329 19:22:00.882273 3060 cli_runner.go:180] docker network inspect kindnet-20220329190230-1328 --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}" returned with exit code 1
I0329 19:22:00.895271 3060 network_create.go:262] running [docker network inspect kindnet-20220329190230-1328] to gather additional debugging logs...
I0329 19:22:00.895271 3060 cli_runner.go:133] Run: docker network inspect kindnet-20220329190230-1328
W0329 19:22:01.452273 3060 cli_runner.go:180] docker network inspect kindnet-20220329190230-1328 returned with exit code 1
I0329 19:22:01.452273 3060 network_create.go:265] error running [docker network inspect kindnet-20220329190230-1328]: docker network inspect kindnet-20220329190230-1328: exit status 1
stdout:
[]
stderr:
Error: No such network: kindnet-20220329190230-1328
I0329 19:22:01.452273 3060 network_create.go:267] output of [docker network inspect kindnet-20220329190230-1328]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: kindnet-20220329190230-1328
** /stderr **
I0329 19:22:01.461276 3060 cli_runner.go:133] Run: docker network inspect bridge --format "{"Name": "{{.Name}}","Driver": "{{.Driver}}","Subnet": "{{range .IPAM.Config}}{{.Subnet}}{{end}}","Gateway": "{{range .IPAM.Config}}{{.Gateway}}{{end}}","MTU": {{if (index .Options "com.docker.network.driver.mtu")}}{{(index .Options "com.docker.network.driver.mtu")}}{{else}}0{{end}}, "ContainerIPs": [{{range $k,$v := .Containers }}"{{$v.IPv4Address}}",{{end}}]}"
I0329 19:22:02.040411 3060 network.go:288] reserving subnet 192.168.49.0 for 1m0s: &{mu:{state:0 sema:0} read:{v:{m:map[] amended:true}} dirty:map[192.168.49.0:0xc00014e470] misses:0}
I0329 19:22:02.041403 3060 network.go:235] using free private subnet 192.168.49.0/24: &{IP:192.168.49.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.49.0/24 Gateway:192.168.49.1 ClientMin:192.168.49.2 ClientMax:192.168.49.254 Broadcast:192.168.49.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I0329 19:22:02.041403 3060 network_create.go:114] attempt to create docker network kindnet-20220329190230-1328 192.168.49.0/24 with gateway 192.168.49.1 and MTU of 1500 ...
I0329 19:22:02.048399 3060 cli_runner.go:133] Run: docker network create --driver=bridge --subnet=192.168.49.0/24 --gateway=192.168.49.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true kindnet-20220329190230-1328
I0329 19:22:02.814865 3060 network_create.go:98] docker network kindnet-20220329190230-1328 192.168.49.0/24 created
I0329 19:22:02.814865 3060 kic.go:106] calculated static IP "192.168.49.2" for the "kindnet-20220329190230-1328" container
I0329 19:22:02.828851 3060 cli_runner.go:133] Run: docker ps -a --format {{.Names}}
I0329 19:22:03.395451 3060 cli_runner.go:133] Run: docker volume create kindnet-20220329190230-1328 --label name.minikube.sigs.k8s.io=kindnet-20220329190230-1328 --label created_by.minikube.sigs.k8s.io=true
I0329 19:22:03.935415 3060 oci.go:102] Successfully created a docker volume kindnet-20220329190230-1328
I0329 19:22:03.947282 3060 cli_runner.go:133] Run: docker run --rm --name kindnet-20220329190230-1328-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=kindnet-20220329190230-1328 --entrypoint /usr/bin/test -v kindnet-20220329190230-1328:/var gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 -d /var/lib
I0329 19:22:02.516052 7144 out.go:203] - Configuring RBAC rules ...
I0329 19:22:02.522054 7144 cni.go:93] Creating CNI manager for ""
I0329 19:22:02.522054 7144 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I0329 19:22:02.522054 7144 ssh_runner.go:195] Run: /bin/bash -c "cat /proc/$(pgrep kube-apiserver)/oom_adj"
I0329 19:22:02.548067 7144 ssh_runner.go:195] Run: sudo /var/lib/minikube/binaries/v1.23.5/kubectl label nodes minikube.k8s.io/version=v1.25.2 minikube.k8s.io/commit=923781973407d6dc536f326caa216e4920fd75c3 minikube.k8s.io/name=cert-expiration-20220329190729-1328 minikube.k8s.io/updated_at=2022_03_29T19_22_02_0700 minikube.k8s.io/primary=true --all --overwrite --kubeconfig=/var/lib/minikube/kubeconfig
I0329 19:22:02.548067 7144 ssh_runner.go:195] Run: sudo /var/lib/minikube/binaries/v1.23.5/kubectl create clusterrolebinding minikube-rbac --clusterrole=cluster-admin --serviceaccount=kube-system:default --kubeconfig=/var/lib/minikube/kubeconfig
I0329 19:22:02.586060 7144 ops.go:34] apiserver oom_adj: -16
I0329 19:22:03.012493 7144 kubeadm.go:1020] duration metric: took 490.4366ms to wait for elevateKubeSystemPrivileges.
I0329 19:22:04.963907 7144 ssh_runner.go:235] Completed: sudo /var/lib/minikube/binaries/v1.23.5/kubectl label nodes minikube.k8s.io/version=v1.25.2 minikube.k8s.io/commit=923781973407d6dc536f326caa216e4920fd75c3 minikube.k8s.io/name=cert-expiration-20220329190729-1328 minikube.k8s.io/updated_at=2022_03_29T19_22_02_0700 minikube.k8s.io/primary=true --all --overwrite --kubeconfig=/var/lib/minikube/kubeconfig: (2.4158259s)
I0329 19:22:04.964919 7144 kubeadm.go:393] StartCluster complete in 40.0915936s
I0329 19:22:04.964919 7144 settings.go:142] acquiring lock: {Name:mkef8bbc389dbb185414693c85b2ca1f1524f773 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0329 19:22:04.964919 7144 settings.go:150] Updating kubeconfig: C:\Users\jenkins.minikube8\minikube-integration\kubeconfig
I0329 19:22:04.967915 7144 lock.go:35] WriteFile acquiring C:\Users\jenkins.minikube8\minikube-integration\kubeconfig: {Name:mkae4c781fbfb916db801be8b13665a6fdce8de8 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0329 19:22:05.733660 7144 kapi.go:244] deployment "coredns" in namespace "kube-system" and context "cert-expiration-20220329190729-1328" rescaled to 1
I0329 19:22:05.733660 7144 ssh_runner.go:195] Run: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.23.5/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml"
I0329 19:22:05.734671 7144 start.go:208] Will wait 6m0s for node &{Name: IP:192.168.76.2 Port:8443 KubernetesVersion:v1.23.5 ContainerRuntime:docker ControlPlane:true Worker:true}
I0329 19:22:05.734671 7144 addons.go:415] enableAddons start: toEnable=map[], additional=[]
I0329 19:22:03.083233 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:05.580131 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:05.734671 7144 addons.go:65] Setting storage-provisioner=true in profile "cert-expiration-20220329190729-1328"
I0329 19:22:05.741658 7144 out.go:176] * Verifying Kubernetes components...
I0329 19:22:05.741658 7144 addons.go:153] Setting addon storage-provisioner=true in "cert-expiration-20220329190729-1328"
W0329 19:22:05.741658 7144 addons.go:165] addon storage-provisioner should already be in state true
I0329 19:22:05.734671 7144 addons.go:65] Setting default-storageclass=true in profile "cert-expiration-20220329190729-1328"
I0329 19:22:05.741658 7144 addons_storage_classes.go:33] enableOrDisableStorageClasses default-storageclass=true on "cert-expiration-20220329190729-1328"
I0329 19:22:05.734671 7144 config.go:176] Loaded profile config "cert-expiration-20220329190729-1328": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.23.5
I0329 19:22:05.741658 7144 host.go:66] Checking if "cert-expiration-20220329190729-1328" exists ...
I0329 19:22:05.759666 7144 cli_runner.go:133] Run: docker container inspect cert-expiration-20220329190729-1328 --format={{.State.Status}}
I0329 19:22:05.759666 7144 cli_runner.go:133] Run: docker container inspect cert-expiration-20220329190729-1328 --format={{.State.Status}}
I0329 19:22:05.761693 7144 ssh_runner.go:195] Run: sudo service kubelet status
I0329 19:22:05.931464 7144 ssh_runner.go:195] Run: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.23.5/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml | sed '/^ forward . \/etc\/resolv.conf.*/i \ hosts {\n 192.168.65.2 host.minikube.internal\n fallthrough\n }' | sudo /var/lib/minikube/binaries/v1.23.5/kubectl --kubeconfig=/var/lib/minikube/kubeconfig replace -f -"
I0329 19:22:05.945469 7144 cli_runner.go:133] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "8443/tcp") 0).HostPort}}'" cert-expiration-20220329190729-1328
I0329 19:22:06.374509 7144 out.go:176] - Using image gcr.io/k8s-minikube/storage-provisioner:v5
I0329 19:22:06.375492 7144 addons.go:348] installing /etc/kubernetes/addons/storage-provisioner.yaml
I0329 19:22:06.375492 7144 ssh_runner.go:362] scp memory --> /etc/kubernetes/addons/storage-provisioner.yaml (2676 bytes)
I0329 19:22:06.394487 7144 cli_runner.go:133] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" cert-expiration-20220329190729-1328
I0329 19:22:06.401488 7144 addons.go:153] Setting addon default-storageclass=true in "cert-expiration-20220329190729-1328"
W0329 19:22:06.401488 7144 addons.go:165] addon default-storageclass should already be in state true
I0329 19:22:06.401488 7144 host.go:66] Checking if "cert-expiration-20220329190729-1328" exists ...
I0329 19:22:06.428477 7144 cli_runner.go:133] Run: docker container inspect cert-expiration-20220329190729-1328 --format={{.State.Status}}
I0329 19:22:06.536484 7144 api_server.go:51] waiting for apiserver process to appear ...
I0329 19:22:06.553473 7144 ssh_runner.go:195] Run: sudo pgrep -xnf kube-apiserver.*minikube.*
I0329 19:22:06.979980 7144 sshutil.go:53] new ssh client: &{IP:127.0.0.1 Port:57522 SSHKeyPath:C:\Users\jenkins.minikube8\minikube-integration\.minikube\machines\cert-expiration-20220329190729-1328\id_rsa Username:docker}
I0329 19:22:07.011940 7144 addons.go:348] installing /etc/kubernetes/addons/storageclass.yaml
I0329 19:22:07.011940 7144 ssh_runner.go:362] scp memory --> /etc/kubernetes/addons/storageclass.yaml (271 bytes)
I0329 19:22:07.026936 7144 cli_runner.go:133] Run: docker container inspect -f "'{{(index (index .NetworkSettings.Ports "22/tcp") 0).HostPort}}'" cert-expiration-20220329190729-1328
I0329 19:22:07.190967 7144 ssh_runner.go:195] Run: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.23.5/kubectl apply -f /etc/kubernetes/addons/storage-provisioner.yaml
I0329 19:22:07.584362 7144 sshutil.go:53] new ssh client: &{IP:127.0.0.1 Port:57522 SSHKeyPath:C:\Users\jenkins.minikube8\minikube-integration\.minikube\machines\cert-expiration-20220329190729-1328\id_rsa Username:docker}
I0329 19:22:07.982573 7144 ssh_runner.go:235] Completed: /bin/bash -c "sudo /var/lib/minikube/binaries/v1.23.5/kubectl --kubeconfig=/var/lib/minikube/kubeconfig -n kube-system get configmap coredns -o yaml | sed '/^ forward . \/etc\/resolv.conf.*/i \ hosts {\n 192.168.65.2 host.minikube.internal\n fallthrough\n }' | sudo /var/lib/minikube/binaries/v1.23.5/kubectl --kubeconfig=/var/lib/minikube/kubeconfig replace -f -": (2.0510966s)
I0329 19:22:07.982573 7144 start.go:777] {"host.minikube.internal": 192.168.65.2} host record injected into CoreDNS
I0329 19:22:07.982573 7144 ssh_runner.go:235] Completed: sudo pgrep -xnf kube-apiserver.*minikube.*: (1.4290918s)
I0329 19:22:07.982573 7144 api_server.go:71] duration metric: took 2.2478882s to wait for apiserver process to appear ...
I0329 19:22:07.982573 7144 api_server.go:87] waiting for apiserver healthz status ...
I0329 19:22:07.982573 7144 api_server.go:240] Checking apiserver healthz at https://127.0.0.1:57521/healthz ...
I0329 19:22:08.009572 7144 api_server.go:266] https://127.0.0.1:57521/healthz returned 200:
ok
I0329 19:22:08.012609 7144 ssh_runner.go:195] Run: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.23.5/kubectl apply -f /etc/kubernetes/addons/storageclass.yaml
I0329 19:22:08.015587 7144 api_server.go:140] control plane version: v1.23.5
I0329 19:22:08.015587 7144 api_server.go:130] duration metric: took 33.0138ms to wait for apiserver health ...
I0329 19:22:08.015587 7144 system_pods.go:43] waiting for kube-system pods to appear ...
I0329 19:22:08.075588 7144 system_pods.go:59] 4 kube-system pods found
I0329 19:22:08.075588 7144 system_pods.go:61] "etcd-cert-expiration-20220329190729-1328" [a4c744ca-2dae-4792-ae64-9b5298c48f87] Running / Ready:ContainersNotReady (containers with unready status: [etcd]) / ContainersReady:ContainersNotReady (containers with unready status: [etcd])
I0329 19:22:08.075588 7144 system_pods.go:61] "kube-apiserver-cert-expiration-20220329190729-1328" [7b54b912-c0df-412b-8a7c-7f79089e3a0e] Running / Ready:ContainersNotReady (containers with unready status: [kube-apiserver]) / ContainersReady:ContainersNotReady (containers with unready status: [kube-apiserver])
I0329 19:22:08.075588 7144 system_pods.go:61] "kube-controller-manager-cert-expiration-20220329190729-1328" [4122b692-b871-4825-b1a5-a40629a77030] Running / Ready:ContainersNotReady (containers with unready status: [kube-controller-manager]) / ContainersReady:ContainersNotReady (containers with unready status: [kube-controller-manager])
I0329 19:22:08.075588 7144 system_pods.go:61] "kube-scheduler-cert-expiration-20220329190729-1328" [7d9509ed-527a-48b6-97d9-2492de5728fe] Running / Ready:ContainersNotReady (containers with unready status: [kube-scheduler]) / ContainersReady:ContainersNotReady (containers with unready status: [kube-scheduler])
I0329 19:22:08.075588 7144 system_pods.go:74] duration metric: took 60.0006ms to wait for pod list to return data ...
I0329 19:22:08.075588 7144 kubeadm.go:548] duration metric: took 2.3409026s to wait for : map[apiserver:true system_pods:true] ...
I0329 19:22:08.075588 7144 node_conditions.go:102] verifying NodePressure condition ...
I0329 19:22:08.095583 7144 node_conditions.go:122] node storage ephemeral capacity is 263174212Ki
I0329 19:22:08.095583 7144 node_conditions.go:123] node cpu capacity is 16
I0329 19:22:08.095583 7144 node_conditions.go:105] duration metric: took 19.9949ms to run NodePressure ...
I0329 19:22:08.095583 7144 start.go:213] waiting for startup goroutines ...
I0329 19:22:08.475365 7144 ssh_runner.go:235] Completed: sudo KUBECONFIG=/var/lib/minikube/kubeconfig /var/lib/minikube/binaries/v1.23.5/kubectl apply -f /etc/kubernetes/addons/storage-provisioner.yaml: (1.2836421s)
I0329 19:22:08.787257 7144 out.go:176] * Enabled addons: storage-provisioner, default-storageclass
I0329 19:22:08.788280 7144 addons.go:417] enableAddons completed in 3.0535912s
I0329 19:22:09.061821 7144 start.go:498] kubectl: 1.18.2, cluster: 1.23.5 (minor skew: 5)
I0329 19:22:09.067837 7144 out.go:176]
W0329 19:22:09.068917 7144 out.go:241] ! C:\ProgramData\chocolatey\bin\kubectl.exe is version 1.18.2, which may have incompatibilites with Kubernetes 1.23.5.
I0329 19:22:09.079843 7144 out.go:176] - Want kubectl v1.23.5? Try 'minikube kubectl -- get pods -A'
I0329 19:22:09.082861 7144 out.go:176] * Done! kubectl is now configured to use "cert-expiration-20220329190729-1328" cluster and "default" namespace by default
I0329 19:22:07.824415 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:10.313321 8332 pod_ready.go:102] pod "cilium-h9rtv" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:07.297404 3060 cli_runner.go:186] Completed: docker run --rm --name kindnet-20220329190230-1328-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=kindnet-20220329190230-1328 --entrypoint /usr/bin/test -v kindnet-20220329190230-1328:/var gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 -d /var/lib: (3.3496831s)
I0329 19:22:07.297404 3060 oci.go:106] Successfully prepared a docker volume kindnet-20220329190230-1328
I0329 19:22:07.297553 3060 preload.go:132] Checking if preload exists for k8s version v1.23.5 and runtime docker
I0329 19:22:07.297553 3060 kic.go:179] Starting extracting preloaded images to volume ...
I0329 19:22:07.306519 3060 cli_runner.go:133] Run: docker run --rm --entrypoint /usr/bin/tar -v C:\Users\jenkins.minikube8\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v17-v1.23.5-docker-overlay2-amd64.tar.lz4:/preloaded.tar:ro -v kindnet-20220329190230-1328:/extractDir gcr.io/k8s-minikube/kicbase-builds:v0.0.30-1647797120-13815@sha256:90e8f7ee4065da728c0b80d303827e05ce4421985fe9bd7bdca30a55218347b5 -I lz4 -xf /preloaded.tar -C /extractDir
I0329 19:22:08.067578 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
I0329 19:22:10.612505 8480 pod_ready.go:102] pod "calico-kube-controllers-8594699699-lbr9j" in "kube-system" namespace has status "Ready":"False"
*
* ==> Docker <==
* -- Logs begin at Tue 2022-03-29 19:20:53 UTC, end at Tue 2022-03-29 19:22:20 UTC. --
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.796552000Z" level=info msg="Starting up"
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.802754700Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.802907900Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.802957100Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///run/containerd/containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.802986700Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.806737600Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.806877400Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.806909500Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///run/containerd/containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Mar 29 19:21:06 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:06.806932300Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.091940100Z" level=info msg="[graphdriver] using prior storage driver: overlay2"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.118636800Z" level=warning msg="Your kernel does not support cgroup blkio weight"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.118864700Z" level=warning msg="Your kernel does not support cgroup blkio weight_device"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.118884300Z" level=warning msg="Your kernel does not support cgroup blkio throttle.read_bps_device"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.118897300Z" level=warning msg="Your kernel does not support cgroup blkio throttle.write_bps_device"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.118910800Z" level=warning msg="Your kernel does not support cgroup blkio throttle.read_iops_device"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.118924900Z" level=warning msg="Your kernel does not support cgroup blkio throttle.write_iops_device"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.119417900Z" level=info msg="Loading containers: start."
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.497500900Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.628190100Z" level=info msg="Loading containers: done."
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.819974700Z" level=info msg="Docker daemon" commit=906f57f graphdriver(s)=overlay2 version=20.10.13
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.820132400Z" level=info msg="Daemon has completed initialization"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 systemd[1]: Started Docker Application Container Engine.
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.941307200Z" level=info msg="API listen on [::]:2376"
Mar 29 19:21:07 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:07.964023700Z" level=info msg="API listen on /var/run/docker.sock"
Mar 29 19:21:55 cert-expiration-20220329190729-1328 dockerd[471]: time="2022-03-29T19:21:55.256694000Z" level=info msg="ignoring event" container=18cab7150785779618a51a9ff96813592838ab6d6a2ab4d8178f1327cdcda987 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
*
* ==> container status <==
* CONTAINER IMAGE CREATED STATE NAME ATTEMPT POD ID
bf1cdbe4a20ef b0c9e5e4dbb14 24 seconds ago Running kube-controller-manager 1 2ada330dad59c
c57b86f45c9a6 25f8c7f3da61c 44 seconds ago Running etcd 0 979cb0256ae4f
5e2dba3aacfee 884d49d6d8c9f 44 seconds ago Running kube-scheduler 0 45418d6e560dd
18cab71507857 b0c9e5e4dbb14 44 seconds ago Exited kube-controller-manager 0 2ada330dad59c
a5210787d0248 3fc1d62d65872 44 seconds ago Running kube-apiserver 0 2d24c5f54f997
*
* ==> describe nodes <==
* Name: cert-expiration-20220329190729-1328
Roles: control-plane,master
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=cert-expiration-20220329190729-1328
kubernetes.io/os=linux
minikube.k8s.io/commit=923781973407d6dc536f326caa216e4920fd75c3
minikube.k8s.io/name=cert-expiration-20220329190729-1328
minikube.k8s.io/primary=true
minikube.k8s.io/updated_at=2022_03_29T19_22_02_0700
minikube.k8s.io/version=v1.25.2
node-role.kubernetes.io/control-plane=
node-role.kubernetes.io/master=
node.kubernetes.io/exclude-from-external-load-balancers=
Annotations: kubeadm.alpha.kubernetes.io/cri-socket: /var/run/dockershim.sock
node.alpha.kubernetes.io/ttl: 0
volumes.kubernetes.io/controller-managed-attach-detach: true
CreationTimestamp: Tue, 29 Mar 2022 19:21:45 +0000
Taints: <none>
Unschedulable: false
Lease:
HolderIdentity: cert-expiration-20220329190729-1328
AcquireTime: <unset>
RenewTime: Tue, 29 Mar 2022 19:22:13 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Tue, 29 Mar 2022 19:22:13 +0000 Tue, 29 Mar 2022 19:21:40 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Tue, 29 Mar 2022 19:22:13 +0000 Tue, 29 Mar 2022 19:21:40 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Tue, 29 Mar 2022 19:22:13 +0000 Tue, 29 Mar 2022 19:21:40 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready True Tue, 29 Mar 2022 19:22:13 +0000 Tue, 29 Mar 2022 19:22:13 +0000 KubeletReady kubelet is posting ready status
Addresses:
InternalIP: 192.168.76.2
Hostname: cert-expiration-20220329190729-1328
Capacity:
cpu: 16
ephemeral-storage: 263174212Ki
hugepages-1Gi: 0
hugepages-2Mi: 0
memory: 52638988Ki
pods: 110
Allocatable:
cpu: 16
ephemeral-storage: 263174212Ki
hugepages-1Gi: 0
hugepages-2Mi: 0
memory: 52638988Ki
pods: 110
System Info:
Machine ID: 140a143b31184b58be947b52a01fff83
System UUID: 140a143b31184b58be947b52a01fff83
Boot ID: c6888bb0-0d7a-4902-95ce-20313bf24adc
Kernel Version: 5.10.102.1-microsoft-standard-WSL2
OS Image: Ubuntu 20.04.4 LTS
Operating System: linux
Architecture: amd64
Container Runtime Version: docker://20.10.13
Kubelet Version: v1.23.5
Kube-Proxy Version: v1.23.5
PodCIDR: 10.244.0.0/24
PodCIDRs: 10.244.0.0/24
Non-terminated Pods: (7 in total)
Namespace Name CPU Requests CPU Limits Memory Requests Memory Limits Age
--------- ---- ------------ ---------- --------------- ------------- ---
kube-system coredns-64897985d-q4js5 100m (0%!)(MISSING) 0 (0%!)(MISSING) 70Mi (0%!)(MISSING) 170Mi (0%!)(MISSING) 6s
kube-system etcd-cert-expiration-20220329190729-1328 100m (0%!)(MISSING) 0 (0%!)(MISSING) 100Mi (0%!)(MISSING) 0 (0%!)(MISSING) 25s
kube-system kube-apiserver-cert-expiration-20220329190729-1328 250m (1%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 22s
kube-system kube-controller-manager-cert-expiration-20220329190729-1328 200m (1%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 27s
kube-system kube-proxy-sn7nc 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 6s
kube-system kube-scheduler-cert-expiration-20220329190729-1328 100m (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 17s
kube-system storage-provisioner 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 13s
Allocated resources:
(Total limits may be over 100 percent, i.e., overcommitted.)
Resource Requests Limits
-------- -------- ------
cpu 750m (4%!)(MISSING) 0 (0%!)(MISSING)
memory 170Mi (0%!)(MISSING) 170Mi (0%!)(MISSING)
ephemeral-storage 0 (0%!)(MISSING) 0 (0%!)(MISSING)
hugepages-1Gi 0 (0%!)(MISSING) 0 (0%!)(MISSING)
hugepages-2Mi 0 (0%!)(MISSING) 0 (0%!)(MISSING)
Events:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal NodeHasSufficientMemory 47s (x8 over 47s) kubelet Node cert-expiration-20220329190729-1328 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 47s (x8 over 47s) kubelet Node cert-expiration-20220329190729-1328 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 47s (x7 over 47s) kubelet Node cert-expiration-20220329190729-1328 status is now: NodeHasSufficientPID
Normal Starting 18s kubelet Starting kubelet.
Normal NodeHasSufficientMemory 18s kubelet Node cert-expiration-20220329190729-1328 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 18s kubelet Node cert-expiration-20220329190729-1328 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 18s kubelet Node cert-expiration-20220329190729-1328 status is now: NodeHasSufficientPID
Normal NodeAllocatableEnforced 17s kubelet Updated Node Allocatable limit across pods
Normal NodeReady 8s kubelet Node cert-expiration-20220329190729-1328 status is now: NodeReady
*
* ==> dmesg <==
* [ +0.000006] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000113] overlayfs: upperdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000009] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000050] overlayfs: upperdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000005] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.006224] overlayfs: upperdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000009] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.003599] overlayfs: upperdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000005] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000453] overlayfs: upperdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000008] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.079129] overlayfs: upperdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[ +0.000007] overlayfs: workdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.
[Mar29 19:07] WSL2: Performing memory compaction.
[Mar29 19:08] WSL2: Performing memory compaction.
[Mar29 19:10] WSL2: Performing memory compaction.
[Mar29 19:11] WSL2: Performing memory compaction.
[Mar29 19:12] WSL2: Performing memory compaction.
[Mar29 19:13] WSL2: Performing memory compaction.
[Mar29 19:14] WSL2: Performing memory compaction.
[Mar29 19:16] WSL2: Performing memory compaction.
[Mar29 19:17] WSL2: Performing memory compaction.
[Mar29 19:18] WSL2: Performing memory compaction.
[Mar29 19:19] WSL2: Performing memory compaction.
[Mar29 19:21] hrtimer: interrupt took 376500 ns
*
* ==> etcd [c57b86f45c9a] <==
* {"level":"info","ts":"2022-03-29T19:22:15.489Z","caller":"traceutil/trace.go:171","msg":"trace[1799190612] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:419; }","duration":"156.9482ms","start":"2022-03-29T19:22:15.332Z","end":"2022-03-29T19:22:15.489Z","steps":["trace[1799190612] 'agreement among raft nodes before linearized reading' (duration: 97.8735ms)","trace[1799190612] 'range keys from in-memory index tree' (duration: 58.7982ms)"],"step_count":2}
{"level":"info","ts":"2022-03-29T19:22:15.490Z","caller":"traceutil/trace.go:171","msg":"trace[1581604826] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"145.4961ms","start":"2022-03-29T19:22:15.344Z","end":"2022-03-29T19:22:15.490Z","steps":["trace[1581604826] 'process raft request' (duration: 145.1402ms)"],"step_count":1}
{"level":"info","ts":"2022-03-29T19:22:15.490Z","caller":"traceutil/trace.go:171","msg":"trace[1889808564] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"151.5908ms","start":"2022-03-29T19:22:15.339Z","end":"2022-03-29T19:22:15.490Z","steps":["trace[1889808564] 'process raft request' (duration: 89.8969ms)","trace[1889808564] 'compare' (duration: 60.3581ms)"],"step_count":2}
{"level":"info","ts":"2022-03-29T19:22:15.490Z","caller":"traceutil/trace.go:171","msg":"trace[200638197] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"142.9989ms","start":"2022-03-29T19:22:15.347Z","end":"2022-03-29T19:22:15.490Z","steps":["trace[200638197] 'process raft request' (duration: 142.3187ms)"],"step_count":1}
{"level":"info","ts":"2022-03-29T19:22:15.490Z","caller":"traceutil/trace.go:171","msg":"trace[149888094] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"146.9958ms","start":"2022-03-29T19:22:15.343Z","end":"2022-03-29T19:22:15.490Z","steps":["trace[149888094] 'process raft request' (duration: 146.0028ms)"],"step_count":1}
{"level":"warn","ts":"2022-03-29T19:22:15.491Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"144.1521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/default\" ","response":"range_response_count:1 size:181"}
{"level":"info","ts":"2022-03-29T19:22:15.491Z","caller":"traceutil/trace.go:171","msg":"trace[918326421] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/default; range_end:; response_count:1; response_revision:429; }","duration":"144.3307ms","start":"2022-03-29T19:22:15.347Z","end":"2022-03-29T19:22:15.491Z","steps":["trace[918326421] 'agreement among raft nodes before linearized reading' (duration: 144.106ms)"],"step_count":1}
{"level":"info","ts":"2022-03-29T19:22:15.659Z","caller":"traceutil/trace.go:171","msg":"trace[1877124612] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"110.0417ms","start":"2022-03-29T19:22:15.549Z","end":"2022-03-29T19:22:15.659Z","steps":["trace[1877124612] 'process raft request' (duration: 93.8619ms)","trace[1877124612] 'compare' (duration: 15.847ms)"],"step_count":2}
{"level":"warn","ts":"2022-03-29T19:22:16.254Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"112.752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:217"}
{"level":"info","ts":"2022-03-29T19:22:16.254Z","caller":"traceutil/trace.go:171","msg":"trace[991327872] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:452; }","duration":"113.1273ms","start":"2022-03-29T19:22:16.141Z","end":"2022-03-29T19:22:16.254Z","steps":["trace[991327872] 'range keys from in-memory index tree' (duration: 112.2081ms)"],"step_count":1}
{"level":"warn","ts":"2022-03-29T19:22:16.948Z","caller":"etcdserver/v3_server.go:815","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15638327318028791105,"retry-timeout":"500ms"}
{"level":"warn","ts":"2022-03-29T19:22:17.449Z","caller":"etcdserver/v3_server.go:815","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15638327318028791105,"retry-timeout":"500ms"}
{"level":"warn","ts":"2022-03-29T19:22:17.513Z","caller":"wal/wal.go:802","msg":"slow fdatasync","took":"1.2369777s","expected-duration":"1s"}
{"level":"info","ts":"2022-03-29T19:22:17.514Z","caller":"traceutil/trace.go:171","msg":"trace[1415518772] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:464; }","duration":"1.0665467s","start":"2022-03-29T19:22:16.447Z","end":"2022-03-29T19:22:17.514Z","steps":["trace[1415518772] 'read index received' (duration: 1.0665302s)","trace[1415518772] 'applied index is now lower than readState.Index' (duration: 12µs)"],"step_count":2}
{"level":"warn","ts":"2022-03-29T19:22:17.521Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"1.0739477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2022-03-29T19:22:17.521Z","caller":"traceutil/trace.go:171","msg":"trace[412171520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"1.0742973s","start":"2022-03-29T19:22:16.447Z","end":"2022-03-29T19:22:17.521Z","steps":["trace[412171520] 'agreement among raft nodes before linearized reading' (duration: 1.0667587s)"],"step_count":1}
{"level":"warn","ts":"2022-03-29T19:22:17.521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2022-03-29T19:22:16.447Z","time spent":"1.0743843s","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2022-03-29T19:22:17.521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2022-03-29T19:22:16.420Z","time spent":"1.101295s","remote":"127.0.0.1:53360","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2022-03-29T19:22:21.683Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"207.0549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 ","response":"range_response_count:1 size:4724"}
{"level":"warn","ts":"2022-03-29T19:22:21.683Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"956.9423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2022-03-29T19:22:21.683Z","caller":"traceutil/trace.go:171","msg":"trace[1585775047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"957.0166ms","start":"2022-03-29T19:22:20.726Z","end":"2022-03-29T19:22:21.683Z","steps":["trace[1585775047] 'range keys from in-memory index tree' (duration: 956.2183ms)"],"step_count":1}
{"level":"warn","ts":"2022-03-29T19:22:21.684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2022-03-29T19:22:20.726Z","time spent":"957.0893ms","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2022-03-29T19:22:21.683Z","caller":"traceutil/trace.go:171","msg":"trace[334367703] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:456; }","duration":"207.2483ms","start":"2022-03-29T19:22:21.476Z","end":"2022-03-29T19:22:21.683Z","steps":["trace[334367703] 'range keys from in-memory index tree' (duration: 206.9241ms)"],"step_count":1}
{"level":"warn","ts":"2022-03-29T19:22:21.684Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"241.5283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2022-03-29T19:22:21.684Z","caller":"traceutil/trace.go:171","msg":"trace[1964069823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"241.6024ms","start":"2022-03-29T19:22:21.442Z","end":"2022-03-29T19:22:21.684Z","steps":["trace[1964069823] 'range keys from in-memory index tree' (duration: 241.3625ms)"],"step_count":1}
*
* ==> kernel <==
* 19:22:22 up 2:11, 0 users, load average: 12.42, 7.01, 5.28
Linux cert-expiration-20220329190729-1328 5.10.102.1-microsoft-standard-WSL2 #1 SMP Wed Mar 2 00:30:59 UTC 2022 x86_64 x86_64 x86_64 GNU/Linux
PRETTY_NAME="Ubuntu 20.04.4 LTS"
*
* ==> kube-apiserver [a5210787d024] <==
* I0329 19:21:53.772880 1 storage_scheduling.go:93] created PriorityClass system-node-critical with value 2000001000
I0329 19:21:53.843762 1 trace.go:205] Trace[2126972864]: "Create" url:/api/v1/namespaces,user-agent:Go-http-client/2.0,audit-id:c880d2c2-b7b6-47d1-b24c-8e105102793f,client:127.0.0.1,accept:application/vnd.kubernetes.protobuf, */*,protocol:HTTP/2.0 (29-Mar-2022 19:21:47.875) (total time: 5968ms):
Trace[2126972864]: ---"Object stored in database" 5967ms (19:21:53.843)
Trace[2126972864]: [5.9681977s] [5.9681977s] END
I0329 19:21:53.869974 1 storage_scheduling.go:93] created PriorityClass system-cluster-critical with value 2000000000
I0329 19:21:53.870002 1 storage_scheduling.go:109] all system priority classes are created successfully or already exist.
I0329 19:21:56.595542 1 controller.go:611] quota admission added evaluator for: leases.coordination.k8s.io
I0329 19:21:58.448288 1 controller.go:611] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I0329 19:21:58.743725 1 controller.go:611] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
I0329 19:21:59.128013 1 alloc.go:329] "allocated clusterIPs" service="default/kubernetes" clusterIPs=map[IPv4:10.96.0.1]
W0329 19:21:59.150974 1 lease.go:233] Resetting endpoints for master service "kubernetes" to [192.168.76.2]
I0329 19:21:59.153555 1 controller.go:611] quota admission added evaluator for: endpoints
I0329 19:21:59.247997 1 controller.go:611] quota admission added evaluator for: endpointslices.discovery.k8s.io
I0329 19:22:00.135977 1 controller.go:611] quota admission added evaluator for: serviceaccounts
I0329 19:22:02.051894 1 controller.go:611] quota admission added evaluator for: deployments.apps
I0329 19:22:02.168567 1 alloc.go:329] "allocated clusterIPs" service="kube-system/kube-dns" clusterIPs=map[IPv4:10.96.0.10]
I0329 19:22:02.249605 1 controller.go:611] quota admission added evaluator for: daemonsets.apps
I0329 19:22:13.350688 1 trace.go:205] Trace[2028502654]: "Get" url:/api/v1/namespaces/kube-system/serviceaccounts/node-controller,user-agent:kube-controller-manager/v1.23.5 (linux/amd64) kubernetes/c285e78/kube-controller-manager,audit-id:bff93973-9940-4354-b40b-bef288e70243,client:192.168.76.2,accept:application/vnd.kubernetes.protobuf, */*,protocol:HTTP/2.0 (29-Mar-2022 19:22:12.815) (total time: 534ms):
Trace[2028502654]: ---"About to write a response" 534ms (19:22:13.350)
Trace[2028502654]: [534.9845ms] [534.9845ms] END
I0329 19:22:13.350782 1 trace.go:205] Trace[2103342587]: "Get" url:/api/v1/namespaces/kube-system/serviceaccounts/service-controller,user-agent:kube-controller-manager/v1.23.5 (linux/amd64) kubernetes/c285e78/tokens-controller,audit-id:9b1b8828-c207-4cca-a56c-2b24c9fd762c,client:192.168.76.2,accept:application/vnd.kubernetes.protobuf, */*,protocol:HTTP/2.0 (29-Mar-2022 19:22:12.814) (total time: 535ms):
Trace[2103342587]: ---"About to write a response" 535ms (19:22:13.350)
Trace[2103342587]: [535.8053ms] [535.8053ms] END
I0329 19:22:15.143180 1 controller.go:611] quota admission added evaluator for: controllerrevisions.apps
I0329 19:22:15.340638 1 controller.go:611] quota admission added evaluator for: replicasets.apps
*
* ==> kube-controller-manager [18cab7150785] <==
* /usr/local/go/src/bytes/buffer.go:204 +0x98
crypto/tls.(*Conn).readFromUntil(0xc000a53500, {0x4d4fe80, 0xc000498c60}, 0x8ed)
/usr/local/go/src/crypto/tls/conn.go:799 +0xe5
crypto/tls.(*Conn).readRecordOrCCS(0xc000a53500, 0x0)
/usr/local/go/src/crypto/tls/conn.go:606 +0x112
crypto/tls.(*Conn).readRecord(...)
/usr/local/go/src/crypto/tls/conn.go:574
crypto/tls.(*Conn).Read(0xc000a53500, {0xc000ab9000, 0x1000, 0x919560})
/usr/local/go/src/crypto/tls/conn.go:1277 +0x16f
bufio.(*Reader).Read(0xc000434060, {0xc0001c22e0, 0x9, 0x934bc2})
/usr/local/go/src/bufio/bufio.go:227 +0x1b4
io.ReadAtLeast({0x4d47860, 0xc000434060}, {0xc0001c22e0, 0x9, 0x9}, 0x9)
/usr/local/go/src/io/io.go:328 +0x9a
io.ReadFull(...)
/usr/local/go/src/io/io.go:347
k8s.io/kubernetes/vendor/golang.org/x/net/http2.readFrameHeader({0xc0001c22e0, 0x9, 0xc001b617a0}, {0x4d47860, 0xc000434060})
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/frame.go:237 +0x6e
k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*Framer).ReadFrame(0xc0001c22a0)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/frame.go:498 +0x95
k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*clientConnReadLoop).run(0xc000427f98)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/transport.go:2101 +0x130
k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*ClientConn).readLoop(0xc000925380)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/transport.go:1997 +0x6f
created by k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*Transport).newClientConn
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/transport.go:725 +0xac5
*
* ==> kube-controller-manager [bf1cdbe4a20e] <==
* I0329 19:22:14.933795 1 event.go:294] "Event occurred" object="cert-expiration-20220329190729-1328" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node cert-expiration-20220329190729-1328 event: Registered Node cert-expiration-20220329190729-1328 in Controller"
I0329 19:22:14.934545 1 shared_informer.go:247] Caches are synced for PV protection
I0329 19:22:14.934852 1 shared_informer.go:247] Caches are synced for expand
I0329 19:22:14.934875 1 shared_informer.go:247] Caches are synced for attach detach
I0329 19:22:14.940012 1 shared_informer.go:247] Caches are synced for disruption
I0329 19:22:14.940212 1 disruption.go:371] Sending events to api server.
I0329 19:22:14.942226 1 shared_informer.go:247] Caches are synced for stateful set
I0329 19:22:14.942455 1 shared_informer.go:247] Caches are synced for endpoint_slice
I0329 19:22:14.946582 1 shared_informer.go:247] Caches are synced for ReplicaSet
I0329 19:22:14.951299 1 shared_informer.go:247] Caches are synced for deployment
I0329 19:22:15.036182 1 shared_informer.go:247] Caches are synced for crt configmap
I0329 19:22:15.038842 1 shared_informer.go:247] Caches are synced for bootstrap_signer
I0329 19:22:15.047026 1 shared_informer.go:247] Caches are synced for job
I0329 19:22:15.050912 1 shared_informer.go:240] Waiting for caches to sync for garbage collector
I0329 19:22:15.052986 1 shared_informer.go:247] Caches are synced for resource quota
I0329 19:22:15.127094 1 shared_informer.go:247] Caches are synced for resource quota
I0329 19:22:15.133204 1 shared_informer.go:247] Caches are synced for TTL after finished
I0329 19:22:15.135835 1 shared_informer.go:247] Caches are synced for persistent volume
I0329 19:22:15.136594 1 shared_informer.go:247] Caches are synced for cronjob
I0329 19:22:15.349941 1 event.go:294] "Event occurred" object="kube-system/kube-proxy" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-sn7nc"
I0329 19:22:15.531743 1 shared_informer.go:247] Caches are synced for garbage collector
I0329 19:22:15.532675 1 event.go:294] "Event occurred" object="kube-system/coredns" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-64897985d to 1"
I0329 19:22:15.627210 1 shared_informer.go:247] Caches are synced for garbage collector
I0329 19:22:15.627250 1 garbagecollector.go:155] Garbage collector: all resource monitors have synced. Proceeding to collect garbage
I0329 19:22:15.791641 1 event.go:294] "Event occurred" object="kube-system/coredns-64897985d" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-64897985d-q4js5"
*
* ==> kube-scheduler [5e2dba3aacfe] <==
* W0329 19:21:52.503795 1 reflector.go:324] k8s.io/apiserver/pkg/server/dynamiccertificates/configmap_cafile_content.go:205: failed to list *v1.ConfigMap: configmaps "extension-apiserver-authentication" is forbidden: User "system:kube-scheduler" cannot list resource "configmaps" in API group "" in the namespace "kube-system"
E0329 19:21:52.503920 1 reflector.go:138] k8s.io/apiserver/pkg/server/dynamiccertificates/configmap_cafile_content.go:205: Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "extension-apiserver-authentication" is forbidden: User "system:kube-scheduler" cannot list resource "configmaps" in API group "" in the namespace "kube-system"
W0329 19:21:52.696767 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User "system:kube-scheduler" cannot list resource "replicasets" in API group "apps" at the cluster scope
E0329 19:21:52.696888 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.ReplicaSet: failed to list *v1.ReplicaSet: replicasets.apps is forbidden: User "system:kube-scheduler" cannot list resource "replicasets" in API group "apps" at the cluster scope
W0329 19:21:52.947219 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
E0329 19:21:52.947337 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: failed to list *v1.Node: nodes is forbidden: User "system:kube-scheduler" cannot list resource "nodes" in API group "" at the cluster scope
W0329 19:21:53.314822 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.CSINode: csinodes.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csinodes" in API group "storage.k8s.io" at the cluster scope
E0329 19:21:53.314957 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.CSINode: failed to list *v1.CSINode: csinodes.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csinodes" in API group "storage.k8s.io" at the cluster scope
W0329 19:21:53.393897 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
E0329 19:21:53.394078 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Namespace: failed to list *v1.Namespace: namespaces is forbidden: User "system:kube-scheduler" cannot list resource "namespaces" in API group "" at the cluster scope
W0329 19:21:53.477448 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
E0329 19:21:53.477568 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:kube-scheduler" cannot list resource "services" in API group "" at the cluster scope
W0329 19:21:54.130945 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
E0329 19:21:54.131035 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
W0329 19:21:54.237041 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.ReplicationController: replicationcontrollers is forbidden: User "system:kube-scheduler" cannot list resource "replicationcontrollers" in API group "" at the cluster scope
E0329 19:21:54.237206 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.ReplicationController: failed to list *v1.ReplicationController: replicationcontrollers is forbidden: User "system:kube-scheduler" cannot list resource "replicationcontrollers" in API group "" at the cluster scope
W0329 19:21:54.851096 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.Pod: pods is forbidden: User "system:kube-scheduler" cannot list resource "pods" in API group "" at the cluster scope
E0329 19:21:54.851170 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: failed to list *v1.Pod: pods is forbidden: User "system:kube-scheduler" cannot list resource "pods" in API group "" at the cluster scope
W0329 19:21:54.962952 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
E0329 19:21:54.963101 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolume: failed to list *v1.PersistentVolume: persistentvolumes is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumes" in API group "" at the cluster scope
W0329 19:21:55.056831 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1beta1.CSIStorageCapacity: csistoragecapacities.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csistoragecapacities" in API group "storage.k8s.io" at the cluster scope
E0329 19:21:55.056994 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1beta1.CSIStorageCapacity: failed to list *v1beta1.CSIStorageCapacity: csistoragecapacities.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "csistoragecapacities" in API group "storage.k8s.io" at the cluster scope
W0329 19:21:55.287659 1 reflector.go:324] k8s.io/client-go/informers/factory.go:134: failed to list *v1.StorageClass: storageclasses.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "storageclasses" in API group "storage.k8s.io" at the cluster scope
E0329 19:21:55.288267 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.StorageClass: failed to list *v1.StorageClass: storageclasses.storage.k8s.io is forbidden: User "system:kube-scheduler" cannot list resource "storageclasses" in API group "storage.k8s.io" at the cluster scope
I0329 19:22:04.951062 1 shared_informer.go:247] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
*
* ==> kubelet <==
* -- Logs begin at Tue 2022-03-29 19:20:53 UTC, end at Tue 2022-03-29 19:22:22 UTC. --
Mar 29 19:22:04 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:04.443103 2194 reconciler.go:157] "Reconciler: start to sync state"
Mar 29 19:22:04 cert-expiration-20220329190729-1328 kubelet[2194]: E0329 19:22:04.484040 2194 kubelet.go:1711] "Failed creating a mirror pod for" err="pods \"kube-apiserver-cert-expiration-20220329190729-1328\" already exists" pod="kube-system/kube-apiserver-cert-expiration-20220329190729-1328"
Mar 29 19:22:04 cert-expiration-20220329190729-1328 kubelet[2194]: E0329 19:22:04.687187 2194 kubelet.go:1711] "Failed creating a mirror pod for" err="pods \"etcd-cert-expiration-20220329190729-1328\" already exists" pod="kube-system/etcd-cert-expiration-20220329190729-1328"
Mar 29 19:22:04 cert-expiration-20220329190729-1328 kubelet[2194]: E0329 19:22:04.687277 2194 kubelet.go:1711] "Failed creating a mirror pod for" err="pods \"kube-controller-manager-cert-expiration-20220329190729-1328\" already exists" pod="kube-system/kube-controller-manager-cert-expiration-20220329190729-1328"
Mar 29 19:22:14 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:14.933923 2194 kuberuntime_manager.go:1105] "Updating runtime config through cri with podcidr" CIDR="10.244.0.0/24"
Mar 29 19:22:14 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:14.936995 2194 docker_service.go:364] "Docker cri received runtime config" runtimeConfig="&RuntimeConfig{NetworkConfig:&NetworkConfig{PodCidr:10.244.0.0/24,},}"
Mar 29 19:22:14 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:14.937774 2194 kubelet_network.go:76] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="10.244.0.0/24"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.270577 2194 topology_manager.go:200] "Topology Admit Handler"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.458771 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9j2n2\" (UniqueName: \"kubernetes.io/projected/48a80e82-c61c-47ce-9b5d-111896a071c9-kube-api-access-9j2n2\") pod \"storage-provisioner\" (UID: \"48a80e82-c61c-47ce-9b5d-111896a071c9\") " pod="kube-system/storage-provisioner"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.459214 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"tmp\" (UniqueName: \"kubernetes.io/host-path/48a80e82-c61c-47ce-9b5d-111896a071c9-tmp\") pod \"storage-provisioner\" (UID: \"48a80e82-c61c-47ce-9b5d-111896a071c9\") " pod="kube-system/storage-provisioner"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.530952 2194 topology_manager.go:200] "Topology Admit Handler"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.727786 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/d0cb3839-3217-42d5-9323-8d98fdef8fe7-kube-proxy\") pod \"kube-proxy-sn7nc\" (UID: \"d0cb3839-3217-42d5-9323-8d98fdef8fe7\") " pod="kube-system/kube-proxy-sn7nc"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.728425 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vzkx4\" (UniqueName: \"kubernetes.io/projected/d0cb3839-3217-42d5-9323-8d98fdef8fe7-kube-api-access-vzkx4\") pod \"kube-proxy-sn7nc\" (UID: \"d0cb3839-3217-42d5-9323-8d98fdef8fe7\") " pod="kube-system/kube-proxy-sn7nc"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.728541 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/d0cb3839-3217-42d5-9323-8d98fdef8fe7-lib-modules\") pod \"kube-proxy-sn7nc\" (UID: \"d0cb3839-3217-42d5-9323-8d98fdef8fe7\") " pod="kube-system/kube-proxy-sn7nc"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.728634 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/d0cb3839-3217-42d5-9323-8d98fdef8fe7-xtables-lock\") pod \"kube-proxy-sn7nc\" (UID: \"d0cb3839-3217-42d5-9323-8d98fdef8fe7\") " pod="kube-system/kube-proxy-sn7nc"
Mar 29 19:22:15 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:15.836205 2194 topology_manager.go:200] "Topology Admit Handler"
Mar 29 19:22:16 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:16.034387 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-k96lx\" (UniqueName: \"kubernetes.io/projected/fd59e68f-9d0b-4b9e-a82d-b7c8695b08c2-kube-api-access-k96lx\") pod \"coredns-64897985d-q4js5\" (UID: \"fd59e68f-9d0b-4b9e-a82d-b7c8695b08c2\") " pod="kube-system/coredns-64897985d-q4js5"
Mar 29 19:22:16 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:16.034596 2194 reconciler.go:221] "operationExecutor.VerifyControllerAttachedVolume started for volume \"config-volume\" (UniqueName: \"kubernetes.io/configmap/fd59e68f-9d0b-4b9e-a82d-b7c8695b08c2-config-volume\") pod \"coredns-64897985d-q4js5\" (UID: \"fd59e68f-9d0b-4b9e-a82d-b7c8695b08c2\") " pod="kube-system/coredns-64897985d-q4js5"
Mar 29 19:22:18 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:18.519836 2194 pod_container_deletor.go:79] "Container not found in pod's containers" containerID="26538294ee5c7e5c6a3b7bf052dfffae60d9de0fa811a9a2d0f0c9fc5631e691"
Mar 29 19:22:18 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:18.528045 2194 pod_container_deletor.go:79] "Container not found in pod's containers" containerID="e2ffc51fbb6b9aaa52bab1575a40ee6342b70f58a3d1f0a97db5e1afa11806f3"
Mar 29 19:22:18 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:18.534920 2194 pod_container_deletor.go:79] "Container not found in pod's containers" containerID="168d1d0c841abc6f4ed810da729e2be8bc71d5cb97b636149ecd75cbcc7abfcc"
Mar 29 19:22:21 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:21.720194 2194 docker_sandbox.go:402] "Failed to read pod IP from plugin/docker" err="Couldn't find network status for kube-system/coredns-64897985d-q4js5 through plugin: invalid network status for"
Mar 29 19:22:22 cert-expiration-20220329190729-1328 kubelet[2194]: I0329 19:22:22.598496 2194 docker_sandbox.go:402] "Failed to read pod IP from plugin/docker" err="Couldn't find network status for kube-system/coredns-64897985d-q4js5 through plugin: invalid network status for"
Mar 29 19:22:22 cert-expiration-20220329190729-1328 kubelet[2194]: E0329 19:22:22.603190 2194 remote_runtime.go:572] "ContainerStatus from runtime service failed" err="rpc error: code = Unknown desc = Error: No such container: 0c9625690410f01f058eb60d8ad4b90e85e76087187af6657b7fd0a46ee98ead" containerID="0c9625690410f01f058eb60d8ad4b90e85e76087187af6657b7fd0a46ee98ead"
Mar 29 19:22:22 cert-expiration-20220329190729-1328 kubelet[2194]: E0329 19:22:22.603333 2194 kuberuntime_manager.go:1079] "getPodContainerStatuses for pod failed" err="rpc error: code = Unknown desc = Error: No such container: 0c9625690410f01f058eb60d8ad4b90e85e76087187af6657b7fd0a46ee98ead" pod="kube-system/coredns-64897985d-q4js5"
-- /stdout --
helpers_test.go:255: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.APIServer}} -p cert-expiration-20220329190729-1328 -n cert-expiration-20220329190729-1328
helpers_test.go:255: (dbg) Done: out/minikube-windows-amd64.exe status --format={{.APIServer}} -p cert-expiration-20220329190729-1328 -n cert-expiration-20220329190729-1328: (4.5763732s)
helpers_test.go:262: (dbg) Run: kubectl --context cert-expiration-20220329190729-1328 get po -o=jsonpath={.items[*].metadata.name} -A --field-selector=status.phase!=Running
helpers_test.go:271: non-running pods:
helpers_test.go:273: ======> post-mortem[TestCertExpiration]: describe non-running pods <======
helpers_test.go:276: (dbg) Run: kubectl --context cert-expiration-20220329190729-1328 describe pod
helpers_test.go:276: (dbg) Non-zero exit: kubectl --context cert-expiration-20220329190729-1328 describe pod : exit status 1 (276.6643ms)
** stderr **
error: resource name may not be empty
** /stderr **
helpers_test.go:278: kubectl --context cert-expiration-20220329190729-1328 describe pod : exit status 1
helpers_test.go:176: Cleaning up "cert-expiration-20220329190729-1328" profile ...
helpers_test.go:179: (dbg) Run: out/minikube-windows-amd64.exe delete -p cert-expiration-20220329190729-1328
E0329 19:22:30.875671 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:30.891659 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:30.907672 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:30.938666 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:30.986041 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:31.079445 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:31.252629 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:31.588251 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:32.234285 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:33.528880 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:36.103510 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
E0329 19:22:41.228646 1328 cert_rotation.go:168] key failed with : open C:\Users\jenkins.minikube8\minikube-integration\.minikube\profiles\custom-weave-20220329190230-1328\client.crt: The system cannot find the path specified.
helpers_test.go:179: (dbg) Done: out/minikube-windows-amd64.exe delete -p cert-expiration-20220329190729-1328: (21.7883415s)
--- FAIL: TestCertExpiration (920.56s)