{"level":"warn","ts":"2026-02-03T10:25:51.448122Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T10:25:51.448198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219: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.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-02-03T10:25:51.448251Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T10:25:51.448259Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-02-03T10:25:51.448379Z","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-02-03T10:25:51.449224Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-02-03T10:25:51.449305Z","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.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-02-03T10:25:51.455342Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.6565ms"} {"level":"info","ts":"2026-02-03T10:25:51.466145Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-02-03T10:25:51.466215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T10:25:51.466245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-02-03T10:25:51.466257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T10:25:51.466282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-02-03T10:25:51.466318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-02-03T10:25:51.476562Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T10:25:51.487014Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T10:25:51.49413Z","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-02-03T10:25:51.49843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T10:25:51.502921Z","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-02-03T10:25:51.50308Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T10:25:51.503097Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T10:25:51.503162Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-02-03T10:25:51.503167Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-02-03T10:25:51.503674Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T10:25:51.503796Z","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-02-03T10:25:51.503825Z","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-02-03T10:25:51.503831Z","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-02-03T10:25:51.50407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-02-03T10:25:51.504129Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-02-03T10:25:52.167899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T10:25:52.167938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T10:25:52.167958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-02-03T10:25:52.167968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-02-03T10:25:52.167974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-02-03T10:25:52.167981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-02-03T10:25:52.167991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-02-03T10:25:52.176559Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T10:25:52.17668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:25:52.17674Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:25:52.176965Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:25:52.17704Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T10:25:52.177107Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T10:25:52.182343Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-02-03T10:25:52.192868Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T10:25:52.216066Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:25:52.216137Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:25:52.216157Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-03T10:26:02.771706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.475944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-03T10:26:02.771913Z","caller":"traceutil/trace.go:171","msg":"trace[1791037863] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:394; }","duration":"121.73311ms","start":"2026-02-03T10:26:02.650166Z","end":"2026-02-03T10:26:02.771899Z","steps":["trace[1791037863] 'range keys from in-memory index tree' (duration: 121.346631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:03.81152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.445493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:03.811614Z","caller":"traceutil/trace.go:171","msg":"trace[2069335117] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"204.98102ms","start":"2026-02-03T10:26:03.606616Z","end":"2026-02-03T10:26:03.811597Z","steps":["trace[2069335117] 'process raft request' (duration: 103.841031ms)","trace[2069335117] 'compare' (duration: 99.650095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:04.366668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.616693ms","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-02-03T10:26:04.366745Z","caller":"traceutil/trace.go:171","msg":"trace[1375132357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"310.737446ms","start":"2026-02-03T10:26:04.05599Z","end":"2026-02-03T10:26:04.366728Z","steps":["trace[1375132357] 'range keys from in-memory index tree' (duration: 310.50661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:04.366797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:04.055972Z","time spent":"310.803157ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:26:04.367284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.515208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T10:26:04.367329Z","caller":"traceutil/trace.go:171","msg":"trace[1732052175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"115.598779ms","start":"2026-02-03T10:26:04.251719Z","end":"2026-02-03T10:26:04.367318Z","steps":["trace[1732052175] 'range keys from in-memory index tree' (duration: 115.421496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:04.367475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.972249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T10:26:04.367512Z","caller":"traceutil/trace.go:171","msg":"trace[1970955109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"106.063031ms","start":"2026-02-03T10:26:04.261441Z","end":"2026-02-03T10:26:04.367504Z","steps":["trace[1970955109] 'range keys from in-memory index tree' (duration: 105.883517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:09.776023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436237004860843,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:26:10.155129Z","caller":"traceutil/trace.go:171","msg":"trace[71110073] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"879.317868ms","start":"2026-02-03T10:26:09.275789Z","end":"2026-02-03T10:26:10.155107Z","steps":["trace[71110073] 'read index received' (duration: 879.160605ms)","trace[71110073] 'applied index is now lower than readState.Index' (duration: 156.413µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:10.155253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.441711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:10.155285Z","caller":"traceutil/trace.go:171","msg":"trace[869184436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"879.518013ms","start":"2026-02-03T10:26:09.275757Z","end":"2026-02-03T10:26:10.155275Z","steps":["trace[869184436] 'agreement among raft nodes before linearized reading' (duration: 879.432151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:10.155315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:09.275744Z","time spent":"879.564074ms","remote":"127.0.0.1:56338","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-02-03T10:26:10.155498Z","caller":"traceutil/trace.go:171","msg":"trace[1233958906] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"976.18914ms","start":"2026-02-03T10:26:09.179298Z","end":"2026-02-03T10:26:10.155487Z","steps":["trace[1233958906] 'process raft request' (duration: 975.681398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:10.155571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:09.179288Z","time spent":"976.234112ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:10.788069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436237004860847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:26:11.288238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436237004860847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:26:11.505932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296409683s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:26:11.506564Z","caller":"traceutil/trace.go:171","msg":"trace[65729506] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.33441916s","start":"2026-02-03T10:26:10.172121Z","end":"2026-02-03T10:26:11.50654Z","steps":["trace[65729506] 'process raft request' (duration: 1.334282317s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:11.50668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:10.172107Z","time spent":"1.334504943s","remote":"127.0.0.1:56752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:11.578987Z","caller":"traceutil/trace.go:171","msg":"trace[2066523962] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:432; }","duration":"1.290935637s","start":"2026-02-03T10:26:10.288028Z","end":"2026-02-03T10:26:11.578964Z","steps":["trace[2066523962] 'read index received' (duration: 1.218465072s)","trace[2066523962] 'applied index is now lower than readState.Index' (duration: 72.466325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:11.579153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291114181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:11.579172Z","caller":"traceutil/trace.go:171","msg":"trace[76059482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"1.291177743s","start":"2026-02-03T10:26:10.287987Z","end":"2026-02-03T10:26:11.579165Z","steps":["trace[76059482] 'agreement among raft nodes before linearized reading' (duration: 1.291091791s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:11.579193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:10.287958Z","time spent":"1.291230804s","remote":"127.0.0.1:56338","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-02-03T10:26:11.579414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169540305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-03T10:26:11.579432Z","caller":"traceutil/trace.go:171","msg":"trace[1849991999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"1.169592247s","start":"2026-02-03T10:26:10.409833Z","end":"2026-02-03T10:26:11.579426Z","steps":["trace[1849991999] 'agreement among raft nodes before linearized reading' (duration: 1.169553776s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:11.579444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:10.409813Z","time spent":"1.169627678s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:26:11.57949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.019017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-03T10:26:11.579544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157254754s","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-02-03T10:26:11.579558Z","caller":"traceutil/trace.go:171","msg":"trace[1186971921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"1.157288046s","start":"2026-02-03T10:26:10.422266Z","end":"2026-02-03T10:26:11.579554Z","steps":["trace[1186971921] 'agreement among raft nodes before linearized reading' (duration: 1.157266355s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:11.579571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:10.422255Z","time spent":"1.157314096s","remote":"127.0.0.1:56582","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":"info","ts":"2026-02-03T10:26:11.579578Z","caller":"traceutil/trace.go:171","msg":"trace[1174614967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"701.123859ms","start":"2026-02-03T10:26:10.878414Z","end":"2026-02-03T10:26:11.579538Z","steps":["trace[1174614967] 'agreement among raft nodes before linearized reading' (duration: 701.020747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:11.579621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:10.878396Z","time spent":"701.216011ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:26:11.579523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130545456s","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-02-03T10:26:11.579761Z","caller":"traceutil/trace.go:171","msg":"trace[1148659116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"1.130812741s","start":"2026-02-03T10:26:10.448943Z","end":"2026-02-03T10:26:11.579756Z","steps":["trace[1148659116] 'agreement among raft nodes before linearized reading' (duration: 1.130563176s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:11.579781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:10.448928Z","time spent":"1.130846483s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T10:26:29.692514Z","caller":"traceutil/trace.go:171","msg":"trace[27829388] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"307.508212ms","start":"2026-02-03T10:26:29.384981Z","end":"2026-02-03T10:26:29.692489Z","steps":["trace[27829388] 'process raft request' (duration: 233.560352ms)","trace[27829388] 'compare' (duration: 73.452607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:29.692659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:29.384968Z","time spent":"307.611875ms","remote":"127.0.0.1:56376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:29.740758Z","caller":"traceutil/trace.go:171","msg":"trace[1056189241] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"239.580221ms","start":"2026-02-03T10:26:29.501164Z","end":"2026-02-03T10:26:29.740744Z","steps":["trace[1056189241] 'process raft request' (duration: 239.496989ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:32.235562Z","caller":"traceutil/trace.go:171","msg":"trace[891950500] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:593; }","duration":"420.91296ms","start":"2026-02-03T10:26:31.814636Z","end":"2026-02-03T10:26:32.235549Z","steps":["trace[891950500] 'read index received' (duration: 22.365771ms)","trace[891950500] 'applied index is now lower than readState.Index' (duration: 398.546429ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:32.235775Z","caller":"traceutil/trace.go:171","msg":"trace[135339457] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"422.039426ms","start":"2026-02-03T10:26:31.813729Z","end":"2026-02-03T10:26:32.235769Z","steps":["trace[135339457] 'process raft request' (duration: 392.632236ms)","trace[135339457] 'compare' (duration: 29.059703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:32.235835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.813705Z","time spent":"422.085567ms","remote":"127.0.0.1:56582","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-02-03T10:26:32.235955Z","caller":"traceutil/trace.go:171","msg":"trace[1332657965] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"422.053917ms","start":"2026-02-03T10:26:31.813896Z","end":"2026-02-03T10:26:32.23595Z","steps":["trace[1332657965] 'process raft request' (duration: 421.613187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.235994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.813889Z","time spent":"422.075998ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:32.236064Z","caller":"traceutil/trace.go:171","msg":"trace[1089624966] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"408.863846ms","start":"2026-02-03T10:26:31.827183Z","end":"2026-02-03T10:26:32.236046Z","steps":["trace[1089624966] 'process raft request' (duration: 408.346773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.236088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.82716Z","time spent":"408.915277ms","remote":"127.0.0.1:56582","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-02-03T10:26:32.236187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.547526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:32.236219Z","caller":"traceutil/trace.go:171","msg":"trace[1607898263] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:577; }","duration":"421.583167ms","start":"2026-02-03T10:26:31.814616Z","end":"2026-02-03T10:26:32.236199Z","steps":["trace[1607898263] 'agreement among raft nodes before linearized reading' (duration: 421.551406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.236235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.814611Z","time spent":"421.620008ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:26:32.244477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.30041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:32.24452Z","caller":"traceutil/trace.go:171","msg":"trace[1193940291] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:578; }","duration":"428.360491ms","start":"2026-02-03T10:26:31.816152Z","end":"2026-02-03T10:26:32.244513Z","steps":["trace[1193940291] 'agreement among raft nodes before linearized reading' (duration: 428.29694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.244556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.816142Z","time spent":"428.408942ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-02-03T10:26:32.244692Z","caller":"traceutil/trace.go:171","msg":"trace[1592685810] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"397.000675ms","start":"2026-02-03T10:26:31.847686Z","end":"2026-02-03T10:26:32.244686Z","steps":["trace[1592685810] 'process raft request' (duration: 396.691748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.244722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.847678Z","time spent":"397.029726ms","remote":"127.0.0.1:56376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T10:26:32.244813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.55255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-03T10:26:32.244847Z","caller":"traceutil/trace.go:171","msg":"trace[1181634417] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"147.58887ms","start":"2026-02-03T10:26:32.097234Z","end":"2026-02-03T10:26:32.244823Z","steps":["trace[1181634417] 'agreement among raft nodes before linearized reading' (duration: 147.537909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.244931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.753147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:26:32.244944Z","caller":"traceutil/trace.go:171","msg":"trace[616008278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:578; }","duration":"229.796137ms","start":"2026-02-03T10:26:32.015144Z","end":"2026-02-03T10:26:32.24494Z","steps":["trace[616008278] 'agreement among raft nodes before linearized reading' (duration: 229.758176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.245018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.73522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-03T10:26:32.245035Z","caller":"traceutil/trace.go:171","msg":"trace[657715140] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"347.774951ms","start":"2026-02-03T10:26:31.897256Z","end":"2026-02-03T10:26:32.245031Z","steps":["trace[657715140] 'agreement among raft nodes before linearized reading' (duration: 347.73629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.245046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.897227Z","time spent":"347.816081ms","remote":"127.0.0.1:56376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:26:32.245104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.860152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:32.245119Z","caller":"traceutil/trace.go:171","msg":"trace[1766328333] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:578; }","duration":"428.889354ms","start":"2026-02-03T10:26:31.816226Z","end":"2026-02-03T10:26:32.245115Z","steps":["trace[1766328333] 'agreement among raft nodes before linearized reading' (duration: 428.866423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.245131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:31.816217Z","time spent":"428.910324ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-03T10:26:36.501277Z","caller":"traceutil/trace.go:171","msg":"trace[28939143] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"205.321538ms","start":"2026-02-03T10:26:36.295933Z","end":"2026-02-03T10:26:36.501254Z","steps":["trace[28939143] 'read index received' (duration: 205.134484ms)","trace[28939143] 'applied index is now lower than readState.Index' (duration: 185.664µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:36.501534Z","caller":"traceutil/trace.go:171","msg":"trace[344605262] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"206.054435ms","start":"2026-02-03T10:26:36.295469Z","end":"2026-02-03T10:26:36.501524Z","steps":["trace[344605262] 'process raft request' (duration: 205.655916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:36.501788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.814259ms","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-02-03T10:26:36.501821Z","caller":"traceutil/trace.go:171","msg":"trace[1407062023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"205.892311ms","start":"2026-02-03T10:26:36.295918Z","end":"2026-02-03T10:26:36.50181Z","steps":["trace[1407062023] 'agreement among raft nodes before linearized reading' (duration: 205.753408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:36.501989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.728263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T10:26:36.502016Z","caller":"traceutil/trace.go:171","msg":"trace[2015775582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"200.766444ms","start":"2026-02-03T10:26:36.301241Z","end":"2026-02-03T10:26:36.502007Z","steps":["trace[2015775582] 'agreement among raft nodes before linearized reading' (duration: 200.690692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:36.502213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.132022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:26:36.50224Z","caller":"traceutil/trace.go:171","msg":"trace[1941392897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:591; }","duration":"187.179283ms","start":"2026-02-03T10:26:36.315053Z","end":"2026-02-03T10:26:36.502232Z","steps":["trace[1941392897] 'agreement among raft nodes before linearized reading' (duration: 187.102581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:41.174976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.473535ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:26:46.791706Z","caller":"traceutil/trace.go:171","msg":"trace[458210274] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"140.866086ms","start":"2026-02-03T10:26:46.650823Z","end":"2026-02-03T10:26:46.791689Z","steps":["trace[458210274] 'process raft request' (duration: 111.043705ms)","trace[458210274] 'compare' (duration: 29.610986ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:47.079233Z","caller":"traceutil/trace.go:171","msg":"trace[46405527] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"106.771958ms","start":"2026-02-03T10:26:46.972423Z","end":"2026-02-03T10:26:47.079195Z","steps":["trace[46405527] 'process raft request' (duration: 105.790406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.819211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436237004861351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:26:47.991657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.266705ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:47.991742Z","caller":"traceutil/trace.go:171","msg":"trace[1217556786] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:667; }","duration":"370.360177ms","start":"2026-02-03T10:26:47.62137Z","end":"2026-02-03T10:26:47.99173Z","steps":["trace[1217556786] 'range keys from in-memory index tree' (duration: 370.257525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.991971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.224061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:47.992045Z","caller":"traceutil/trace.go:171","msg":"trace[1468582972] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"672.914175ms","start":"2026-02-03T10:26:47.319125Z","end":"2026-02-03T10:26:47.99204Z","steps":["trace[1468582972] 'read index received' (duration: 592.270713ms)","trace[1468582972] 'applied index is now lower than readState.Index' (duration: 80.642652ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:47.99218Z","caller":"traceutil/trace.go:171","msg":"trace[1159276630] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"886.758249ms","start":"2026-02-03T10:26:47.105414Z","end":"2026-02-03T10:26:47.992173Z","steps":["trace[1159276630] 'process raft request' (duration: 136.286493ms)","trace[1159276630] 'compare' (duration: 749.701909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:47.99222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:47.105405Z","time spent":"886.792969ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4718,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:47.99233Z","caller":"traceutil/trace.go:171","msg":"trace[1160026087] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"885.408187ms","start":"2026-02-03T10:26:47.106914Z","end":"2026-02-03T10:26:47.992322Z","steps":["trace[1160026087] 'process raft request' (duration: 885.09218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.992356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:47.106906Z","time spent":"885.437508ms","remote":"127.0.0.1:56402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T10:26:47.992463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.336034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-03T10:26:47.992483Z","caller":"traceutil/trace.go:171","msg":"trace[1013017903] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:669; }","duration":"673.387076ms","start":"2026-02-03T10:26:47.319091Z","end":"2026-02-03T10:26:47.992478Z","steps":["trace[1013017903] 'agreement among raft nodes before linearized reading' (duration: 673.345735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.992497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:47.319075Z","time spent":"673.418856ms","remote":"127.0.0.1:56514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-03T10:26:47.992593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.400126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-03T10:26:47.992609Z","caller":"traceutil/trace.go:171","msg":"trace[1858351341] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:669; }","duration":"673.427277ms","start":"2026-02-03T10:26:47.319177Z","end":"2026-02-03T10:26:47.992604Z","steps":["trace[1858351341] 'agreement among raft nodes before linearized reading' (duration: 673.395286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.992623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:47.319173Z","time spent":"673.446567ms","remote":"127.0.0.1:56514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-03T10:26:47.993249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.911308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-03T10:26:47.993298Z","caller":"traceutil/trace.go:171","msg":"trace[1824886387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:669; }","duration":"674.00839ms","start":"2026-02-03T10:26:47.319277Z","end":"2026-02-03T10:26:47.993285Z","steps":["trace[1824886387] 'agreement among raft nodes before linearized reading' (duration: 673.933418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.993321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:47.319229Z","time spent":"674.086052ms","remote":"127.0.0.1:56514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-02-03T10:26:48.564911Z","caller":"traceutil/trace.go:171","msg":"trace[790767964] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"526.935482ms","start":"2026-02-03T10:26:48.03796Z","end":"2026-02-03T10:26:48.564895Z","steps":["trace[790767964] 'process raft request' (duration: 526.84684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:48.565005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:48.037957Z","time spent":"526.997253ms","remote":"127.0.0.1:56582","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-02-03T10:26:48.565141Z","caller":"traceutil/trace.go:171","msg":"trace[194230592] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"527.225919ms","start":"2026-02-03T10:26:48.03791Z","end":"2026-02-03T10:26:48.565136Z","steps":["trace[194230592] 'process raft request' (duration: 517.415235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:48.56517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:48.037894Z","time spent":"527.26194ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:48.579743Z","caller":"traceutil/trace.go:171","msg":"trace[919010415] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:694; }","duration":"301.72431ms","start":"2026-02-03T10:26:48.278001Z","end":"2026-02-03T10:26:48.579725Z","steps":["trace[919010415] 'read index received' (duration: 277.311623ms)","trace[919010415] 'applied index is now lower than readState.Index' (duration: 24.412067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:48.579829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.818622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:48.579858Z","caller":"traceutil/trace.go:171","msg":"trace[1717433681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"301.874033ms","start":"2026-02-03T10:26:48.27797Z","end":"2026-02-03T10:26:48.579844Z","steps":["trace[1717433681] 'agreement among raft nodes before linearized reading' (duration: 301.810072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:48.579883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:48.277956Z","time spent":"301.922485ms","remote":"127.0.0.1:56338","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-02-03T10:26:48.580103Z","caller":"traceutil/trace.go:171","msg":"trace[1193345620] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"511.003239ms","start":"2026-02-03T10:26:48.069092Z","end":"2026-02-03T10:26:48.580096Z","steps":["trace[1193345620] 'process raft request' (duration: 510.540678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:48.580169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:48.069075Z","time spent":"511.05787ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4718,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:48.925173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.757454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:48.925414Z","caller":"traceutil/trace.go:171","msg":"trace[580356390] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:698; }","duration":"129.161659ms","start":"2026-02-03T10:26:48.796236Z","end":"2026-02-03T10:26:48.925397Z","steps":["trace[580356390] 'read index received' (duration: 38.361µs)","trace[580356390] 'applied index is now lower than readState.Index' (duration: 129.122308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:48.925565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.328932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:26:48.925598Z","caller":"traceutil/trace.go:171","msg":"trace[156615405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"129.391184ms","start":"2026-02-03T10:26:48.796197Z","end":"2026-02-03T10:26:48.925589Z","steps":["trace[156615405] 'agreement among raft nodes before linearized reading' (duration: 129.263121ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:48.925838Z","caller":"traceutil/trace.go:171","msg":"trace[1890646815] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"258.049772ms","start":"2026-02-03T10:26:48.667777Z","end":"2026-02-03T10:26:48.925827Z","steps":["trace[1890646815] 'process raft request' (duration: 63.576062ms)","trace[1890646815] 'compare' (duration: 193.436307ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:48.925958Z","caller":"traceutil/trace.go:171","msg":"trace[732579452] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"257.636742ms","start":"2026-02-03T10:26:48.668312Z","end":"2026-02-03T10:26:48.925948Z","steps":["trace[732579452] 'process raft request' (duration: 256.992538ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:48.926064Z","caller":"traceutil/trace.go:171","msg":"trace[1604271537] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"256.095077ms","start":"2026-02-03T10:26:48.669961Z","end":"2026-02-03T10:26:48.926056Z","steps":["trace[1604271537] 'process raft request' (duration: 255.406181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:49.251468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.502672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:49.252505Z","caller":"traceutil/trace.go:171","msg":"trace[918246173] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"320.667463ms","start":"2026-02-03T10:26:48.931589Z","end":"2026-02-03T10:26:49.252256Z","steps":["trace[918246173] 'process raft request' (duration: 195.133746ms)","trace[918246173] 'compare' (duration: 124.362509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:49.252601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:48.931574Z","time spent":"320.970789ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:52.751611Z","caller":"traceutil/trace.go:171","msg":"trace[1776044468] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"156.298799ms","start":"2026-02-03T10:26:52.595283Z","end":"2026-02-03T10:26:52.751581Z","steps":["trace[1776044468] 'process raft request' (duration: 155.874659ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:52.878213Z","caller":"traceutil/trace.go:171","msg":"trace[744335835] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"147.505419ms","start":"2026-02-03T10:26:52.730691Z","end":"2026-02-03T10:26:52.878197Z","steps":["trace[744335835] 'process raft request' (duration: 70.380508ms)","trace[744335835] 'compare' (duration: 76.890036ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:53.096967Z","caller":"traceutil/trace.go:171","msg":"trace[222932891] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"116.990192ms","start":"2026-02-03T10:26:52.979958Z","end":"2026-02-03T10:26:53.096948Z","steps":["trace[222932891] 'read index received' (duration: 59.892278ms)","trace[222932891] 'applied index is now lower than readState.Index' (duration: 57.097014ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:53.097134Z","caller":"traceutil/trace.go:171","msg":"trace[547423537] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"136.439385ms","start":"2026-02-03T10:26:52.960688Z","end":"2026-02-03T10:26:53.097128Z","steps":["trace[547423537] 'process raft request' (duration: 79.148807ms)","trace[547423537] 'compare' (duration: 56.542321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:53.097286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.332149ms","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-02-03T10:26:53.097305Z","caller":"traceutil/trace.go:171","msg":"trace[798249055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"117.399751ms","start":"2026-02-03T10:26:52.979901Z","end":"2026-02-03T10:26:53.0973Z","steps":["trace[798249055] 'agreement among raft nodes before linearized reading' (duration: 117.322049ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:53.635568Z","caller":"traceutil/trace.go:171","msg":"trace[545196031] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"535.069598ms","start":"2026-02-03T10:26:53.100471Z","end":"2026-02-03T10:26:53.635541Z","steps":["trace[545196031] 'process raft request' (duration: 472.444558ms)","trace[545196031] 'compare' (duration: 62.405865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:53.63577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:53.100459Z","time spent":"535.186261ms","remote":"127.0.0.1:56582","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-02-03T10:26:54.075686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.229758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:54.075812Z","caller":"traceutil/trace.go:171","msg":"trace[2049633329] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:750; }","duration":"808.372219ms","start":"2026-02-03T10:26:53.267424Z","end":"2026-02-03T10:26:54.075796Z","steps":["trace[2049633329] 'read index received' (duration: 305.504416ms)","trace[2049633329] 'applied index is now lower than readState.Index' (duration: 502.866443ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:54.07593Z","caller":"traceutil/trace.go:171","msg":"trace[1530549051] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"973.9623ms","start":"2026-02-03T10:26:53.101924Z","end":"2026-02-03T10:26:54.075886Z","steps":["trace[1530549051] 'process raft request' (duration: 662.466166ms)","trace[1530549051] 'compare' (duration: 311.055244ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:54.076018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:53.101917Z","time spent":"974.042522ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:54.076145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.743552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:54.07621Z","caller":"traceutil/trace.go:171","msg":"trace[1681248966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"711.857705ms","start":"2026-02-03T10:26:53.364336Z","end":"2026-02-03T10:26:54.076194Z","steps":["trace[1681248966] 'agreement among raft nodes before linearized reading' (duration: 711.744652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:54.076248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:53.36432Z","time spent":"711.920736ms","remote":"127.0.0.1:56338","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-02-03T10:26:54.07647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.040404ms","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-02-03T10:26:54.076507Z","caller":"traceutil/trace.go:171","msg":"trace[1478220596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"809.103636ms","start":"2026-02-03T10:26:53.267392Z","end":"2026-02-03T10:26:54.076495Z","steps":["trace[1478220596] 'agreement among raft nodes before linearized reading' (duration: 808.984933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:54.076539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:53.267376Z","time spent":"809.155786ms","remote":"127.0.0.1:56582","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-02-03T10:26:58.119065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.61909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:58.119121Z","caller":"traceutil/trace.go:171","msg":"trace[131044494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"242.716792ms","start":"2026-02-03T10:26:57.876392Z","end":"2026-02-03T10:26:58.119109Z","steps":["trace[131044494] 'range keys from in-memory index tree' (duration: 242.555959ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:00.283224Z","caller":"traceutil/trace.go:171","msg":"trace[973940526] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"147.366168ms","start":"2026-02-03T10:27:00.13584Z","end":"2026-02-03T10:27:00.283206Z","steps":["trace[973940526] 'process raft request' (duration: 147.253445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:07.042489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.643927ms","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-02-03T10:27:07.042541Z","caller":"traceutil/trace.go:171","msg":"trace[1600400105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"245.74903ms","start":"2026-02-03T10:27:06.796779Z","end":"2026-02-03T10:27:07.042528Z","steps":["trace[1600400105] 'range keys from in-memory index tree' (duration: 245.508705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:07.042694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.527374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:07.042713Z","caller":"traceutil/trace.go:171","msg":"trace[911820577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"184.574456ms","start":"2026-02-03T10:27:06.858133Z","end":"2026-02-03T10:27:07.042707Z","steps":["trace[911820577] 'range keys from in-memory index tree' (duration: 184.476623ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:08.479978Z","caller":"traceutil/trace.go:171","msg":"trace[2008248532] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"134.603988ms","start":"2026-02-03T10:27:08.345355Z","end":"2026-02-03T10:27:08.479959Z","steps":["trace[2008248532] 'process raft request' (duration: 67.321245ms)","trace[2008248532] 'compare' (duration: 66.944075ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:17.254728Z","caller":"traceutil/trace.go:171","msg":"trace[1816137209] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"136.276886ms","start":"2026-02-03T10:27:17.118434Z","end":"2026-02-03T10:27:17.254711Z","steps":["trace[1816137209] 'process raft request' (duration: 136.0214ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:18.657716Z","caller":"traceutil/trace.go:171","msg":"trace[930442062] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"291.882043ms","start":"2026-02-03T10:27:18.365813Z","end":"2026-02-03T10:27:18.657695Z","steps":["trace[930442062] 'process raft request' (duration: 291.7688ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:18.658028Z","caller":"traceutil/trace.go:171","msg":"trace[139528350] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:833; }","duration":"149.854636ms","start":"2026-02-03T10:27:18.508163Z","end":"2026-02-03T10:27:18.658017Z","steps":["trace[139528350] 'read index received' (duration: 149.849656ms)","trace[139528350] 'applied index is now lower than readState.Index' (duration: 4.03µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:18.658182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.016939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:27:18.659445Z","caller":"traceutil/trace.go:171","msg":"trace[216561660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"151.321838ms","start":"2026-02-03T10:27:18.50811Z","end":"2026-02-03T10:27:18.659432Z","steps":["trace[216561660] 'agreement among raft nodes before linearized reading' (duration: 149.970948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:18.997965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.679834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:27:18.998047Z","caller":"traceutil/trace.go:171","msg":"trace[1573485448] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"335.825224ms","start":"2026-02-03T10:27:18.662209Z","end":"2026-02-03T10:27:18.998034Z","steps":["trace[1573485448] 'process raft request' (duration: 45.012246ms)","trace[1573485448] 'compare' (duration: 290.593333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:18.998086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:18.662196Z","time spent":"335.871715ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:27:30.107134Z","caller":"traceutil/trace.go:171","msg":"trace[19337378] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"224.374083ms","start":"2026-02-03T10:27:29.882692Z","end":"2026-02-03T10:27:30.107066Z","steps":["trace[19337378] 'read index received' (duration: 224.24058ms)","trace[19337378] 'applied index is now lower than readState.Index' (duration: 132.843µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:30.10728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.573638ms","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-02-03T10:27:30.107302Z","caller":"traceutil/trace.go:171","msg":"trace[1159422112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"224.642669ms","start":"2026-02-03T10:27:29.882651Z","end":"2026-02-03T10:27:30.107294Z","steps":["trace[1159422112] 'agreement among raft nodes before linearized reading' (duration: 224.530787ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:30.107519Z","caller":"traceutil/trace.go:171","msg":"trace[332129215] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"416.028172ms","start":"2026-02-03T10:27:29.691483Z","end":"2026-02-03T10:27:30.107511Z","steps":["trace[332129215] 'process raft request' (duration: 415.49107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:30.107577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:29.691462Z","time spent":"416.074903ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:27:36.164674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:35.637634Z","time spent":"527.036964ms","remote":"127.0.0.1:56352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T10:27:36.164979Z","caller":"traceutil/trace.go:171","msg":"trace[2051737791] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:936; }","duration":"200.996973ms","start":"2026-02-03T10:27:35.963969Z","end":"2026-02-03T10:27:36.164966Z","steps":["trace[2051737791] 'read index received' (duration: 200.993463ms)","trace[2051737791] 'applied index is now lower than readState.Index' (duration: 2.7µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:36.165044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.060394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:27:36.165064Z","caller":"traceutil/trace.go:171","msg":"trace[1943188239] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:899; }","duration":"201.119925ms","start":"2026-02-03T10:27:35.963939Z","end":"2026-02-03T10:27:36.165058Z","steps":["trace[1943188239] 'agreement among raft nodes before linearized reading' (duration: 201.063064ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:36.219538Z","caller":"traceutil/trace.go:171","msg":"trace[1848106347] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"116.526096ms","start":"2026-02-03T10:27:36.102988Z","end":"2026-02-03T10:27:36.219514Z","steps":["trace[1848106347] 'process raft request' (duration: 116.382853ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:36.419639Z","caller":"traceutil/trace.go:171","msg":"trace[2143089579] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"212.568895ms","start":"2026-02-03T10:27:36.20705Z","end":"2026-02-03T10:27:36.419619Z","steps":["trace[2143089579] 'read index received' (duration: 13.099539ms)","trace[2143089579] 'applied index is now lower than readState.Index' (duration: 199.468346ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:36.419664Z","caller":"traceutil/trace.go:171","msg":"trace[1599417645] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"254.259785ms","start":"2026-02-03T10:27:36.165375Z","end":"2026-02-03T10:27:36.419634Z","steps":["trace[1599417645] 'process raft request' (duration: 173.177197ms)","trace[1599417645] 'compare' (duration: 80.956415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:36.419842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.774809ms","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-02-03T10:27:36.419868Z","caller":"traceutil/trace.go:171","msg":"trace[1198993220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"212.852371ms","start":"2026-02-03T10:27:36.207008Z","end":"2026-02-03T10:27:36.419861Z","steps":["trace[1198993220] 'agreement among raft nodes before linearized reading' (duration: 212.709738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:36.420084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.027621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:36.420105Z","caller":"traceutil/trace.go:171","msg":"trace[1713392300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"140.094272ms","start":"2026-02-03T10:27:36.280005Z","end":"2026-02-03T10:27:36.420099Z","steps":["trace[1713392300] 'agreement among raft nodes before linearized reading' (duration: 139.99311ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:36.626993Z","caller":"traceutil/trace.go:171","msg":"trace[836716725] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"202.75753ms","start":"2026-02-03T10:27:36.42421Z","end":"2026-02-03T10:27:36.626967Z","steps":["trace[836716725] 'read index received' (duration: 130.734019ms)","trace[836716725] 'applied index is now lower than readState.Index' (duration: 72.022001ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:36.627255Z","caller":"traceutil/trace.go:171","msg":"trace[1897848155] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"203.362505ms","start":"2026-02-03T10:27:36.423875Z","end":"2026-02-03T10:27:36.627237Z","steps":["trace[1897848155] 'process raft request' (duration: 131.122828ms)","trace[1897848155] 'compare' (duration: 71.201153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:36.627449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.08203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:27:36.627569Z","caller":"traceutil/trace.go:171","msg":"trace[308827952] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:902; }","duration":"200.223203ms","start":"2026-02-03T10:27:36.427329Z","end":"2026-02-03T10:27:36.627552Z","steps":["trace[308827952] 'agreement among raft nodes before linearized reading' (duration: 199.998068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:36.627779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.563209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:36.627827Z","caller":"traceutil/trace.go:171","msg":"trace[2058764090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"203.629431ms","start":"2026-02-03T10:27:36.424189Z","end":"2026-02-03T10:27:36.627818Z","steps":["trace[2058764090] 'agreement among raft nodes before linearized reading' (duration: 203.564409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:36.627999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.115877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:36.628042Z","caller":"traceutil/trace.go:171","msg":"trace[1578510461] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:902; }","duration":"109.163108ms","start":"2026-02-03T10:27:36.518867Z","end":"2026-02-03T10:27:36.62803Z","steps":["trace[1578510461] 'agreement among raft nodes before linearized reading' (duration: 109.083757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:37.726943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.465504ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:37.727094Z","caller":"traceutil/trace.go:171","msg":"trace[1902299485] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:906; }","duration":"105.631407ms","start":"2026-02-03T10:27:37.621445Z","end":"2026-02-03T10:27:37.727076Z","steps":["trace[1902299485] 'range keys from in-memory index tree' (duration: 105.448303ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:38.749145Z","caller":"traceutil/trace.go:171","msg":"trace[1173103797] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"114.848697ms","start":"2026-02-03T10:27:38.63428Z","end":"2026-02-03T10:27:38.749129Z","steps":["trace[1173103797] 'process raft request' (duration: 114.736665ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:42.975179Z","caller":"traceutil/trace.go:171","msg":"trace[1313670936] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"210.506077ms","start":"2026-02-03T10:27:42.76465Z","end":"2026-02-03T10:27:42.975156Z","steps":["trace[1313670936] 'process raft request' (duration: 210.335593ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:42.975743Z","caller":"traceutil/trace.go:171","msg":"trace[764837223] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:956; }","duration":"128.107349ms","start":"2026-02-03T10:27:42.847623Z","end":"2026-02-03T10:27:42.97573Z","steps":["trace[764837223] 'read index received' (duration: 128.100999ms)","trace[764837223] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:42.975914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.185837ms","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-02-03T10:27:42.975956Z","caller":"traceutil/trace.go:171","msg":"trace[691817615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"123.248059ms","start":"2026-02-03T10:27:42.852699Z","end":"2026-02-03T10:27:42.975947Z","steps":["trace[691817615] 'agreement among raft nodes before linearized reading' (duration: 123.115436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:42.976114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.483067ms","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-02-03T10:27:42.976142Z","caller":"traceutil/trace.go:171","msg":"trace[1347948363] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:918; }","duration":"128.537159ms","start":"2026-02-03T10:27:42.847597Z","end":"2026-02-03T10:27:42.976134Z","steps":["trace[1347948363] 'agreement among raft nodes before linearized reading' (duration: 128.488858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:43.608425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.150587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:27:43.608483Z","caller":"traceutil/trace.go:171","msg":"trace[1564028890] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"468.1123ms","start":"2026-02-03T10:27:43.14036Z","end":"2026-02-03T10:27:43.608473Z","steps":["trace[1564028890] 'read index received' (duration: 24.101µs)","trace[1564028890] 'applied index is now lower than readState.Index' (duration: 468.087459ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:43.608598Z","caller":"traceutil/trace.go:171","msg":"trace[434667902] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"629.842286ms","start":"2026-02-03T10:27:42.978749Z","end":"2026-02-03T10:27:43.608592Z","steps":["trace[434667902] 'process raft request' (duration: 94.473724ms)","trace[434667902] 'compare' (duration: 534.803119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:43.608631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:42.97873Z","time spent":"629.882936ms","remote":"127.0.0.1:56582","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-02-03T10:27:43.60877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.409147ms","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-02-03T10:27:43.608784Z","caller":"traceutil/trace.go:171","msg":"trace[673743231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"468.448587ms","start":"2026-02-03T10:27:43.140331Z","end":"2026-02-03T10:27:43.608779Z","steps":["trace[673743231] 'agreement among raft nodes before linearized reading' (duration: 468.383726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:43.608799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.140318Z","time spent":"468.477328ms","remote":"127.0.0.1:56582","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-02-03T10:27:43.60889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.576647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:43.608903Z","caller":"traceutil/trace.go:171","msg":"trace[741837782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"324.616259ms","start":"2026-02-03T10:27:43.284283Z","end":"2026-02-03T10:27:43.608899Z","steps":["trace[741837782] 'agreement among raft nodes before linearized reading' (duration: 324.593858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:43.608915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.284269Z","time spent":"324.643449ms","remote":"127.0.0.1:56338","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-02-03T10:27:43.609068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.620696ms","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-02-03T10:27:43.609081Z","caller":"traceutil/trace.go:171","msg":"trace[1883833411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"112.649268ms","start":"2026-02-03T10:27:43.496428Z","end":"2026-02-03T10:27:43.609077Z","steps":["trace[1883833411] 'agreement among raft nodes before linearized reading' (duration: 112.605907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:43.609162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.284437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-03T10:27:43.609173Z","caller":"traceutil/trace.go:171","msg":"trace[1708427572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"122.321057ms","start":"2026-02-03T10:27:43.486849Z","end":"2026-02-03T10:27:43.60917Z","steps":["trace[1708427572] 'agreement among raft nodes before linearized reading' (duration: 122.286246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:43.609234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.753368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:43.609247Z","caller":"traceutil/trace.go:171","msg":"trace[517278723] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:919; }","duration":"323.792329ms","start":"2026-02-03T10:27:43.28545Z","end":"2026-02-03T10:27:43.609242Z","steps":["trace[517278723] 'agreement among raft nodes before linearized reading' (duration: 323.768849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:43.609259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.285438Z","time spent":"323.81779ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:27:44.031036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.254296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:27:44.031143Z","caller":"traceutil/trace.go:171","msg":"trace[1330813821] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"417.654429ms","start":"2026-02-03T10:27:43.613471Z","end":"2026-02-03T10:27:44.031125Z","steps":["trace[1330813821] 'process raft request' (duration: 295.257949ms)","trace[1330813821] 'compare' (duration: 122.172425ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:44.031194Z","caller":"traceutil/trace.go:171","msg":"trace[1150148763] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"409.553044ms","start":"2026-02-03T10:27:43.62163Z","end":"2026-02-03T10:27:44.031184Z","steps":["trace[1150148763] 'process raft request' (duration: 409.529273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:44.03123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.62162Z","time spent":"409.594785ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:27:44.031232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.613458Z","time spent":"417.72539ms","remote":"127.0.0.1:56582","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-02-03T10:27:44.031381Z","caller":"traceutil/trace.go:171","msg":"trace[2039781001] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"416.502252ms","start":"2026-02-03T10:27:43.614873Z","end":"2026-02-03T10:27:44.031375Z","steps":["trace[2039781001] 'process raft request' (duration: 416.236746ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:44.031412Z","caller":"traceutil/trace.go:171","msg":"trace[797340257] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"417.874654ms","start":"2026-02-03T10:27:43.613531Z","end":"2026-02-03T10:27:44.031405Z","steps":["trace[797340257] 'read index received' (duration: 295.204418ms)","trace[797340257] 'applied index is now lower than readState.Index' (duration: 122.669656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:44.031417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.614862Z","time spent":"416.536534ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:27:44.031471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.939415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:44.031487Z","caller":"traceutil/trace.go:171","msg":"trace[159896277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"417.985166ms","start":"2026-02-03T10:27:43.613497Z","end":"2026-02-03T10:27:44.031483Z","steps":["trace[159896277] 'agreement among raft nodes before linearized reading' (duration: 417.956535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:44.0315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:43.613483Z","time spent":"418.014777ms","remote":"127.0.0.1:56342","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-02-03T10:27:44.031594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.048368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:44.031606Z","caller":"traceutil/trace.go:171","msg":"trace[1033855130] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:922; }","duration":"293.089959ms","start":"2026-02-03T10:27:43.738513Z","end":"2026-02-03T10:27:44.031603Z","steps":["trace[1033855130] 'agreement among raft nodes before linearized reading' (duration: 293.068359ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:48.417821Z","caller":"traceutil/trace.go:171","msg":"trace[1312007138] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"373.266017ms","start":"2026-02-03T10:27:48.044537Z","end":"2026-02-03T10:27:48.417803Z","steps":["trace[1312007138] 'process raft request' (duration: 373.165935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:48.41795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:48.044518Z","time spent":"373.361289ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:27:48.418334Z","caller":"traceutil/trace.go:171","msg":"trace[754848197] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"373.526523ms","start":"2026-02-03T10:27:48.044798Z","end":"2026-02-03T10:27:48.418324Z","steps":["trace[754848197] 'read index received' (duration: 372.849078ms)","trace[754848197] 'applied index is now lower than readState.Index' (duration: 676.575µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:48.418441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.638175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T10:27:48.418461Z","caller":"traceutil/trace.go:171","msg":"trace[1609311499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"373.667276ms","start":"2026-02-03T10:27:48.044788Z","end":"2026-02-03T10:27:48.418455Z","steps":["trace[1609311499] 'agreement among raft nodes before linearized reading' (duration: 373.590794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:48.418479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:48.044783Z","time spent":"373.691446ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:27:48.420007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.884954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T10:27:48.420043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.671259ms","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-02-03T10:27:48.420067Z","caller":"traceutil/trace.go:171","msg":"trace[1981490281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"141.984277ms","start":"2026-02-03T10:27:48.27807Z","end":"2026-02-03T10:27:48.420054Z","steps":["trace[1981490281] 'agreement among raft nodes before linearized reading' (duration: 141.866344ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:48.420071Z","caller":"traceutil/trace.go:171","msg":"trace[299677791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"374.723591ms","start":"2026-02-03T10:27:48.04534Z","end":"2026-02-03T10:27:48.420063Z","steps":["trace[299677791] 'agreement among raft nodes before linearized reading' (duration: 374.580088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:48.420166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:48.045336Z","time spent":"374.754151ms","remote":"127.0.0.1:56582","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-02-03T10:27:48.978534Z","caller":"traceutil/trace.go:171","msg":"trace[2019771684] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"554.013456ms","start":"2026-02-03T10:27:48.424502Z","end":"2026-02-03T10:27:48.978515Z","steps":["trace[2019771684] 'process raft request' (duration: 532.409593ms)","trace[2019771684] 'compare' (duration: 21.523971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:48.978632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:48.424492Z","time spent":"554.091119ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:27:48.978791Z","caller":"traceutil/trace.go:171","msg":"trace[2066271682] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"552.493991ms","start":"2026-02-03T10:27:48.426292Z","end":"2026-02-03T10:27:48.978786Z","steps":["trace[2066271682] 'process raft request' (duration: 552.46766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:48.978818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:48.426275Z","time spent":"552.528272ms","remote":"127.0.0.1:56582","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-02-03T10:27:56.836979Z","caller":"traceutil/trace.go:171","msg":"trace[1670197189] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"360.335532ms","start":"2026-02-03T10:27:56.476622Z","end":"2026-02-03T10:27:56.836957Z","steps":["trace[1670197189] 'read index received' (duration: 360.111517ms)","trace[1670197189] 'applied index is now lower than readState.Index' (duration: 223.145µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:56.837209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.564387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-03T10:27:56.83724Z","caller":"traceutil/trace.go:171","msg":"trace[138259860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"360.628809ms","start":"2026-02-03T10:27:56.476602Z","end":"2026-02-03T10:27:56.837231Z","steps":["trace[138259860] 'agreement among raft nodes before linearized reading' (duration: 360.442515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:56.837274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:56.476596Z","time spent":"360.67051ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T10:27:56.83735Z","caller":"traceutil/trace.go:171","msg":"trace[1091376024] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"362.908061ms","start":"2026-02-03T10:27:56.474416Z","end":"2026-02-03T10:27:56.837324Z","steps":["trace[1091376024] 'process raft request' (duration: 362.379599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:56.8375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:56.474398Z","time spent":"363.010084ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:27:57.313145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.995056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T10:27:57.313214Z","caller":"traceutil/trace.go:171","msg":"trace[1212469599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"296.101638ms","start":"2026-02-03T10:27:57.017097Z","end":"2026-02-03T10:27:57.313198Z","steps":["trace[1212469599] 'range keys from in-memory index tree' (duration: 295.838362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:57.31349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.456131ms","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-02-03T10:27:57.313526Z","caller":"traceutil/trace.go:171","msg":"trace[2079385383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"262.550294ms","start":"2026-02-03T10:27:57.050966Z","end":"2026-02-03T10:27:57.313517Z","steps":["trace[2079385383] 'range keys from in-memory index tree' (duration: 262.295277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:57.3137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.315944ms","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-02-03T10:27:57.313734Z","caller":"traceutil/trace.go:171","msg":"trace[1460356196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"260.371404ms","start":"2026-02-03T10:27:57.053355Z","end":"2026-02-03T10:27:57.313726Z","steps":["trace[1460356196] 'range keys from in-memory index tree' (duration: 260.230052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:57.313868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.304955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:57.313901Z","caller":"traceutil/trace.go:171","msg":"trace[221241189] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:963; }","duration":"171.343396ms","start":"2026-02-03T10:27:57.14255Z","end":"2026-02-03T10:27:57.313893Z","steps":["trace[221241189] 'count revisions from in-memory index tree' (duration: 171.242324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:58.866373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.580228ms","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-02-03T10:27:58.866477Z","caller":"traceutil/trace.go:171","msg":"trace[599471608] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:979; }","duration":"116.762161ms","start":"2026-02-03T10:27:58.749694Z","end":"2026-02-03T10:27:58.866456Z","steps":["trace[599471608] 'count revisions from in-memory index tree' (duration: 116.495435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:00.96464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.987193ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:28:01.344277Z","caller":"traceutil/trace.go:171","msg":"trace[138892237] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"440.455115ms","start":"2026-02-03T10:28:00.903803Z","end":"2026-02-03T10:28:01.344258Z","steps":["trace[138892237] 'process raft request' (duration: 440.336642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.344399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:00.903787Z","time spent":"440.550307ms","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5005,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:01.34504Z","caller":"traceutil/trace.go:171","msg":"trace[1192472023] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1035; }","duration":"380.12405ms","start":"2026-02-03T10:28:00.964905Z","end":"2026-02-03T10:28:01.345029Z","steps":["trace[1192472023] 'read index received' (duration: 380.12029ms)","trace[1192472023] 'applied index is now lower than readState.Index' (duration: 3.19µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:01.345159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.105479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-03T10:28:01.345184Z","caller":"traceutil/trace.go:171","msg":"trace[536836913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"437.150889ms","start":"2026-02-03T10:28:00.908025Z","end":"2026-02-03T10:28:01.345176Z","steps":["trace[536836913] 'agreement among raft nodes before linearized reading' (duration: 437.059037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.345205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:00.908017Z","time spent":"437.18266ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:01.784003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.063408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:01.784121Z","caller":"traceutil/trace.go:171","msg":"trace[345543438] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"433.820834ms","start":"2026-02-03T10:28:01.350289Z","end":"2026-02-03T10:28:01.784109Z","steps":["trace[345543438] 'process raft request' (duration: 433.785573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.784162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.350275Z","time spent":"433.867855ms","remote":"127.0.0.1:56582","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-02-03T10:28:01.784405Z","caller":"traceutil/trace.go:171","msg":"trace[1151123198] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"784.141823ms","start":"2026-02-03T10:28:01.000255Z","end":"2026-02-03T10:28:01.784397Z","steps":["trace[1151123198] 'process raft request' (duration: 468.632914ms)","trace[1151123198] 'compare' (duration: 314.975537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:01.784443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.000219Z","time spent":"784.203805ms","remote":"127.0.0.1:56582","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-02-03T10:28:01.784497Z","caller":"traceutil/trace.go:171","msg":"trace[680928434] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"439.409929ms","start":"2026-02-03T10:28:01.345064Z","end":"2026-02-03T10:28:01.784474Z","steps":["trace[680928434] 'read index received' (duration: 123.717837ms)","trace[680928434] 'applied index is now lower than readState.Index' (duration: 315.689702ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:01.784637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.112911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:01.78466Z","caller":"traceutil/trace.go:171","msg":"trace[38132153] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:994; }","duration":"460.168862ms","start":"2026-02-03T10:28:01.324485Z","end":"2026-02-03T10:28:01.784654Z","steps":["trace[38132153] 'agreement among raft nodes before linearized reading' (duration: 460.13048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.784682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.324467Z","time spent":"460.207813ms","remote":"127.0.0.1:56524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:28:01.784716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.614798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:28:01.784742Z","caller":"traceutil/trace.go:171","msg":"trace[988841873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"440.670659ms","start":"2026-02-03T10:28:01.344064Z","end":"2026-02-03T10:28:01.784734Z","steps":["trace[988841873] 'agreement among raft nodes before linearized reading' (duration: 440.548375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.784764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.344054Z","time spent":"440.705121ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:01.784846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.559959ms","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-02-03T10:28:01.784869Z","caller":"traceutil/trace.go:171","msg":"trace[2064785565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"441.620811ms","start":"2026-02-03T10:28:01.343241Z","end":"2026-02-03T10:28:01.784862Z","steps":["trace[2064785565] 'agreement among raft nodes before linearized reading' (duration: 441.525259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.784886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.343231Z","time spent":"441.651381ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:01.784998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.165515ms","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-02-03T10:28:01.785016Z","caller":"traceutil/trace.go:171","msg":"trace[1780912065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"446.191195ms","start":"2026-02-03T10:28:01.338819Z","end":"2026-02-03T10:28:01.78501Z","steps":["trace[1780912065] 'agreement among raft nodes before linearized reading' (duration: 446.140784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:01.785033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.338812Z","time spent":"446.216716ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:02.510658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.454445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:02.510824Z","caller":"traceutil/trace.go:171","msg":"trace[565166253] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1037; }","duration":"233.230483ms","start":"2026-02-03T10:28:02.277583Z","end":"2026-02-03T10:28:02.510813Z","steps":["trace[565166253] 'read index received' (duration: 229.115699ms)","trace[565166253] 'applied index is now lower than readState.Index' (duration: 4.114104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:02.510884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.305784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:02.510901Z","caller":"traceutil/trace.go:171","msg":"trace[1307397243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"233.351336ms","start":"2026-02-03T10:28:02.277544Z","end":"2026-02-03T10:28:02.510895Z","steps":["trace[1307397243] 'agreement among raft nodes before linearized reading' (duration: 233.311015ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:02.511124Z","caller":"traceutil/trace.go:171","msg":"trace[1791101107] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"719.386984ms","start":"2026-02-03T10:28:01.791729Z","end":"2026-02-03T10:28:02.511116Z","steps":["trace[1791101107] 'process raft request' (duration: 423.410497ms)","trace[1791101107] 'compare' (duration: 295.292631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:02.51117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.791712Z","time spent":"719.435544ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:02.511276Z","caller":"traceutil/trace.go:171","msg":"trace[2056180296] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"718.988375ms","start":"2026-02-03T10:28:01.792261Z","end":"2026-02-03T10:28:02.51125Z","steps":["trace[2056180296] 'process raft request' (duration: 718.474223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:02.511314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.792247Z","time spent":"719.049516ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:02.511437Z","caller":"traceutil/trace.go:171","msg":"trace[89368989] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"717.790667ms","start":"2026-02-03T10:28:01.793641Z","end":"2026-02-03T10:28:02.511431Z","steps":["trace[89368989] 'process raft request' (duration: 717.127702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:02.51147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.793635Z","time spent":"717.816737ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:02.511572Z","caller":"traceutil/trace.go:171","msg":"trace[680923626] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"666.862021ms","start":"2026-02-03T10:28:01.844672Z","end":"2026-02-03T10:28:02.511534Z","steps":["trace[680923626] 'process raft request' (duration: 666.118664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:02.511602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:01.844656Z","time spent":"666.928593ms","remote":"127.0.0.1:56582","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-02-03T10:28:04.324079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.276504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-03T10:28:04.324146Z","caller":"traceutil/trace.go:171","msg":"trace[575887679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"516.385057ms","start":"2026-02-03T10:28:03.807746Z","end":"2026-02-03T10:28:04.324131Z","steps":["trace[575887679] 'range keys from in-memory index tree' (duration: 516.156821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.324176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.807729Z","time spent":"516.438997ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:04.324244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.556132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:04.324296Z","caller":"traceutil/trace.go:171","msg":"trace[137075440] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:999; }","duration":"951.675685ms","start":"2026-02-03T10:28:03.372603Z","end":"2026-02-03T10:28:04.324279Z","steps":["trace[137075440] 'count revisions from in-memory index tree' (duration: 951.457729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.32433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.372568Z","time spent":"951.753016ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-03T10:28:04.817397Z","caller":"traceutil/trace.go:171","msg":"trace[1512947433] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"220.055362ms","start":"2026-02-03T10:28:04.597319Z","end":"2026-02-03T10:28:04.817374Z","steps":["trace[1512947433] 'process raft request' (duration: 219.97633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.818086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.401262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:04.818147Z","caller":"traceutil/trace.go:171","msg":"trace[85882717] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1006; }","duration":"124.500354ms","start":"2026-02-03T10:28:04.693633Z","end":"2026-02-03T10:28:04.818133Z","steps":["trace[85882717] 'agreement among raft nodes before linearized reading' (duration: 124.085105ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:04.818329Z","caller":"traceutil/trace.go:171","msg":"trace[1478790210] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"220.604414ms","start":"2026-02-03T10:28:04.597687Z","end":"2026-02-03T10:28:04.818291Z","steps":["trace[1478790210] 'process raft request' (duration: 219.644603ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:04.818608Z","caller":"traceutil/trace.go:171","msg":"trace[1453033838] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"224.3063ms","start":"2026-02-03T10:28:04.594285Z","end":"2026-02-03T10:28:04.818591Z","steps":["trace[1453033838] 'process raft request' (duration: 138.300858ms)","trace[1453033838] 'compare' (duration: 84.515038ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:04.819018Z","caller":"traceutil/trace.go:171","msg":"trace[1175290280] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"223.00481ms","start":"2026-02-03T10:28:04.595999Z","end":"2026-02-03T10:28:04.819004Z","steps":["trace[1175290280] 'process raft request' (duration: 221.25144ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:04.818584Z","caller":"traceutil/trace.go:171","msg":"trace[238195638] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"181.812374ms","start":"2026-02-03T10:28:04.63661Z","end":"2026-02-03T10:28:04.818422Z","steps":["trace[238195638] 'process raft request' (duration: 180.815172ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:04.817646Z","caller":"traceutil/trace.go:171","msg":"trace[1740051259] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1044; }","duration":"123.953211ms","start":"2026-02-03T10:28:04.693673Z","end":"2026-02-03T10:28:04.817626Z","steps":["trace[1740051259] 'read index received' (duration: 38.925723ms)","trace[1740051259] 'applied index is now lower than readState.Index' (duration: 85.026158ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:07.20861Z","caller":"traceutil/trace.go:171","msg":"trace[1222247773] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"159.588331ms","start":"2026-02-03T10:28:07.049002Z","end":"2026-02-03T10:28:07.208591Z","steps":["trace[1222247773] 'process raft request' (duration: 158.71739ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:10.002703Z","caller":"traceutil/trace.go:171","msg":"trace[1806290337] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"175.289586ms","start":"2026-02-03T10:28:09.827396Z","end":"2026-02-03T10:28:10.002685Z","steps":["trace[1806290337] 'process raft request' (duration: 175.171524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:15.129353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.736193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:28:15.129433Z","caller":"traceutil/trace.go:171","msg":"trace[1024550841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"125.847436ms","start":"2026-02-03T10:28:15.003565Z","end":"2026-02-03T10:28:15.129413Z","steps":["trace[1024550841] 'agreement among raft nodes before linearized reading' (duration: 77.606843ms)","trace[1024550841] 'range keys from in-memory index tree' (duration: 48.016528ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:15.130392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.169528ms","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-02-03T10:28:15.130439Z","caller":"traceutil/trace.go:171","msg":"trace[1657537282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"180.24377ms","start":"2026-02-03T10:28:14.950183Z","end":"2026-02-03T10:28:15.130427Z","steps":["trace[1657537282] 'agreement among raft nodes before linearized reading' (duration: 130.946973ms)","trace[1657537282] 'range keys from in-memory index tree' (duration: 48.069269ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:15.253534Z","caller":"traceutil/trace.go:171","msg":"trace[808344744] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"118.636821ms","start":"2026-02-03T10:28:15.134863Z","end":"2026-02-03T10:28:15.2535Z","steps":["trace[808344744] 'process raft request' (duration: 114.294233ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:15.257669Z","caller":"traceutil/trace.go:171","msg":"trace[1559232276] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"120.502944ms","start":"2026-02-03T10:28:15.137148Z","end":"2026-02-03T10:28:15.257651Z","steps":["trace[1559232276] 'process raft request' (duration: 120.396472ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:15.257823Z","caller":"traceutil/trace.go:171","msg":"trace[255831200] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"118.54508ms","start":"2026-02-03T10:28:15.139271Z","end":"2026-02-03T10:28:15.257817Z","steps":["trace[255831200] 'process raft request' (duration: 118.352575ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:15.25795Z","caller":"traceutil/trace.go:171","msg":"trace[1091960694] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"116.963534ms","start":"2026-02-03T10:28:15.140979Z","end":"2026-02-03T10:28:15.257942Z","steps":["trace[1091960694] 'process raft request' (duration: 116.812101ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:21.261802Z","caller":"traceutil/trace.go:171","msg":"trace[1756323722] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"103.510019ms","start":"2026-02-03T10:28:21.158272Z","end":"2026-02-03T10:28:21.261782Z","steps":["trace[1756323722] 'process raft request' (duration: 68.006004ms)","trace[1756323722] 'compare' (duration: 35.214468ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:30:55.397097Z","caller":"traceutil/trace.go:171","msg":"trace[758377302] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"103.746403ms","start":"2026-02-03T10:30:55.293331Z","end":"2026-02-03T10:30:55.397077Z","steps":["trace[758377302] 'process raft request' (duration: 103.637981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:25.215266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.723275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:31:25.215348Z","caller":"traceutil/trace.go:171","msg":"trace[1818786968] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1874; }","duration":"169.865678ms","start":"2026-02-03T10:31:25.045464Z","end":"2026-02-03T10:31:25.215329Z","steps":["trace[1818786968] 'count revisions from in-memory index tree' (duration: 169.649534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:25.215453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.076906ms","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-02-03T10:31:25.215505Z","caller":"traceutil/trace.go:171","msg":"trace[1620808822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"401.176788ms","start":"2026-02-03T10:31:24.814316Z","end":"2026-02-03T10:31:25.215493Z","steps":["trace[1620808822] 'range keys from in-memory index tree' (duration: 400.932943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:25.215528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:24.814301Z","time spent":"401.221549ms","remote":"127.0.0.1:56582","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-02-03T10:31:25.215724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.592155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-03T10:31:25.21574Z","caller":"traceutil/trace.go:171","msg":"trace[993308800] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1874; }","duration":"356.639286ms","start":"2026-02-03T10:31:24.859096Z","end":"2026-02-03T10:31:25.215735Z","steps":["trace[993308800] 'count revisions from in-memory index tree' (duration: 356.504454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:25.215753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:24.859082Z","time spent":"356.666837ms","remote":"127.0.0.1:56402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":156,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:31:26.15151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.936405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-03T10:31:26.151606Z","caller":"traceutil/trace.go:171","msg":"trace[2073780939] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1876; }","duration":"341.066987ms","start":"2026-02-03T10:31:25.810518Z","end":"2026-02-03T10:31:26.151585Z","steps":["trace[2073780939] 'range keys from in-memory index tree' (duration: 340.763411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:26.151608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.027649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-02-03T10:31:26.15165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:25.810507Z","time spent":"341.133978ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-03T10:31:26.151672Z","caller":"traceutil/trace.go:171","msg":"trace[1495968087] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1876; }","duration":"193.128382ms","start":"2026-02-03T10:31:25.958524Z","end":"2026-02-03T10:31:26.151653Z","steps":["trace[1495968087] 'range keys from in-memory index tree' (duration: 192.878846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:26.151913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.972588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:31:26.151966Z","caller":"traceutil/trace.go:171","msg":"trace[323430516] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:1876; }","duration":"500.0601ms","start":"2026-02-03T10:31:25.651895Z","end":"2026-02-03T10:31:26.151955Z","steps":["trace[323430516] 'range keys from in-memory index tree' (duration: 499.865146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:26.151994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:25.651879Z","time spent":"500.109381ms","remote":"127.0.0.1:56352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.219\" "} {"level":"warn","ts":"2026-02-03T10:31:26.152424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.482898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-03T10:31:26.15246Z","caller":"traceutil/trace.go:171","msg":"trace[801691907] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1876; }","duration":"354.555599ms","start":"2026-02-03T10:31:25.797894Z","end":"2026-02-03T10:31:26.15245Z","steps":["trace[801691907] 'range keys from in-memory index tree' (duration: 354.380125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:26.15249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:25.797873Z","time spent":"354.609651ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-03T10:31:26.730061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.161016Z","time spent":"569.041231ms","remote":"127.0.0.1:56352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-03T10:31:27.790607Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.060758755s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:31:27.79191Z","caller":"traceutil/trace.go:171","msg":"trace[924561306] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1960; }","duration":"1.407554049s","start":"2026-02-03T10:31:26.384319Z","end":"2026-02-03T10:31:27.791873Z","steps":["trace[924561306] 'read index received' (duration: 345.640409ms)","trace[924561306] 'applied index is now lower than readState.Index' (duration: 1.06191284s)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:31:27.792097Z","caller":"traceutil/trace.go:171","msg":"trace[1367233134] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"1.631008344s","start":"2026-02-03T10:31:26.161081Z","end":"2026-02-03T10:31:27.79209Z","steps":["trace[1367233134] 'process raft request' (duration: 1.630628535s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.792176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.16106Z","time spent":"1.631067325s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:31:27.793222Z","caller":"traceutil/trace.go:171","msg":"trace[393514069] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"1.628853916s","start":"2026-02-03T10:31:26.164341Z","end":"2026-02-03T10:31:27.793195Z","steps":["trace[393514069] 'process raft request' (duration: 1.627465674s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.793363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.164335Z","time spent":"1.628957658s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:31:27.793481Z","caller":"traceutil/trace.go:171","msg":"trace[1512105151] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"1.628584718s","start":"2026-02-03T10:31:26.164889Z","end":"2026-02-03T10:31:27.793473Z","steps":["trace[1512105151] 'process raft request' (duration: 1.62694347s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.793516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.164882Z","time spent":"1.628614469s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:31:27.793598Z","caller":"traceutil/trace.go:171","msg":"trace[2081633741] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"1.627461983s","start":"2026-02-03T10:31:26.16613Z","end":"2026-02-03T10:31:27.793592Z","steps":["trace[2081633741] 'process raft request' (duration: 1.625723264s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.793629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.166124Z","time spent":"1.627487164s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:31:27.793783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271130163s","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-02-03T10:31:27.793812Z","caller":"traceutil/trace.go:171","msg":"trace[947074310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"1.271198005s","start":"2026-02-03T10:31:26.522607Z","end":"2026-02-03T10:31:27.793805Z","steps":["trace[947074310] 'agreement among raft nodes before linearized reading' (duration: 1.271064352s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.793833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.522586Z","time spent":"1.271242497s","remote":"127.0.0.1:56582","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-02-03T10:31:27.79384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409519304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-03T10:31:27.793869Z","caller":"traceutil/trace.go:171","msg":"trace[892454325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1882; }","duration":"1.409586605s","start":"2026-02-03T10:31:26.384274Z","end":"2026-02-03T10:31:27.793861Z","steps":["trace[892454325] 'agreement among raft nodes before linearized reading' (duration: 1.409433152s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.793889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.384256Z","time spent":"1.409628997s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:31:27.794496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.238179ms","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-02-03T10:31:27.794526Z","caller":"traceutil/trace.go:171","msg":"trace[339734308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1882; }","duration":"514.280801ms","start":"2026-02-03T10:31:27.280236Z","end":"2026-02-03T10:31:27.794517Z","steps":["trace[339734308] 'agreement among raft nodes before linearized reading' (duration: 514.202159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.79455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:27.280229Z","time spent":"514.314561ms","remote":"127.0.0.1:56582","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":"info","ts":"2026-02-03T10:31:27.794649Z","caller":"traceutil/trace.go:171","msg":"trace[1966520242] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"1.063394154s","start":"2026-02-03T10:31:26.731246Z","end":"2026-02-03T10:31:27.794641Z","steps":["trace[1966520242] 'process raft request' (duration: 1.061921681s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.794693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.731229Z","time spent":"1.063439664s","remote":"127.0.0.1:56352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:31:27.794832Z","caller":"traceutil/trace.go:171","msg":"trace[1429769093] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"1.055939407s","start":"2026-02-03T10:31:26.738883Z","end":"2026-02-03T10:31:27.794822Z","steps":["trace[1429769093] 'process raft request' (duration: 1.05432954s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.79488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.738874Z","time spent":"1.055981717s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:31:27.795033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.028235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:31:27.795056Z","caller":"traceutil/trace.go:171","msg":"trace[1201785605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1882; }","duration":"175.098107ms","start":"2026-02-03T10:31:27.619952Z","end":"2026-02-03T10:31:27.79505Z","steps":["trace[1201785605] 'agreement among raft nodes before linearized reading' (duration: 175.012675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.7954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.925199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:31:27.795422Z","caller":"traceutil/trace.go:171","msg":"trace[58857784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"939.97625ms","start":"2026-02-03T10:31:26.855439Z","end":"2026-02-03T10:31:27.795416Z","steps":["trace[58857784] 'agreement among raft nodes before linearized reading' (duration: 939.941699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.795439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.85541Z","time spent":"940.025581ms","remote":"127.0.0.1:56338","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-02-03T10:31:27.795534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.933933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:31:27.795551Z","caller":"traceutil/trace.go:171","msg":"trace[504559265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"517.983054ms","start":"2026-02-03T10:31:27.277563Z","end":"2026-02-03T10:31:27.795546Z","steps":["trace[504559265] 'agreement among raft nodes before linearized reading' (duration: 517.957143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.795567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:27.277545Z","time spent":"518.017725ms","remote":"127.0.0.1:56342","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-02-03T10:31:27.795892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089410849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-03T10:31:27.795916Z","caller":"traceutil/trace.go:171","msg":"trace[1583428899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1882; }","duration":"1.089478741s","start":"2026-02-03T10:31:26.706431Z","end":"2026-02-03T10:31:27.79591Z","steps":["trace[1583428899] 'agreement among raft nodes before linearized reading' (duration: 1.089385439s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:27.795935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:31:26.706412Z","time spent":"1.089518371s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-03T10:31:32.066074Z","caller":"traceutil/trace.go:171","msg":"trace[512107240] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"155.806782ms","start":"2026-02-03T10:31:31.910241Z","end":"2026-02-03T10:31:32.066048Z","steps":["trace[512107240] 'read index received' (duration: 111.435074ms)","trace[512107240] 'applied index is now lower than readState.Index' (duration: 44.370808ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:31:32.066253Z","caller":"traceutil/trace.go:171","msg":"trace[1989840284] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"164.109538ms","start":"2026-02-03T10:31:31.902134Z","end":"2026-02-03T10:31:32.066244Z","steps":["trace[1989840284] 'process raft request' (duration: 119.590157ms)","trace[1989840284] 'compare' (duration: 44.053581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:31:32.06643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.17597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-03T10:31:32.066455Z","caller":"traceutil/trace.go:171","msg":"trace[1529515499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1901; }","duration":"156.235811ms","start":"2026-02-03T10:31:31.910213Z","end":"2026-02-03T10:31:32.066449Z","steps":["trace[1529515499] 'agreement among raft nodes before linearized reading' (duration: 156.144639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:32.066558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.19311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:31:32.066578Z","caller":"traceutil/trace.go:171","msg":"trace[2107074223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1901; }","duration":"156.244161ms","start":"2026-02-03T10:31:31.910329Z","end":"2026-02-03T10:31:32.066573Z","steps":["trace[2107074223] 'agreement among raft nodes before linearized reading' (duration: 156.19734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:32.0669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.545382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363634"} {"level":"info","ts":"2026-02-03T10:31:32.066924Z","caller":"traceutil/trace.go:171","msg":"trace[636616274] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1901; }","duration":"113.572173ms","start":"2026-02-03T10:31:31.953346Z","end":"2026-02-03T10:31:32.066918Z","steps":["trace[636616274] 'agreement among raft nodes before linearized reading' (duration: 113.320386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:32.067221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.996572ms","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-02-03T10:31:32.067242Z","caller":"traceutil/trace.go:171","msg":"trace[285067596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1901; }","duration":"150.032283ms","start":"2026-02-03T10:31:31.917205Z","end":"2026-02-03T10:31:32.067237Z","steps":["trace[285067596] 'agreement among raft nodes before linearized reading' (duration: 149.957361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:32.067335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.391935ms","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-02-03T10:31:32.067354Z","caller":"traceutil/trace.go:171","msg":"trace[780657043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1901; }","duration":"156.425046ms","start":"2026-02-03T10:31:31.910925Z","end":"2026-02-03T10:31:32.06735Z","steps":["trace[780657043] 'agreement among raft nodes before linearized reading' (duration: 156.375775ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:31:43.015779Z","caller":"traceutil/trace.go:171","msg":"trace[991142170] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"121.0219ms","start":"2026-02-03T10:31:42.894729Z","end":"2026-02-03T10:31:43.015751Z","steps":["trace[991142170] 'process raft request' (duration: 25.883611ms)","trace[991142170] 'compare' (duration: 94.832622ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:31:43.016063Z","caller":"traceutil/trace.go:171","msg":"trace[1244330366] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"118.256398ms","start":"2026-02-03T10:31:42.897782Z","end":"2026-02-03T10:31:43.016038Z","steps":["trace[1244330366] 'process raft request' (duration: 117.830919ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:31:46.560044Z","caller":"traceutil/trace.go:171","msg":"trace[768377164] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"184.237831ms","start":"2026-02-03T10:31:46.375788Z","end":"2026-02-03T10:31:46.560026Z","steps":["trace[768377164] 'process raft request' (duration: 118.34367ms)","trace[768377164] 'compare' (duration: 65.568204ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:31:48.158702Z","caller":"traceutil/trace.go:171","msg":"trace[1736432600] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2057; }","duration":"118.045653ms","start":"2026-02-03T10:31:48.040637Z","end":"2026-02-03T10:31:48.158683Z","steps":["trace[1736432600] 'read index received' (duration: 90.186067ms)","trace[1736432600] 'applied index is now lower than readState.Index' (duration: 27.858456ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:31:48.159369Z","caller":"traceutil/trace.go:171","msg":"trace[180690254] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"131.23887ms","start":"2026-02-03T10:31:48.028112Z","end":"2026-02-03T10:31:48.159351Z","steps":["trace[180690254] 'process raft request' (duration: 102.716889ms)","trace[180690254] 'compare' (duration: 27.54269ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:31:48.160238Z","caller":"traceutil/trace.go:171","msg":"trace[643951599] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"125.372347ms","start":"2026-02-03T10:31:48.034852Z","end":"2026-02-03T10:31:48.160224Z","steps":["trace[643951599] 'process raft request' (duration: 123.787822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:31:48.160741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.080578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:31:48.160793Z","caller":"traceutil/trace.go:171","msg":"trace[2011888781] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1970; }","duration":"120.145281ms","start":"2026-02-03T10:31:48.040634Z","end":"2026-02-03T10:31:48.16078Z","steps":["trace[2011888781] 'agreement among raft nodes before linearized reading' (duration: 120.050938ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:32:06.568817Z","caller":"traceutil/trace.go:171","msg":"trace[1580842487] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"128.998009ms","start":"2026-02-03T10:32:06.439793Z","end":"2026-02-03T10:32:06.568791Z","steps":["trace[1580842487] 'process raft request' (duration: 55.904026ms)","trace[1580842487] 'compare' (duration: 72.725864ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:32:06.707427Z","caller":"traceutil/trace.go:171","msg":"trace[1364697636] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"127.900316ms","start":"2026-02-03T10:32:06.579505Z","end":"2026-02-03T10:32:06.707405Z","steps":["trace[1364697636] 'read index received' (duration: 122.475444ms)","trace[1364697636] 'applied index is now lower than readState.Index' (duration: 5.423742ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:32:06.707898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.371826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g9rfs\" ","response":"range_response_count:1 size:18062"} {"level":"info","ts":"2026-02-03T10:32:06.708141Z","caller":"traceutil/trace.go:171","msg":"trace[1797166322] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g9rfs; range_end:; response_count:1; response_revision:2046; }","duration":"128.629931ms","start":"2026-02-03T10:32:06.579471Z","end":"2026-02-03T10:32:06.708101Z","steps":["trace[1797166322] 'agreement among raft nodes before linearized reading' (duration: 128.284543ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:32:06.708846Z","caller":"traceutil/trace.go:171","msg":"trace[544818959] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"129.370899ms","start":"2026-02-03T10:32:06.579444Z","end":"2026-02-03T10:32:06.708815Z","steps":["trace[544818959] 'process raft request' (duration: 122.602146ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:32:06.710698Z","caller":"traceutil/trace.go:171","msg":"trace[349220170] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"131.092686ms","start":"2026-02-03T10:32:06.579598Z","end":"2026-02-03T10:32:06.71069Z","steps":["trace[349220170] 'process raft request' (duration: 130.922823ms)"],"step_count":1}