=== RUN TestCertOptions
=== PAUSE TestCertOptions
=== CONT TestCertOptions
cert_options_test.go:48: (dbg) Run: out/minikube-windows-amd64.exe start -p cert-options-20210915202501-22848 --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
E0915 20:25:46.756068 22848 cert_rotation.go:168] key failed with : open C:\Users\jenkins\minikube-integration\.minikube\profiles\skaffold-20210915200110-22848\client.crt: The system cannot find the path specified.
E0915 20:27:09.831013 22848 cert_rotation.go:168] key failed with : open C:\Users\jenkins\minikube-integration\.minikube\profiles\skaffold-20210915200110-22848\client.crt: The system cannot find the path specified.
E0915 20:27:35.665923 22848 cert_rotation.go:168] key failed with : open C:\Users\jenkins\minikube-integration\.minikube\profiles\functional-20210915185528-22848\client.crt: The system cannot find the path specified.
E0915 20:28:32.253220 22848 cert_rotation.go:168] key failed with : open C:\Users\jenkins\minikube-integration\.minikube\profiles\addons-20210915183056-22848\client.crt: The system cannot find the path specified.
E0915 20:30:46.754620 22848 cert_rotation.go:168] key failed with : open C:\Users\jenkins\minikube-integration\.minikube\profiles\skaffold-20210915200110-22848\client.crt: The system cannot find the path specified.
=== CONT TestCertOptions
cert_options_test.go:48: (dbg) Done: out/minikube-windows-amd64.exe start -p cert-options-20210915202501-22848 --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: (8m11.6994885s)
cert_options_test.go:59: (dbg) Run: out/minikube-windows-amd64.exe -p cert-options-20210915202501-22848 ssh "openssl x509 -text -noout -in /var/lib/minikube/certs/apiserver.crt"
=== CONT TestCertOptions
cert_options_test.go:59: (dbg) Done: out/minikube-windows-amd64.exe -p cert-options-20210915202501-22848 ssh "openssl x509 -text -noout -in /var/lib/minikube/certs/apiserver.crt": (6.3612805s)
cert_options_test.go:74: (dbg) Run: kubectl --context cert-options-20210915202501-22848 config view
cert_options_test.go:79: apiserver server port incorrect. Output of 'kubectl config view' = "\n-- stdout --\n\tapiVersion: v1\n\tclusters:\n\t- cluster:\n\t certificate-authority: C:\\Users\\jenkins\\minikube-integration\\.minikube\\ca.crt\n\t extensions:\n\t - extension:\n\t last-update: Wed, 15 Sep 2021 20:32:51 GMT\n\t provider: minikube.sigs.k8s.io\n\t version: v1.23.0\n\t name: cluster_info\n\t server: https://localhost:57343\n\t name: cert-options-20210915202501-22848\n\t- cluster:\n\t certificate-authority: C:\\Users\\jenkins\\minikube-integration\\.minikube\\ca.crt\n\t extensions:\n\t - extension:\n\t last-update: Wed, 15 Sep 2021 20:32:56 GMT\n\t provider: minikube.sigs.k8s.io\n\t version: v1.23.0\n\t name: cluster_info\n\t server: https://127.0.0.1:57304\n\t name: docker-flags-20210915202413-22848\n\t- cluster:\n\t certificate-authority: C:\\Users\\jenkins\\minikube-integration\\.minikube\\ca.crt\n\t server: http
s://127.0.0.1:57338\n\t name: missing-upgrade-20210915202421-22848\n\t- cluster:\n\t certificate-authority: C:\\Users\\jenkins\\minikube-integration\\.minikube\\ca.crt\n\t extensions:\n\t - extension:\n\t last-update: Wed, 15 Sep 2021 20:17:15 GMT\n\t provider: minikube.sigs.k8s.io\n\t version: v1.23.0\n\t name: cluster_info\n\t server: https://127.0.0.1:57020\n\t name: pause-20210915200708-22848\n\tcontexts:\n\t- context:\n\t cluster: cert-options-20210915202501-22848\n\t extensions:\n\t - extension:\n\t last-update: Wed, 15 Sep 2021 20:32:51 GMT\n\t provider: minikube.sigs.k8s.io\n\t version: v1.23.0\n\t name: context_info\n\t namespace: default\n\t user: cert-options-20210915202501-22848\n\t name: cert-options-20210915202501-22848\n\t- context:\n\t cluster: docker-flags-20210915202413-22848\n\t extensions:\n\t - extension:\n\t last-update: Wed, 15 Sep 2021 20:32:56 GMT\n\t provider: minikube.sigs.k8s.io
\n\t version: v1.23.0\n\t name: context_info\n\t namespace: default\n\t user: docker-flags-20210915202413-22848\n\t name: docker-flags-20210915202413-22848\n\t- context:\n\t cluster: missing-upgrade-20210915202421-22848\n\t user: missing-upgrade-20210915202421-22848\n\t name: missing-upgrade-20210915202421-22848\n\t- context:\n\t cluster: pause-20210915200708-22848\n\t extensions:\n\t - extension:\n\t last-update: Wed, 15 Sep 2021 20:17:15 GMT\n\t provider: minikube.sigs.k8s.io\n\t version: v1.23.0\n\t name: context_info\n\t namespace: default\n\t user: pause-20210915200708-22848\n\t name: pause-20210915200708-22848\n\tcurrent-context: docker-flags-20210915202413-22848\n\tkind: Config\n\tpreferences: {}\n\tusers:\n\t- name: cert-options-20210915202501-22848\n\t user:\n\t client-certificate: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\cert-options-20210915202501-22848\\client.crt\n\t client-key: C:\\Users\\jenkins\\mi
nikube-integration\\.minikube\\profiles\\cert-options-20210915202501-22848\\client.key\n\t- name: docker-flags-20210915202413-22848\n\t user:\n\t client-certificate: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\docker-flags-20210915202413-22848\\client.crt\n\t client-key: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\docker-flags-20210915202413-22848\\client.key\n\t- name: missing-upgrade-20210915202421-22848\n\t user:\n\t client-certificate: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\missing-upgrade-20210915202421-22848\\client.crt\n\t client-key: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\missing-upgrade-20210915202421-22848\\client.key\n\t- name: pause-20210915200708-22848\n\t user:\n\t client-certificate: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\pause-20210915200708-22848\\client.crt\n\t client-key: C:\\Users\\jenkins\\minikube-integration\\.minikube\\profiles\\pause-20210915200708-22848\\
client.key\n\n-- /stdout --"
cert_options_test.go:82: *** TestCertOptions FAILED at 2021-09-15 20:33:20.3074433 +0000 GMT m=+7448.685775801
helpers_test.go:223: -----------------------post-mortem--------------------------------
helpers_test.go:231: ======> post-mortem[TestCertOptions]: docker inspect <======
helpers_test.go:232: (dbg) Run: docker inspect cert-options-20210915202501-22848
helpers_test.go:232: (dbg) Done: docker inspect cert-options-20210915202501-22848: (1.0327979s)
helpers_test.go:236: (dbg) docker inspect cert-options-20210915202501-22848:
-- stdout --
[
{
"Id": "18fc1254e4db0300c1a06b56b45fd17d583a0315bd8f358589c58d3fae871ce4",
"Created": "2021-09-15T20:25:26.901697Z",
"Path": "/usr/local/bin/entrypoint",
"Args": [
"/sbin/init"
],
"State": {
"Status": "running",
"Running": true,
"Paused": false,
"Restarting": false,
"OOMKilled": false,
"Dead": false,
"Pid": 160797,
"ExitCode": 0,
"Error": "",
"StartedAt": "2021-09-15T20:25:32.4381771Z",
"FinishedAt": "0001-01-01T00:00:00Z"
},
"Image": "sha256:83b5a81388468b1ffcd3874b4f24c1406c63c33ac07797cc8bed6ad0207d36a8",
"ResolvConfPath": "/var/lib/docker/containers/18fc1254e4db0300c1a06b56b45fd17d583a0315bd8f358589c58d3fae871ce4/resolv.conf",
"HostnamePath": "/var/lib/docker/containers/18fc1254e4db0300c1a06b56b45fd17d583a0315bd8f358589c58d3fae871ce4/hostname",
"HostsPath": "/var/lib/docker/containers/18fc1254e4db0300c1a06b56b45fd17d583a0315bd8f358589c58d3fae871ce4/hosts",
"LogPath": "/var/lib/docker/containers/18fc1254e4db0300c1a06b56b45fd17d583a0315bd8f358589c58d3fae871ce4/18fc1254e4db0300c1a06b56b45fd17d583a0315bd8f358589c58d3fae871ce4-json.log",
"Name": "/cert-options-20210915202501-22848",
"RestartCount": 0,
"Driver": "overlay2",
"Platform": "linux",
"MountLabel": "",
"ProcessLabel": "",
"AppArmorProfile": "",
"ExecIDs": null,
"HostConfig": {
"Binds": [
"/lib/modules:/lib/modules:ro",
"cert-options-20210915202501-22848:/var"
],
"ContainerIDFile": "",
"LogConfig": {
"Type": "json-file",
"Config": {}
},
"NetworkMode": "cert-options-20210915202501-22848",
"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"
}
],
"8555/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": [
{
"PathOnHost": "/dev/fuse",
"PathInContainer": "/dev/fuse",
"CgroupPermissions": "rwm"
}
],
"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/eef8a6f7ccb5c8643704566458fded2b99fd2571c4a987baf463133666436a74-init/diff:/var/lib/docker/overlay2/a259804ff45c264548e9459111f8eb7e789339b3253b50b62afde896e9e19e34/diff:/var/lib/docker/overlay2/61882a81480713e64bf02bef67583a0609b2be0589d08187547a88789584af86/diff:/var/lib/docker/overlay2/a41d1f5e24156c1d438fe25c567f3c3492d15cb77b1bf5545be9086be845138a/diff:/var/lib/docker/overlay2/86e30e10438032d0a02b54850ad0316347488f3d5b831234af1e91f943269850/diff:/var/lib/docker/overlay2/f6962936c0c1b0636454847e8e963a472786602e15a00d5e020827c2372acfce/diff:/var/lib/docker/overlay2/5eee83c6029359aefecbba85cc6d456e3a5a97c3ef6e9f4850e8a53c62b30ef5/diff:/var/lib/docker/overlay2/fdaa4e134ab960962e0a388adaa3a6aa59dd139cc016dfd4cdf4565bc80e8469/diff:/var/lib/docker/overlay2/9e1b9be7e17136fa81b0a224e2fab9704d3234ca119d87c14f9a676bbdb023f5/diff:/var/lib/docker/overlay2/ffe06185e93cb7ae8d48d84ea9be8817f2ae3d2aae85114ce41477579e23debd/diff:/var/lib/docker/overlay2/221713
20a621ffe79c2acb0c13308b1b0cd3bc94a4083992e7b8589b820c625c/diff:/var/lib/docker/overlay2/eb2fb3ccafd6cb1c26a9642601357b3e0563e9e9361a5ab359bf1af592a0d709/diff:/var/lib/docker/overlay2/6081368e802a14f6f6a7424eb7af3f5f29f85bf59ed0a0709ce25b53738095cb/diff:/var/lib/docker/overlay2/fd7176e5912a824a0543fa3ab5170921538a287401ff8a451c90e1ef0fd8adea/diff:/var/lib/docker/overlay2/eec5078968f5e7332ff82191a780be0efef38aef75ea7cd67723ab3d2760c281/diff:/var/lib/docker/overlay2/d18d41a44c04cb695c4b69ac0db0d5807cee4ca8a5a695629f97e2d8d9cf9461/diff:/var/lib/docker/overlay2/b125406c01cea6a83fa5515a19bb6822d1194fcd47eeb1ed541b9304804a54be/diff:/var/lib/docker/overlay2/b49ae7a2c3101c5b094f611e08fb7b68d8688cb3c333066f697aafc1dc7c2c7e/diff:/var/lib/docker/overlay2/ce599106d279966257baab0cc43ed0366d690702b449073e812a47ae6698dedf/diff:/var/lib/docker/overlay2/5f005c2e8ab4cd52b59f5118e6f5e352dd834afde547ba1ee7b71141319e3547/diff:/var/lib/docker/overlay2/2b1f9abca5d32e21fe1da66b2604d858599b74fc9359bd55e050cebccaba5c7d/diff:/var/lib/d
ocker/overlay2/a5f956d0de2a0313dfbaefb921518d8a75267b71a9e7c68207a81682db5394b5/diff:/var/lib/docker/overlay2/e0050af32b9eb0f12404cf384139cd48050d4a969d090faaa07b9f42fe954627/diff:/var/lib/docker/overlay2/f18c15fd90b361f7a13265b5426d985a47e261abde790665028916551b5218f3/diff:/var/lib/docker/overlay2/0f266ad6b65c857206fd10e121b74564370ca213f5706493619b6a590c496660/diff:/var/lib/docker/overlay2/fc044060d3681022984120753b0c02afc05afbb256dbdfc9f7f5e966e1d98820/diff:/var/lib/docker/overlay2/91df5011d1388013be2af7bb3097195366fd38d1f46d472e630aab583779f7c0/diff:/var/lib/docker/overlay2/f810a7fbc880b9ff7c367b14e34088e851fa045d860ce4bf4c49999fcf814a6e/diff:/var/lib/docker/overlay2/318584cae4acc059b81627e00ae703167673c73d234d6e64e894fc3500750f90/diff:/var/lib/docker/overlay2/a2e1d86ffb5aec517fe891619294d506621a002f4c53e8d3103d5d4ce777ebaf/diff:/var/lib/docker/overlay2/12fd1d215a6881aa03a06f2b8a5415b483530db121b120b66940e1e5cd2e1b96/diff:/var/lib/docker/overlay2/28bbbfc0404aecb7d7d79b4c2bfec07cd44260c922a982af523bda70bbd
7be20/diff:/var/lib/docker/overlay2/4dc0077174d58a8904abddfc67a48e6dd082a1eebc72518af19da37b4eff7b2c/diff:/var/lib/docker/overlay2/4d39db844b44258dbb67b16662175b453df7bfd43274abbf1968486539955750/diff:/var/lib/docker/overlay2/ca34d73c6c31358a3eb714a014a5961863e05dee505a1cfca2c8829380ce362b/diff:/var/lib/docker/overlay2/0c0595112799a0b3604c58158946fb3d0657c4198a6a72e12fbe29a74174d3ea/diff:/var/lib/docker/overlay2/5fc43276da56e90293816918613014e7cec7bedc292a062d39d034c95d56351d/diff:/var/lib/docker/overlay2/71a282cb60752128ee370ced1695c67c421341d364956818e5852fd6714a0e64/diff:/var/lib/docker/overlay2/07723c7054e35caae4987fa66d3d1fd44de0d2875612274dde2bf04e8349b0a0/diff:/var/lib/docker/overlay2/0433db88749fb49b0f02cc65b7113c97134270991a8a82bbe7ff4432aae7e502/diff",
"MergedDir": "/var/lib/docker/overlay2/eef8a6f7ccb5c8643704566458fded2b99fd2571c4a987baf463133666436a74/merged",
"UpperDir": "/var/lib/docker/overlay2/eef8a6f7ccb5c8643704566458fded2b99fd2571c4a987baf463133666436a74/diff",
"WorkDir": "/var/lib/docker/overlay2/eef8a6f7ccb5c8643704566458fded2b99fd2571c4a987baf463133666436a74/work"
},
"Name": "overlay2"
},
"Mounts": [
{
"Type": "bind",
"Source": "/lib/modules",
"Destination": "/lib/modules",
"Mode": "ro",
"RW": false,
"Propagation": "rprivate"
},
{
"Type": "volume",
"Name": "cert-options-20210915202501-22848",
"Source": "/var/lib/docker/volumes/cert-options-20210915202501-22848/_data",
"Destination": "/var",
"Driver": "local",
"Mode": "z",
"RW": true,
"Propagation": ""
}
],
"Config": {
"Hostname": "cert-options-20210915202501-22848",
"Domainname": "",
"User": "root",
"AttachStdin": false,
"AttachStdout": false,
"AttachStderr": false,
"ExposedPorts": {
"22/tcp": {},
"2376/tcp": {},
"32443/tcp": {},
"5000/tcp": {},
"8555/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.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56",
"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-options-20210915202501-22848",
"name.minikube.sigs.k8s.io": "cert-options-20210915202501-22848",
"role.minikube.sigs.k8s.io": ""
},
"StopSignal": "SIGRTMIN+3"
},
"NetworkSettings": {
"Bridge": "",
"SandboxID": "9af57c4fe7c8b894b753781159e9494bc9a2a6e28601a33b4b47764188634b54",
"HairpinMode": false,
"LinkLocalIPv6Address": "",
"LinkLocalIPv6PrefixLen": 0,
"Ports": {
"22/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57339"
}
],
"2376/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57340"
}
],
"32443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57341"
}
],
"5000/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57342"
}
],
"8555/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "57343"
}
]
},
"SandboxKey": "/var/run/docker/netns/9af57c4fe7c8",
"SecondaryIPAddresses": null,
"SecondaryIPv6Addresses": null,
"EndpointID": "",
"Gateway": "",
"GlobalIPv6Address": "",
"GlobalIPv6PrefixLen": 0,
"IPAddress": "",
"IPPrefixLen": 0,
"IPv6Gateway": "",
"MacAddress": "",
"Networks": {
"cert-options-20210915202501-22848": {
"IPAMConfig": {
"IPv4Address": "192.168.58.2"
},
"Links": null,
"Aliases": [
"18fc1254e4db",
"cert-options-20210915202501-22848"
],
"NetworkID": "68d2a14e215a557d84dc4fe81f7d14978d10b954a25cf03cd9d584ff332d7512",
"EndpointID": "a17d9fce11b967dceca787eb1b7bd8f2a738b418dd5ccfebc69bcf298d66745d",
"Gateway": "192.168.58.1",
"IPAddress": "192.168.58.2",
"IPPrefixLen": 24,
"IPv6Gateway": "",
"GlobalIPv6Address": "",
"GlobalIPv6PrefixLen": 0,
"MacAddress": "02:42:c0:a8:3a:02",
"DriverOpts": null
}
}
}
}
]
-- /stdout --
helpers_test.go:240: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.Host}} -p cert-options-20210915202501-22848 -n cert-options-20210915202501-22848
helpers_test.go:240: (dbg) Done: out/minikube-windows-amd64.exe status --format={{.Host}} -p cert-options-20210915202501-22848 -n cert-options-20210915202501-22848: (8.4443282s)
helpers_test.go:245: <<< TestCertOptions FAILED: start of post-mortem logs <<<
helpers_test.go:246: ======> post-mortem[TestCertOptions]: minikube logs <======
helpers_test.go:248: (dbg) Run: out/minikube-windows-amd64.exe -p cert-options-20210915202501-22848 logs -n 25
E0915 20:33:32.251070 22848 cert_rotation.go:168] key failed with : open C:\Users\jenkins\minikube-integration\.minikube\profiles\addons-20210915183056-22848\client.crt: The system cannot find the path specified.
helpers_test.go:248: (dbg) Done: out/minikube-windows-amd64.exe -p cert-options-20210915202501-22848 logs -n 25: (12.6908007s)
helpers_test.go:253: TestCertOptions logs:
-- stdout --
*
* ==> Audit <==
* |---------|-------------------------------------------|-------------------------------------------|-------------------------|---------|-------------------------------|-------------------------------|
| Command | Args | Profile | User | Version | Start Time | End Time |
|---------|-------------------------------------------|-------------------------------------------|-------------------------|---------|-------------------------------|-------------------------------|
| delete | -p | insufficient-storage-20210915200614-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:06:56 GMT | Wed, 15 Sep 2021 20:07:07 GMT |
| | insufficient-storage-20210915200614-22848 | | | | | |
| start | -p pause-20210915200708-22848 | pause-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:07:08 GMT | Wed, 15 Sep 2021 20:15:59 GMT |
| | --memory=2048 | | | | | |
| | --install-addons=false | | | | | |
| | --wait=all --driver=docker | | | | | |
| start | -p pause-20210915200708-22848 | pause-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:16:00 GMT | Wed, 15 Sep 2021 20:17:33 GMT |
| | --alsologtostderr -v=1 | | | | | |
| | --driver=docker | | | | | |
| pause | -p pause-20210915200708-22848 | pause-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:17:33 GMT | Wed, 15 Sep 2021 20:17:43 GMT |
| | --alsologtostderr -v=5 | | | | | |
| start | -p | offline-docker-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:07:08 GMT | Wed, 15 Sep 2021 20:18:07 GMT |
| | offline-docker-20210915200708-22848 | | | | | |
| | --alsologtostderr -v=1 | | | | | |
| | --memory=2048 --wait=true | | | | | |
| | --driver=docker | | | | | |
| unpause | -p pause-20210915200708-22848 | pause-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:18:21 GMT | Wed, 15 Sep 2021 20:18:30 GMT |
| | --alsologtostderr -v=5 | | | | | |
| delete | -p | offline-docker-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:18:08 GMT | Wed, 15 Sep 2021 20:18:33 GMT |
| | offline-docker-20210915200708-22848 | | | | | |
| pause | -p pause-20210915200708-22848 | pause-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:18:30 GMT | Wed, 15 Sep 2021 20:18:43 GMT |
| | --alsologtostderr -v=5 | | | | | |
| start | -p | running-upgrade-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:19:14 GMT | Wed, 15 Sep 2021 20:23:00 GMT |
| | running-upgrade-20210915200708-22848 | | | | | |
| | --memory=2200 --alsologtostderr -v=1 | | | | | |
| | --driver=docker | | | | | |
| delete | -p | running-upgrade-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:23:01 GMT | Wed, 15 Sep 2021 20:23:29 GMT |
| | running-upgrade-20210915200708-22848 | | | | | |
| start | -p | stopped-upgrade-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:20:05 GMT | Wed, 15 Sep 2021 20:23:29 GMT |
| | stopped-upgrade-20210915200708-22848 | | | | | |
| | --memory=2200 --alsologtostderr -v=1 | | | | | |
| | --driver=docker | | | | | |
| delete | -p | flannel-20210915202329-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:23:29 GMT | Wed, 15 Sep 2021 20:23:38 GMT |
| | flannel-20210915202329-22848 | | | | | |
| logs | -p | stopped-upgrade-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:23:30 GMT | Wed, 15 Sep 2021 20:23:46 GMT |
| | stopped-upgrade-20210915200708-22848 | | | | | |
| delete | -p | stopped-upgrade-20210915200708-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:23:46 GMT | Wed, 15 Sep 2021 20:24:13 GMT |
| | stopped-upgrade-20210915200708-22848 | | | | | |
| start | -p | force-systemd-flag-20210915201833-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:18:33 GMT | Wed, 15 Sep 2021 20:24:20 GMT |
| | force-systemd-flag-20210915201833-22848 | | | | | |
| | --memory=2048 --force-systemd | | | | | |
| | --alsologtostderr -v=5 --driver=docker | | | | | |
| -p | force-systemd-flag-20210915201833-22848 | force-systemd-flag-20210915201833-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:24:20 GMT | Wed, 15 Sep 2021 20:24:29 GMT |
| | ssh docker info --format | | | | | |
| | {{.CgroupDriver}} | | | | | |
| delete | -p | force-systemd-flag-20210915201833-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:24:30 GMT | Wed, 15 Sep 2021 20:25:01 GMT |
| | force-systemd-flag-20210915201833-22848 | | | | | |
| start | -p | force-systemd-env-20210915202338-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:23:38 GMT | Wed, 15 Sep 2021 20:32:27 GMT |
| | force-systemd-env-20210915202338-22848 | | | | | |
| | --memory=2048 --alsologtostderr -v=5 | | | | | |
| | --driver=docker | | | | | |
| -p | force-systemd-env-20210915202338-22848 | force-systemd-env-20210915202338-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:32:28 GMT | Wed, 15 Sep 2021 20:32:42 GMT |
| | ssh docker info --format | | | | | |
| | {{.CgroupDriver}} | | | | | |
| start | -p | docker-flags-20210915202413-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:24:14 GMT | Wed, 15 Sep 2021 20:33:02 GMT |
| | docker-flags-20210915202413-22848 | | | | | |
| | --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-20210915202413-22848 | docker-flags-20210915202413-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:33:03 GMT | Wed, 15 Sep 2021 20:33:08 GMT |
| | ssh sudo systemctl show docker | | | | | |
| | --property=Environment --no-pager | | | | | |
| start | -p | cert-options-20210915202501-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:25:02 GMT | Wed, 15 Sep 2021 20:33:13 GMT |
| | cert-options-20210915202501-22848 | | | | | |
| | --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 | docker-flags-20210915202413-22848 | docker-flags-20210915202413-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:33:09 GMT | Wed, 15 Sep 2021 20:33:15 GMT |
| | ssh sudo systemctl show docker | | | | | |
| | --property=ExecStart --no-pager | | | | | |
| delete | -p | force-systemd-env-20210915202338-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:32:42 GMT | Wed, 15 Sep 2021 20:33:15 GMT |
| | force-systemd-env-20210915202338-22848 | | | | | |
| -p | cert-options-20210915202501-22848 | cert-options-20210915202501-22848 | WINDOWS-SERVER-\jenkins | v1.23.0 | Wed, 15 Sep 2021 20:33:14 GMT | Wed, 15 Sep 2021 20:33:19 GMT |
| | ssh openssl x509 -text -noout -in | | | | | |
| | /var/lib/minikube/certs/apiserver.crt | | | | | |
|---------|-------------------------------------------|-------------------------------------------|-------------------------|---------|-------------------------------|-------------------------------|
*
* ==> Last Start <==
* Log file created at: 2021/09/15 20:33:16
Running on machine: windows-server-1
Binary: Built with gc go1.17 for windows/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I0915 20:33:16.184661 84484 out.go:298] Setting OutFile to fd 2512 ...
I0915 20:33:16.185659 84484 out.go:345] TERM=,COLORTERM=, which probably does not support color
I0915 20:33:16.186674 84484 out.go:311] Setting ErrFile to fd 2588...
I0915 20:33:16.186674 84484 out.go:345] TERM=,COLORTERM=, which probably does not support color
I0915 20:33:16.211666 84484 out.go:305] Setting JSON to false
I0915 20:33:16.219688 84484 start.go:111] hostinfo: {"hostname":"windows-server-1","uptime":9157469,"bootTime":1622580527,"procs":164,"os":"windows","platform":"Microsoft Windows Server 2019 Datacenter","platformFamily":"Server","platformVersion":"10.0.17763 Build 17763","kernelVersion":"10.0.17763 Build 17763","kernelArch":"x86_64","virtualizationSystem":"","virtualizationRole":"","hostId":"9879231f-6171-435d-bab4-5b366cc6391b"}
W0915 20:33:16.219688 84484 start.go:119] gopshost.Virtualization returned error: not implemented yet
I0915 20:33:16.220690 84484 out.go:177] * [kubernetes-upgrade-20210915203315-22848] minikube v1.23.0 on Microsoft Windows Server 2019 Datacenter 10.0.17763 Build 17763
I0915 20:33:16.220690 84484 notify.go:169] Checking for updates...
I0915 20:33:16.220690 84484 out.go:177] - KUBECONFIG=C:\Users\jenkins\minikube-integration\kubeconfig
I0915 20:33:16.229869 84484 out.go:177] - MINIKUBE_HOME=C:\Users\jenkins\minikube-integration\.minikube
W0915 20:33:13.507936 25104 cli_runner.go:162] docker network inspect missing-upgrade-20210915202421-22848 returned with exit code 1
I0915 20:33:13.507936 25104 network_create.go:258] error running [docker network inspect missing-upgrade-20210915202421-22848]: docker network inspect missing-upgrade-20210915202421-22848: exit status 1
stdout:
[]
stderr:
Error: No such network: missing-upgrade-20210915202421-22848
I0915 20:33:13.507936 25104 network_create.go:260] output of [docker network inspect missing-upgrade-20210915202421-22848]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: missing-upgrade-20210915202421-22848
** /stderr **
I0915 20:33:13.517951 25104 cli_runner.go:115] 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}}]}"
I0915 20:33:14.412159 25104 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:0xc000006270] misses:0}
I0915 20:33:14.412159 25104 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:}}
I0915 20:33:14.412159 25104 network_create.go:106] attempt to create docker network missing-upgrade-20210915202421-22848 192.168.49.0/24 with gateway 192.168.49.1 and MTU of 1500 ...
I0915 20:33:14.424162 25104 cli_runner.go:115] 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 missing-upgrade-20210915202421-22848
W0915 20:33:15.245647 25104 cli_runner.go:162] 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 missing-upgrade-20210915202421-22848 returned with exit code 1
W0915 20:33:15.245842 25104 network_create.go:98] failed to create docker network missing-upgrade-20210915202421-22848 192.168.49.0/24, will retry: subnet is taken
I0915 20:33:15.272298 25104 network.go:279] skipping subnet 192.168.49.0 that has unexpired reservation: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc000006270] amended:false}} dirty:map[] misses:0}
I0915 20:33:15.272885 25104 network.go:238] skipping subnet 192.168.49.0/24 that is reserved: &{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:}}
I0915 20:33:15.298302 25104 network.go:288] reserving subnet 192.168.58.0 for 1m0s: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc000006270] amended:true}} dirty:map[192.168.49.0:0xc000006270 192.168.58.0:0xc0000063c8] misses:0}
I0915 20:33:15.298302 25104 network.go:235] using free private subnet 192.168.58.0/24: &{IP:192.168.58.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.58.0/24 Gateway:192.168.58.1 ClientMin:192.168.58.2 ClientMax:192.168.58.254 Broadcast:192.168.58.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I0915 20:33:15.298302 25104 network_create.go:106] attempt to create docker network missing-upgrade-20210915202421-22848 192.168.58.0/24 with gateway 192.168.58.1 and MTU of 1500 ...
I0915 20:33:15.316716 25104 cli_runner.go:115] Run: docker network create --driver=bridge --subnet=192.168.58.0/24 --gateway=192.168.58.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true missing-upgrade-20210915202421-22848
W0915 20:33:16.163656 25104 cli_runner.go:162] docker network create --driver=bridge --subnet=192.168.58.0/24 --gateway=192.168.58.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true missing-upgrade-20210915202421-22848 returned with exit code 1
W0915 20:33:16.163656 25104 network_create.go:98] failed to create docker network missing-upgrade-20210915202421-22848 192.168.58.0/24, will retry: subnet is taken
I0915 20:33:16.181662 25104 network.go:279] skipping subnet 192.168.58.0 that has unexpired reservation: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc000006270] amended:true}} dirty:map[192.168.49.0:0xc000006270 192.168.58.0:0xc0000063c8] misses:1}
I0915 20:33:16.181662 25104 network.go:238] skipping subnet 192.168.58.0/24 that is reserved: &{IP:192.168.58.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.58.0/24 Gateway:192.168.58.1 ClientMin:192.168.58.2 ClientMax:192.168.58.254 Broadcast:192.168.58.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I0915 20:33:16.195770 25104 network.go:288] reserving subnet 192.168.67.0 for 1m0s: &{mu:{state:0 sema:0} read:{v:{m:map[192.168.49.0:0xc000006270] amended:true}} dirty:map[192.168.49.0:0xc000006270 192.168.58.0:0xc0000063c8 192.168.67.0:0xc0003827b0] misses:1}
I0915 20:33:16.195770 25104 network.go:235] using free private subnet 192.168.67.0/24: &{IP:192.168.67.0 Netmask:255.255.255.0 Prefix:24 CIDR:192.168.67.0/24 Gateway:192.168.67.1 ClientMin:192.168.67.2 ClientMax:192.168.67.254 Broadcast:192.168.67.255 Interface:{IfaceName: IfaceIPv4: IfaceMTU:0 IfaceMAC:}}
I0915 20:33:16.195770 25104 network_create.go:106] attempt to create docker network missing-upgrade-20210915202421-22848 192.168.67.0/24 with gateway 192.168.67.1 and MTU of 1500 ...
I0915 20:33:16.211666 25104 cli_runner.go:115] Run: docker network create --driver=bridge --subnet=192.168.67.0/24 --gateway=192.168.67.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true missing-upgrade-20210915202421-22848
I0915 20:33:17.550592 25104 cli_runner.go:168] Completed: docker network create --driver=bridge --subnet=192.168.67.0/24 --gateway=192.168.67.1 -o --ip-masq -o --icc -o com.docker.network.driver.mtu=1500 --label=created_by.minikube.sigs.k8s.io=true missing-upgrade-20210915202421-22848: (1.3379531s)
I0915 20:33:17.550592 25104 network_create.go:90] docker network missing-upgrade-20210915202421-22848 192.168.67.0/24 created
I0915 20:33:17.550592 25104 kic.go:106] calculated static IP "192.168.67.2" for the "missing-upgrade-20210915202421-22848" container
I0915 20:33:17.587957 25104 cli_runner.go:115] Run: docker ps -a --format {{.Names}}
I0915 20:33:16.232688 84484 out.go:177] - MINIKUBE_LOCATION=12425
I0915 20:33:16.237659 84484 config.go:177] Loaded profile config "cert-options-20210915202501-22848": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.22.1
I0915 20:33:16.237659 84484 config.go:177] Loaded profile config "docker-flags-20210915202413-22848": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.22.1
I0915 20:33:16.238685 84484 config.go:177] Loaded profile config "missing-upgrade-20210915202421-22848": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.18.0
I0915 20:33:16.239653 84484 config.go:177] Loaded profile config "pause-20210915200708-22848": Driver=docker, ContainerRuntime=docker, KubernetesVersion=v1.22.1
I0915 20:33:16.241658 84484 driver.go:343] Setting default libvirt URI to qemu:///system
I0915 20:33:18.596755 84484 docker.go:132] docker version: linux-20.10.5
I0915 20:33:18.607738 84484 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I0915 20:33:20.180314 84484 cli_runner.go:168] Completed: docker system info --format "{{json .}}": (1.5725865s)
I0915 20:33:20.182311 84484 info.go:263] docker info: {ID:AZM6:4F7P:D7J3:PGKE:EIYN:3OQU:SEA3:BB2T:P6VC:GKKH:UKSA:R2VX Containers:3 ContainersRunning:3 ContainersPaused:0 ContainersStopped:0 Images:7 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true]] 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:false NFd:68 OomKillDisable:true NGoroutines:69 SystemTime:2021-09-15 20:33:19.4442539 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:4 KernelVersion:4.19.121-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServerAddress:https://inde
x.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:4 MemTotal:20973547520 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy: HTTPSProxy: NoProxy: Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.5 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:269548fa27e0089a8b8278fc4fc781d7f65a939b Expected:269548fa27e0089a8b8278fc4fc781d7f65a939b} RuncCommit:{ID:ff819c7e9184c13b7c2607fe6c30ae19403a7aff Expected:ff819c7e9184c13b7c2607fe6c30ae19403a7aff} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,profile=default] ProductLicense: Warnings:<nil> ServerErrors:[]
ClientInfo:{Debug:false Plugins:[map[Experimental:true Name:app Path:C:\ProgramData\Docker\cli-plugins\docker-app.exe SchemaVersion:0.1.0 ShortDescription:Docker App Vendor:Docker Inc. Version:v0.9.1-beta3] map[Name:buildx Path:C:\ProgramData\Docker\cli-plugins\docker-buildx.exe SchemaVersion:0.1.0 ShortDescription:Build with BuildKit Vendor:Docker Inc. Version:v0.5.1-docker] map[Name:scan Path:C:\ProgramData\Docker\cli-plugins\docker-scan.exe SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.5.0]] Warnings:<nil>}}
I0915 20:33:20.186183 84484 out.go:177] * Using the docker driver based on user configuration
I0915 20:33:20.186183 84484 start.go:278] selected driver: docker
I0915 20:33:20.186183 84484 start.go:751] validating driver "docker" against <nil>
I0915 20:33:20.186583 84484 start.go:762] status for docker: {Installed:true Healthy:true Running:false NeedsImprovement:false Error:<nil> Reason: Fix: Doc:}
I0915 20:33:20.335434 84484 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I0915 20:33:22.062775 84484 cli_runner.go:168] Completed: docker system info --format "{{json .}}": (1.7273523s)
I0915 20:33:22.063620 84484 info.go:263] docker info: {ID:AZM6:4F7P:D7J3:PGKE:EIYN:3OQU:SEA3:BB2T:P6VC:GKKH:UKSA:R2VX Containers:4 ContainersRunning:3 ContainersPaused:0 ContainersStopped:1 Images:7 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true]] 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:false NFd:74 OomKillDisable:true NGoroutines:88 SystemTime:2021-09-15 20:33:21.2670988 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:4 KernelVersion:4.19.121-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServerAddress:https://inde
x.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:4 MemTotal:20973547520 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy: HTTPSProxy: NoProxy: Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.5 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:269548fa27e0089a8b8278fc4fc781d7f65a939b Expected:269548fa27e0089a8b8278fc4fc781d7f65a939b} RuncCommit:{ID:ff819c7e9184c13b7c2607fe6c30ae19403a7aff Expected:ff819c7e9184c13b7c2607fe6c30ae19403a7aff} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,profile=default] ProductLicense: Warnings:<nil> ServerErrors:[]
ClientInfo:{Debug:false Plugins:[map[Experimental:true Name:app Path:C:\ProgramData\Docker\cli-plugins\docker-app.exe SchemaVersion:0.1.0 ShortDescription:Docker App Vendor:Docker Inc. Version:v0.9.1-beta3] map[Name:buildx Path:C:\ProgramData\Docker\cli-plugins\docker-buildx.exe SchemaVersion:0.1.0 ShortDescription:Build with BuildKit Vendor:Docker Inc. Version:v0.5.1-docker] map[Name:scan Path:C:\ProgramData\Docker\cli-plugins\docker-scan.exe SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.5.0]] Warnings:<nil>}}
I0915 20:33:22.063935 84484 start_flags.go:264] no existing cluster config was found, will generate one from the flags
I0915 20:33:22.064353 84484 start_flags.go:719] Wait components to verify : map[apiserver:true system_pods:true]
I0915 20:33:22.064353 84484 cni.go:93] Creating CNI manager for ""
I0915 20:33:22.064353 84484 cni.go:167] CNI unnecessary in this configuration, recommending no CNI
I0915 20:33:22.064353 84484 start_flags.go:278] config:
{Name:kubernetes-upgrade-20210915203315-22848 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 Memory:2200 CPUs:2 DiskSize:20000 VMDriver: Driver:docker HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.99.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.14.0 ClusterName:kubernetes-upgrade-20210915203315-22848 Namespace:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:clu
ster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0}
I0915 20:33:22.064353 84484 out.go:177] * Starting control plane node kubernetes-upgrade-20210915203315-22848 in cluster kubernetes-upgrade-20210915203315-22848
I0915 20:33:22.064353 84484 cache.go:118] Beginning downloading kic base image for docker with docker
I0915 20:33:22.064353 84484 out.go:177] * Pulling base image ...
I0915 20:33:22.064353 84484 preload.go:131] Checking if preload exists for k8s version v1.14.0 and runtime docker
I0915 20:33:22.064353 84484 image.go:75] Checking for gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 in local docker daemon
I0915 20:33:22.064353 84484 preload.go:147] Found local preload: C:\Users\jenkins\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v12-v1.14.0-docker-overlay2-amd64.tar.lz4
I0915 20:33:22.064353 84484 cache.go:57] Caching tarball of preloaded images
I0915 20:33:22.064353 84484 preload.go:173] Found C:\Users\jenkins\minikube-integration\.minikube\cache\preloaded-tarball\preloaded-images-k8s-v12-v1.14.0-docker-overlay2-amd64.tar.lz4 in cache, skipping download
I0915 20:33:22.064353 84484 cache.go:60] Finished verifying existence of preloaded tar for v1.14.0 on docker
I0915 20:33:22.071238 84484 profile.go:148] Saving config to C:\Users\jenkins\minikube-integration\.minikube\profiles\kubernetes-upgrade-20210915203315-22848\config.json ...
I0915 20:33:22.071238 84484 lock.go:36] WriteFile acquiring C:\Users\jenkins\minikube-integration\.minikube\profiles\kubernetes-upgrade-20210915203315-22848\config.json: {Name:mke54b38418203b5d6dced4dbad9cdb3b7980054 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0915 20:33:22.933167 84484 image.go:79] Found gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 in local docker daemon, skipping pull
I0915 20:33:22.933167 84484 cache.go:140] gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 exists in daemon, skipping load
I0915 20:33:22.933762 84484 cache.go:206] Successfully downloaded all kic artifacts
I0915 20:33:22.933952 84484 start.go:313] acquiring machines lock for kubernetes-upgrade-20210915203315-22848: {Name:mke5bb5a1d11ad10a34d5d3dd0f81b170f08fb85 Clock:{} Delay:500ms Timeout:10m0s Cancel:<nil>}
I0915 20:33:22.934439 84484 start.go:317] acquired machines lock for "kubernetes-upgrade-20210915203315-22848" in 487.3µs
I0915 20:33:22.934816 84484 start.go:89] Provisioning new machine with config: &{Name:kubernetes-upgrade-20210915203315-22848 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage:gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 Memory:2200 CPUs:2 DiskSize:20000 VMDriver: Driver:docker HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[] ContainerVolumeMounts:[] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR:192.168.99.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.14.0 ClusterName:kubernetes-upgrade-20210915203315-22848 Namespac
e:default APIServerName:minikubeCA APIServerNames:[] APIServerIPs:[] DNSDomain:cluster.local ContainerRuntime:docker CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR:10.96.0.0/12 ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: CustomIngressCert: ExtraOptions:[] ShouldLoadCachedImages:true EnableDefaultCNI:false CNI: NodeIP: NodePort:8443 NodeName:} Nodes:[{Name: IP: Port:8443 KubernetesVersion:v1.14.0 ControlPlane:true Worker:true}] Addons:map[] CustomAddonImages:map[] CustomAddonRegistries:map[] VerifyComponents:map[apiserver:true system_pods:true] StartHostTimeout:6m0s ScheduledStop:<nil> ExposedPorts:[] ListenAddress: Network: MultiNodeRequested:false ExtraDisks:0} &{Name: IP: Port:8443 KubernetesVersion:v1.14.0 ControlPlane:true Worker:true}
I0915 20:33:22.935114 84484 start.go:126] createHost starting for "" (driver="docker")
I0915 20:33:18.438754 25104 cli_runner.go:115] Run: docker volume create missing-upgrade-20210915202421-22848 --label name.minikube.sigs.k8s.io=missing-upgrade-20210915202421-22848 --label created_by.minikube.sigs.k8s.io=true
I0915 20:33:19.215730 25104 oci.go:102] Successfully created a docker volume missing-upgrade-20210915202421-22848
I0915 20:33:19.237507 25104 cli_runner.go:115] Run: docker run --rm --name missing-upgrade-20210915202421-22848-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=missing-upgrade-20210915202421-22848 --entrypoint /usr/bin/test -v missing-upgrade-20210915202421-22848:/var gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 -d /var/lib
I0915 20:33:22.938696 84484 out.go:204] * Creating docker container (CPUs=2, Memory=2200MB) ...
I0915 20:33:22.940068 84484 start.go:160] libmachine.API.Create for "kubernetes-upgrade-20210915203315-22848" (driver="docker")
I0915 20:33:22.940240 84484 client.go:168] LocalClient.Create starting
I0915 20:33:22.941565 84484 main.go:130] libmachine: Reading certificate data from C:\Users\jenkins\minikube-integration\.minikube\certs\ca.pem
I0915 20:33:22.942132 84484 main.go:130] libmachine: Decoding PEM data...
I0915 20:33:22.942321 84484 main.go:130] libmachine: Parsing certificate...
I0915 20:33:22.942940 84484 main.go:130] libmachine: Reading certificate data from C:\Users\jenkins\minikube-integration\.minikube\certs\cert.pem
I0915 20:33:22.943554 84484 main.go:130] libmachine: Decoding PEM data...
I0915 20:33:22.943554 84484 main.go:130] libmachine: Parsing certificate...
I0915 20:33:22.963722 84484 cli_runner.go:115] Run: docker network inspect kubernetes-upgrade-20210915203315-22848 --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}}]}"
W0915 20:33:23.823384 84484 cli_runner.go:162] docker network inspect kubernetes-upgrade-20210915203315-22848 --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
I0915 20:33:23.834763 84484 network_create.go:255] running [docker network inspect kubernetes-upgrade-20210915203315-22848] to gather additional debugging logs...
I0915 20:33:23.834763 84484 cli_runner.go:115] Run: docker network inspect kubernetes-upgrade-20210915203315-22848
W0915 20:33:24.680636 84484 cli_runner.go:162] docker network inspect kubernetes-upgrade-20210915203315-22848 returned with exit code 1
I0915 20:33:24.681024 84484 network_create.go:258] error running [docker network inspect kubernetes-upgrade-20210915203315-22848]: docker network inspect kubernetes-upgrade-20210915203315-22848: exit status 1
stdout:
[]
stderr:
Error: No such network: kubernetes-upgrade-20210915203315-22848
I0915 20:33:24.681214 84484 network_create.go:260] output of [docker network inspect kubernetes-upgrade-20210915203315-22848]: -- stdout --
[]
-- /stdout --
** stderr **
Error: No such network: kubernetes-upgrade-20210915203315-22848
** /stderr **
I0915 20:33:24.691294 84484 cli_runner.go:115] 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}}]}"
I0915 20:33:25.581761 84484 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:0xc00094e260] misses:0}
I0915 20:33:25.582201 84484 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:}}
I0915 20:33:25.582201 84484 network_create.go:106] attempt to create docker network kubernetes-upgrade-20210915203315-22848 192.168.49.0/24 with gateway 192.168.49.1 and MTU of 1500 ...
I0915 20:33:25.597522 84484 cli_runner.go:115] 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 kubernetes-upgrade-20210915203315-22848
I0915 20:33:25.307532 25104 cli_runner.go:168] Completed: docker run --rm --name missing-upgrade-20210915202421-22848-preload-sidecar --label created_by.minikube.sigs.k8s.io=true --label name.minikube.sigs.k8s.io=missing-upgrade-20210915202421-22848 --entrypoint /usr/bin/test -v missing-upgrade-20210915202421-22848:/var gcr.io/k8s-minikube/kicbase-builds:v0.0.26-1631295795-12425@sha256:7d61c0b6cf6832c8015ada78640635c5ab74b72f12f51bcc4c7660b0be01af56 -d /var/lib: (6.0699474s)
I0915 20:33:25.307637 25104 oci.go:106] Successfully prepared a docker volume missing-upgrade-20210915202421-22848
I0915 20:33:25.308017 25104 preload.go:131] Checking if preload exists for k8s version v1.18.0 and runtime docker
I0915 20:33:25.348221 25104 cli_runner.go:115] Run: docker system info --format "{{json .}}"
I0915 20:33:26.996811 25104 cli_runner.go:168] Completed: docker system info --format "{{json .}}": (1.6484699s)
I0915 20:33:26.996811 25104 info.go:263] docker info: {ID:AZM6:4F7P:D7J3:PGKE:EIYN:3OQU:SEA3:BB2T:P6VC:GKKH:UKSA:R2VX Containers:3 ContainersRunning:3 ContainersPaused:0 ContainersStopped:0 Images:7 Driver:overlay2 DriverStatus:[[Backing Filesystem extfs] [Supports d_type true] [Native Overlay Diff true]] 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:false NFd:70 OomKillDisable:true NGoroutines:75 SystemTime:2021-09-15 20:33:26.1785579 +0000 UTC LoggingDriver:json-file CgroupDriver:cgroupfs NEventsListener:4 KernelVersion:4.19.121-linuxkit OperatingSystem:Docker Desktop OSType:linux Architecture:x86_64 IndexServerAddress:https://inde
x.docker.io/v1/ RegistryConfig:{AllowNondistributableArtifactsCIDRs:[] AllowNondistributableArtifactsHostnames:[] InsecureRegistryCIDRs:[127.0.0.0/8] IndexConfigs:{DockerIo:{Name:docker.io Mirrors:[] Secure:true Official:true}} Mirrors:[]} NCPU:4 MemTotal:20973547520 GenericResources:<nil> DockerRootDir:/var/lib/docker HTTPProxy: HTTPSProxy: NoProxy: Name:docker-desktop Labels:[] ExperimentalBuild:false ServerVersion:20.10.5 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:269548fa27e0089a8b8278fc4fc781d7f65a939b Expected:269548fa27e0089a8b8278fc4fc781d7f65a939b} RuncCommit:{ID:ff819c7e9184c13b7c2607fe6c30ae19403a7aff Expected:ff819c7e9184c13b7c2607fe6c30ae19403a7aff} InitCommit:{ID:de40ad0 Expected:de40ad0} SecurityOptions:[name=seccomp,profile=default] ProductLicense: Warnings:<nil> ServerErrors:[]
ClientInfo:{Debug:false Plugins:[map[Experimental:true Name:app Path:C:\ProgramData\Docker\cli-plugins\docker-app.exe SchemaVersion:0.1.0 ShortDescription:Docker App Vendor:Docker Inc. Version:v0.9.1-beta3] map[Name:buildx Path:C:\ProgramData\Docker\cli-plugins\docker-buildx.exe SchemaVersion:0.1.0 ShortDescription:Build with BuildKit Vendor:Docker Inc. Version:v0.5.1-docker] map[Name:scan Path:C:\ProgramData\Docker\cli-plugins\docker-scan.exe SchemaVersion:0.1.0 ShortDescription:Docker Scan Vendor:Docker Inc. Version:v0.5.0]] Warnings:<nil>}}
I0915 20:33:27.009797 25104 cli_runner.go:115] Run: docker info --format "'{{json .SecurityOptions}}'"
*
* ==> Docker <==
* -- Logs begin at Wed 2021-09-15 20:25:36 UTC, end at Wed 2021-09-15 20:33:38 UTC. --
Sep 15 20:30:36 cert-options-20210915202501-22848 dockerd[468]: time="2021-09-15T20:30:36.960715900Z" level=info msg="Processing signal 'terminated'"
Sep 15 20:30:36 cert-options-20210915202501-22848 dockerd[468]: time="2021-09-15T20:30:36.992561900Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Sep 15 20:30:36 cert-options-20210915202501-22848 dockerd[468]: time="2021-09-15T20:30:36.997775900Z" level=info msg="Daemon shutdown complete"
Sep 15 20:30:37 cert-options-20210915202501-22848 systemd[1]: docker.service: Succeeded.
Sep 15 20:30:37 cert-options-20210915202501-22848 systemd[1]: Stopped Docker Application Container Engine.
Sep 15 20:30:37 cert-options-20210915202501-22848 systemd[1]: Starting Docker Application Container Engine...
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.354885500Z" level=info msg="Starting up"
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.365639700Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.365679400Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.365722000Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///run/containerd/containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.365740200Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.372900200Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.373271300Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.373310800Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///run/containerd/containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.373326500Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.410486600Z" level=info msg="[graphdriver] using prior storage driver: overlay2"
Sep 15 20:30:37 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:37.485584600Z" level=info msg="Loading containers: start."
Sep 15 20:30:38 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:38.680656800Z" 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"
Sep 15 20:30:39 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:39.042246100Z" level=info msg="Loading containers: done."
Sep 15 20:30:39 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:39.202800400Z" level=info msg="Docker daemon" commit=75249d8 graphdriver(s)=overlay2 version=20.10.8
Sep 15 20:30:39 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:39.203720700Z" level=info msg="Daemon has completed initialization"
Sep 15 20:30:39 cert-options-20210915202501-22848 systemd[1]: Started Docker Application Container Engine.
Sep 15 20:30:39 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:39.605625200Z" level=info msg="API listen on [::]:2376"
Sep 15 20:30:39 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:30:39.665603100Z" level=info msg="API listen on /var/run/docker.sock"
Sep 15 20:32:09 cert-options-20210915202501-22848 dockerd[779]: time="2021-09-15T20:32:09.687229200Z" level=info msg="ignoring event" container=181000836a33cc82efff2410d5abbccca3eab598baa3fb2597d2e52f08b95720 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
*
* ==> container status <==
* CONTAINER IMAGE CREATED STATE NAME ATTEMPT POD ID
3a0a53c101635 8d147537fb7d1 12 seconds ago Running coredns 0 57dcd5b65d006
d47d0abd865e9 6e38f40d628db 20 seconds ago Running storage-provisioner 0 ac541741b63c1
b83643181d2c5 36c4ebbc9d979 22 seconds ago Running kube-proxy 0 21c06b15319bc
34599660af1b0 6e002eb89a881 About a minute ago Running kube-controller-manager 1 044dd5bc3a00b
0eed1b850b347 aca5ededae9c8 2 minutes ago Running kube-scheduler 0 572c2ed575eb8
181000836a33c 6e002eb89a881 2 minutes ago Exited kube-controller-manager 0 044dd5bc3a00b
13e85a8e932d2 f30469a2491a5 2 minutes ago Running kube-apiserver 0 27e045d9e301c
a86db474fd3eb 0048118155842 2 minutes ago Running etcd 0 9666b129cc8cb
*
* ==> coredns [3a0a53c10163] <==
* .:53
[INFO] plugin/reload: Running configuration MD5 = db32ca3650231d74073ff4cf814959a7
CoreDNS-1.8.4
linux/amd64, go1.16.4, 053c4d5
*
* ==> describe nodes <==
* Name: cert-options-20210915202501-22848
Roles: control-plane,master
Labels: beta.kubernetes.io/arch=amd64
beta.kubernetes.io/os=linux
kubernetes.io/arch=amd64
kubernetes.io/hostname=cert-options-20210915202501-22848
kubernetes.io/os=linux
minikube.k8s.io/commit=0d321606059ead2904f4f5ddd59a9a7026c7ee04
minikube.k8s.io/name=cert-options-20210915202501-22848
minikube.k8s.io/updated_at=2021_09_15T20_32_44_0700
minikube.k8s.io/version=v1.23.0
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: Wed, 15 Sep 2021 20:32:08 +0000
Taints: <none>
Unschedulable: false
Lease:
HolderIdentity: cert-options-20210915202501-22848
AcquireTime: <unset>
RenewTime: Wed, 15 Sep 2021 20:33:34 +0000
Conditions:
Type Status LastHeartbeatTime LastTransitionTime Reason Message
---- ------ ----------------- ------------------ ------ -------
MemoryPressure False Wed, 15 Sep 2021 20:33:09 +0000 Wed, 15 Sep 2021 20:32:04 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
DiskPressure False Wed, 15 Sep 2021 20:33:09 +0000 Wed, 15 Sep 2021 20:32:04 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
PIDPressure False Wed, 15 Sep 2021 20:33:09 +0000 Wed, 15 Sep 2021 20:32:04 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
Ready True Wed, 15 Sep 2021 20:33:09 +0000 Wed, 15 Sep 2021 20:33:09 +0000 KubeletReady kubelet is posting ready status
Addresses:
InternalIP: 192.168.58.2
Hostname: cert-options-20210915202501-22848
Capacity:
cpu: 4
ephemeral-storage: 65792556Ki
hugepages-1Gi: 0
hugepages-2Mi: 0
memory: 20481980Ki
pods: 110
Allocatable:
cpu: 4
ephemeral-storage: 65792556Ki
hugepages-1Gi: 0
hugepages-2Mi: 0
memory: 20481980Ki
pods: 110
System Info:
Machine ID: 4b5e5cdd53d44f5ab575bb522d42acca
System UUID: 3e66b93a-1e96-43c0-afbc-2b516574f845
Boot ID: 7b7b18db-3e3e-49d3-a2cb-ac38329b7bd9
Kernel Version: 4.19.121-linuxkit
OS Image: Ubuntu 20.04.2 LTS
Operating System: linux
Architecture: amd64
Container Runtime Version: docker://20.10.8
Kubelet Version: v1.22.1
Kube-Proxy Version: v1.22.1
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-78fcd69978-h2n6w 100m (2%!)(MISSING) 0 (0%!)(MISSING) 70Mi (0%!)(MISSING) 170Mi (0%!)(MISSING) 35s
kube-system etcd-cert-options-20210915202501-22848 100m (2%!)(MISSING) 0 (0%!)(MISSING) 100Mi (0%!)(MISSING) 0 (0%!)(MISSING) 29s
kube-system kube-apiserver-cert-options-20210915202501-22848 250m (6%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 69s
kube-system kube-controller-manager-cert-options-20210915202501-22848 200m (5%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 80s
kube-system kube-proxy-nbv99 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 35s
kube-system kube-scheduler-cert-options-20210915202501-22848 100m (2%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 82s
kube-system storage-provisioner 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 0 (0%!)(MISSING) 28s
Allocated resources:
(Total limits may be over 100 percent, i.e., overcommitted.)
Resource Requests Limits
-------- -------- ------
cpu 750m (18%!)(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 Starting 47s kubelet Starting kubelet.
Normal NodeHasSufficientMemory 44s kubelet Node cert-options-20210915202501-22848 status is now: NodeHasSufficientMemory
Normal NodeHasNoDiskPressure 44s kubelet Node cert-options-20210915202501-22848 status is now: NodeHasNoDiskPressure
Normal NodeHasSufficientPID 44s kubelet Node cert-options-20210915202501-22848 status is now: NodeHasSufficientPID
Normal NodeNotReady 41s kubelet Node cert-options-20210915202501-22848 status is now: NodeNotReady
Normal NodeAllocatableEnforced 31s kubelet Updated Node Allocatable limit across pods
Normal NodeReady 30s kubelet Node cert-options-20210915202501-22848 status is now: NodeReady
*
* ==> dmesg <==
* [ +0.000000] hrtimer_interrupt+0x92/0x165
[ +0.000000] hv_stimer0_isr+0x20/0x2d
[ +0.000000] hv_stimer0_vector_handler+0x3b/0x57
[ +0.000000] hv_stimer0_callback_vector+0xf/0x20
[ +0.000000] </IRQ>
[ +0.000000] RIP: 0010:arch_local_irq_enable+0x7/0x8
[ +0.000000] Code: ef ff ff 0f 20 d8 0f 1f 40 00 c3 48 89 f8 0f 1f 40 00 c3 48 89 f8 0f 1f 40 00 c3 48 89 f8 0f 1f 40 00 c3 fb 66 0f 1f 44 00 00 <c3> 0f 1f 44 00 00 40 f6 c7 02 74 12 48 b8 ff 0f 00 00 00 00 f0 ff
[ +0.000000] RSP: 0000:ffffbcaf423f7ee0 EFLAGS: 00000206 ORIG_RAX: ffffffffffffff12
[ +0.000000] RAX: 0000000080000000 RBX: 0000000000000000 RCX: 0000000000000000
[ +0.000000] RDX: 000055a9735499db RSI: 0000000000000004 RDI: ffffbcaf423f7f58
[ +0.000000] RBP: ffffbcaf423f7f58 R08: 0000000000000000 R09: 0000000000000000
[ +0.000000] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000004
[ +0.000000] R13: 000055a9735499db R14: ffff97d483b18dc0 R15: ffff97d4e4dc7400
[ +0.000000] __do_page_fault+0x17f/0x42d
[ +0.000000] ? page_fault+0x8/0x30
[ +0.000000] page_fault+0x1e/0x30
[ +0.000000] RIP: 0033:0x55a9730c8f03
[ +0.000000] Code: 0f 6f d9 66 0f ef 0d ec 85 97 00 66 0f ef 15 f4 85 97 00 66 0f ef 1d fc 85 97 00 66 0f 38 dc c9 66 0f 38 dc d2 66 0f 38 dc db <f3> 0f 6f 20 f3 0f 6f 68 10 f3 0f 6f 74 08 e0 f3 0f 6f 7c 08 f0 66
[ +0.000000] RSP: 002b:000000c00004bdc8 EFLAGS: 00010287
[ +0.000000] RAX: 000055a9735499db RBX: 000055a9730cb860 RCX: 0000000000000022
[ +0.000000] RDX: 000000c00004bde0 RSI: 000000c00004be48 RDI: 000000c000080868
[ +0.000000] RBP: 000000c00004be28 R08: 000055a97353d681 R09: 0000000000000000
[ +0.000000] R10: 0000000000000004 R11: 000000c0000807d0 R12: 000000000000001a
[ +0.000000] R13: 0000000000000006 R14: 0000000000000008 R15: 0000000000000017
[ +0.000000] ---[ end trace cdbbbbc925f6eff0 ]---
*
* ==> etcd [a86db474fd3e] <==
* {"level":"warn","ts":"2021-09-15T20:32:44.448Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"118.255ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3238505856976812706 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" mod_revision:284 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" value_size:168 >> failure:<request_range:<key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" > >>","response":"size:16"}
{"level":"info","ts":"2021-09-15T20:32:44.448Z","caller":"traceutil/trace.go:171","msg":"trace[2059151567] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"125.2239ms","start":"2021-09-15T20:32:44.323Z","end":"2021-09-15T20:32:44.448Z","steps":["trace[2059151567] 'read index received' (duration: 1.1026ms)","trace[2059151567] 'applied index is now lower than readState.Index' (duration: 124.1195ms)"],"step_count":2}
{"level":"info","ts":"2021-09-15T20:32:44.448Z","caller":"traceutil/trace.go:171","msg":"trace[1417659081] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"242.2229ms","start":"2021-09-15T20:32:44.206Z","end":"2021-09-15T20:32:44.448Z","steps":["trace[1417659081] 'process raft request' (duration: 139.9146ms)","trace[1417659081] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/serviceaccounts/kube-system/bootstrap-signer; req_size:227; } (duration: 101.2888ms)"],"step_count":2}
{"level":"warn","ts":"2021-09-15T20:32:44.461Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"112.1024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2021-09-15T20:32:44.461Z","caller":"traceutil/trace.go:171","msg":"trace[62791144] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:287; }","duration":"112.1683ms","start":"2021-09-15T20:32:44.349Z","end":"2021-09-15T20:32:44.461Z","steps":["trace[62791144] 'agreement among raft nodes before linearized reading' (duration: 112.0845ms)"],"step_count":1}
{"level":"warn","ts":"2021-09-15T20:32:44.461Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"111.8924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podsecuritypolicy/\" range_end:\"/registry/podsecuritypolicy0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2021-09-15T20:32:44.461Z","caller":"traceutil/trace.go:171","msg":"trace[1007801193] range","detail":"{range_begin:/registry/podsecuritypolicy/; range_end:/registry/podsecuritypolicy0; response_count:0; response_revision:287; }","duration":"111.9308ms","start":"2021-09-15T20:32:44.349Z","end":"2021-09-15T20:32:44.461Z","steps":["trace[1007801193] 'agreement among raft nodes before linearized reading' (duration: 111.8735ms)"],"step_count":1}
{"level":"warn","ts":"2021-09-15T20:32:44.461Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"160.6979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:234"}
{"level":"info","ts":"2021-09-15T20:32:44.461Z","caller":"traceutil/trace.go:171","msg":"trace[880189914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:287; }","duration":"161.2847ms","start":"2021-09-15T20:32:44.300Z","end":"2021-09-15T20:32:44.461Z","steps":["trace[880189914] 'agreement among raft nodes before linearized reading' (duration: 160.616ms)"],"step_count":1}
{"level":"warn","ts":"2021-09-15T20:32:57.760Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"110.3659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:216"}
{"level":"info","ts":"2021-09-15T20:32:57.760Z","caller":"traceutil/trace.go:171","msg":"trace[815592747] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:332; }","duration":"116.5058ms","start":"2021-09-15T20:32:57.644Z","end":"2021-09-15T20:32:57.760Z","steps":["trace[815592747] 'agreement among raft nodes before linearized reading' (duration: 41.9733ms)","trace[815592747] 'range keys from in-memory index tree' (duration: 68.3181ms)"],"step_count":2}
{"level":"warn","ts":"2021-09-15T20:32:57.857Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"186.4186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:216"}
{"level":"info","ts":"2021-09-15T20:32:57.858Z","caller":"traceutil/trace.go:171","msg":"trace[316722377] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:332; }","duration":"223.8494ms","start":"2021-09-15T20:32:57.634Z","end":"2021-09-15T20:32:57.858Z","steps":["trace[316722377] 'agreement among raft nodes before linearized reading' (duration: 28.51ms)","trace[316722377] 'range keys from in-memory index tree' (duration: 156.818ms)"],"step_count":2}
{"level":"warn","ts":"2021-09-15T20:33:01.021Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"110.8433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:218"}
{"level":"info","ts":"2021-09-15T20:33:01.039Z","caller":"traceutil/trace.go:171","msg":"trace[1830635816] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:369; }","duration":"128.4562ms","start":"2021-09-15T20:33:00.910Z","end":"2021-09-15T20:33:01.039Z","steps":["trace[1830635816] 'agreement among raft nodes before linearized reading' (duration: 22.0249ms)","trace[1830635816] 'get authentication metadata' (duration: 82.9124ms)"],"step_count":2}
{"level":"info","ts":"2021-09-15T20:33:01.734Z","caller":"traceutil/trace.go:171","msg":"trace[1846979718] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"154.8333ms","start":"2021-09-15T20:33:01.579Z","end":"2021-09-15T20:33:01.734Z","steps":["trace[1846979718] 'process raft request' (duration: 59.1533ms)","trace[1846979718] 'compare' (duration: 76.5461ms)","trace[1846979718] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/serviceaccounts/kube-system/cronjob-controller; req_size:185; } (duration: 15.0738ms)"],"step_count":3}
{"level":"warn","ts":"2021-09-15T20:33:03.701Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"206.6381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:269"}
{"level":"info","ts":"2021-09-15T20:33:03.704Z","caller":"traceutil/trace.go:171","msg":"trace[255449043] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:398; }","duration":"209.2165ms","start":"2021-09-15T20:33:03.495Z","end":"2021-09-15T20:33:03.704Z","steps":["trace[255449043] 'agreement among raft nodes before linearized reading' (duration: 61.6909ms)","trace[255449043] 'range keys from bolt db' (duration: 144.8926ms)"],"step_count":2}
{"level":"info","ts":"2021-09-15T20:33:04.177Z","caller":"traceutil/trace.go:171","msg":"trace[2041936905] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"108.0473ms","start":"2021-09-15T20:33:04.069Z","end":"2021-09-15T20:33:04.177Z","steps":["trace[2041936905] 'process raft request' (duration: 35.2488ms)","trace[2041936905] 'compare' (duration: 54.8224ms)"],"step_count":2}
{"level":"info","ts":"2021-09-15T20:33:04.188Z","caller":"traceutil/trace.go:171","msg":"trace[906213293] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"115.6473ms","start":"2021-09-15T20:33:04.072Z","end":"2021-09-15T20:33:04.187Z","steps":["trace[906213293] 'process raft request' (duration: 104.8185ms)"],"step_count":1}
{"level":"info","ts":"2021-09-15T20:33:04.188Z","caller":"traceutil/trace.go:171","msg":"trace[1267883987] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"116.2484ms","start":"2021-09-15T20:33:04.072Z","end":"2021-09-15T20:33:04.188Z","steps":["trace[1267883987] 'process raft request' (duration: 104.5078ms)"],"step_count":1}
{"level":"info","ts":"2021-09-15T20:33:04.204Z","caller":"traceutil/trace.go:171","msg":"trace[1083793347] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"206.0219ms","start":"2021-09-15T20:33:03.998Z","end":"2021-09-15T20:33:04.204Z","steps":["trace[1083793347] 'process raft request' (duration: 178.835ms)"],"step_count":1}
{"level":"warn","ts":"2021-09-15T20:33:04.204Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"106.9084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2021-09-15T20:33:04.204Z","caller":"traceutil/trace.go:171","msg":"trace[705182327] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:418; }","duration":"106.9706ms","start":"2021-09-15T20:33:04.097Z","end":"2021-09-15T20:33:04.204Z","steps":["trace[705182327] 'agreement among raft nodes before linearized reading' (duration: 106.889ms)"],"step_count":1}
{"level":"info","ts":"2021-09-15T20:33:04.223Z","caller":"traceutil/trace.go:171","msg":"trace[1134737840] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"118.7785ms","start":"2021-09-15T20:33:04.105Z","end":"2021-09-15T20:33:04.223Z","steps":["trace[1134737840] 'process raft request' (duration: 72.1989ms)"],"step_count":1}
*
* ==> kernel <==
* 20:33:39 up 2:08, 0 users, load average: 53.85, 32.22, 21.83
Linux cert-options-20210915202501-22848 4.19.121-linuxkit #1 SMP Thu Jan 21 15:36:34 UTC 2021 x86_64 x86_64 x86_64 GNU/Linux
PRETTY_NAME="Ubuntu 20.04.2 LTS"
*
* ==> kube-apiserver [13e85a8e932d] <==
* I0915 20:32:08.788453 1 storage_scheduling.go:132] created PriorityClass system-cluster-critical with value 2000000000
I0915 20:32:08.788486 1 storage_scheduling.go:148] all system priority classes are created successfully or already exist.
I0915 20:32:08.927305 1 trace.go:205] Trace[291207942]: "Create" url:/api/v1/namespaces,user-agent:kube-apiserver/v1.22.1 (linux/amd64) kubernetes/632ed30,audit-id:588cb227-a7bb-4845-b94c-cbc54b61b05f,client:127.0.0.1,accept:application/vnd.kubernetes.protobuf, */*,protocol:HTTP/2.0 (15-Sep-2021 20:32:08.262) (total time: 664ms):
Trace[291207942]: ---"Object stored in database" 663ms (20:32:08.926)
Trace[291207942]: [664.9982ms] [664.9982ms] END
I0915 20:32:19.176802 1 controller.go:611] quota admission added evaluator for: leases.coordination.k8s.io
I0915 20:32:24.333579 1 controller.go:611] quota admission added evaluator for: roles.rbac.authorization.k8s.io
I0915 20:32:25.796679 1 controller.go:611] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io
W0915 20:32:26.929409 1 lease.go:233] Resetting endpoints for master service "kubernetes" to [192.168.58.2]
I0915 20:32:26.933665 1 controller.go:611] quota admission added evaluator for: endpoints
I0915 20:32:26.975551 1 controller.go:611] quota admission added evaluator for: endpointslices.discovery.k8s.io
I0915 20:32:40.711748 1 controller.go:611] quota admission added evaluator for: serviceaccounts
I0915 20:32:41.990499 1 controller.go:611] quota admission added evaluator for: deployments.apps
I0915 20:32:43.189418 1 controller.go:611] quota admission added evaluator for: daemonsets.apps
I0915 20:33:03.479634 1 controller.go:611] quota admission added evaluator for: replicasets.apps
I0915 20:33:03.845587 1 controller.go:611] quota admission added evaluator for: controllerrevisions.apps
I0915 20:33:21.809205 1 trace.go:205] Trace[210199679]: "Patch" url:/api/v1/namespaces/kube-system/pods/kube-apiserver-cert-options-20210915202501-22848/status,user-agent:kubelet/v1.22.1 (linux/amd64) kubernetes/632ed30,audit-id:c5e4de68-11e1-452c-a8af-36476d42218c,client:192.168.58.2,accept:application/vnd.kubernetes.protobuf,application/json,protocol:HTTP/2.0 (15-Sep-2021 20:33:21.249) (total time: 559ms):
Trace[210199679]: ---"Recorded the audit event" 313ms (20:33:21.562)
Trace[210199679]: ---"About to check admission control" 175ms (20:33:21.738)
Trace[210199679]: [559.3449ms] [559.3449ms] END
I0915 20:33:25.712010 1 trace.go:205] Trace[1773007356]: "Patch" url:/api/v1/namespaces/kube-system/pods/kube-apiserver-cert-options-20210915202501-22848/status,user-agent:kubelet/v1.22.1 (linux/amd64) kubernetes/632ed30,audit-id:981f3afb-9733-407f-b50a-13df940127d0,client:192.168.58.2,accept:application/vnd.kubernetes.protobuf,application/json,protocol:HTTP/2.0 (15-Sep-2021 20:33:25.182) (total time: 529ms):
Trace[1773007356]: ---"Recorded the audit event" 270ms (20:33:25.452)
Trace[1773007356]: ---"About to check admission control" 125ms (20:33:25.583)
Trace[1773007356]: ---"Object stored in database" 127ms (20:33:25.711)
Trace[1773007356]: [529.9061ms] [529.9061ms] END
*
* ==> kube-controller-manager [181000836a33] <==
* /usr/local/go/src/bytes/buffer.go:204 +0xbe
crypto/tls.(*Conn).readFromUntil(0xc00093d180, 0x5176ac0, 0xc000450228, 0x5, 0xc000450228, 0x400)
/usr/local/go/src/crypto/tls/conn.go:798 +0xf3
crypto/tls.(*Conn).readRecordOrCCS(0xc00093d180, 0x0, 0x0, 0x1)
/usr/local/go/src/crypto/tls/conn.go:605 +0x115
crypto/tls.(*Conn).readRecord(...)
/usr/local/go/src/crypto/tls/conn.go:573
crypto/tls.(*Conn).Read(0xc00093d180, 0xc000f08000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
/usr/local/go/src/crypto/tls/conn.go:1276 +0x165
bufio.(*Reader).Read(0xc0003c8c00, 0xc000e263b8, 0x9, 0x9, 0x99f88b, 0xc0009f1c78, 0x4071a5)
/usr/local/go/src/bufio/bufio.go:227 +0x222
io.ReadAtLeast(0x516f400, 0xc0003c8c00, 0xc000e263b8, 0x9, 0x9, 0x9, 0xc000e40120, 0xf13f81a3867d00, 0xc000e40120)
/usr/local/go/src/io/io.go:328 +0x87
io.ReadFull(...)
/usr/local/go/src/io/io.go:347
k8s.io/kubernetes/vendor/golang.org/x/net/http2.readFrameHeader(0xc000e263b8, 0x9, 0x9, 0x516f400, 0xc0003c8c00, 0x0, 0x0, 0x0, 0x0)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/frame.go:237 +0x89
k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*Framer).ReadFrame(0xc000e26380, 0xc000e03950, 0x0, 0x0, 0x0)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/frame.go:492 +0xa5
k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*clientConnReadLoop).run(0xc0009f1fa8, 0x0, 0x0)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/transport.go:1821 +0xd8
k8s.io/kubernetes/vendor/golang.org/x/net/http2.(*ClientConn).readLoop(0xc0005e9e00)
/workspace/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/vendor/golang.org/x/net/http2/transport.go:1743 +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:695 +0x6c5
*
* ==> kube-controller-manager [34599660af1b] <==
* I0915 20:33:02.758139 1 shared_informer.go:247] Caches are synced for ephemeral
I0915 20:33:02.758213 1 shared_informer.go:247] Caches are synced for certificate-csrsigning-legacy-unknown
I0915 20:33:02.773851 1 shared_informer.go:247] Caches are synced for attach detach
I0915 20:33:02.786710 1 shared_informer.go:247] Caches are synced for taint
I0915 20:33:02.786901 1 node_lifecycle_controller.go:1398] Initializing eviction metric for zone:
W0915 20:33:02.787410 1 node_lifecycle_controller.go:1013] Missing timestamp for Node cert-options-20210915202501-22848. Assuming now as a timestamp.
I0915 20:33:02.787511 1 node_lifecycle_controller.go:1164] Controller detected that all Nodes are not-Ready. Entering master disruption mode.
I0915 20:33:02.807410 1 taint_manager.go:187] "Starting NoExecuteTaintManager"
I0915 20:33:02.813777 1 event.go:291] "Event occurred" object="cert-options-20210915202501-22848" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node cert-options-20210915202501-22848 event: Registered Node cert-options-20210915202501-22848 in Controller"
I0915 20:33:02.841745 1 shared_informer.go:247] Caches are synced for endpoint_slice_mirroring
I0915 20:33:02.906313 1 shared_informer.go:247] Caches are synced for endpoint_slice
I0915 20:33:02.991540 1 event.go:291] "Event occurred" object="kube-system/kube-scheduler-cert-options-20210915202501-22848" kind="Pod" apiVersion="v1" type="Warning" reason="NodeNotReady" message="Node is not ready"
I0915 20:33:03.048322 1 shared_informer.go:247] Caches are synced for resource quota
I0915 20:33:03.074910 1 shared_informer.go:247] Caches are synced for daemon sets
I0915 20:33:03.089497 1 shared_informer.go:240] Waiting for caches to sync for garbage collector
I0915 20:33:03.089544 1 shared_informer.go:247] Caches are synced for resource quota
I0915 20:33:03.143896 1 shared_informer.go:247] Caches are synced for stateful set
I0915 20:33:03.147482 1 event.go:291] "Event occurred" object="kube-system/kube-apiserver-cert-options-20210915202501-22848" kind="Pod" apiVersion="v1" type="Warning" reason="NodeNotReady" message="Node is not ready"
I0915 20:33:03.484840 1 shared_informer.go:247] Caches are synced for garbage collector
I0915 20:33:03.486391 1 garbagecollector.go:151] Garbage collector: all resource monitors have synced. Proceeding to collect garbage
I0915 20:33:03.491811 1 shared_informer.go:247] Caches are synced for garbage collector
I0915 20:33:03.709233 1 event.go:291] "Event occurred" object="kube-system/coredns" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-78fcd69978 to 1"
I0915 20:33:04.350204 1 event.go:291] "Event occurred" object="kube-system/coredns-78fcd69978" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-78fcd69978-h2n6w"
I0915 20:33:04.438892 1 event.go:291] "Event occurred" object="kube-system/kube-proxy" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-nbv99"
I0915 20:33:12.830062 1 node_lifecycle_controller.go:1191] Controller detected that some Nodes are Ready. Exiting master disruption mode.
*
* ==> kube-proxy [b83643181d2c] <==
* I0915 20:33:24.680285 1 node.go:172] Successfully retrieved node IP: 192.168.58.2
I0915 20:33:24.680422 1 server_others.go:140] Detected node IP 192.168.58.2
W0915 20:33:24.680618 1 server_others.go:565] Unknown proxy mode "", assuming iptables proxy
I0915 20:33:27.257231 1 server_others.go:206] kube-proxy running in dual-stack mode, IPv4-primary
I0915 20:33:27.257298 1 server_others.go:212] Using iptables Proxier.
I0915 20:33:27.257329 1 server_others.go:219] creating dualStackProxier for iptables.
W0915 20:33:27.257395 1 server_others.go:495] detect-local-mode set to ClusterCIDR, but no IPv6 cluster CIDR defined, , defaulting to no-op detect-local for IPv6
I0915 20:33:27.261574 1 server.go:649] Version: v1.22.1
I0915 20:33:27.380243 1 config.go:315] Starting service config controller
I0915 20:33:27.380316 1 shared_informer.go:240] Waiting for caches to sync for service config
I0915 20:33:27.381861 1 config.go:224] Starting endpoint slice config controller
I0915 20:33:27.381874 1 shared_informer.go:240] Waiting for caches to sync for endpoint slice config
I0915 20:33:27.500484 1 shared_informer.go:247] Caches are synced for endpoint slice config
I0915 20:33:27.580558 1 shared_informer.go:247] Caches are synced for service config
E0915 20:33:27.620722 1 event_broadcaster.go:253] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"cert-options-20210915202501-22848.16a51912886086cc", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ClusterName:"", ManagedFields:[]v1.ManagedFieldsEntry(nil)}, EventTime:v1.MicroTime{Time:time.Time{wall:0xc048b405d6a325d0, ext:6698032801, loc:(*time.Location)(0x2d81340)}}, Series:(*v1.EventSeries)(nil), ReportingController:"kube-proxy", ReportingInstance:"kube-proxy-cert-options-20210915202501-22848", Action:"StartKubeProxy", Reason:"Starting", Regarding:v1.ObjectReference{Kind:"Node", Namespace:""
, Name:"cert-options-20210915202501-22848", UID:"cert-options-20210915202501-22848", APIVersion:"", ResourceVersion:"", FieldPath:""}, Related:(*v1.ObjectReference)(nil), Note:"", Type:"Normal", DeprecatedSource:v1.EventSource{Component:"", Host:""}, DeprecatedFirstTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, DeprecatedLastTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, DeprecatedCount:0}': 'Event "cert-options-20210915202501-22848.16a51912886086cc" is invalid: involvedObject.namespace: Invalid value: "": does not match event.namespace' (will not retry!)
*
* ==> kube-scheduler [0eed1b850b34] <==
* E0915 20:32:12.400381 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
E0915 20:32:12.400557 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PodDisruptionBudget: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E0915 20:32:12.408149 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
E0915 20:32:12.651344 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
E0915 20:32:12.761487 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
E0915 20:32:12.761604 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolumeClaim: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
E0915 20:32:12.791287 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
E0915 20:32:12.900036 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E0915 20:32:14.762822 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
E0915 20:32:15.477495 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
E0915 20:32:15.611322 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"
E0915 20:32:15.818498 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
E0915 20:32:16.038509 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PodDisruptionBudget: failed to list *v1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
E0915 20:32:16.251339 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
E0915 20:32:16.462989 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
E0915 20:32:16.681481 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.PersistentVolumeClaim: failed to list *v1.PersistentVolumeClaim: persistentvolumeclaims is forbidden: User "system:kube-scheduler" cannot list resource "persistentvolumeclaims" in API group "" at the cluster scope
E0915 20:32:16.762519 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
E0915 20:32:17.738067 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
E0915 20:32:17.746448 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
E0915 20:32:17.989675 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
E0915 20:32:18.462374 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
E0915 20:32:18.483354 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
E0915 20:32:18.687802 1 reflector.go:138] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.StatefulSet: failed to list *v1.StatefulSet: statefulsets.apps is forbidden: User "system:kube-scheduler" cannot list resource "statefulsets" in API group "apps" at the cluster scope
E0915 20:32:25.044732 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"
I0915 20:32:41.464291 1 shared_informer.go:247] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
*
* ==> kubelet <==
* -- Logs begin at Wed 2021-09-15 20:25:36 UTC, end at Wed 2021-09-15 20:33:42 UTC. --
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.443809 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"config-volume\" (UniqueName: \"kubernetes.io/configmap/02e9083e-8922-473c-aec2-457f2fd5c0b4-config-volume\") pod \"coredns-78fcd69978-h2n6w\" (UID: \"02e9083e-8922-473c-aec2-457f2fd5c0b4\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.476315 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"etcd-data\" (UniqueName: \"kubernetes.io/host-path/2bbcd2ea99180f436b9b9aa2216bd5af-etcd-data\") pod \"etcd-cert-options-20210915202501-22848\" (UID: \"2bbcd2ea99180f436b9b9aa2216bd5af\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.489264 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/7f772fda85c5c8f6e268392341d1d775-ca-certs\") pod \"kube-apiserver-cert-options-20210915202501-22848\" (UID: \"7f772fda85c5c8f6e268392341d1d775\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.493218 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0d7803f8-6632-4e3b-93de-68ef8872041c-lib-modules\") pod \"kube-proxy-nbv99\" (UID: \"0d7803f8-6632-4e3b-93de-68ef8872041c\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.493833 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ldjdp\" (UniqueName: \"kubernetes.io/projected/02e9083e-8922-473c-aec2-457f2fd5c0b4-kube-api-access-ldjdp\") pod \"coredns-78fcd69978-h2n6w\" (UID: \"02e9083e-8922-473c-aec2-457f2fd5c0b4\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.495323 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flexvolume-dir\" (UniqueName: \"kubernetes.io/host-path/c3263dcf16f55cf01b2d033292a46fa3-flexvolume-dir\") pod \"kube-controller-manager-cert-options-20210915202501-22848\" (UID: \"c3263dcf16f55cf01b2d033292a46fa3\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.502834 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"etcd-certs\" (UniqueName: \"kubernetes.io/host-path/2bbcd2ea99180f436b9b9aa2216bd5af-etcd-certs\") pod \"etcd-cert-options-20210915202501-22848\" (UID: \"2bbcd2ea99180f436b9b9aa2216bd5af\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.505862 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0d7803f8-6632-4e3b-93de-68ef8872041c-xtables-lock\") pod \"kube-proxy-nbv99\" (UID: \"0d7803f8-6632-4e3b-93de-68ef8872041c\") "
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:10.506286 2787 reconciler.go:157] "Reconciler: start to sync state"
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: E0915 20:33:10.555179 2787 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-controller-manager-cert-options-20210915202501-22848\" already exists" pod="kube-system/kube-controller-manager-cert-options-20210915202501-22848"
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: E0915 20:33:10.560886 2787 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-apiserver-cert-options-20210915202501-22848\" already exists" pod="kube-system/kube-apiserver-cert-options-20210915202501-22848"
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: E0915 20:33:10.559580 2787 kubelet.go:1701] "Failed creating a mirror pod for" err="pods \"kube-scheduler-cert-options-20210915202501-22848\" already exists" pod="kube-system/kube-scheduler-cert-options-20210915202501-22848"
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: W0915 20:33:10.594061 2787 container.go:586] Failed to update stats for container "/kubepods/besteffort/pod0d7803f8-6632-4e3b-93de-68ef8872041c": /sys/fs/cgroup/cpuset/kubepods/besteffort/pod0d7803f8-6632-4e3b-93de-68ef8872041c/cpuset.mems found to be empty, continuing to push stats
Sep 15 20:33:10 cert-options-20210915202501-22848 kubelet[2787]: W0915 20:33:10.941631 2787 container.go:586] Failed to update stats for container "/kubepods/burstable/pod02e9083e-8922-473c-aec2-457f2fd5c0b4": /sys/fs/cgroup/cpuset/kubepods/burstable/pod02e9083e-8922-473c-aec2-457f2fd5c0b4/cpuset.mems found to be empty, continuing to push stats
Sep 15 20:33:11 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:11.690811 2787 topology_manager.go:200] "Topology Admit Handler"
Sep 15 20:33:11 cert-options-20210915202501-22848 kubelet[2787]: W0915 20:33:11.866463 2787 container.go:586] Failed to update stats for container "/kubepods/besteffort/podf9adb350-56b4-4d29-917b-36a69681ba74": /sys/fs/cgroup/cpuset/kubepods/besteffort/podf9adb350-56b4-4d29-917b-36a69681ba74/cpuset.mems found to be empty, continuing to push stats
Sep 15 20:33:11 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:11.931454 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"tmp\" (UniqueName: \"kubernetes.io/host-path/f9adb350-56b4-4d29-917b-36a69681ba74-tmp\") pod \"storage-provisioner\" (UID: \"f9adb350-56b4-4d29-917b-36a69681ba74\") "
Sep 15 20:33:11 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:11.931846 2787 reconciler.go:224] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qbqfk\" (UniqueName: \"kubernetes.io/projected/f9adb350-56b4-4d29-917b-36a69681ba74-kube-api-access-qbqfk\") pod \"storage-provisioner\" (UID: \"f9adb350-56b4-4d29-917b-36a69681ba74\") "
Sep 15 20:33:13 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:13.960484 2787 pod_container_deletor.go:79] "Container not found in pod's containers" containerID="ac541741b63c1ae778907058d057c27f130323d3e9f3018ffd231945c8c1587b"
Sep 15 20:33:24 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:24.625654 2787 pod_container_deletor.go:79] "Container not found in pod's containers" containerID="57dcd5b65d006760d9b858bff60219d2df70e2f79fc202d8e8191f8eff548430"
Sep 15 20:33:25 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:25.045890 2787 docker_sandbox.go:401] "Failed to read pod IP from plugin/docker" err="Couldn't find network status for kube-system/coredns-78fcd69978-h2n6w through plugin: invalid network status for"
Sep 15 20:33:26 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:26.925523 2787 pod_container_deletor.go:79] "Container not found in pod's containers" containerID="21c06b15319bc1a4fd028800a2b795f7bd10ee1543eee2398c406d16a02b891e"
Sep 15 20:33:28 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:28.559912 2787 docker_sandbox.go:401] "Failed to read pod IP from plugin/docker" err="Couldn't find network status for kube-system/coredns-78fcd69978-h2n6w through plugin: invalid network status for"
Sep 15 20:33:28 cert-options-20210915202501-22848 kubelet[2787]: E0915 20:33:28.894458 2787 cadvisor_stats_provider.go:415] "Partial failure issuing cadvisor.ContainerInfoV2" err="partial failures: [\"/kubepods/burstable/pod02e9083e-8922-473c-aec2-457f2fd5c0b4\": RecentStats: unable to find data in memory cache]"
Sep 15 20:33:31 cert-options-20210915202501-22848 kubelet[2787]: I0915 20:33:31.377118 2787 docker_sandbox.go:401] "Failed to read pod IP from plugin/docker" err="Couldn't find network status for kube-system/coredns-78fcd69978-h2n6w through plugin: invalid network status for"
*
* ==> storage-provisioner [d47d0abd865e] <==
* I0915 20:33:28.477362 1 storage_provisioner.go:116] Initializing the minikube storage provisioner...
-- /stdout --
** stderr **
! Executing "docker container inspect cert-options-20210915202501-22848 --format={{.State.Status}}" took an unusually long time: 2.4419446s
* Restarting the docker service may improve performance.
** /stderr **
helpers_test.go:255: (dbg) Run: out/minikube-windows-amd64.exe status --format={{.APIServer}} -p cert-options-20210915202501-22848 -n cert-options-20210915202501-22848
helpers_test.go:255: (dbg) Done: out/minikube-windows-amd64.exe status --format={{.APIServer}} -p cert-options-20210915202501-22848 -n cert-options-20210915202501-22848: (7.284061s)
helpers_test.go:262: (dbg) Run: kubectl --context cert-options-20210915202501-22848 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[TestCertOptions]: describe non-running pods <======
helpers_test.go:276: (dbg) Run: kubectl --context cert-options-20210915202501-22848 describe pod
helpers_test.go:276: (dbg) Non-zero exit: kubectl --context cert-options-20210915202501-22848 describe pod : exit status 1 (322.1465ms)
** stderr **
error: resource name may not be empty
** /stderr **
helpers_test.go:278: kubectl --context cert-options-20210915202501-22848 describe pod : exit status 1
helpers_test.go:176: Cleaning up "cert-options-20210915202501-22848" profile ...
helpers_test.go:179: (dbg) Run: out/minikube-windows-amd64.exe delete -p cert-options-20210915202501-22848
=== CONT TestCertOptions
helpers_test.go:179: (dbg) Done: out/minikube-windows-amd64.exe delete -p cert-options-20210915202501-22848: (29.1293923s)
--- FAIL: TestCertOptions (558.72s)