{"level":"warn","ts":"2026-01-27T19:44:50.135959Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T19:44:50.136201Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.17:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.17:2380","--initial-cluster=instance=https://199.19.213.17:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.17:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.17:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-27T19:44:50.136389Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T19:44:50.136419Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-01-27T19:44:50.136589Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-27T19:44:50.137416Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"]} {"level":"info","ts":"2026-01-27T19:44:50.13774Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.17:2380"],"listen-peer-urls":["https://199.19.213.17:2380"],"advertise-client-urls":["https://199.19.213.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.17:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-27T19:44:50.143011Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.352796ms"} {"level":"info","ts":"2026-01-27T19:44:50.177072Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"27c4b87f3ea730e3","cluster-id":"cdce983f4a7486fe"} {"level":"info","ts":"2026-01-27T19:44:50.177747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=()"} {"level":"info","ts":"2026-01-27T19:44:50.17783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 0"} {"level":"info","ts":"2026-01-27T19:44:50.17788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 27c4b87f3ea730e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-27T19:44:50.177922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 1"} {"level":"info","ts":"2026-01-27T19:44:50.178023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"warn","ts":"2026-01-27T19:44:50.199022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-27T19:44:50.206076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-27T19:44:50.212189Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-27T19:44:50.217522Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"27c4b87f3ea730e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-27T19:44:50.21878Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"27c4b87f3ea730e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-27T19:44:50.225357Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-27T19:44:50.225457Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-27T19:44:50.225507Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-27T19:44:50.225777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"info","ts":"2026-01-27T19:44:50.225914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdce983f4a7486fe","local-member-id":"27c4b87f3ea730e3","added-peer-id":"27c4b87f3ea730e3","added-peer-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-01-27T19:44:50.23968Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-27T19:44:50.240034Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"27c4b87f3ea730e3","initial-advertise-peer-urls":["https://199.19.213.17:2380"],"listen-peer-urls":["https://199.19.213.17:2380"],"advertise-client-urls":["https://199.19.213.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-27T19:44:50.240084Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-27T19:44:50.240198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-01-27T19:44:50.24027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-01-27T19:44:50.278649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 is starting a new election at term 1"} {"level":"info","ts":"2026-01-27T19:44:50.278741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-27T19:44:50.278773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgPreVoteResp from 27c4b87f3ea730e3 at term 1"} {"level":"info","ts":"2026-01-27T19:44:50.278825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became candidate at term 2"} {"level":"info","ts":"2026-01-27T19:44:50.278846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgVoteResp from 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-01-27T19:44:50.278869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became leader at term 2"} {"level":"info","ts":"2026-01-27T19:44:50.278897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 27c4b87f3ea730e3 elected leader 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-01-27T19:44:50.282038Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:44:50.28274Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"27c4b87f3ea730e3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.17:2379]}","request-path":"/0/members/27c4b87f3ea730e3/attributes","cluster-id":"cdce983f4a7486fe","publish-timeout":"7s"} {"level":"info","ts":"2026-01-27T19:44:50.282932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T19:44:50.283053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdce983f4a7486fe","local-member-id":"27c4b87f3ea730e3","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:44:50.283187Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:44:50.283568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T19:44:50.283613Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:44:50.283823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-27T19:44:50.283894Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-27T19:44:50.286699Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-27T19:44:50.289148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.17:2379"} {"level":"warn","ts":"2026-01-27T19:44:57.200242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.450128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-01-27T19:44:57.200323Z","caller":"traceutil/trace.go:171","msg":"trace[1993484090] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:308; }","duration":"463.618132ms","start":"2026-01-27T19:44:56.736689Z","end":"2026-01-27T19:44:57.200307Z","steps":["trace[1993484090] 'range keys from in-memory index tree' (duration: 463.352335ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:44:57.200357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:44:56.736666Z","time spent":"463.683554ms","remote":"127.0.0.1:54566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-01-27T19:44:57.200719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.243032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:44:57.200736Z","caller":"traceutil/trace.go:171","msg":"trace[2117294027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:308; }","duration":"193.291263ms","start":"2026-01-27T19:44:57.007438Z","end":"2026-01-27T19:44:57.20073Z","steps":["trace[2117294027] 'range keys from in-memory index tree' (duration: 193.129259ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:07.871939Z","caller":"traceutil/trace.go:171","msg":"trace[998803812] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"108.84377ms","start":"2026-01-27T19:45:07.763068Z","end":"2026-01-27T19:45:07.871912Z","steps":["trace[998803812] 'process raft request' (duration: 108.366918ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:13.375499Z","caller":"traceutil/trace.go:171","msg":"trace[885625019] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"250.770557ms","start":"2026-01-27T19:45:13.124664Z","end":"2026-01-27T19:45:13.375434Z","steps":["trace[885625019] 'process raft request' (duration: 250.372787ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:24.168863Z","caller":"traceutil/trace.go:171","msg":"trace[1026925987] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"166.602922ms","start":"2026-01-27T19:45:24.002233Z","end":"2026-01-27T19:45:24.168836Z","steps":["trace[1026925987] 'process raft request' (duration: 166.206672ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:25.92684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.322333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:45:25.926944Z","caller":"traceutil/trace.go:171","msg":"trace[1292388844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"227.527628ms","start":"2026-01-27T19:45:25.699396Z","end":"2026-01-27T19:45:25.926924Z","steps":["trace[1292388844] 'range keys from in-memory index tree' (duration: 227.231731ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:26.464467Z","caller":"traceutil/trace.go:171","msg":"trace[1321474726] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"197.990737ms","start":"2026-01-27T19:45:26.266449Z","end":"2026-01-27T19:45:26.46444Z","steps":["trace[1321474726] 'read index received' (duration: 197.757111ms)","trace[1321474726] 'applied index is now lower than readState.Index' (duration: 232.666µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:45:26.464773Z","caller":"traceutil/trace.go:171","msg":"trace[1652613851] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"287.252335ms","start":"2026-01-27T19:45:26.177514Z","end":"2026-01-27T19:45:26.464766Z","steps":["trace[1652613851] 'process raft request' (duration: 286.767604ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:26.465014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.546199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-27T19:45:26.465036Z","caller":"traceutil/trace.go:171","msg":"trace[965846441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"198.636202ms","start":"2026-01-27T19:45:26.266392Z","end":"2026-01-27T19:45:26.465028Z","steps":["trace[965846441] 'agreement among raft nodes before linearized reading' (duration: 198.518439ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:26.465148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.457035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-27T19:45:26.465163Z","caller":"traceutil/trace.go:171","msg":"trace[263913599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"190.486956ms","start":"2026-01-27T19:45:26.274671Z","end":"2026-01-27T19:45:26.465158Z","steps":["trace[263913599] 'agreement among raft nodes before linearized reading' (duration: 190.443625ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:26.79906Z","caller":"traceutil/trace.go:171","msg":"trace[227803397] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"326.194212ms","start":"2026-01-27T19:45:26.472835Z","end":"2026-01-27T19:45:26.799029Z","steps":["trace[227803397] 'process raft request' (duration: 326.013778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:26.799749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:26.472811Z","time spent":"326.330396ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:45:27.561708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.888117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:45:27.562127Z","caller":"traceutil/trace.go:171","msg":"trace[789817477] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"680.562963ms","start":"2026-01-27T19:45:26.881539Z","end":"2026-01-27T19:45:27.562102Z","steps":["trace[789817477] 'process raft request' (duration: 680.495953ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:27.562213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:26.881504Z","time spent":"680.664236ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:45:27.562419Z","caller":"traceutil/trace.go:171","msg":"trace[506423205] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.088254619s","start":"2026-01-27T19:45:26.474151Z","end":"2026-01-27T19:45:27.562405Z","steps":["trace[506423205] 'process raft request' (duration: 642.871256ms)","trace[506423205] 'compare' (duration: 443.480097ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:27.562472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:26.474145Z","time spent":"1.08829964s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:45:27.562596Z","caller":"traceutil/trace.go:171","msg":"trace[788028817] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"861.786767ms","start":"2026-01-27T19:45:26.700799Z","end":"2026-01-27T19:45:27.562585Z","steps":["trace[788028817] 'read index received' (duration: 98.489582ms)","trace[788028817] 'applied index is now lower than readState.Index' (duration: 763.295925ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:27.563336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.534185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:45:27.563384Z","caller":"traceutil/trace.go:171","msg":"trace[1620096232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"862.630677ms","start":"2026-01-27T19:45:26.700741Z","end":"2026-01-27T19:45:27.563372Z","steps":["trace[1620096232] 'agreement among raft nodes before linearized reading' (duration: 861.879309ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:27.563418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:26.700716Z","time spent":"862.696429ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:45:28.133594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.549972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:45:28.133935Z","caller":"traceutil/trace.go:171","msg":"trace[1519458182] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"508.794699ms","start":"2026-01-27T19:45:27.625116Z","end":"2026-01-27T19:45:28.133911Z","steps":["trace[1519458182] 'process raft request' (duration: 360.812736ms)","trace[1519458182] 'compare' (duration: 147.394338ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:28.134017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:27.625097Z","time spent":"508.877791ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:45:32.444594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.249711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6szn7\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-27T19:45:32.444671Z","caller":"traceutil/trace.go:171","msg":"trace[1024211687] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6szn7; range_end:; response_count:1; response_revision:542; }","duration":"119.383114ms","start":"2026-01-27T19:45:32.32527Z","end":"2026-01-27T19:45:32.444653Z","steps":["trace[1024211687] 'range keys from in-memory index tree' (duration: 119.097868ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:32.444761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.522458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-27T19:45:32.444859Z","caller":"traceutil/trace.go:171","msg":"trace[913580090] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:542; }","duration":"561.665242ms","start":"2026-01-27T19:45:31.883169Z","end":"2026-01-27T19:45:32.444834Z","steps":["trace[913580090] 'range keys from in-memory index tree' (duration: 561.407845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:32.444906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:31.883153Z","time spent":"561.742903ms","remote":"127.0.0.1:54474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-27T19:45:32.444965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.464936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-27T19:45:32.444989Z","caller":"traceutil/trace.go:171","msg":"trace[1275593858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"254.489366ms","start":"2026-01-27T19:45:32.190493Z","end":"2026-01-27T19:45:32.444982Z","steps":["trace[1275593858] 'range keys from in-memory index tree' (duration: 254.334532ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:34.759353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.844274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-27T19:45:34.75948Z","caller":"traceutil/trace.go:171","msg":"trace[579878720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"279.022798ms","start":"2026-01-27T19:45:34.480426Z","end":"2026-01-27T19:45:34.759449Z","steps":["trace[579878720] 'range keys from in-memory index tree' (duration: 278.620869ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:34.759893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.866599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-27T19:45:34.759927Z","caller":"traceutil/trace.go:171","msg":"trace[2140945516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"115.987312ms","start":"2026-01-27T19:45:34.64393Z","end":"2026-01-27T19:45:34.759917Z","steps":["trace[2140945516] 'range keys from in-memory index tree' (duration: 115.633093ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:47.030459Z","caller":"traceutil/trace.go:171","msg":"trace[1602277532] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"134.511129ms","start":"2026-01-27T19:45:46.895917Z","end":"2026-01-27T19:45:47.030428Z","steps":["trace[1602277532] 'process raft request' (duration: 134.332084ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:47.043002Z","caller":"traceutil/trace.go:171","msg":"trace[984401676] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"137.109412ms","start":"2026-01-27T19:45:46.90588Z","end":"2026-01-27T19:45:47.04299Z","steps":["trace[984401676] 'read index received' (duration: 124.92108ms)","trace[984401676] 'applied index is now lower than readState.Index' (duration: 12.187572ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:47.043239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.335047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:45:47.043265Z","caller":"traceutil/trace.go:171","msg":"trace[1946103922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"137.397768ms","start":"2026-01-27T19:45:46.905856Z","end":"2026-01-27T19:45:47.043254Z","steps":["trace[1946103922] 'agreement among raft nodes before linearized reading' (duration: 137.218804ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:47.043482Z","caller":"traceutil/trace.go:171","msg":"trace[1631323265] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"146.346002ms","start":"2026-01-27T19:45:46.897128Z","end":"2026-01-27T19:45:47.043474Z","steps":["trace[1631323265] 'process raft request' (duration: 145.793479ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:50.144615Z","caller":"traceutil/trace.go:171","msg":"trace[1715996830] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"138.511645ms","start":"2026-01-27T19:45:50.006086Z","end":"2026-01-27T19:45:50.144598Z","steps":["trace[1715996830] 'process raft request' (duration: 138.416782ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:51.167021Z","caller":"traceutil/trace.go:171","msg":"trace[2117418278] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"123.393353ms","start":"2026-01-27T19:45:51.043611Z","end":"2026-01-27T19:45:51.167005Z","steps":["trace[2117418278] 'process raft request' (duration: 123.27075ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:51.167427Z","caller":"traceutil/trace.go:171","msg":"trace[1945949778] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:747; }","duration":"116.055307ms","start":"2026-01-27T19:45:51.051366Z","end":"2026-01-27T19:45:51.167422Z","steps":["trace[1945949778] 'read index received' (duration: 116.050807ms)","trace[1945949778] 'applied index is now lower than readState.Index' (duration: 4.12µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:51.168324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.569898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-27T19:45:51.168348Z","caller":"traceutil/trace.go:171","msg":"trace[1249981844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"116.991199ms","start":"2026-01-27T19:45:51.05135Z","end":"2026-01-27T19:45:51.168341Z","steps":["trace[1249981844] 'agreement among raft nodes before linearized reading' (duration: 116.558719ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:51.602928Z","caller":"traceutil/trace.go:171","msg":"trace[980834642] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"424.898227ms","start":"2026-01-27T19:45:51.178003Z","end":"2026-01-27T19:45:51.602901Z","steps":["trace[980834642] 'process raft request' (duration: 338.50526ms)","trace[980834642] 'compare' (duration: 86.215183ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:51.60305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:51.17799Z","time spent":"424.999709ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:45:51.946845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.188386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:45:51.946944Z","caller":"traceutil/trace.go:171","msg":"trace[1731583924] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"480.824015ms","start":"2026-01-27T19:45:51.466104Z","end":"2026-01-27T19:45:51.946928Z","steps":["trace[1731583924] 'read index received' (duration: 50.411116ms)","trace[1731583924] 'applied index is now lower than readState.Index' (duration: 430.411589ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:51.947235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.104152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-01-27T19:45:51.947297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.015313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:45:51.947334Z","caller":"traceutil/trace.go:171","msg":"trace[1034041419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"245.104295ms","start":"2026-01-27T19:45:51.702218Z","end":"2026-01-27T19:45:51.947322Z","steps":["trace[1034041419] 'agreement among raft nodes before linearized reading' (duration: 245.050523ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:51.947329Z","caller":"traceutil/trace.go:171","msg":"trace[173342134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"481.257976ms","start":"2026-01-27T19:45:51.466055Z","end":"2026-01-27T19:45:51.947313Z","steps":["trace[173342134] 'agreement among raft nodes before linearized reading' (duration: 481.01415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:51.947399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:51.466035Z","time spent":"481.329118ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-27T19:45:51.948345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.470119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:45:51.948383Z","caller":"traceutil/trace.go:171","msg":"trace[2088509430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"199.244818ms","start":"2026-01-27T19:45:51.749128Z","end":"2026-01-27T19:45:51.948373Z","steps":["trace[2088509430] 'agreement among raft nodes before linearized reading' (duration: 198.366727ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:51.947669Z","caller":"traceutil/trace.go:171","msg":"trace[1666399021] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"768.283345ms","start":"2026-01-27T19:45:51.179367Z","end":"2026-01-27T19:45:51.94765Z","steps":["trace[1666399021] 'process raft request' (duration: 647.211647ms)","trace[1666399021] 'compare' (duration: 119.966291ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:51.949013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:51.179359Z","time spent":"769.569825ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:45:54.127291Z","caller":"traceutil/trace.go:171","msg":"trace[1029331634] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"517.573725ms","start":"2026-01-27T19:45:53.609692Z","end":"2026-01-27T19:45:54.127266Z","steps":["trace[1029331634] 'process raft request' (duration: 517.417661ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.127627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:53.609683Z","time spent":"517.772189ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:45:54.128063Z","caller":"traceutil/trace.go:171","msg":"trace[1029400396] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:757; }","duration":"430.112372ms","start":"2026-01-27T19:45:53.697941Z","end":"2026-01-27T19:45:54.128054Z","steps":["trace[1029400396] 'read index received' (duration: 430.107592ms)","trace[1029400396] 'applied index is now lower than readState.Index' (duration: 3.79µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:54.1282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.252745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:45:54.128269Z","caller":"traceutil/trace.go:171","msg":"trace[1505173488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"430.325328ms","start":"2026-01-27T19:45:53.697915Z","end":"2026-01-27T19:45:54.128241Z","steps":["trace[1505173488] 'agreement among raft nodes before linearized reading' (duration: 430.253646ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.128982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:53.697902Z","time spent":"431.069855ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-27T19:45:54.517315Z","caller":"traceutil/trace.go:171","msg":"trace[1015730569] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"389.132892ms","start":"2026-01-27T19:45:54.128144Z","end":"2026-01-27T19:45:54.517277Z","steps":["trace[1015730569] 'read index received' (duration: 388.805734ms)","trace[1015730569] 'applied index is now lower than readState.Index' (duration: 325.538µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:45:54.517479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.395067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.17\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-27T19:45:54.51754Z","caller":"traceutil/trace.go:171","msg":"trace[2121870758] range","detail":"{range_begin:/registry/masterleases/199.19.213.17; range_end:; response_count:1; response_revision:735; }","duration":"399.51355ms","start":"2026-01-27T19:45:54.118011Z","end":"2026-01-27T19:45:54.517524Z","steps":["trace[2121870758] 'agreement among raft nodes before linearized reading' (duration: 399.296685ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.517571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:54.117984Z","time spent":"399.582221ms","remote":"127.0.0.1:54388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.17\" "} {"level":"warn","ts":"2026-01-27T19:45:54.517631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.108415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-27T19:45:54.517672Z","caller":"traceutil/trace.go:171","msg":"trace[1378425459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"512.188006ms","start":"2026-01-27T19:45:54.005472Z","end":"2026-01-27T19:45:54.51766Z","steps":["trace[1378425459] 'agreement among raft nodes before linearized reading' (duration: 511.985131ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.51771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:54.005468Z","time spent":"512.233467ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-27T19:45:54.51782Z","caller":"traceutil/trace.go:171","msg":"trace[431277745] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"488.839287ms","start":"2026-01-27T19:45:54.028973Z","end":"2026-01-27T19:45:54.517812Z","steps":["trace[431277745] 'process raft request' (duration: 488.042607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.517893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:54.028962Z","time spent":"488.884449ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:45:54.518039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.580022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-01-27T19:45:54.518062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.051292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-27T19:45:54.518065Z","caller":"traceutil/trace.go:171","msg":"trace[1416065255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"516.624713ms","start":"2026-01-27T19:45:54.001432Z","end":"2026-01-27T19:45:54.518057Z","steps":["trace[1416065255] 'agreement among raft nodes before linearized reading' (duration: 516.547601ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:45:54.518081Z","caller":"traceutil/trace.go:171","msg":"trace[1989929154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"565.094312ms","start":"2026-01-27T19:45:53.95298Z","end":"2026-01-27T19:45:54.518074Z","steps":["trace[1989929154] 'agreement among raft nodes before linearized reading' (duration: 565.041611ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.518085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:54.001424Z","time spent":"516.657043ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:45:54.518097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:53.952966Z","time spent":"565.127083ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:45:54.518183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.457582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:45:54.518197Z","caller":"traceutil/trace.go:171","msg":"trace[123479514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"387.469172ms","start":"2026-01-27T19:45:54.130722Z","end":"2026-01-27T19:45:54.518192Z","steps":["trace[123479514] 'agreement among raft nodes before linearized reading' (duration: 387.433351ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:45:54.518216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:45:54.1307Z","time spent":"387.512894ms","remote":"127.0.0.1:54380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-27T19:46:05.082551Z","caller":"traceutil/trace.go:171","msg":"trace[1519249187] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"163.862711ms","start":"2026-01-27T19:46:04.918658Z","end":"2026-01-27T19:46:05.082521Z","steps":["trace[1519249187] 'process raft request' (duration: 163.700237ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:05.41634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.373198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-27T19:46:05.416443Z","caller":"traceutil/trace.go:171","msg":"trace[825989867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"183.553152ms","start":"2026-01-27T19:46:05.232865Z","end":"2026-01-27T19:46:05.416419Z","steps":["trace[825989867] 'range keys from in-memory index tree' (duration: 183.148753ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:06.506917Z","caller":"traceutil/trace.go:171","msg":"trace[139820698] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"252.616604ms","start":"2026-01-27T19:46:06.25428Z","end":"2026-01-27T19:46:06.506896Z","steps":["trace[139820698] 'read index received' (duration: 252.475561ms)","trace[139820698] 'applied index is now lower than readState.Index' (duration: 140.423µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:06.50704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.739857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:06.507059Z","caller":"traceutil/trace.go:171","msg":"trace[912181518] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:775; }","duration":"252.816999ms","start":"2026-01-27T19:46:06.254237Z","end":"2026-01-27T19:46:06.507054Z","steps":["trace[912181518] 'agreement among raft nodes before linearized reading' (duration: 252.735367ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:06.507336Z","caller":"traceutil/trace.go:171","msg":"trace[1862180528] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"310.990752ms","start":"2026-01-27T19:46:06.19634Z","end":"2026-01-27T19:46:06.50733Z","steps":["trace[1862180528] 'process raft request' (duration: 310.458419ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:06.507394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:06.196325Z","time spent":"311.026282ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:46:06.846059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.55917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:06.846135Z","caller":"traceutil/trace.go:171","msg":"trace[935664031] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:775; }","duration":"162.672013ms","start":"2026-01-27T19:46:06.683446Z","end":"2026-01-27T19:46:06.846118Z","steps":["trace[935664031] 'count revisions from in-memory index tree' (duration: 162.460938ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:06.846362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.820758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-01-27T19:46:06.84636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.744606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:46:06.846376Z","caller":"traceutil/trace.go:171","msg":"trace[964850081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"165.87368ms","start":"2026-01-27T19:46:06.680498Z","end":"2026-01-27T19:46:06.846372Z","steps":["trace[964850081] 'range keys from in-memory index tree' (duration: 165.637394ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:06.846381Z","caller":"traceutil/trace.go:171","msg":"trace[666294810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"165.776307ms","start":"2026-01-27T19:46:06.680598Z","end":"2026-01-27T19:46:06.846375Z","steps":["trace[666294810] 'range keys from in-memory index tree' (duration: 165.636283ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:06.846532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.616965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-27T19:46:06.846546Z","caller":"traceutil/trace.go:171","msg":"trace[1440952906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"186.667257ms","start":"2026-01-27T19:46:06.659874Z","end":"2026-01-27T19:46:06.846542Z","steps":["trace[1440952906] 'range keys from in-memory index tree' (duration: 186.526283ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:14.925969Z","caller":"traceutil/trace.go:171","msg":"trace[1061207944] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"227.31439ms","start":"2026-01-27T19:46:14.698628Z","end":"2026-01-27T19:46:14.925942Z","steps":["trace[1061207944] 'read index received' (duration: 227.073994ms)","trace[1061207944] 'applied index is now lower than readState.Index' (duration: 239.226µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:46:14.926125Z","caller":"traceutil/trace.go:171","msg":"trace[151709747] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"379.027701ms","start":"2026-01-27T19:46:14.54708Z","end":"2026-01-27T19:46:14.926108Z","steps":["trace[151709747] 'process raft request' (duration: 378.681092ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:14.926286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:14.547064Z","time spent":"379.089282ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:46:14.926426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.788842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:14.926497Z","caller":"traceutil/trace.go:171","msg":"trace[1121409341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"227.928305ms","start":"2026-01-27T19:46:14.698553Z","end":"2026-01-27T19:46:14.926481Z","steps":["trace[1121409341] 'agreement among raft nodes before linearized reading' (duration: 227.831172ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:15.100626Z","caller":"traceutil/trace.go:171","msg":"trace[291790276] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"164.64179ms","start":"2026-01-27T19:46:14.935971Z","end":"2026-01-27T19:46:15.100613Z","steps":["trace[291790276] 'process raft request' (duration: 83.470387ms)","trace[291790276] 'compare' (duration: 80.544677ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:46:15.10092Z","caller":"traceutil/trace.go:171","msg":"trace[158117761] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"164.772774ms","start":"2026-01-27T19:46:14.93614Z","end":"2026-01-27T19:46:15.100912Z","steps":["trace[158117761] 'process raft request' (duration: 164.013605ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:15.100263Z","caller":"traceutil/trace.go:171","msg":"trace[1340778155] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"164.051656ms","start":"2026-01-27T19:46:14.936186Z","end":"2026-01-27T19:46:15.100238Z","steps":["trace[1340778155] 'process raft request' (duration: 164.008595ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:15.39312Z","caller":"traceutil/trace.go:171","msg":"trace[1559317077] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"169.342132ms","start":"2026-01-27T19:46:15.22375Z","end":"2026-01-27T19:46:15.393092Z","steps":["trace[1559317077] 'process raft request' (duration: 142.233723ms)","trace[1559317077] 'compare' (duration: 26.799691ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:15.63358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.414781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-27T19:46:15.633649Z","caller":"traceutil/trace.go:171","msg":"trace[1288941844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"110.537114ms","start":"2026-01-27T19:46:15.523096Z","end":"2026-01-27T19:46:15.633633Z","steps":["trace[1288941844] 'range keys from in-memory index tree' (duration: 110.256127ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:16.287251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.019261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:16.287332Z","caller":"traceutil/trace.go:171","msg":"trace[2040995888] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:802; }","duration":"209.180915ms","start":"2026-01-27T19:46:16.078131Z","end":"2026-01-27T19:46:16.287312Z","steps":["trace[2040995888] 'count revisions from in-memory index tree' (duration: 208.91913ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:29.886266Z","caller":"traceutil/trace.go:171","msg":"trace[1332755361] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"118.502294ms","start":"2026-01-27T19:46:29.767748Z","end":"2026-01-27T19:46:29.88625Z","steps":["trace[1332755361] 'process raft request' (duration: 118.388671ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:32.541896Z","caller":"traceutil/trace.go:171","msg":"trace[1876940255] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"644.140545ms","start":"2026-01-27T19:46:31.897739Z","end":"2026-01-27T19:46:32.541879Z","steps":["trace[1876940255] 'process raft request' (duration: 644.030292ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:32.542007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:31.897716Z","time spent":"644.239646ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:46:32.801123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.297454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:32.8012Z","caller":"traceutil/trace.go:171","msg":"trace[796232967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"101.412276ms","start":"2026-01-27T19:46:32.699766Z","end":"2026-01-27T19:46:32.801178Z","steps":["trace[796232967] 'range keys from in-memory index tree' (duration: 101.233282ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:39.647554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.342913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:46:39.647729Z","caller":"traceutil/trace.go:171","msg":"trace[1160842970] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"421.085865ms","start":"2026-01-27T19:46:39.226601Z","end":"2026-01-27T19:46:39.647687Z","steps":["trace[1160842970] 'process raft request' (duration: 139.487967ms)","trace[1160842970] 'compare' (duration: 281.194669ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:39.647885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:39.226557Z","time spent":"421.239789ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:46:39.648102Z","caller":"traceutil/trace.go:171","msg":"trace[120706958] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"320.486548ms","start":"2026-01-27T19:46:39.327604Z","end":"2026-01-27T19:46:39.648091Z","steps":["trace[120706958] 'read index received' (duration: 38.533422ms)","trace[120706958] 'applied index is now lower than readState.Index' (duration: 281.952216ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:39.923641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.925823ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-27T19:46:39.923751Z","caller":"traceutil/trace.go:171","msg":"trace[678078473] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"275.607085ms","start":"2026-01-27T19:46:39.648125Z","end":"2026-01-27T19:46:39.923732Z","steps":["trace[678078473] 'read index received' (duration: 65.371µs)","trace[678078473] 'applied index is now lower than readState.Index' (duration: 275.540034ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:39.923859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.233988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-27T19:46:39.923938Z","caller":"traceutil/trace.go:171","msg":"trace[981133564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"596.35783ms","start":"2026-01-27T19:46:39.327557Z","end":"2026-01-27T19:46:39.923915Z","steps":["trace[981133564] 'agreement among raft nodes before linearized reading' (duration: 320.617861ms)","trace[981133564] 'range keys from in-memory index tree' (duration: 275.530094ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:39.923968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:39.327536Z","time spent":"596.425312ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-27T19:46:39.924112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.132848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:46:39.924155Z","caller":"traceutil/trace.go:171","msg":"trace[974603834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"556.19987ms","start":"2026-01-27T19:46:39.367947Z","end":"2026-01-27T19:46:39.924147Z","steps":["trace[974603834] 'agreement among raft nodes before linearized reading' (duration: 556.028906ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:39.924182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:39.367943Z","time spent":"556.232481ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:46:39.924259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.81723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:39.92428Z","caller":"traceutil/trace.go:171","msg":"trace[863994344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"225.297402ms","start":"2026-01-27T19:46:39.698977Z","end":"2026-01-27T19:46:39.924275Z","steps":["trace[863994344] 'agreement among raft nodes before linearized reading' (duration: 225.25358ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:39.924406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.513498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:46:39.924431Z","caller":"traceutil/trace.go:171","msg":"trace[260322918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"556.572969ms","start":"2026-01-27T19:46:39.367853Z","end":"2026-01-27T19:46:39.924426Z","steps":["trace[260322918] 'agreement among raft nodes before linearized reading' (duration: 556.511628ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:39.924444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:39.367831Z","time spent":"556.60964ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:46:41.086038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.379017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-27T19:46:41.086117Z","caller":"traceutil/trace.go:171","msg":"trace[1590212261] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:921; }","duration":"236.50923ms","start":"2026-01-27T19:46:40.849588Z","end":"2026-01-27T19:46:41.086097Z","steps":["trace[1590212261] 'count revisions from in-memory index tree' (duration: 236.288905ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:46.871517Z","caller":"traceutil/trace.go:171","msg":"trace[362628392] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"173.192023ms","start":"2026-01-27T19:46:46.698301Z","end":"2026-01-27T19:46:46.871494Z","steps":["trace[362628392] 'read index received' (duration: 172.971197ms)","trace[362628392] 'applied index is now lower than readState.Index' (duration: 220.086µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:46.871748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.415658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:46:46.871769Z","caller":"traceutil/trace.go:171","msg":"trace[1546712972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"173.479769ms","start":"2026-01-27T19:46:46.698283Z","end":"2026-01-27T19:46:46.871763Z","steps":["trace[1546712972] 'agreement among raft nodes before linearized reading' (duration: 173.296205ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:46.872151Z","caller":"traceutil/trace.go:171","msg":"trace[1552050504] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"187.886718ms","start":"2026-01-27T19:46:46.684256Z","end":"2026-01-27T19:46:46.872143Z","steps":["trace[1552050504] 'process raft request' (duration: 187.087998ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:48.254345Z","caller":"traceutil/trace.go:171","msg":"trace[933999359] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"159.374799ms","start":"2026-01-27T19:46:48.094944Z","end":"2026-01-27T19:46:48.254318Z","steps":["trace[933999359] 'read index received' (duration: 159.143613ms)","trace[933999359] 'applied index is now lower than readState.Index' (duration: 230.256µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:46:48.254554Z","caller":"traceutil/trace.go:171","msg":"trace[748025588] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"168.057978ms","start":"2026-01-27T19:46:48.086467Z","end":"2026-01-27T19:46:48.254525Z","steps":["trace[748025588] 'process raft request' (duration: 167.690219ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:48.256357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.363847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-27T19:46:48.256467Z","caller":"traceutil/trace.go:171","msg":"trace[1927601104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"161.53917ms","start":"2026-01-27T19:46:48.094916Z","end":"2026-01-27T19:46:48.256455Z","steps":["trace[1927601104] 'agreement among raft nodes before linearized reading' (duration: 159.498911ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:46:48.467572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.789124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:46:48.467915Z","caller":"traceutil/trace.go:171","msg":"trace[1021390015] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"367.616698ms","start":"2026-01-27T19:46:48.100271Z","end":"2026-01-27T19:46:48.467888Z","steps":["trace[1021390015] 'process raft request' (duration: 200.85552ms)","trace[1021390015] 'compare' (duration: 165.565989ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:46:48.468029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:46:48.10026Z","time spent":"367.726641ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:46:48.472798Z","caller":"traceutil/trace.go:171","msg":"trace[647142043] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"208.325191ms","start":"2026-01-27T19:46:48.264457Z","end":"2026-01-27T19:46:48.472782Z","steps":["trace[647142043] 'process raft request' (duration: 208.232379ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:56.570334Z","caller":"traceutil/trace.go:171","msg":"trace[1345487739] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"167.554917ms","start":"2026-01-27T19:46:56.402755Z","end":"2026-01-27T19:46:56.57031Z","steps":["trace[1345487739] 'process raft request' (duration: 167.117796ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:46:56.732766Z","caller":"traceutil/trace.go:171","msg":"trace[57805056] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"157.35171ms","start":"2026-01-27T19:46:56.575387Z","end":"2026-01-27T19:46:56.732738Z","steps":["trace[57805056] 'process raft request' (duration: 128.180115ms)","trace[57805056] 'compare' (duration: 28.743155ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:47:06.847498Z","caller":"traceutil/trace.go:171","msg":"trace[272761375] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"106.743468ms","start":"2026-01-27T19:47:06.740706Z","end":"2026-01-27T19:47:06.847449Z","steps":["trace[272761375] 'process raft request' (duration: 105.852977ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:22.67002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.841216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-27T19:50:22.670334Z","caller":"traceutil/trace.go:171","msg":"trace[1881773357] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1887; }","duration":"104.230315ms","start":"2026-01-27T19:50:22.566083Z","end":"2026-01-27T19:50:22.670313Z","steps":["trace[1881773357] 'range keys from in-memory index tree' (duration: 103.645421ms)"],"step_count":1}