=== RUN TestAddons/parallel/Registry
=== PAUSE TestAddons/parallel/Registry
=== CONT TestAddons/parallel/Registry
=== CONT TestAddons/parallel/Registry
addons_test.go:199: registry stabilized in 22.218473ms
=== CONT TestAddons/parallel/Registry
addons_test.go:201: (dbg) TestAddons/parallel/Registry: waiting 6m0s for pods matching "actual-registry=true" in namespace "kube-system" ...
helpers_test.go:333: "registry-wbts4" [8892c84c-3938-4ded-a15a-9333012648e2] Running
=== CONT TestAddons/parallel/Registry
addons_test.go:201: (dbg) TestAddons/parallel/Registry: actual-registry=true healthy within 5.035298841s
=== CONT TestAddons/parallel/Registry
addons_test.go:204: (dbg) TestAddons/parallel/Registry: waiting 10m0s for pods matching "registry-proxy=true" in namespace "kube-system" ...
helpers_test.go:333: "registry-proxy-js2w2" [8ab364e7-de9a-4498-a665-85e8ad7001b5] Running
=== CONT TestAddons/parallel/Registry
addons_test.go:204: (dbg) TestAddons/parallel/Registry: registry-proxy=true healthy within 5.009668878s
addons_test.go:209: (dbg) Run: kubectl --context addons-20201109132301-342799 delete po -l run=registry-test --now
addons_test.go:214: (dbg) Run: kubectl --context addons-20201109132301-342799 run --rm registry-test --restart=Never --image=busybox -it -- sh -c "wget --spider -S http://registry.kube-system.svc.cluster.local"
=== CONT TestAddons/parallel/Registry
addons_test.go:214: (dbg) Non-zero exit: kubectl --context addons-20201109132301-342799 run --rm registry-test --restart=Never --image=busybox -it -- sh -c "wget --spider -S http://registry.kube-system.svc.cluster.local": exit status 128 (4.628503361s)
-- stdout --
pod "registry-test" deleted
-- /stdout --
** stderr **
pod default/registry-test terminated (ContainerCannotRun)
OCI runtime create failed: container_linux.go:349: starting container process caused "process_linux.go:449: container init caused \"rootfs_linux.go:58: mounting \\\"/var/lib/minikube/google_application_credentials.json\\\" to rootfs \\\"/var/lib/docker/overlay2/8954344407b890efb9e33682a8716dbd7d5922081f9d9979967ea660eb497bf8/merged\\\" at \\\"/google-app-creds.json\\\" caused \\\"stat /var/lib/minikube/google_application_credentials.json: no such file or directory\\\"\"": unknown
** /stderr **
addons_test.go:216: failed to hit registry.kube-system.svc.cluster.local. args "kubectl --context addons-20201109132301-342799 run --rm registry-test --restart=Never --image=busybox -it -- sh -c \"wget --spider -S http://registry.kube-system.svc.cluster.local\"" failed: exit status 128
addons_test.go:220: expected curl response be "HTTP/1.1 200", but got *pod "registry-test" deleted
*
addons_test.go:228: (dbg) Run: out/minikube-linux-amd64 -p addons-20201109132301-342799 ip
2020/11/09 13:25:53 [DEBUG] GET http://192.168.49.16:5000
addons_test.go:257: (dbg) Run: out/minikube-linux-amd64 -p addons-20201109132301-342799 addons disable registry --alsologtostderr -v=1
=== CONT TestAddons/parallel/Registry
addons_test.go:257: (dbg) Done: out/minikube-linux-amd64 -p addons-20201109132301-342799 addons disable registry --alsologtostderr -v=1: (4.219533168s)
helpers_test.go:216: -----------------------post-mortem--------------------------------
helpers_test.go:224: ======> post-mortem[TestAddons/parallel/Registry]: docker inspect <======
helpers_test.go:225: (dbg) Run: docker inspect addons-20201109132301-342799
helpers_test.go:229: (dbg) docker inspect addons-20201109132301-342799:
-- stdout --
[
{
"Id": "05568404e6b02c4d5394dbeef4d25d91e458764af41cb561be218af62b27721b",
"Created": "2020-11-09T21:23:03.102320439Z",
"Path": "/usr/local/bin/entrypoint",
"Args": [
"/sbin/init"
],
"State": {
"Status": "running",
"Running": true,
"Paused": false,
"Restarting": false,
"OOMKilled": false,
"Dead": false,
"Pid": 359503,
"ExitCode": 0,
"Error": "",
"StartedAt": "2020-11-09T21:23:03.660045221Z",
"FinishedAt": "0001-01-01T00:00:00Z"
},
"Image": "sha256:e0876e0a2db41a04c8143cbd27a7a5f9e10b610cb093093def031c59e5b44b0c",
"ResolvConfPath": "/var/lib/docker/containers/05568404e6b02c4d5394dbeef4d25d91e458764af41cb561be218af62b27721b/resolv.conf",
"HostnamePath": "/var/lib/docker/containers/05568404e6b02c4d5394dbeef4d25d91e458764af41cb561be218af62b27721b/hostname",
"HostsPath": "/var/lib/docker/containers/05568404e6b02c4d5394dbeef4d25d91e458764af41cb561be218af62b27721b/hosts",
"LogPath": "/var/lib/docker/containers/05568404e6b02c4d5394dbeef4d25d91e458764af41cb561be218af62b27721b/05568404e6b02c4d5394dbeef4d25d91e458764af41cb561be218af62b27721b-json.log",
"Name": "/addons-20201109132301-342799",
"RestartCount": 0,
"Driver": "overlay2",
"Platform": "linux",
"MountLabel": "",
"ProcessLabel": "",
"AppArmorProfile": "",
"ExecIDs": null,
"HostConfig": {
"Binds": [
"/lib/modules:/lib/modules:ro",
"addons-20201109132301-342799:/var"
],
"ContainerIDFile": "",
"LogConfig": {
"Type": "json-file",
"Config": {}
},
"NetworkMode": "addons-20201109132301-342799",
"PortBindings": {
"22/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": ""
}
],
"2376/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": ""
}
],
"5000/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": ""
}
],
"8443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": ""
}
]
},
"RestartPolicy": {
"Name": "no",
"MaximumRetryCount": 0
},
"AutoRemove": false,
"VolumeDriver": "",
"VolumesFrom": null,
"CapAdd": null,
"CapDrop": null,
"Capabilities": null,
"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": 2726297600,
"NanoCpus": 2000000000,
"CgroupParent": "",
"BlkioWeight": 0,
"BlkioWeightDevice": [],
"BlkioDeviceReadBps": null,
"BlkioDeviceWriteBps": null,
"BlkioDeviceReadIOps": null,
"BlkioDeviceWriteIOps": null,
"CpuPeriod": 0,
"CpuQuota": 0,
"CpuRealtimePeriod": 0,
"CpuRealtimeRuntime": 0,
"CpusetCpus": "",
"CpusetMems": "",
"Devices": [],
"DeviceCgroupRules": null,
"DeviceRequests": null,
"KernelMemory": 0,
"KernelMemoryTCP": 0,
"MemoryReservation": 0,
"MemorySwap": -1,
"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/dab5eef1d484caf5ab575eabba4e458ffcac8086435d8bb3adc7e62f5dfa3712-init/diff:/var/lib/docker/overlay2/95e86d765bff52afd170fbce58d14aba80acb592622f18ddf93f06d4a9f68b7b/diff:/var/lib/docker/overlay2/f3b9d7ca87d0bc2c33a2b73d4acfe36b9573ed84758410f9fb2646a520f241fe/diff:/var/lib/docker/overlay2/207fc0f6ee67dd96ccb25c63e93e7189f5f397b509f8c71ead5583a80c5f94b5/diff:/var/lib/docker/overlay2/8caa5ec9f05b14f99c7708700c1734fe9d519d629a5228c0c3cb1231f1cef09e/diff:/var/lib/docker/overlay2/f2f5d9470c647e56b32cee1438db6b6249291738854b6e7a0a4a405034649aa6/diff:/var/lib/docker/overlay2/99b3672a581ed70eb5d25da5aed44455272a7adbd48148c91dc38abfbaa6e93d/diff:/var/lib/docker/overlay2/4bc3f7ac122327f368cdfb9bf82d578fce25cf7f63160aa08d869610da8889f4/diff:/var/lib/docker/overlay2/0f945a2ada7520d0f95289ddf08a8e7aedfd5dbfad8087529175cd81d61b52bc/diff:/var/lib/docker/overlay2/a14122d44a5bdb05416a0e7fbe1684738e86454c2eeb7671ecc856e6fdaa0273/diff:/var/lib/docker/overlay2/e669c0
1451df27b1aeed4835d4d9d40fae282947f2b260d9bc1d86355f464b80/diff:/var/lib/docker/overlay2/f0227d1f2e3671850f58a9e89d347d8c2a26c40d79b078f02122819452c582d8/diff:/var/lib/docker/overlay2/35b3431b00b5c7bd66b1d24487a8a45645708ce7a87848986068c0df1515b812/diff:/var/lib/docker/overlay2/c1af3457c560ed19e519e64ff919ac7035d8d9801f4f44adf7b160339827ebd0/diff:/var/lib/docker/overlay2/3861f37a52f49a93d483d69cc77384a5792b260325af8224c6225039f7480889/diff:/var/lib/docker/overlay2/05239e7556fc89f82e761ff1ddd2d1ed0ee1b426a039ad3c9a3ba0519d737f51/diff:/var/lib/docker/overlay2/cb1f04e7554841e6f2102080f7854a3c7e464e45648c1419ef2f8b4d8a03f0bb/diff:/var/lib/docker/overlay2/80908997b1122a686bee670e8dd93659dece6b51614d8576f02c11b96178534d/diff:/var/lib/docker/overlay2/6c4d0be909f5f0a95818a6009b44c355b57e39b0709de1cce2ba45597354d590/diff:/var/lib/docker/overlay2/3a855d5f2b162c2d938a5745ba8131c9a2b244967b4a03f6cddd177236f3a934/diff:/var/lib/docker/overlay2/0ab0a2a3e614830a41a389aa6ff55503f9c10b1227acfb927550f9726eb9a605/diff:/var/lib/d
ocker/overlay2/3331dc047d4c1c4d3b6bf48aba65be1ad34cff6d25329ef56e91ad8e4ee65fde/diff:/var/lib/docker/overlay2/d286c2ac5bb00869e09924bb7fc7389073b7267af1cc4c3ae6a339b29e4c0cc1/diff:/var/lib/docker/overlay2/d0a518fbd2bc93756d11040cfe4d7757f96e813dd9ddf918a54a82f7e61cb791/diff:/var/lib/docker/overlay2/2d63359a4d0da5faa54d017f487391caaee972fe4fb98db4b82e7aee2d9b01ee/diff:/var/lib/docker/overlay2/40ca142bba947004e2782bce25bd04810589fffbf8086ba91e3a074517cfa13c/diff:/var/lib/docker/overlay2/5b738c1270b81825ef44e9bd466dd8533bc4e3e3796355d7dc5dc104fb3a18c8/diff:/var/lib/docker/overlay2/c57d8a6f6b648bd2cf9d7140762b8590e1a71b9040c8a0f2b8ab31f65011bcd2/diff:/var/lib/docker/overlay2/37f83aba64f92a6cf695468f5b0a1016634c95e09933dcecec297cca22b9038b/diff:/var/lib/docker/overlay2/396d3843ed4e04893bd4bf961eab2522ce90962e626e057f5be38cbe0ed65d4e/diff",
"MergedDir": "/var/lib/docker/overlay2/dab5eef1d484caf5ab575eabba4e458ffcac8086435d8bb3adc7e62f5dfa3712/merged",
"UpperDir": "/var/lib/docker/overlay2/dab5eef1d484caf5ab575eabba4e458ffcac8086435d8bb3adc7e62f5dfa3712/diff",
"WorkDir": "/var/lib/docker/overlay2/dab5eef1d484caf5ab575eabba4e458ffcac8086435d8bb3adc7e62f5dfa3712/work"
},
"Name": "overlay2"
},
"Mounts": [
{
"Type": "bind",
"Source": "/lib/modules",
"Destination": "/lib/modules",
"Mode": "ro",
"RW": false,
"Propagation": "rprivate"
},
{
"Type": "volume",
"Name": "addons-20201109132301-342799",
"Source": "/var/lib/docker/volumes/addons-20201109132301-342799/_data",
"Destination": "/var",
"Driver": "local",
"Mode": "z",
"RW": true,
"Propagation": ""
}
],
"Config": {
"Hostname": "addons-20201109132301-342799",
"Domainname": "",
"User": "root",
"AttachStdin": false,
"AttachStdout": false,
"AttachStderr": false,
"ExposedPorts": {
"22/tcp": {},
"2376/tcp": {},
"5000/tcp": {},
"8443/tcp": {}
},
"Tty": true,
"OpenStdin": false,
"StdinOnce": false,
"Env": [
"container=docker",
"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"
],
"Cmd": null,
"Image": "gcr.io/k8s-minikube/kicbase:v0.0.14-snapshot@sha256:1e303d96e9d72371235cb28ed77f9b3ba67fb4966085202238e635f1d80181f8",
"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": "addons-20201109132301-342799",
"name.minikube.sigs.k8s.io": "addons-20201109132301-342799",
"role.minikube.sigs.k8s.io": ""
},
"StopSignal": "SIGRTMIN+3"
},
"NetworkSettings": {
"Bridge": "",
"SandboxID": "a351502a35abc3394fd8a69013af684e9f5543df76a2a09d0059abd567bf85d8",
"HairpinMode": false,
"LinkLocalIPv6Address": "",
"LinkLocalIPv6PrefixLen": 0,
"Ports": {
"22/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "32975"
}
],
"2376/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "32974"
}
],
"5000/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "32973"
}
],
"8443/tcp": [
{
"HostIp": "127.0.0.1",
"HostPort": "32972"
}
]
},
"SandboxKey": "/var/run/docker/netns/a351502a35ab",
"SecondaryIPAddresses": null,
"SecondaryIPv6Addresses": null,
"EndpointID": "",
"Gateway": "",
"GlobalIPv6Address": "",
"GlobalIPv6PrefixLen": 0,
"IPAddress": "",
"IPPrefixLen": 0,
"IPv6Gateway": "",
"MacAddress": "",
"Networks": {
"addons-20201109132301-342799": {
"IPAMConfig": {
"IPv4Address": "192.168.49.16"
},
"Links": null,
"Aliases": [
"05568404e6b0"
],
"NetworkID": "dba738592c7b0e3b1aadec675a6342fa7fe73c937a2ea48aacf065c2fb880e96",
"EndpointID": "f2a2450cc0d221e9495a0c83db22099bd0ad19412a5fa7a2ed3fa0c54768bc59",
"Gateway": "192.168.49.1",
"IPAddress": "192.168.49.16",
"IPPrefixLen": 24,
"IPv6Gateway": "",
"GlobalIPv6Address": "",
"GlobalIPv6PrefixLen": 0,
"MacAddress": "02:42:c0:a8:31:10",
"DriverOpts": null
}
}
}
}
]
-- /stdout --
helpers_test.go:233: (dbg) Run: out/minikube-linux-amd64 status --format={{.Host}} -p addons-20201109132301-342799 -n addons-20201109132301-342799
helpers_test.go:233: (dbg) Done: out/minikube-linux-amd64 status --format={{.Host}} -p addons-20201109132301-342799 -n addons-20201109132301-342799: (2.740199169s)
helpers_test.go:238: <<< TestAddons/parallel/Registry FAILED: start of post-mortem logs <<<
helpers_test.go:239: ======> post-mortem[TestAddons/parallel/Registry]: minikube logs <======
helpers_test.go:241: (dbg) Run: out/minikube-linux-amd64 -p addons-20201109132301-342799 logs -n 25
=== CONT TestAddons/parallel/Registry
helpers_test.go:241: (dbg) Done: out/minikube-linux-amd64 -p addons-20201109132301-342799 logs -n 25: (12.412906759s)
helpers_test.go:246: TestAddons/parallel/Registry logs:
-- stdout --
* ==> Docker <==
* -- Logs begin at Mon 2020-11-09 21:23:04 UTC, end at Mon 2020-11-09 21:26:04 UTC. --
* Nov 09 21:24:07 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:07.793642306Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:24:08 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:08.681175108Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:24:08 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:08.794992148Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:24:08 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:08.893914906Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:24:09 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:09.970462409Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:24:12 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:12.673423007Z" level=error msg="stream copy error: reading from a closed fifo"
* Nov 09 21:24:12 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:12.673586647Z" level=error msg="stream copy error: reading from a closed fifo"
* Nov 09 21:24:13 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:13.027814887Z" level=error msg="2e04aca88e96f715bb73e3f009c8d9fc9dad16a022687d685f4f8d224ec5cf05 cleanup: failed to delete container from containerd: no such container"
* Nov 09 21:24:13 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:13.027895568Z" level=error msg="Handler for POST /v1.40/containers/2e04aca88e96f715bb73e3f009c8d9fc9dad16a022687d685f4f8d224ec5cf05/start returned error: OCI runtime create failed: container_linux.go:349: starting container process caused \"process_linux.go:449: container init caused \\\"\\\"\": unknown"
* Nov 09 21:24:22 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:22.982205770Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:24:29 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:24:29.858486774Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:25:33 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:33.107107782Z" level=warning msg="Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap."
* Nov 09 21:25:33 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:33.193785235Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:25:52 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:52.313473061Z" level=error msg="stream copy error: reading from a closed fifo"
* Nov 09 21:25:52 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:52.357738088Z" level=error msg="92e456ec081489aeecf1ebb5cdd01d0abc780263a56cb340a29420ad6cbc7e68 cleanup: failed to delete container from containerd: no such container"
* Nov 09 21:25:52 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:52.357819444Z" level=error msg="Handler for POST /v1.40/containers/92e456ec081489aeecf1ebb5cdd01d0abc780263a56cb340a29420ad6cbc7e68/start returned error: OCI runtime create failed: container_linux.go:349: starting container process caused \"process_linux.go:449: container init caused \\\"rootfs_linux.go:58: mounting \\\\\\\"/var/lib/minikube/google_application_credentials.json\\\\\\\" to rootfs \\\\\\\"/var/lib/docker/overlay2/8954344407b890efb9e33682a8716dbd7d5922081f9d9979967ea660eb497bf8/merged\\\\\\\" at \\\\\\\"/google-app-creds.json\\\\\\\" caused \\\\\\\"stat /var/lib/minikube/google_application_credentials.json: no such file or directory\\\\\\\"\\\"\": unknown"
* Nov 09 21:25:56 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:56.786252891Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:25:56 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:56.885992637Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:25:57 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:57.688069873Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:25:57 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:57.689618430Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:25:57 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:25:57.892854769Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:26:02 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:26:02.987937730Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:26:03 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:26:03.068908023Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
* Nov 09 21:26:03 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:26:03.168036276Z" level=info msg="Container f70c7f7a07470763ab76d8e9e5fc48f3e49ae61ef5cba6efcc9c40b8d45199b5 failed to exit within 30 seconds of signal 15 - using the force"
* Nov 09 21:26:03 addons-20201109132301-342799 dockerd[669]: time="2020-11-09T21:26:03.591803040Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
*
* ==> container status <==
* CONTAINER IMAGE CREATED STATE NAME ATTEMPT POD ID
* 92e456ec08148 busybox@sha256:a9286defaba7b3a519d585ba0e37d0b2cbee74ebfe590960b0b1d6a5e97d1e1d 12 seconds ago Created registry-test 0 a5431e532e73a
* c7a63228099cd busybox@sha256:bda689514be526d9557ad442312e5d541757c453c50b8cf2ae68597c291385a1 21 seconds ago Running busybox 0 b29319af12bee
* d02981da985e5 quay.io/k8scsi/livenessprobe@sha256:dde617756e0f602adc566ab71fd885f1dad451ad3fb063ac991c95a2ff47aea5 27 seconds ago Running liveness-probe 0 7428ca744648e
* 338301c40c8f4 quay.io/operator-framework/olm@sha256:0d15ffb5d10a176ef6e831d7865f98d51255ea5b0d16403618c94a004d049373 27 seconds ago Running packageserver 1 942da155b3f30
* d5bca5462f58a quay.io/operator-framework/upstream-community-operators@sha256:abaa54d83d2825c7d2bc9367edbc1a3707df88e43ded36ff441398f23f030b6e 31 seconds ago Running registry-server 0 ec17478e3c745
* 70d50f434d6d6 quay.io/k8scsi/hostpathplugin@sha256:aa223f9df8c1d477a9f2a4a2a7d104561e6d365e54671aacbc770dffcc0683ad 52 seconds ago Running hostpath 0 7428ca744648e
* f70c7f7a07470 quay.io/operator-framework/olm@sha256:0d15ffb5d10a176ef6e831d7865f98d51255ea5b0d16403618c94a004d049373 53 seconds ago Exited packageserver 0 cd8a2d6aedd22
* cedf981027bca k8s.gcr.io/ingress-nginx/controller@sha256:46ba23c3fbaafd9e5bd01ea85b2f921d9f2217be082580edc22e6c704a83f02f 54 seconds ago Running controller 0 d2b360d3b9356
* dedb03463031b quay.io/operator-framework/olm@sha256:0d15ffb5d10a176ef6e831d7865f98d51255ea5b0d16403618c94a004d049373 54 seconds ago Exited packageserver 0 942da155b3f30
* c861c8efd87bb quay.io/operator-framework/olm@sha256:0d15ffb5d10a176ef6e831d7865f98d51255ea5b0d16403618c94a004d049373 54 seconds ago Running packageserver 0 aa3d3a549cf70
* 5179ea69fcca1 gcr.io/k8s-staging-sig-storage/csi-provisioner@sha256:8f36191970a82677ffe222007b08395dd7af0a5bb5b93db0e82523b43de2bfb2 About a minute ago Running csi-provisioner 0 1a919ea81e8a7
* 089fd703ac9bf quay.io/k8scsi/csi-snapshotter@sha256:35ead85dd09aa8cc612fdb598d4e0e2f048bef816f1b74df5eeab67cd21b10aa About a minute ago Running csi-snapshotter 0 d126e5bfe58b1
* 0a024f7ce677d quay.io/k8scsi/csi-attacher@sha256:8fcb9472310dd424c4da8ee06ff200b5e6f091dff39a079e470599e4d0dcf328 About a minute ago Running csi-attacher 0 6e38746223fe5
* 517c8ad369300 gcr.io/google_containers/kube-registry-proxy@sha256:1040f25a5273de0d72c54865a8efd47e3292de9fb8e5353e3fa76736b854f2da About a minute ago Exited registry-proxy 0 3ef068e4107c3
* 91ef93e61fd4f quay.io/k8scsi/csi-resizer@sha256:75ad39004ac49267981c9cb3323a7f73f0b203e1c181117363bf215e10144e8a About a minute ago Running csi-resizer 0 2babb38313a8a
* 3150d3c07e04c gcr.io/kubernetes-helm/tiller@sha256:6003775d503546087266eda39418d221f9afb5ccfe35f637c32a1161619a3f9c About a minute ago Running tiller 0 fa8c77396a9c0
* df950196bbe97 quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 About a minute ago Running node-driver-registrar 0 7428ca744648e
* 34ce9cf34424b 4d4f44df9f905 About a minute ago Exited patch 2 32b78f453a386
* 293c4db1c11a5 quay.io/operator-framework/olm@sha256:0d15ffb5d10a176ef6e831d7865f98d51255ea5b0d16403618c94a004d049373 About a minute ago Running catalog-operator 0 82d805fac726b
* 5dff05bbc9e94 registry.hub.docker.com/library/registry@sha256:8be26f81ffea54106bae012c6f349df70f4d5e7e2ec01b143c46e2c03b9e551d About a minute ago Exited registry 0 f02bfa54d9106
* 4560c5cb8d47b k8s.gcr.io/metrics-server-amd64@sha256:49a9f12f7067d11f42c803dbe61ed2c1299959ad85cb315b25ff7eef8e6b8892 About a minute ago Running metrics-server 0 c1d619218b777
* d932ee1dec2f3 quay.io/operator-framework/olm@sha256:0d15ffb5d10a176ef6e831d7865f98d51255ea5b0d16403618c94a004d049373 About a minute ago Running olm-operator 0 d853037b41e22
* fb8007c362513 jettech/kube-webhook-certgen@sha256:ff01fba91131ed260df3f3793009efbf9686f5a5ce78a85f81c386a4403f7689 About a minute ago Exited create 0 f7d55bcd63c71
* ec0ccc76e41a9 jettech/kube-webhook-certgen@sha256:ff01fba91131ed260df3f3793009efbf9686f5a5ce78a85f81c386a4403f7689 About a minute ago Exited patch 0 4e0406059a26d
* 4c1c3ed986eee jettech/kube-webhook-certgen@sha256:da8122a78d7387909cf34a0f34db0cce672da1379ee4fd57c626a4afe9ac12b7 About a minute ago Exited create 0 a5b40655675cc
* d0ee375969ff4 bad58561c4be7 2 minutes ago Running storage-provisioner 0 424442e55015d
* 4df414df190c1 gcr.io/k8s-staging-csi/snapshot-controller@sha256:9a44a869d23e42f5d7954c9a5c9ec1a76a0a5d6f23fce5e68e1232a017d3d38c 2 minutes ago Running volume-snapshot-controller 0 573db206850f7
* 5a9ac29e96e41 bfe3a36ebd252 2 minutes ago Running coredns 0 74f0243cb0bc6
* f6b75defdecc6 d373dd5a8593a 2 minutes ago Running kube-proxy 0 de0defa2a1c08
* 6a5979ff16b30 607331163122e 2 minutes ago Running kube-apiserver 0 871adcb247aa7
* ddd078525f2e4 8603821e1a7a5 2 minutes ago Running kube-controller-manager 0 5845cd99df907
* 9e0d25030346b 2f32d66b884f8 2 minutes ago Running kube-scheduler 0 df5c151cc2bec
* 338b62eadcf17 0369cf4303ffd 2 minutes ago Running etcd 0 83d131d562144
*
* ==> coredns [5a9ac29e96e4] <==
* .:53
* [INFO] plugin/reload: Running configuration MD5 = db32ca3650231d74073ff4cf814959a7
* CoreDNS-1.7.0
* linux/amd64, go1.14.4, f59c03d
*
* ==> describe nodes <==
* Name: addons-20201109132301-342799
* Roles: master
* Labels: beta.kubernetes.io/arch=amd64
* beta.kubernetes.io/os=linux
* kubernetes.io/arch=amd64
* kubernetes.io/hostname=addons-20201109132301-342799
* kubernetes.io/os=linux
* minikube.k8s.io/commit=21ac2a6a37964be4739a8be2fb5a50a8d224597d
* minikube.k8s.io/name=addons-20201109132301-342799
* minikube.k8s.io/updated_at=2020_11_09T13_23_33_0700
* minikube.k8s.io/version=v1.14.2
* node-role.kubernetes.io/master=
* topology.hostpath.csi/node=addons-20201109132301-342799
* Annotations: csi.volume.kubernetes.io/nodeid: {"hostpath.csi.k8s.io":"addons-20201109132301-342799"}
* 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: Mon, 09 Nov 2020 21:23:30 +0000
* Taints: <none>
* Unschedulable: false
* Lease:
* HolderIdentity: addons-20201109132301-342799
* AcquireTime: <unset>
* RenewTime: Mon, 09 Nov 2020 21:25:58 +0000
* Conditions:
* Type Status LastHeartbeatTime LastTransitionTime Reason Message
* ---- ------ ----------------- ------------------ ------ -------
* MemoryPressure False Mon, 09 Nov 2020 21:25:48 +0000 Mon, 09 Nov 2020 21:23:26 +0000 KubeletHasSufficientMemory kubelet has sufficient memory available
* DiskPressure False Mon, 09 Nov 2020 21:25:48 +0000 Mon, 09 Nov 2020 21:23:26 +0000 KubeletHasNoDiskPressure kubelet has no disk pressure
* PIDPressure False Mon, 09 Nov 2020 21:25:48 +0000 Mon, 09 Nov 2020 21:23:26 +0000 KubeletHasSufficientPID kubelet has sufficient PID available
* Ready True Mon, 09 Nov 2020 21:25:48 +0000 Mon, 09 Nov 2020 21:23:45 +0000 KubeletReady kubelet is posting ready status
* Addresses:
* InternalIP: 192.168.49.16
* Hostname: addons-20201109132301-342799
* Capacity:
* cpu: 8
* ephemeral-storage: 515928484Ki
* hugepages-1Gi: 0
* hugepages-2Mi: 0
* memory: 30887000Ki
* pods: 110
* Allocatable:
* cpu: 8
* ephemeral-storage: 515928484Ki
* hugepages-1Gi: 0
* hugepages-2Mi: 0
* memory: 30887000Ki
* pods: 110
* System Info:
* Machine ID: a4c2a4e521d240fea258754d777b87c4
* System UUID: 892f015f-7314-4b78-be60-ccca57970c90
* Boot ID: 9ad1ab50-5be9-48e2-8ae1-dc31113bc120
* Kernel Version: 4.9.0-14-amd64
* OS Image: Ubuntu 20.04.1 LTS
* Operating System: linux
* Architecture: amd64
* Container Runtime Version: docker://19.3.13
* Kubelet Version: v1.19.2
* Kube-Proxy Version: v1.19.2
* Non-terminated Pods: (27 in total)
* Namespace Name CPU Requests CPU Limits Memory Requests Memory Limits AGE
* --------- ---- ------------ ---------- --------------- ------------- ---
* default busybox 0 (0%) 0 (0%) 0 (0%) 0 (0%) 28s
* default task-pv-pod 0 (0%) 0 (0%) 0 (0%) 0 (0%) 28s
* gcp-auth gcp-auth-74f9689fd7-h8p7p 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m23s
* kube-system coredns-f9fd979d6-6sj4j 100m (1%) 0 (0%) 70Mi (0%) 170Mi (0%) 2m20s
* kube-system csi-hostpath-attacher-0 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m22s
* kube-system csi-hostpath-provisioner-0 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m22s
* kube-system csi-hostpath-resizer-0 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m23s
* kube-system csi-hostpath-snapshotter-0 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m23s
* kube-system csi-hostpathplugin-0 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m22s
* kube-system etcd-addons-20201109132301-342799 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m32s
* kube-system ingress-nginx-controller-9bc9f8988-z6j2k 100m (1%) 0 (0%) 90Mi (0%) 0 (0%) 2m27s
* kube-system kube-apiserver-addons-20201109132301-342799 250m (3%) 0 (0%) 0 (0%) 0 (0%) 2m32s
* kube-system kube-controller-manager-addons-20201109132301-342799 200m (2%) 0 (0%) 0 (0%) 0 (0%) 2m31s
* kube-system kube-proxy-2f6bk 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m28s
* kube-system kube-scheduler-addons-20201109132301-342799 100m (1%) 0 (0%) 0 (0%) 0 (0%) 2m31s
* kube-system metrics-server-d9b576748-kl5sr 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m20s
* kube-system registry-proxy-js2w2 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m12s
* kube-system registry-wbts4 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m19s
* kube-system storage-provisioner 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m30s
* kube-system tiller-deploy-565984b594-frbdj 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m20s
* kube-system volume-snapshot-controller-0 0 (0%) 0 (0%) 0 (0%) 0 (0%) 2m27s
* olm catalog-operator-69c9c9d9bd-d7cnl 10m (0%) 0 (0%) 80Mi (0%) 0 (0%) 2m22s
* olm olm-operator-69fc5f5699-n6pwr 10m (0%) 0 (0%) 160Mi (0%) 0 (0%) 2m23s
* olm operatorhubio-catalog-xhkpn 10m (0%) 100m (1%) 50Mi (0%) 100Mi (0%) 106s
* olm packageserver-66bb6d45f7-8ct82 10m (0%) 0 (0%) 50Mi (0%) 0 (0%) 109s
* olm packageserver-6bcb8dd987-6ppbc 10m (0%) 0 (0%) 50Mi (0%) 0 (0%) 110s
* olm packageserver-6bcb8dd987-lxv8s 10m (0%) 0 (0%) 50Mi (0%) 0 (0%) 111s
* Allocated resources:
* (Total limits may be over 100 percent, i.e., overcommitted.)
* Resource Requests Limits
* -------- -------- ------
* cpu 810m (10%) 100m (1%)
* memory 600Mi (1%) 270Mi (0%)
* ephemeral-storage 0 (0%) 0 (0%)
* hugepages-1Gi 0 (0%) 0 (0%)
* hugepages-2Mi 0 (0%) 0 (0%)
* Events:
* Type Reason Age From Message
* ---- ------ ---- ---- -------
* Normal NodeHasSufficientMemory 2m44s (x5 over 2m44s) kubelet Node addons-20201109132301-342799 status is now: NodeHasSufficientMemory
* Normal NodeHasNoDiskPressure 2m44s (x4 over 2m44s) kubelet Node addons-20201109132301-342799 status is now: NodeHasNoDiskPressure
* Normal NodeHasSufficientPID 2m44s (x4 over 2m44s) kubelet Node addons-20201109132301-342799 status is now: NodeHasSufficientPID
* Normal Starting 2m33s kubelet Starting kubelet.
* Normal NodeHasSufficientMemory 2m33s kubelet Node addons-20201109132301-342799 status is now: NodeHasSufficientMemory
* Normal NodeHasNoDiskPressure 2m33s kubelet Node addons-20201109132301-342799 status is now: NodeHasNoDiskPressure
* Normal NodeHasSufficientPID 2m33s kubelet Node addons-20201109132301-342799 status is now: NodeHasSufficientPID
* Normal NodeNotReady 2m33s kubelet Node addons-20201109132301-342799 status is now: NodeNotReady
* Normal NodeAllocatableEnforced 2m32s kubelet Updated Node Allocatable limit across pods
* Normal Starting 2m24s kube-proxy Starting kube-proxy.
* Normal NodeReady 2m22s kubelet Node addons-20201109132301-342799 status is now: NodeReady
*
* ==> dmesg <==
* [ +0.000004] ll header: 00000000: ff ff ff ff ff ff 0a fe 8d 22 fe 64 08 06 .........".d..
* [Nov 9 21:20] cgroup: cgroup2: unknown option "nsdelegate"
* [ +0.259017] cgroup: cgroup2: unknown option "nsdelegate"
* [ +0.699115] cgroup: cgroup2: unknown option "nsdelegate"
* [Nov 9 21:22] IPv4: martian source 10.85.0.3 from 10.85.0.3, on dev cni0
* [ +0.000004] ll header: 00000000: ff ff ff ff ff ff 56 18 e6 be 5d 26 08 06 ......V...]&..
* [ +0.000011] IPv4: martian source 10.85.0.3 from 10.85.0.3, on dev eth0
* [ +0.000002] ll header: 00000000: ff ff ff ff ff ff 56 18 e6 be 5d 26 08 06 ......V...]&..
* [ +0.001476] IPv4: martian source 10.85.0.2 from 10.85.0.2, on dev cni0
* [ +0.000003] ll header: 00000000: ff ff ff ff ff ff 26 2e b7 a0 21 84 08 06 ......&...!...
* [ +0.000009] IPv4: martian source 10.85.0.2 from 10.85.0.2, on dev eth0
* [ +0.000002] ll header: 00000000: ff ff ff ff ff ff 26 2e b7 a0 21 84 08 06 ......&...!...
* [ +4.233543] IPv4: martian source 10.85.0.2 from 10.85.0.2, on dev cni0
* [ +0.000003] ll header: 00000000: ff ff ff ff ff ff 66 aa 8e 8e 7d d3 08 06 ......f...}...
* [ +0.000007] IPv4: martian source 10.85.0.2 from 10.85.0.2, on dev eth0
* [ +0.000001] ll header: 00000000: ff ff ff ff ff ff 66 aa 8e 8e 7d d3 08 06 ......f...}...
* [ +0.280683] IPv4: martian source 10.85.0.3 from 10.85.0.3, on dev eth0
* [ +0.000009] ll header: 00000000: ff ff ff ff ff ff ae 6d 23 60 52 db 08 06 .......m#`R...
* [ +11.187645] IPv4: martian source 10.244.0.2 from 10.244.0.2, on dev veth42837a93
* [ +0.000003] ll header: 00000000: ff ff ff ff ff ff 36 2e b8 98 4d 23 08 06 ......6...M#..
* [ +13.774367] IPv4: martian source 10.85.0.4 from 10.85.0.4, on dev eth0
* [ +0.000003] ll header: 00000000: ff ff ff ff ff ff ca 28 f7 29 84 fa 08 06 .......(.)....
* [ +12.851572] IPv4: martian source 10.244.0.2 from 10.244.0.2, on dev vethd2fa70cc
* [ +0.000003] ll header: 00000000: ff ff ff ff ff ff 26 58 58 bd 5b 5c 08 06 ......&XX.[\..
* [Nov 9 21:23] cgroup: cgroup2: unknown option "nsdelegate"
*
* ==> etcd [338b62eadcf1] <==
* 2020-11-09 21:26:01.067791 W | etcdserver: read-only range request "key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3e8ff82ba4b\" " with result "range_response_count:1 size:823" took too long (293.930269ms) to execute
* 2020-11-09 21:26:01.069157 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/clusterserviceversions/olm/packageserver\" " with result "range_response_count:1 size:9920" took too long (104.308808ms) to execute
* 2020-11-09 21:26:01.275253 W | etcdserver: read-only range request "key:\"/registry/health\" " with result "range_response_count:0 size:5" took too long (106.784579ms) to execute
* 2020-11-09 21:26:01.359143 W | etcdserver: read-only range request "key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" " with result "range_response_count:1 size:795" took too long (185.452957ms) to execute
* 2020-11-09 21:26:01.380492 W | etcdserver: read-only range request "key:\"/registry/services/endpoints/kube-system/k8s.io-minikube-hostpath\" " with result "range_response_count:1 size:620" took too long (202.416109ms) to execute
* 2020-11-09 21:26:01.564105 W | etcdserver: request "header:<ID:12712383767199948166 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" mod_revision:729 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" > >>" with result "size:18" took too long (203.136444ms) to execute
* 2020-11-09 21:26:01.566050 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/operatorgroups/\" range_end:\"/registry/operators.coreos.com/operatorgroups0\" " with result "range_response_count:2 size:2446" took too long (392.352389ms) to execute
* 2020-11-09 21:26:01.785263 W | etcdserver: request "header:<ID:12712383767199948172 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" mod_revision:813 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" > >>" with result "size:18" took too long (127.326282ms) to execute
* 2020-11-09 21:26:01.971283 W | etcdserver: read-only range request "key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3ef5c8b0a3d\" " with result "range_response_count:1 size:829" took too long (112.162564ms) to execute
* 2020-11-09 21:26:01.973099 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/clusterserviceversions/olm/packageserver\" " with result "range_response_count:1 size:9920" took too long (112.628501ms) to execute
* 2020-11-09 21:26:02.063031 W | etcdserver: read-only range request "key:\"/registry/namespaces/default\" " with result "range_response_count:1 size:257" took too long (102.144654ms) to execute
* 2020-11-09 21:26:02.175066 W | etcdserver: read-only range request "key:\"/registry/csinodes\" range_end:\"/registry/csinodet\" count_only:true " with result "range_response_count:0 size:7" took too long (114.590342ms) to execute
* 2020-11-09 21:26:02.181783 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/operatorgroups/olm/\" range_end:\"/registry/operators.coreos.com/operatorgroups/olm0\" " with result "range_response_count:1 size:1332" took too long (109.082645ms) to execute
* 2020-11-09 21:26:02.266741 W | etcdserver: read-only range request "key:\"/registry/services/specs/default/kubernetes\" " with result "range_response_count:1 size:610" took too long (191.285101ms) to execute
* 2020-11-09 21:26:02.270940 W | etcdserver: read-only range request "key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c03a8256\" " with result "range_response_count:1 size:857" took too long (198.96592ms) to execute
* 2020-11-09 21:26:02.374817 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/operatorgroups/\" range_end:\"/registry/operators.coreos.com/operatorgroups0\" " with result "range_response_count:2 size:2446" took too long (108.57116ms) to execute
* 2020-11-09 21:26:02.578528 W | etcdserver: request "header:<ID:12712383767199948192 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" mod_revision:1110 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" > >>" with result "size:18" took too long (192.131347ms) to execute
* 2020-11-09 21:26:02.676434 W | etcdserver: read-only range request "key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" " with result "range_response_count:2 size:5355" took too long (110.36684ms) to execute
* 2020-11-09 21:26:03.086578 W | etcdserver: request "header:<ID:12712383767199948217 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" mod_revision:852 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" > >>" with result "size:18" took too long (109.298167ms) to execute
* 2020-11-09 21:26:03.116560 W | etcdserver: read-only range request "key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" " with result "range_response_count:20 size:88558" took too long (550.815058ms) to execute
* 2020-11-09 21:26:04.808865 W | etcdserver: read-only range request "key:\"/registry/pods/\" range_end:\"/registry/pods0\" " with result "range_response_count:29 size:132038" took too long (127.669266ms) to execute
* 2020-11-09 21:26:07.395129 W | etcdserver: read-only range request "key:\"/registry/events/\" range_end:\"/registry/events0\" " with result "range_response_count:221 size:180081" took too long (2.525012011s) to execute
* 2020-11-09 21:26:09.860457 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/operatorgroups/\" range_end:\"/registry/operators.coreos.com/operatorgroups0\" " with result "range_response_count:2 size:2446" took too long (180.444172ms) to execute
* 2020-11-09 21:26:10.383059 W | etcdserver: read-only range request "key:\"/registry/operators.coreos.com/operatorgroups/\" range_end:\"/registry/operators.coreos.com/operatorgroups0\" " with result "range_response_count:2 size:2446" took too long (101.331378ms) to execute
* 2020-11-09 21:26:10.859475 I | etcdserver/api/etcdhttp: /health OK (status code 200)
*
* ==> kernel <==
* 21:26:11 up 1:08, 0 users, load average: 2.69, 4.18, 6.16
* Linux addons-20201109132301-342799 4.9.0-14-amd64 #1 SMP Debian 4.9.240-2 (2020-10-30) x86_64 x86_64 x86_64 GNU/Linux
* PRETTY_NAME="Ubuntu 20.04.1 LTS"
*
* ==> kube-apiserver [6a5979ff16b3] <==
* I1109 21:25:54.478866 1 trace.go:205] Trace[2027621802]: "List etcd3" key:/pods/kube-system,resourceVersion:,resourceVersionMatch:,limit:0,continue: (09-Nov-2020 21:25:53.667) (total time: 810ms):
* Trace[2027621802]: [810.90739ms] [810.90739ms] END
* I1109 21:25:54.482503 1 trace.go:205] Trace[166959150]: "List" url:/api/v1/namespaces/kube-system/pods,user-agent:kubectl/v1.19.3 (linux/amd64) kubernetes/1e11e4a,client:192.168.49.1 (09-Nov-2020 21:25:53.667) (total time: 814ms):
* Trace[166959150]: ---"Listing from storage done" 810ms (21:25:00.478)
* Trace[166959150]: [814.558544ms] [814.558544ms] END
* I1109 21:25:59.073542 1 trace.go:205] Trace[89682460]: "Delete" url:/api/v1/namespaces/gcp-auth/secrets/default-token-n7txb,user-agent:kube-controller-manager/v1.19.2 (linux/amd64) kubernetes/f574309/tokens-controller,client:192.168.49.16 (09-Nov-2020 21:25:58.268) (total time: 805ms):
* Trace[89682460]: ---"Object deleted from database" 805ms (21:25:00.073)
* Trace[89682460]: [805.326949ms] [805.326949ms] END
* I1109 21:25:59.881290 1 client.go:360] parsed scheme: "passthrough"
* I1109 21:25:59.881393 1 passthrough.go:48] ccResolverWrapper: sending update to cc: {[{https://127.0.0.1:2379 <nil> 0 <nil>}] <nil> <nil>}
* I1109 21:25:59.881407 1 clientconn.go:948] ClientConn switching balancer to "pick_first"
* I1109 21:26:00.673717 1 trace.go:205] Trace[1335460069]: "List etcd3" key:/events/gcp-auth,resourceVersion:,resourceVersionMatch:,limit:0,continue: (09-Nov-2020 21:25:58.363) (total time: 2309ms):
* Trace[1335460069]: [2.309800445s] [2.309800445s] END
* I1109 21:26:03.160034 1 trace.go:205] Trace[819747574]: "List etcd3" key:/pods/kube-system,resourceVersion:,resourceVersionMatch:,limit:0,continue: (09-Nov-2020 21:26:02.564) (total time: 595ms):
* Trace[819747574]: [595.770632ms] [595.770632ms] END
* I1109 21:26:03.165560 1 trace.go:205] Trace[2145046238]: "List" url:/api/v1/namespaces/kube-system/pods,user-agent:kubectl/v1.19.3 (linux/amd64) kubernetes/1e11e4a,client:192.168.49.1 (09-Nov-2020 21:26:02.564) (total time: 599ms):
* Trace[2145046238]: ---"Listing from storage done" 595ms (21:26:00.160)
* Trace[2145046238]: [599.332158ms] [599.332158ms] END
* I1109 21:26:03.476565 1 trace.go:205] Trace[157952960]: "Delete" url:/apis/events.k8s.io/v1/namespaces/gcp-auth/events (09-Nov-2020 21:25:58.363) (total time: 5112ms):
* Trace[157952960]: [5.112782061s] [5.112782061s] END
* I1109 21:26:07.400313 1 trace.go:205] Trace[1448069030]: "List etcd3" key:/events,resourceVersion:,resourceVersionMatch:,limit:0,continue: (09-Nov-2020 21:26:04.869) (total time: 2530ms):
* Trace[1448069030]: [2.530825895s] [2.530825895s] END
* I1109 21:26:07.401038 1 trace.go:205] Trace[395661830]: "List" url:/api/v1/events,user-agent:kubectl/v1.19.2 (linux/amd64) kubernetes/f574309,client:127.0.0.1 (09-Nov-2020 21:26:04.869) (total time: 2531ms):
* Trace[395661830]: ---"Listing from storage done" 2530ms (21:26:00.400)
* Trace[395661830]: [2.53159701s] [2.53159701s] END
*
* ==> kube-controller-manager [ddd078525f2e] <==
* E1109 21:24:15.598447 1 memcache.go:101] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
* I1109 21:24:15.759379 1 event.go:291] "Event occurred" object="olm/packageserver" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set packageserver-6bcb8dd987 to 2"
* W1109 21:24:16.174829 1 garbagecollector.go:642] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
* E1109 21:24:16.258889 1 memcache.go:196] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
* I1109 21:24:16.789074 1 event.go:291] "Event occurred" object="olm/packageserver-6bcb8dd987" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: packageserver-6bcb8dd987-lxv8s"
* I1109 21:24:17.293896 1 event.go:291] "Event occurred" object="olm/packageserver" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set packageserver-66bb6d45f7 to 1"
* E1109 21:24:17.559449 1 memcache.go:196] couldn't get resource list for packages.operators.coreos.com/v1: the server could not find the requested resource
* E1109 21:24:17.759083 1 memcache.go:101] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
* I1109 21:24:17.760329 1 shared_informer.go:240] Waiting for caches to sync for garbage collector
* I1109 21:24:17.760393 1 shared_informer.go:247] Caches are synced for garbage collector
* I1109 21:24:17.813212 1 event.go:291] "Event occurred" object="olm/packageserver-6bcb8dd987" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: packageserver-6bcb8dd987-6ppbc"
* I1109 21:24:18.362300 1 event.go:291] "Event occurred" object="olm/packageserver-66bb6d45f7" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: packageserver-66bb6d45f7-8ct82"
* I1109 21:24:26.805943 1 event.go:291] "Event occurred" object="gcp-auth/gcp-auth-certs-patch" kind="Job" apiVersion="batch/v1" type="Normal" reason="Completed" message="Job completed"
* E1109 21:24:41.566834 1 resource_quota_controller.go:408] unable to retrieve the complete list of server APIs: packages.operators.coreos.com/v1: the server is currently unable to handle the request
* I1109 21:24:49.510631 1 request.go:645] Throttling request took 1.04838045s, request: GET:https://192.168.49.16:8443/apis/operators.coreos.com/v1?timeout=32s
* W1109 21:24:50.462247 1 garbagecollector.go:642] failed to discover some groups: map[packages.operators.coreos.com/v1:the server is currently unable to handle the request]
* E1109 21:25:12.168783 1 resource_quota_controller.go:408] unable to retrieve the complete list of server APIs: packages.operators.coreos.com/v1: the server is currently unable to handle the request
* I1109 21:25:22.257737 1 request.go:645] Throttling request took 1.091911962s, request: GET:https://192.168.49.16:8443/apis/networking.k8s.io/v1?timeout=32s
* W1109 21:25:23.177094 1 garbagecollector.go:642] failed to discover some groups: map[packages.operators.coreos.com/v1:the server is currently unable to handle the request]
* I1109 21:25:39.187410 1 event.go:291] "Event occurred" object="default/hpvc" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="ExternalProvisioning" message="waiting for a volume to be created, either by external provisioner \"hostpath.csi.k8s.io\" or manually created by system administrator"
* I1109 21:25:39.187451 1 event.go:291] "Event occurred" object="default/hpvc" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="ExternalProvisioning" message="waiting for a volume to be created, either by external provisioner \"hostpath.csi.k8s.io\" or manually created by system administrator"
* I1109 21:25:39.588185 1 reconciler.go:275] attacherDetacher.AttachVolume started for volume "pvc-0cf96e21-5e74-4972-a50d-167015748f6f" (UniqueName: "kubernetes.io/csi/hostpath.csi.k8s.io^1d208636-22d2-11eb-8601-0242ac11000d") from node "addons-20201109132301-342799"
* I1109 21:25:39.627725 1 operation_generator.go:361] AttachVolume.Attach succeeded for volume "pvc-0cf96e21-5e74-4972-a50d-167015748f6f" (UniqueName: "kubernetes.io/csi/hostpath.csi.k8s.io^1d208636-22d2-11eb-8601-0242ac11000d") from node "addons-20201109132301-342799"
* I1109 21:25:39.627881 1 event.go:291] "Event occurred" object="default/task-pv-pod" kind="Pod" apiVersion="v1" type="Normal" reason="SuccessfulAttachVolume" message="AttachVolume.Attach succeeded for volume \"pvc-0cf96e21-5e74-4972-a50d-167015748f6f\" "
* E1109 21:25:42.771490 1 resource_quota_controller.go:408] unable to retrieve the complete list of server APIs: packages.operators.coreos.com/v1: the server is currently unable to handle the request
*
* ==> kube-proxy [f6b75defdecc] <==
* I1109 21:23:43.568795 1 node.go:136] Successfully retrieved node IP: 192.168.49.16
* I1109 21:23:43.568966 1 server_others.go:111] kube-proxy node IP is an IPv4 address (192.168.49.16), assume IPv4 operation
* W1109 21:23:43.781526 1 server_others.go:579] Unknown proxy mode "", assuming iptables proxy
* I1109 21:23:43.781623 1 server_others.go:186] Using iptables Proxier.
* W1109 21:23:43.781642 1 server_others.go:456] detect-local-mode set to ClusterCIDR, but no cluster CIDR defined
* I1109 21:23:43.781647 1 server_others.go:467] detect-local-mode: ClusterCIDR , defaulting to no-op detect-local
* I1109 21:23:43.782066 1 server.go:650] Version: v1.19.2
* I1109 21:23:43.785657 1 conntrack.go:52] Setting nf_conntrack_max to 262144
* I1109 21:23:43.785796 1 conntrack.go:100] Set sysctl 'net/netfilter/nf_conntrack_tcp_timeout_established' to 86400
* I1109 21:23:43.785876 1 conntrack.go:100] Set sysctl 'net/netfilter/nf_conntrack_tcp_timeout_close_wait' to 3600
* I1109 21:23:43.786077 1 config.go:315] Starting service config controller
* I1109 21:23:43.786100 1 shared_informer.go:240] Waiting for caches to sync for service config
* I1109 21:23:43.786129 1 config.go:224] Starting endpoint slice config controller
* I1109 21:23:43.786138 1 shared_informer.go:240] Waiting for caches to sync for endpoint slice config
* I1109 21:23:43.886340 1 shared_informer.go:247] Caches are synced for service config
* I1109 21:23:43.886398 1 shared_informer.go:247] Caches are synced for endpoint slice config
*
* ==> kube-scheduler [9e0d25030346] <==
* I1109 21:23:30.180222 1 registry.go:173] Registering SelectorSpread plugin
* I1109 21:23:30.180259 1 registry.go:173] Registering SelectorSpread plugin
* I1109 21:23:30.184042 1 secure_serving.go:197] Serving securely on 127.0.0.1:10259
* I1109 21:23:30.258507 1 configmap_cafile_content.go:202] Starting client-ca::kube-system::extension-apiserver-authentication::client-ca-file
* I1109 21:23:30.258532 1 shared_informer.go:240] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
* I1109 21:23:30.258980 1 tlsconfig.go:240] Starting DynamicServingCertificateController
* E1109 21:23:30.261857 1 reflector.go:127] 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
* E1109 21:23:30.262018 1 reflector.go:127] 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
* E1109 21:23:30.262454 1 reflector.go:127] 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
* E1109 21:23:30.262684 1 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1beta1.PodDisruptionBudget: failed to list *v1beta1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
* E1109 21:23:30.262819 1 reflector.go:127] 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
* E1109 21:23:30.263078 1 reflector.go:127] 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
* E1109 21:23:30.264112 1 reflector.go:127] 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
* E1109 21:23:30.264182 1 reflector.go:127] k8s.io/apiserver/pkg/server/dynamiccertificates/configmap_cafile_content.go:206: 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"
* E1109 21:23:30.264348 1 reflector.go:127] 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
* E1109 21:23:30.264538 1 reflector.go:127] 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
* E1109 21:23:30.264593 1 reflector.go:127] 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
* E1109 21:23:30.264641 1 reflector.go:127] k8s.io/kubernetes/cmd/kube-scheduler/app/server.go:188: 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
* E1109 21:23:30.266387 1 reflector.go:127] 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
* E1109 21:23:31.142573 1 reflector.go:127] 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
* E1109 21:23:31.233302 1 reflector.go:127] 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
* E1109 21:23:31.316895 1 reflector.go:127] 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
* E1109 21:23:31.358734 1 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1beta1.PodDisruptionBudget: failed to list *v1beta1.PodDisruptionBudget: poddisruptionbudgets.policy is forbidden: User "system:kube-scheduler" cannot list resource "poddisruptionbudgets" in API group "policy" at the cluster scope
* E1109 21:23:31.478391 1 reflector.go:127] k8s.io/apiserver/pkg/server/dynamiccertificates/configmap_cafile_content.go:206: 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"
* I1109 21:23:34.758723 1 shared_informer.go:247] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
*
* ==> kubelet <==
* -- Logs begin at Mon 2020-11-09 21:23:04 UTC, end at Mon 2020-11-09 21:26:12 UTC. --
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: W1109 21:26:04.465263 2533 docker_sandbox.go:402] failed to read pod IP from plugin/docker: Couldn't find network status for olm/packageserver-66bb6d45f7-8ct82 through plugin: invalid network status for
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:04.567531 2533 topology_manager.go:219] [topologymanager] RemoveContainer - Container ID: 517c8ad3693001366728f69278940bfa3275acc5af0d1079b455487ee9dd82ed
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: E1109 21:26:04.574390 2533 remote_runtime.go:329] ContainerStatus "517c8ad3693001366728f69278940bfa3275acc5af0d1079b455487ee9dd82ed" from runtime service failed: rpc error: code = Unknown desc = Error: No such container: 517c8ad3693001366728f69278940bfa3275acc5af0d1079b455487ee9dd82ed
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: W1109 21:26:04.574443 2533 pod_container_deletor.go:52] [pod_container_deletor] DeleteContainer returned error for (id={docker 517c8ad3693001366728f69278940bfa3275acc5af0d1079b455487ee9dd82ed}): failed to get container status "517c8ad3693001366728f69278940bfa3275acc5af0d1079b455487ee9dd82ed": rpc error: code = Unknown desc = Error: No such container: 517c8ad3693001366728f69278940bfa3275acc5af0d1079b455487ee9dd82ed
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:04.627007 2533 topology_manager.go:219] [topologymanager] RemoveContainer - Container ID: 5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: W1109 21:26:04.668840 2533 docker_sandbox.go:402] failed to read pod IP from plugin/docker: Couldn't find network status for default/task-pv-pod through plugin: invalid network status for
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:04.675383 2533 reconciler.go:196] operationExecutor.UnmountVolume started for volume "default-token-74rlq" (UniqueName: "kubernetes.io/secret/8ab364e7-de9a-4498-a665-85e8ad7001b5-default-token-74rlq") pod "8ab364e7-de9a-4498-a665-85e8ad7001b5" (UID: "8ab364e7-de9a-4498-a665-85e8ad7001b5")
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:04.757780 2533 operation_generator.go:788] UnmountVolume.TearDown succeeded for volume "kubernetes.io/secret/8ab364e7-de9a-4498-a665-85e8ad7001b5-default-token-74rlq" (OuterVolumeSpecName: "default-token-74rlq") pod "8ab364e7-de9a-4498-a665-85e8ad7001b5" (UID: "8ab364e7-de9a-4498-a665-85e8ad7001b5"). InnerVolumeSpecName "default-token-74rlq". PluginName "kubernetes.io/secret", VolumeGidValue ""
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:04.759739 2533 topology_manager.go:219] [topologymanager] RemoveContainer - Container ID: 5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: E1109 21:26:04.760959 2533 remote_runtime.go:329] ContainerStatus "5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d" from runtime service failed: rpc error: code = Unknown desc = Error: No such container: 5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: W1109 21:26:04.761008 2533 pod_container_deletor.go:52] [pod_container_deletor] DeleteContainer returned error for (id={docker 5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d}): failed to get container status "5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d": rpc error: code = Unknown desc = Error: No such container: 5dff05bbc9e9433decf66c788969fa7dd2bc74c15d0a7742adce1fb06bcbd43d
* Nov 09 21:26:04 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:04.775902 2533 reconciler.go:319] Volume detached for volume "default-token-74rlq" (UniqueName: "kubernetes.io/secret/8ab364e7-de9a-4498-a665-85e8ad7001b5-default-token-74rlq") on node "addons-20201109132301-342799" DevicePath ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.683915 2533 reconciler.go:196] operationExecutor.UnmountVolume started for volume "default-token-74rlq" (UniqueName: "kubernetes.io/secret/8892c84c-3938-4ded-a15a-9333012648e2-default-token-74rlq") pod "8892c84c-3938-4ded-a15a-9333012648e2" (UID: "8892c84c-3938-4ded-a15a-9333012648e2")
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.684160 2533 reconciler.go:196] operationExecutor.UnmountVolume started for volume "default-token-n7txb" (UniqueName: "kubernetes.io/secret/3b43bf3b-441d-4814-99a9-da234703dfae-default-token-n7txb") pod "3b43bf3b-441d-4814-99a9-da234703dfae" (UID: "3b43bf3b-441d-4814-99a9-da234703dfae")
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.684203 2533 reconciler.go:196] operationExecutor.UnmountVolume started for volume "gcp-project" (UniqueName: "kubernetes.io/host-path/3b43bf3b-441d-4814-99a9-da234703dfae-gcp-project") pod "3b43bf3b-441d-4814-99a9-da234703dfae" (UID: "3b43bf3b-441d-4814-99a9-da234703dfae")
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.684303 2533 reconciler.go:196] operationExecutor.UnmountVolume started for volume "webhook-certs" (UniqueName: "kubernetes.io/secret/3b43bf3b-441d-4814-99a9-da234703dfae-webhook-certs") pod "3b43bf3b-441d-4814-99a9-da234703dfae" (UID: "3b43bf3b-441d-4814-99a9-da234703dfae")
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.684408 2533 operation_generator.go:788] UnmountVolume.TearDown succeeded for volume "kubernetes.io/host-path/3b43bf3b-441d-4814-99a9-da234703dfae-gcp-project" (OuterVolumeSpecName: "gcp-project") pod "3b43bf3b-441d-4814-99a9-da234703dfae" (UID: "3b43bf3b-441d-4814-99a9-da234703dfae"). InnerVolumeSpecName "gcp-project". PluginName "kubernetes.io/host-path", VolumeGidValue ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.760591 2533 operation_generator.go:788] UnmountVolume.TearDown succeeded for volume "kubernetes.io/secret/3b43bf3b-441d-4814-99a9-da234703dfae-webhook-certs" (OuterVolumeSpecName: "webhook-certs") pod "3b43bf3b-441d-4814-99a9-da234703dfae" (UID: "3b43bf3b-441d-4814-99a9-da234703dfae"). InnerVolumeSpecName "webhook-certs". PluginName "kubernetes.io/secret", VolumeGidValue ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.760746 2533 operation_generator.go:788] UnmountVolume.TearDown succeeded for volume "kubernetes.io/secret/3b43bf3b-441d-4814-99a9-da234703dfae-default-token-n7txb" (OuterVolumeSpecName: "default-token-n7txb") pod "3b43bf3b-441d-4814-99a9-da234703dfae" (UID: "3b43bf3b-441d-4814-99a9-da234703dfae"). InnerVolumeSpecName "default-token-n7txb". PluginName "kubernetes.io/secret", VolumeGidValue ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.784822 2533 reconciler.go:319] Volume detached for volume "default-token-n7txb" (UniqueName: "kubernetes.io/secret/3b43bf3b-441d-4814-99a9-da234703dfae-default-token-n7txb") on node "addons-20201109132301-342799" DevicePath ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.784872 2533 reconciler.go:319] Volume detached for volume "gcp-project" (UniqueName: "kubernetes.io/host-path/3b43bf3b-441d-4814-99a9-da234703dfae-gcp-project") on node "addons-20201109132301-342799" DevicePath ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.784896 2533 reconciler.go:319] Volume detached for volume "webhook-certs" (UniqueName: "kubernetes.io/secret/3b43bf3b-441d-4814-99a9-da234703dfae-webhook-certs") on node "addons-20201109132301-342799" DevicePath ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.859398 2533 operation_generator.go:788] UnmountVolume.TearDown succeeded for volume "kubernetes.io/secret/8892c84c-3938-4ded-a15a-9333012648e2-default-token-74rlq" (OuterVolumeSpecName: "default-token-74rlq") pod "8892c84c-3938-4ded-a15a-9333012648e2" (UID: "8892c84c-3938-4ded-a15a-9333012648e2"). InnerVolumeSpecName "default-token-74rlq". PluginName "kubernetes.io/secret", VolumeGidValue ""
* Nov 09 21:26:06 addons-20201109132301-342799 kubelet[2533]: I1109 21:26:06.885279 2533 reconciler.go:319] Volume detached for volume "default-token-74rlq" (UniqueName: "kubernetes.io/secret/8892c84c-3938-4ded-a15a-9333012648e2-default-token-74rlq") on node "addons-20201109132301-342799" DevicePath ""
* Nov 09 21:26:07 addons-20201109132301-342799 kubelet[2533]: W1109 21:26:07.992884 2533 docker_sandbox.go:402] failed to read pod IP from plugin/docker: Couldn't find network status for olm/packageserver-6bcb8dd987-lxv8s through plugin: invalid network status for
*
* ==> storage-provisioner [d0ee375969ff] <==
* I1109 21:24:04.372013 1 leaderelection.go:242] attempting to acquire leader lease kube-system/k8s.io-minikube-hostpath...
* I1109 21:24:04.460772 1 leaderelection.go:252] successfully acquired lease kube-system/k8s.io-minikube-hostpath
* I1109 21:24:04.461233 1 controller.go:799] Starting provisioner controller k8s.io/minikube-hostpath_addons-20201109132301-342799_388244a2-344c-4577-a94d-661729601e1e!
* I1109 21:24:04.461680 1 event.go:281] Event(v1.ObjectReference{Kind:"Endpoints", Namespace:"kube-system", Name:"k8s.io-minikube-hostpath", UID:"b8dd61cc-7749-4a36-b1c2-b9b3b8a8ec0e", APIVersion:"v1", ResourceVersion:"828", FieldPath:""}): type: 'Normal' reason: 'LeaderElection' addons-20201109132301-342799_388244a2-344c-4577-a94d-661729601e1e became leader
* I1109 21:24:04.561803 1 controller.go:848] Started provisioner controller k8s.io/minikube-hostpath_addons-20201109132301-342799_388244a2-344c-4577-a94d-661729601e1e!
-- /stdout --
** stderr **
E1109 13:26:11.014857 369719 out.go:286] unable to execute * 2020-11-09 21:26:01.564105 W | etcdserver: request "header:<ID:12712383767199948166 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" mod_revision:729 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" > >>" with result "size:18" took too long (203.136444ms) to execute
: html/template:* 2020-11-09 21:26:01.564105 W | etcdserver: request "header:<ID:12712383767199948166 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" mod_revision:729 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb53b04991\" > >>" with result "size:18" took too long (203.136444ms) to execute
: "\"" in attribute name: " username:\\\"kube-apiserver-etcd-" - returning raw string.
E1109 13:26:11.026034 369719 out.go:286] unable to execute * 2020-11-09 21:26:01.785263 W | etcdserver: request "header:<ID:12712383767199948172 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" mod_revision:813 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" > >>" with result "size:18" took too long (127.326282ms) to execute
: html/template:* 2020-11-09 21:26:01.785263 W | etcdserver: request "header:<ID:12712383767199948172 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" mod_revision:813 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3eb656994a8\" > >>" with result "size:18" took too long (127.326282ms) to execute
: "\"" in attribute name: " username:\\\"kube-apiserver-etcd-" - returning raw string.
E1109 13:26:11.055352 369719 out.go:286] unable to execute * 2020-11-09 21:26:02.578528 W | etcdserver: request "header:<ID:12712383767199948192 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" mod_revision:1110 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" > >>" with result "size:18" took too long (192.131347ms) to execute
: html/template:* 2020-11-09 21:26:02.578528 W | etcdserver: request "header:<ID:12712383767199948192 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" mod_revision:1110 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-74f9689fd7-h8p7p.1645f3f8c551cd70\" > >>" with result "size:18" took too long (192.131347ms) to execute
: "\"" in attribute name: " username:\\\"kube-apiserver-etcd-" - returning raw string.
E1109 13:26:11.066647 369719 out.go:286] unable to execute * 2020-11-09 21:26:03.086578 W | etcdserver: request "header:<ID:12712383767199948217 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" mod_revision:852 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" > >>" with result "size:18" took too long (109.298167ms) to execute
: html/template:* 2020-11-09 21:26:03.086578 W | etcdserver: request "header:<ID:12712383767199948217 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" mod_revision:852 > success:<request_delete_range:<key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" > > failure:<request_range:<key:\"/registry/events/gcp-auth/gcp-auth-certs-create-6ffdw.1645f3ee6b1bf292\" > >>" with result "size:18" took too long (109.298167ms) to execute
: "\"" in attribute name: " username:\\\"kube-apiserver-etcd-" - returning raw string.
** /stderr **
helpers_test.go:248: (dbg) Run: out/minikube-linux-amd64 status --format={{.APIServer}} -p addons-20201109132301-342799 -n addons-20201109132301-342799
=== CONT TestAddons/parallel/Registry
helpers_test.go:255: (dbg) Run: kubectl --context addons-20201109132301-342799 get po -o=jsonpath={.items[*].metadata.name} -A --field-selector=status.phase!=Running
helpers_test.go:261: non-running pods: task-pv-pod gcp-auth-74f9689fd7-h8p7p ingress-nginx-admission-create-czqhj ingress-nginx-admission-patch-q9t46
helpers_test.go:263: ======> post-mortem[TestAddons/parallel/Registry]: describe non-running pods <======
helpers_test.go:266: (dbg) Run: kubectl --context addons-20201109132301-342799 describe pod task-pv-pod gcp-auth-74f9689fd7-h8p7p ingress-nginx-admission-create-czqhj ingress-nginx-admission-patch-q9t46
helpers_test.go:266: (dbg) Non-zero exit: kubectl --context addons-20201109132301-342799 describe pod task-pv-pod gcp-auth-74f9689fd7-h8p7p ingress-nginx-admission-create-czqhj ingress-nginx-admission-patch-q9t46: exit status 1 (415.438802ms)
-- stdout --
Name: task-pv-pod
Namespace: default
Priority: 0
Node: addons-20201109132301-342799/192.168.49.16
Start Time: Mon, 09 Nov 2020 13:25:39 -0800
Labels: app=task-pv-pod
Annotations: <none>
Status: Pending
IP:
IPs: <none>
Containers:
task-pv-container:
Container ID:
Image: nginx
Image ID:
Port: 80/TCP
Host Port: 0/TCP
State: Waiting
Reason: ContainerCreating
Ready: False
Restart Count: 0
Environment:
GOOGLE_APPLICATION_CREDENTIALS: /google-app-creds.json
PROJECT_ID: this_is_fake
GCP_PROJECT: this_is_fake
GCLOUD_PROJECT: this_is_fake
GOOGLE_CLOUD_PROJECT: this_is_fake
CLOUDSDK_CORE_PROJECT: this_is_fake
Mounts:
/google-app-creds.json from gcp-creds (ro)
/usr/share/nginx/html from task-pv-storage (rw)
/var/run/secrets/kubernetes.io/serviceaccount from default-token-q7wzn (ro)
Conditions:
Type Status
Initialized True
Ready False
ContainersReady False
PodScheduled True
Volumes:
task-pv-storage:
Type: PersistentVolumeClaim (a reference to a PersistentVolumeClaim in the same namespace)
ClaimName: hpvc
ReadOnly: false
default-token-q7wzn:
Type: Secret (a volume populated by a Secret)
SecretName: default-token-q7wzn
Optional: false
gcp-creds:
Type: HostPath (bare host directory volume)
Path: /var/lib/minikube/google_application_credentials.json
HostPathType: File
QoS Class: BestEffort
Node-Selectors: <none>
Tolerations: node.kubernetes.io/not-ready:NoExecute op=Exists for 300s
node.kubernetes.io/unreachable:NoExecute op=Exists for 300s
Events:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal Scheduled 35s default-scheduler Successfully assigned default/task-pv-pod to addons-20201109132301-342799
Normal SuccessfulAttachVolume 35s attachdetach-controller AttachVolume.Attach succeeded for volume "pvc-0cf96e21-5e74-4972-a50d-167015748f6f"
Normal Pulling 11s kubelet Pulling image "nginx"
-- /stdout --
** stderr **
Error from server (NotFound): pods "gcp-auth-74f9689fd7-h8p7p" not found
Error from server (NotFound): pods "ingress-nginx-admission-create-czqhj" not found
Error from server (NotFound): pods "ingress-nginx-admission-patch-q9t46" not found
** /stderr **
helpers_test.go:268: kubectl --context addons-20201109132301-342799 describe pod task-pv-pod gcp-auth-74f9689fd7-h8p7p ingress-nginx-admission-create-czqhj ingress-nginx-admission-patch-q9t46: exit status 1
--- FAIL: TestAddons/parallel/Registry (36.13s)